Re: 2.6.23-rc4-mm1

Andrew Morton
2007-09-01T16:16:58Z

> On Sat, 1 Sep 2007 18:07:48 +0200 "Torsten Kaiser" <just.for.lkml@googlemail.com> wrote:
> On 9/1/07, Andrew Morton <akpm@linux-foundation.org> wrote:
> >
> > ftp://ftp.kernel.org/pub/linux/kernel/people/akpm/patches/2.6/2.6.23-rc4/2.6.23-rc4-mm1/
>
> The good:
> > +hpet-force-enable-on-vt8235-37-chipsets.patch
> > +hpet-force-enable-on-vt8235-37-chipsets-fix.patch
>
> Kernel 2.6.23-rc4-mm1 works on one of my systems with:
> ...
> It now has a working HPET.

Great, thanks.

> The bad:
> sata_sil24 and/or libata are broken.

yup. Let's cc linux-ide.

> On my second system (MCP55 + SiI 3132) I see this:
> [ 3.890000] scsi0 : sata_sil24
> [ 3.900000] scsi1 : sata_sil24
> [ 3.900000] ata1: SATA max UDMA/100 host m128@0xefeffc00 port
> 0xefef8000 irq 16
> [ 3.920000] ata2: SATA max UDMA/100 host m128@0xefeffc00 port
> 0xefefa000 irq 16
> [ 4.300000] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> [ 4.360000] ata1.00: ATA-7: MAXTOR STM3320820AS, 3.AAE, max UDMA/133
> [ 4.370000] ata1.00: 625142448 sectors, multi 0: LBA48 NCQ (depth 31/32)
> [ 4.430000] ata1.00: configured for UDMA/100
> [ 4.500000] ieee1394: Node added: ID:BUS[0-00:1023] GUID[0010dc00005cc354]
> [ 4.500000] ieee1394: Host added: ID:BUS[0-01:1023] GUID[0011d80000c4c261]
> [ 4.790000] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> [ 4.850000] ata2.00: ATA-7: MAXTOR STM3320820AS, 3.AAE, max UDMA/133
> [ 4.860000] ata2.00: 625142448 sectors, multi 0: LBA48 NCQ (depth 31/32)
> [ 4.920000] ata2.00: configured for UDMA/100
> [ 4.930000] scsi 0:0:0:0: Direct-Access ATA MAXTOR
> STM332082 3.AA PQ: 0 ANSI: 5
> [ 4.960000] sd 0:0:0:0: [sda] 625142448 512-byte hardware sectors (320073 MB)
> [ 4.980000] sd 0:0:0:0: [sda] Write Protect is off
> [ 4.990000] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
> [ 4.990000] sd 0:0:0:0: [sda] Write cache: enabled, read cache:
> enabled, doesn't support DPO or FUA
> [ 5.020000] sd 0:0:0:0: [sda] 625142448 512-byte hardware sectors (320073 MB)
> [ 5.040000] sd 0:0:0:0: [sda] Write Protect is off
> [ 5.050000] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
> [ 5.050000] sd 0:0:0:0: [sda] Write cache: enabled, read cache:
> enabled, doesn't support DPO or FUA
> [ 5.080000] sda: sda1 sda2
> [ 5.110000] sd 0:0:0:0: [sda] Attached SCSI disk
> [ 5.120000] scsi 1:0:0:0: Direct-Access ATA MAXTOR
> STM332082 3.AA PQ: 0 ANSI: 5
> [ 5.140000] sd 1:0:0:0: [sdb] 625142448 512-byte hardware sectors (320073 MB)
> [ 5.170000] sd 1:0:0:0: [sdb] Write Protect is off
> [ 5.180000] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
> [ 5.180000] sd 1:0:0:0: [sdb] Write cache: enabled, read cache:
> enabled, doesn't support DPO or FUA
> [ 5.210000] sd 1:0:0:0: [sdb] 625142448 512-byte hardware sectors (320073 MB)
> [ 5.230000] sd 1:0:0:0: [sdb] Write Protect is off
> [ 5.240000] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
> [ 5.240000] sd 1:0:0:0: [sdb] Write cache: enabled, read cache:
> enabled, doesn't support DPO or FUA
> [ 5.270000] sdb: sdb1 sdb2
> [ 5.300000] sd 1:0:0:0: [sdb] Attached SCSI disk
> [snip]
> [ 12.120000] Freeing unused kernel memory: 340k freed
> [ 33.210000] md: Autodetecting RAID arrays.
> [ 33.300000] md: Scanned 5 and added 5 devices.
> [ 33.300000] md: autorun ...
> [ 33.300000] md: considering sdc2 ...
> [ 33.300000] md: adding sdc2 ...
> [ 33.300000] md: adding sdb2 ...
> [ 33.300000] md: sdb1 has different UUID to sdc2
> [ 33.300000] md: adding sda2 ...
> [ 33.300000] md: sda1 has different UUID to sdc2
> [ 33.300000] md: created md1
> [ 33.300000] md: bind<sda2>
> [ 33.300000] md: bind<sdb2>
> [ 33.300000] md: bind<sdc2>
> [ 33.300000] md: running: <sdc2><sdb2><sda2>
> [ 33.310000] raid5: device sdc2 operational as raid disk 2
> [ 33.310000] raid5: device sdb2 operational as raid disk 1
> [ 33.310000] raid5: device sda2 operational as raid disk 0
> [ 33.310000] raid5: allocated 3224kB for md1
> [ 33.310000] raid5: raid level 5 set md1 active with 3 out of 3
> devices, algorithm 2
> [ 33.310000] RAID5 conf printout:
> [ 33.310000] --- rd:3 wd:3
> [ 33.310000] disk 0, o:1, dev:sda2
> [ 33.310000] disk 1, o:1, dev:sdb2
> [ 33.310000] disk 2, o:1, dev:sdc2
> [ 33.320000] md1: bitmap initialized from disk: read 10/10 pages, set 115 bits
> [ 33.320000] created bitmap (145 pages) for device md1
> [ 63.420000] ata2.00: exception Emask 0x0 SAct 0x1 SErr 0x0 action 0x6 frozen
> [ 63.420000] ata2.00: cmd 61/08:00:09:d6:42/00:00:25:00:00/40 tag 0
> cdb 0x0 data 4096 out
> [ 63.420000] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
> 0x4 (timeout)
> [ 63.420000] ata2.00: status: {DRDY }
> [ 63.420000] ata2: hard resetting link
> [ 65.720000] ata2: softreset failed (port not ready)
> [ 65.720000] ata2: reset failed (errno=-5), retrying in 8 secs
> [ 73.420000] ata2: hard resetting link
> [ 75.720000] ata2: softreset failed (port not ready)
> [ 75.720000] ata2: reset failed (errno=-5), retrying in 8 secs
> [ 83.420000] ata2: hard resetting link
> [ 85.720000] ata2: softreset failed (port not ready)
> [ 85.720000] ata2: reset failed (errno=-5), retrying in 33 secs
> [ 118.420000] ata2: limiting SATA link speed to 1.5 Gbps
> [ 118.420000] ata2: hard resetting link
> [ 120.720000] ata2: softreset failed (port not ready)
> [ 120.720000] ata2: reset failed, giving up
> [ 120.720000] ata2.00: disabled
> [ 120.720000] ata2: EH complete
> [ 120.720000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 120.720000] end_request: I/O error, dev sdb, sector 625137161
> [ 120.720000] md: super_written gets error=-5, uptodate=0
> [ 120.720000] raid5: Disk failure on sdb2, disabling device.
> Operation continuing on 2 devices
> [ 120.750000] md: considering sdb1 ...
> [ 120.750000] RAID5 conf printout:
> [ 120.750000] --- rd:3 wd:2
> [ 120.750000] md: adding sdb1 ...
> [ 120.750000] disk 0, o:1, dev:sda2
> [ 120.750000] disk 1, o:0, dev:sdb2
> [ 120.750000] disk 2, o:1, dev:sdc2
> [ 120.750000] md: adding sda1 ...
> [ 120.750000] md: created md0
> [ 120.750000] md: bind<sda1>
> [ 120.750000] md: bind<sdb1>
> [ 120.750000] md: running: <sdb1><sda1>
> [ 120.760000] raid1: raid set md0 active with 2 out of 2 mirrors
> [ 120.760000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 120.760000] end_request: I/O error, dev sdb, sector 19550919
> [ 120.780000] RAID5 conf printout:
> [ 120.780000] --- rd:3 wd:2
> [ 120.780000] disk 0, o:1, dev:sda2
> [ 120.780000] disk 2, o:1, dev:sdc2
> [ 120.780000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 120.780000] end_request: I/O error, dev sdb, sector 19550927
> [ 120.780000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 120.780000] end_request: I/O error, dev sdb, sector 19550935
> [ 120.780000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 120.780000] end_request: I/O error, dev sdb, sector 19550943
> [ 120.780000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 120.780000] end_request: I/O error, dev sdb, sector 19550951
> [ 120.780000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 120.780000] end_request: I/O error, dev sdb, sector 19550959
> [ 120.780000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 120.780000] end_request: I/O error, dev sdb, sector 19550967
> [ 120.790000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 120.790000] end_request: I/O error, dev sdb, sector 19550975
> [ 120.790000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 120.790000] end_request: I/O error, dev sdb, sector 19550983
> [ 120.790000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 120.790000] end_request: I/O error, dev sdb, sector 19550991
> [ 120.790000] md0: bitmap initialized from disk: read 10/10 pages, set 0 bits
> [ 120.790000] created bitmap (150 pages) for device md0
> [ 120.790000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 120.790000] end_request: I/O error, dev sdb, sector 19550919
> [ 120.790000] md: super_written gets error=-5, uptodate=0
> [ 120.790000] raid1: Disk failure on sdb1, disabling device.
> [ 120.790000] Operation continuing on 1 devices
> [ 120.810000] md: ... autorun DONE.
> [ 120.810000] RAID1 conf printout:
> [ 120.810000] --- wd:1 rd:2
> [ 120.810000] disk 0, wo:0, o:1, dev:sda1
> [ 120.810000] disk 1, wo:1, o:0, dev:sdb1
> [ 120.860000] RAID1 conf printout:
> [ 120.860000] --- wd:1 rd:2
> [ 120.860000] disk 0, wo:0, o:1, dev:sda1
> [ 129.360000] Filesystem "dm-0": Disabling barriers, trial barrier write failed
> [ 129.390000] XFS mounting filesystem dm-0
> [ 129.600000] Ending clean XFS mount for filesystem: dm-0
> [ 132.850000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 132.850000] end_request: I/O error, dev sdb, sector 625137153
> [ 132.850000] Buffer I/O error on device sdb2, logical block 75698256
> [ 132.850000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 132.850000] end_request: I/O error, dev sdb, sector 625137153
> [ 132.850000] Buffer I/O error on device sdb2, logical block 75698256
> [ 132.850000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 132.850000] end_request: I/O error, dev sdb, sector 625137153
> [ 132.850000] Buffer I/O error on device sdb2, logical block 75698256
> [ 132.850000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 132.850000] end_request: I/O error, dev sdb, sector 19551105
> [ 132.850000] Buffer I/O error on device sdb2, logical block 0
> [ 132.850000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 132.850000] end_request: I/O error, dev sdb, sector 19551113
> [ 132.850000] Buffer I/O error on device sdb2, logical block 1
> [ 132.850000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 132.850000] end_request: I/O error, dev sdb, sector 19551105
> [ 132.850000] Buffer I/O error on device sdb2, logical block 0
> [ 132.850000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 132.850000] end_request: I/O error, dev sdb, sector 19551105
> [ 132.850000] Buffer I/O error on device sdb2, logical block 0
> [ 132.850000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 132.850000] end_request: I/O error, dev sdb, sector 625137337
> [ 132.850000] Buffer I/O error on device sdb2, logical block 75698279
> [ 132.850000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 132.850000] end_request: I/O error, dev sdb, sector 625137337
> [ 132.850000] Buffer I/O error on device sdb2, logical block 75698279
> [ 132.850000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 132.850000] end_request: I/O error, dev sdb, sector 625137337
> [ 132.850000] Buffer I/O error on device sdb2, logical block 75698279
> [ 132.850000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 132.850000] end_request: I/O error, dev sdb, sector 625137337
> [ 132.850000] sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET
> driverbyte=DRIVER_OK,SUGGEST_OK
> [ 132.850000] end_request: I/O error, dev sdb, sector 625137337
> ...
>
> After that the system booted up fine, only running with the two of
> three RAID drives.
> (sda is on sata_sil24, sdc on sata_nv. I used the sata_nv.swncq=1 switch)
>
> The ugly:
> I wanted to verify that this was not a onetime bug and rebooted the system.
> This time md kicked sdb because it was stale and then also kicked sda
> with an error similar to the above. Which killed the RAID5 completely.
> :(
> At least I was able to resurrect it with mdadm --force.
>
> So the sata_sil24 error seems repeatable, but also not limited to one
> specific port.
>
> The system is now up again running 2.6.23-rc3-mm1 with all three drives.
>
> Torsten