linux-ide.vger.kernel.org archive mirror
 help / color / mirror / Atom feed
* sata_promise: port is slow to respond, reset failed
@ 2007-09-02 11:12 Peter Favrholdt
  2007-10-14  9:21 ` Bug is fixed in 2.6.23.1: " Peter Favrholdt
  0 siblings, 1 reply; 3+ messages in thread
From: Peter Favrholdt @ 2007-09-02 11:12 UTC (permalink / raw)
  To: linux-ide

Hi,

I'm still experiencing the same "port is slow to respond" problem using 
sata_promise in linux-2.6.22.6 with my Promise Technology, Inc. PDC40718 
(SATA 300 TX4) (rev 02) and 4 Seagate 500GB ES drives:
         Model Number:       ST3500630NS
         Firmware Revision:  3.AEE
         (with 1.5/3.0Gbps jumper removed = 3.0Gbps)

After doing:

dd if=/dev/sda of=/dev/null bs=1M &
dd if=/dev/sdb of=/dev/null bs=1M &
dd if=/dev/sdc of=/dev/null bs=1M &
dd if=/dev/sdd of=/dev/null bs=1M &

it runs fine for a while, then:

[  810.545909] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x1380000 
action 0x2 frozen
[  810.545923] ata1.00: cmd c8/00:00:00:33:e6/00:00:00:00:00/e1 tag 0 
cdb 0x0 data 131072 in
[  810.545926]          res 40/00:28:00:00:00/00:00:00:00:00/40 Emask 
0x4 (timeout)
[  815.913113] ata1: port is slow to respond, please be patient (Status 
0xff)
[  820.590706] ata1: device not ready (errno=-16), forcing hardreset
[  820.590716] ata1: hard resetting port
[  826.137780] ata1: port is slow to respond, please be patient (Status 
0xff)
[  830.635488] ata1: COMRESET failed (errno=-16)
[  830.635497] ata1: hard resetting port
[  836.182563] ata1: port is slow to respond, please be patient (Status 
0xff)
[  840.680236] ata1: COMRESET failed (errno=-16)
[  840.680245] ata1: hard resetting port
[  846.227361] ata1: port is slow to respond, please be patient (Status 
0xff)
[  875.672028] ata1: COMRESET failed (errno=-16)
[  875.672037] ata1: limiting SATA link speed to 1.5 Gbps
[  875.672041] ata1: hard resetting port
[  880.679454] ata1: COMRESET failed (errno=-16)
[  880.679463] ata1: reset failed, giving up
[  880.679466] ata1.00: disabled
[  880.679480] ata1: EH complete
[  880.679545] sd 0:0:0:0: [sda] Result: hostbyte=0x04 driverbyte=0x00
[  880.679550] end_request: I/O error, dev sda, sector 31863552
[  880.679555] Buffer I/O error on device sda, logical block 3982944
[  880.679561] Buffer I/O error on device sda, logical block 3982945
[  880.679565] Buffer I/O error on device sda, logical block 3982946
[  880.679569] Buffer I/O error on device sda, logical block 3982947
[  880.679573] Buffer I/O error on device sda, logical block 3982948
[  880.679578] Buffer I/O error on device sda, logical block 3982949
[  880.679582] Buffer I/O error on device sda, logical block 3982950
[  880.679586] Buffer I/O error on device sda, logical block 3982951
[  880.679590] Buffer I/O error on device sda, logical block 3982952
[  880.679594] Buffer I/O error on device sda, logical block 3982953
[  880.680296] sd 0:0:0:0: [sda] Result: hostbyte=0x04 driverbyte=0x00
[  880.680301] end_request: I/O error, dev sda, sector 31863808
[  880.680877] sd 0:0:0:0: [sda] Result: hostbyte=0x04 driverbyte=0x00
[  880.680882] end_request: I/O error, dev sda, sector 31863552
[  880.681383] sd 0:0:0:0: [sda] Result: hostbyte=0x04 driverbyte=0x00
[  880.681388] end_request: I/O error, dev sda, sector 31863552

The "funny" thing is that it runs fine using linux-2.6.21-rc2 with 
Mikael Pettersson's "1.5Gbps only" patch.

I have replaced the cables without any change. I'm quite sure this isn't 
a hardware problem as I have uptime counting in months without any problems.

Here is the relevant part of dmesg (detecting drives):

[   27.612125] scsi3 : sata_promise
[   27.612214] ata1: SATA max UDMA/133 cmd 0xe0814380 ctl 0xe08143b8 
bmdma 0x00000000 irq 19
[   27.612274] ata2: SATA max UDMA/133 cmd 0xe0814280 ctl 0xe08142b8 
bmdma 0x00000000 irq 19
[   27.612334] ata3: SATA max UDMA/133 cmd 0xe0814200 ctl 0xe0814238 
bmdma 0x00000000 irq 19
[   27.612394] ata4: SATA max UDMA/133 cmd 0xe0814300 ctl 0xe0814338 
bmdma 0x00000000 irq 19
[   28.092787] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[   28.143577] ata1.00: ATA-7: ST3500630NS, 3.AEE, max UDMA/133
[   28.143626] ata1.00: 976773168 sectors, multi 0: LBA48 NCQ (depth 0/32)
[   28.235153] ata1.00: configured for UDMA/133
[   28.722457] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[   28.770752] ata2.00: ATA-7: ST3500630NS, 3.AEE, max UDMA/133
[   28.770800] ata2.00: 976773168 sectors, multi 0: LBA48 NCQ (depth 0/32)
[   28.854011] ata2.00: configured for UDMA/133
[   29.342135] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[   29.389819] ata3.00: ATA-7: ST3500630NS, 3.AEE, max UDMA/133
[   29.389867] ata3.00: 976773168 sectors, multi 0: LBA48 NCQ (depth 0/32)
[   29.473081] ata3.00: configured for UDMA/133
[   29.961812] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[   30.012878] ata4.00: ATA-7: ST3500630NS, 3.AEE, max UDMA/133
[   30.012926] ata4.00: 976773168 sectors, multi 0: LBA48 NCQ (depth 0/32)
[   30.104452] ata4.00: configured for UDMA/133
[   30.104580] scsi 0:0:0:0: Direct-Access     ATA      ST3500630NS 
  3.AE PQ: 0 ANSI: 5
[   30.104720] sd 0:0:0:0: [sda] 976773168 512-byte hardware sectors 
(500108 MB)
[   30.104780] sd 0:0:0:0: [sda] Write Protect is off
[   30.104827] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[   30.104841] sd 0:0:0:0: [sda] Write cache: enabled, read cache: 
enabled, doesn't support DPO or FUA
[   30.104936] sd 0:0:0:0: [sda] 976773168 512-byte hardware sectors 
(500108 MB)
[   30.104992] sd 0:0:0:0: [sda] Write Protect is off
[   30.105039] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[   30.105051] sd 0:0:0:0: [sda] Write cache: enabled, read cache: 
enabled, doesn't support DPO or FUA
[   30.105110]  sda: unknown partition table
[   30.123283] sd 0:0:0:0: [sda] Attached SCSI disk
[   30.123389] sd 0:0:0:0: Attached scsi generic sg0 type 0
[   30.123494] scsi 1:0:0:0: Direct-Access     ATA      ST3500630NS 
  3.AE PQ: 0 ANSI: 5
[   30.123620] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors 
(500108 MB)
[   30.123676] sd 1:0:0:0: [sdb] Write Protect is off
[   30.123723] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[   30.123735] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: 
enabled, doesn't support DPO or FUA
[   30.123823] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors 
(500108 MB)
[   30.123878] sd 1:0:0:0: [sdb] Write Protect is off
[   30.123925] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[   30.123938] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: 
enabled, doesn't support DPO or FUA
[   30.123997]  sdb: unknown partition table
[   30.142694] sd 1:0:0:0: [sdb] Attached SCSI disk
[   30.142796] sd 1:0:0:0: Attached scsi generic sg1 type 0
[   30.142897] scsi 2:0:0:0: Direct-Access     ATA      ST3500630NS 
  3.AE PQ: 0 ANSI: 5
[   30.143025] sd 2:0:0:0: [sdc] 976773168 512-byte hardware sectors 
(500108 MB)
[   30.143080] sd 2:0:0:0: [sdc] Write Protect is off
[   30.143127] sd 2:0:0:0: [sdc] Mode Sense: 00 3a 00 00
[   30.143140] sd 2:0:0:0: [sdc] Write cache: enabled, read cache: 
enabled, doesn't support DPO or FUA
[   30.143225] sd 2:0:0:0: [sdc] 976773168 512-byte hardware sectors 
(500108 MB)
[   30.143279] sd 2:0:0:0: [sdc] Write Protect is off
[   30.143326] sd 2:0:0:0: [sdc] Mode Sense: 00 3a 00 00
[   30.143339] sd 2:0:0:0: [sdc] Write cache: enabled, read cache: 
enabled, doesn't support DPO or FUA
[   30.143396]  sdc: unknown partition table
[   30.154054] sd 2:0:0:0: [sdc] Attached SCSI disk
[   30.154149] sd 2:0:0:0: Attached scsi generic sg2 type 0
[   30.154257] scsi 3:0:0:0: Direct-Access     ATA      ST3500630NS 
  3.AE PQ: 0 ANSI: 5
[   30.154381] sd 3:0:0:0: [sdd] 976773168 512-byte hardware sectors 
(500108 MB)
[   30.154437] sd 3:0:0:0: [sdd] Write Protect is off
[   30.154484] sd 3:0:0:0: [sdd] Mode Sense: 00 3a 00 00
[   30.154496] sd 3:0:0:0: [sdd] Write cache: enabled, read cache: 
enabled, doesn't support DPO or FUA
[   30.154580] sd 3:0:0:0: [sdd] 976773168 512-byte hardware sectors 
(500108 MB)
[   30.154635] sd 3:0:0:0: [sdd] Write Protect is off
[   30.154682] sd 3:0:0:0: [sdd] Mode Sense: 00 3a 00 00
[   30.154694] sd 3:0:0:0: [sdd] Write cache: enabled, read cache: 
enabled, doesn't support DPO or FUA
[   30.154753]  sdd: unknown partition table
[   30.169230] sd 3:0:0:0: [sdd] Attached SCSI disk
[   30.169329] sd 3:0:0:0: Attached scsi generic sg3 type 0

I'll be happy to try any patches/suggestions.

Best regards,

Peter

^ permalink raw reply	[flat|nested] 3+ messages in thread

* Bug is fixed in 2.6.23.1: sata_promise: port is slow to respond, reset failed
  2007-09-02 11:12 sata_promise: port is slow to respond, reset failed Peter Favrholdt
@ 2007-10-14  9:21 ` Peter Favrholdt
  2007-11-09 23:58   ` Correction " Peter Favrholdt
  0 siblings, 1 reply; 3+ messages in thread
From: Peter Favrholdt @ 2007-10-14  9:21 UTC (permalink / raw)
  To: linux-ide

Hi,

The problem is solved in 2.6.23.1 regarding the "port slow to respond" 
issue.

I'm using sata_promise on Promise Technology, Inc. PDC40718 (SATA 300 
TX4) (rev 02) and 4 Seagate 500GB ES drives.

Using 2.6.23.1 it is possible to run

dd if=/dev/sda of=/dev/null bs=1M &
dd if=/dev/sdb of=/dev/null bs=1M &
dd if=/dev/sdc of=/dev/null bs=1M &
dd if=/dev/sdd of=/dev/null bs=1M &

And it just runs perfectly to the end with no hickups :-)

Thank you very much :-)

Best regards,

Peter

Peter Favrholdt wrote:
> Hi,
> 
> I'm still experiencing the same "port is slow to respond" problem using 
> sata_promise in linux-2.6.22.6 with my Promise Technology, Inc. PDC40718 
> (SATA 300 TX4) (rev 02) and 4 Seagate 500GB ES drives:
>         Model Number:       ST3500630NS
>         Firmware Revision:  3.AEE
>         (with 1.5/3.0Gbps jumper removed = 3.0Gbps)
> 
> After doing:
> 
> dd if=/dev/sda of=/dev/null bs=1M &
> dd if=/dev/sdb of=/dev/null bs=1M &
> dd if=/dev/sdc of=/dev/null bs=1M &
> dd if=/dev/sdd of=/dev/null bs=1M &
> 
> it runs fine for a while, then:
> 
> [  810.545909] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x1380000 
> action 0x2 frozen
> [  810.545923] ata1.00: cmd c8/00:00:00:33:e6/00:00:00:00:00/e1 tag 0 
> cdb 0x0 data 131072 in
> [  810.545926]          res 40/00:28:00:00:00/00:00:00:00:00/40 Emask 
> 0x4 (timeout)
> [  815.913113] ata1: port is slow to respond, please be patient (Status 
> 0xff)
> [  820.590706] ata1: device not ready (errno=-16), forcing hardreset
> [  820.590716] ata1: hard resetting port
> [  826.137780] ata1: port is slow to respond, please be patient (Status 
> 0xff)
> [  830.635488] ata1: COMRESET failed (errno=-16)
> [  830.635497] ata1: hard resetting port
> [  836.182563] ata1: port is slow to respond, please be patient (Status 
> 0xff)
> [  840.680236] ata1: COMRESET failed (errno=-16)
> [  840.680245] ata1: hard resetting port
> [  846.227361] ata1: port is slow to respond, please be patient (Status 
> 0xff)
> [  875.672028] ata1: COMRESET failed (errno=-16)
> [  875.672037] ata1: limiting SATA link speed to 1.5 Gbps
> [  875.672041] ata1: hard resetting port
> [  880.679454] ata1: COMRESET failed (errno=-16)
> [  880.679463] ata1: reset failed, giving up
> [  880.679466] ata1.00: disabled
> [  880.679480] ata1: EH complete
> [  880.679545] sd 0:0:0:0: [sda] Result: hostbyte=0x04 driverbyte=0x00
> [  880.679550] end_request: I/O error, dev sda, sector 31863552
> [  880.679555] Buffer I/O error on device sda, logical block 3982944
> [  880.679561] Buffer I/O error on device sda, logical block 3982945
> [  880.679565] Buffer I/O error on device sda, logical block 3982946
> [  880.679569] Buffer I/O error on device sda, logical block 3982947
> [  880.679573] Buffer I/O error on device sda, logical block 3982948
> [  880.679578] Buffer I/O error on device sda, logical block 3982949
> [  880.679582] Buffer I/O error on device sda, logical block 3982950
> [  880.679586] Buffer I/O error on device sda, logical block 3982951
> [  880.679590] Buffer I/O error on device sda, logical block 3982952
> [  880.679594] Buffer I/O error on device sda, logical block 3982953
> [  880.680296] sd 0:0:0:0: [sda] Result: hostbyte=0x04 driverbyte=0x00
> [  880.680301] end_request: I/O error, dev sda, sector 31863808
> [  880.680877] sd 0:0:0:0: [sda] Result: hostbyte=0x04 driverbyte=0x00
> [  880.680882] end_request: I/O error, dev sda, sector 31863552
> [  880.681383] sd 0:0:0:0: [sda] Result: hostbyte=0x04 driverbyte=0x00
> [  880.681388] end_request: I/O error, dev sda, sector 31863552
> 
> The "funny" thing is that it runs fine using linux-2.6.21-rc2 with 
> Mikael Pettersson's "1.5Gbps only" patch.
> 
> I have replaced the cables without any change. I'm quite sure this isn't 
> a hardware problem as I have uptime counting in months without any 
> problems.
> 
> Here is the relevant part of dmesg (detecting drives):
> 
> [   27.612125] scsi3 : sata_promise
> [   27.612214] ata1: SATA max UDMA/133 cmd 0xe0814380 ctl 0xe08143b8 
> bmdma 0x00000000 irq 19
> [   27.612274] ata2: SATA max UDMA/133 cmd 0xe0814280 ctl 0xe08142b8 
> bmdma 0x00000000 irq 19
> [   27.612334] ata3: SATA max UDMA/133 cmd 0xe0814200 ctl 0xe0814238 
> bmdma 0x00000000 irq 19
> [   27.612394] ata4: SATA max UDMA/133 cmd 0xe0814300 ctl 0xe0814338 
> bmdma 0x00000000 irq 19
> [   28.092787] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> [   28.143577] ata1.00: ATA-7: ST3500630NS, 3.AEE, max UDMA/133
> [   28.143626] ata1.00: 976773168 sectors, multi 0: LBA48 NCQ (depth 0/32)
> [   28.235153] ata1.00: configured for UDMA/133
> [   28.722457] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> [   28.770752] ata2.00: ATA-7: ST3500630NS, 3.AEE, max UDMA/133
> [   28.770800] ata2.00: 976773168 sectors, multi 0: LBA48 NCQ (depth 0/32)
> [   28.854011] ata2.00: configured for UDMA/133
> [   29.342135] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> [   29.389819] ata3.00: ATA-7: ST3500630NS, 3.AEE, max UDMA/133
> [   29.389867] ata3.00: 976773168 sectors, multi 0: LBA48 NCQ (depth 0/32)
> [   29.473081] ata3.00: configured for UDMA/133
> [   29.961812] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> [   30.012878] ata4.00: ATA-7: ST3500630NS, 3.AEE, max UDMA/133
> [   30.012926] ata4.00: 976773168 sectors, multi 0: LBA48 NCQ (depth 0/32)
> [   30.104452] ata4.00: configured for UDMA/133
> [   30.104580] scsi 0:0:0:0: Direct-Access     ATA      ST3500630NS 
>  3.AE PQ: 0 ANSI: 5
> [   30.104720] sd 0:0:0:0: [sda] 976773168 512-byte hardware sectors 
> (500108 MB)
> [   30.104780] sd 0:0:0:0: [sda] Write Protect is off
> [   30.104827] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
> [   30.104841] sd 0:0:0:0: [sda] Write cache: enabled, read cache: 
> enabled, doesn't support DPO or FUA
> [   30.104936] sd 0:0:0:0: [sda] 976773168 512-byte hardware sectors 
> (500108 MB)
> [   30.104992] sd 0:0:0:0: [sda] Write Protect is off
> [   30.105039] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
> [   30.105051] sd 0:0:0:0: [sda] Write cache: enabled, read cache: 
> enabled, doesn't support DPO or FUA
> [   30.105110]  sda: unknown partition table
> [   30.123283] sd 0:0:0:0: [sda] Attached SCSI disk
> [   30.123389] sd 0:0:0:0: Attached scsi generic sg0 type 0
> [   30.123494] scsi 1:0:0:0: Direct-Access     ATA      ST3500630NS 
>  3.AE PQ: 0 ANSI: 5
> [   30.123620] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors 
> (500108 MB)
> [   30.123676] sd 1:0:0:0: [sdb] Write Protect is off
> [   30.123723] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
> [   30.123735] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: 
> enabled, doesn't support DPO or FUA
> [   30.123823] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors 
> (500108 MB)
> [   30.123878] sd 1:0:0:0: [sdb] Write Protect is off
> [   30.123925] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
> [   30.123938] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: 
> enabled, doesn't support DPO or FUA
> [   30.123997]  sdb: unknown partition table
> [   30.142694] sd 1:0:0:0: [sdb] Attached SCSI disk
> [   30.142796] sd 1:0:0:0: Attached scsi generic sg1 type 0
> [   30.142897] scsi 2:0:0:0: Direct-Access     ATA      ST3500630NS 
>  3.AE PQ: 0 ANSI: 5
> [   30.143025] sd 2:0:0:0: [sdc] 976773168 512-byte hardware sectors 
> (500108 MB)
> [   30.143080] sd 2:0:0:0: [sdc] Write Protect is off
> [   30.143127] sd 2:0:0:0: [sdc] Mode Sense: 00 3a 00 00
> [   30.143140] sd 2:0:0:0: [sdc] Write cache: enabled, read cache: 
> enabled, doesn't support DPO or FUA
> [   30.143225] sd 2:0:0:0: [sdc] 976773168 512-byte hardware sectors 
> (500108 MB)
> [   30.143279] sd 2:0:0:0: [sdc] Write Protect is off
> [   30.143326] sd 2:0:0:0: [sdc] Mode Sense: 00 3a 00 00
> [   30.143339] sd 2:0:0:0: [sdc] Write cache: enabled, read cache: 
> enabled, doesn't support DPO or FUA
> [   30.143396]  sdc: unknown partition table
> [   30.154054] sd 2:0:0:0: [sdc] Attached SCSI disk
> [   30.154149] sd 2:0:0:0: Attached scsi generic sg2 type 0
> [   30.154257] scsi 3:0:0:0: Direct-Access     ATA      ST3500630NS 
>  3.AE PQ: 0 ANSI: 5
> [   30.154381] sd 3:0:0:0: [sdd] 976773168 512-byte hardware sectors 
> (500108 MB)
> [   30.154437] sd 3:0:0:0: [sdd] Write Protect is off
> [   30.154484] sd 3:0:0:0: [sdd] Mode Sense: 00 3a 00 00
> [   30.154496] sd 3:0:0:0: [sdd] Write cache: enabled, read cache: 
> enabled, doesn't support DPO or FUA
> [   30.154580] sd 3:0:0:0: [sdd] 976773168 512-byte hardware sectors 
> (500108 MB)
> [   30.154635] sd 3:0:0:0: [sdd] Write Protect is off
> [   30.154682] sd 3:0:0:0: [sdd] Mode Sense: 00 3a 00 00
> [   30.154694] sd 3:0:0:0: [sdd] Write cache: enabled, read cache: 
> enabled, doesn't support DPO or FUA
> [   30.154753]  sdd: unknown partition table
> [   30.169230] sd 3:0:0:0: [sdd] Attached SCSI disk
> [   30.169329] sd 3:0:0:0: Attached scsi generic sg3 type 0
> 
> I'll be happy to try any patches/suggestions.
> 
> Best regards,
> 
> Peter
> -
> To unsubscribe from this list: send the line "unsubscribe linux-ide" in
> the body of a message to majordomo@vger.kernel.org
> More majordomo info at  http://vger.kernel.org/majordomo-info.html


^ permalink raw reply	[flat|nested] 3+ messages in thread

* Correction Re: Bug is fixed in 2.6.23.1: sata_promise: port is slow to respond, reset failed
  2007-10-14  9:21 ` Bug is fixed in 2.6.23.1: " Peter Favrholdt
@ 2007-11-09 23:58   ` Peter Favrholdt
  0 siblings, 0 replies; 3+ messages in thread
From: Peter Favrholdt @ 2007-11-09 23:58 UTC (permalink / raw)
  To: linux-ide

Hi,

I previously wrote this issue was fixed by upgrading to 2.6.23.1

There were some mails on this list regarding a workaround for an Asic 
bug and of course I'm looking forward to trying it :-)

Anyway here goes for completeness:

* 2.6.23.1 completed dd-stress tests as described earlier (these same 
tests would always make 2.6.22.9 fail before completing even a single run)

* after 21 days and 8 hours normal operation, one sata channel froze 
while doing checkarray with the following dmesg output (only md/sata 
stuff - rest deleted):

01:06:02 kernel: [1843824.893109] md: data-check of RAID array md0
01:06:02 kernel: [1843824.893117] md: minimum _guaranteed_  speed: 1000 
KB/sec/disk.
01:06:02 kernel: [1843824.893121] md: using maximum available idle IO 
bandwidth (but not more than 200000 KB/sec) for data-check.
01:06:02 kernel: [1843824.893126] md: using 128k window, over a total of 
488386496 blocks.
01:06:02 mdadm: RebuildStarted event detected on md device /dev/md0
01:15:30 kernel: [1844393.053517] ata1.00: exception Emask 0x0 SAct 0x0 
SErr 0x1380000 action 0x2 frozen
01:15:30 kernel: [1844393.053533] ata1.00: cmd 
25/00:00:00:1e:e6/00:04:01:00:00/e0 tag 0 cdb 0x0 data 524288 in
01:15:30 kernel: [1844393.053535]          res 
40/00:28:00:00:00/00:00:00:00:00/40 Emask 0x4 (timeout)
01:15:35 kernel: [1844398.420543] ata1: port is slow to respond, please 
be patient (Status 0xff)
01:15:40 kernel: [1844403.098409] ata1: device not ready (errno=-16), 
forcing hardreset
01:15:40 kernel: [1844403.098420] ata1: hard resetting port
01:15:46 kernel: [1844408.645861] ata1: port is slow to respond, please 
be patient (Status 0xff)
01:15:50 kernel: [1844413.144653] ata1: COMRESET failed (errno=-16)
01:15:50 kernel: [1844413.144663] ata1: hard resetting port
01:15:56 kernel: [1844418.691270] ata1: port is slow to respond, please 
be patient (Status 0xff)
01:16:00 kernel: [1844423.189228] ata1: COMRESET failed (errno=-16)
01:16:00 kernel: [1844423.189237] ata1: hard resetting port
01:16:06 kernel: [1844428.736687] ata1: port is slow to respond, please 
be patient (Status 0xff)
01:16:35 kernel: [1844458.193217] ata1: COMRESET failed (errno=-16)
01:16:35 kernel: [1844458.193228] ata1: limiting SATA link speed to 1.5 Gbps
01:16:35 kernel: [1844458.193231] ata1: hard resetting port
01:16:40 kernel: [1844463.201458] ata1: COMRESET failed (errno=-16)
01:16:40 kernel: [1844463.201468] ata1: reset failed, giving up
01:16:40 kernel: [1844463.201472] ata1.00: disabled
01:16:40 kernel: [1844463.201483] ata1: EH pending after completion, 
repeating EH (cnt=4)
01:16:40 kernel: [1844463.201491] ata1: exception Emask 0x10 SAct 0x0 
SErr 0x1390002 action 0x2 frozen
01:16:40 kernel: [1844463.201495] ata1: hotplug_status 0x80
01:16:40 kernel: [1844463.201506] ata1: hard resetting port
01:16:46 kernel: [1844469.148300] ata1: port is slow to respond, please 
be patient (Status 0xff)
01:16:50 kernel: [1844473.226345] ata1: COMRESET failed (errno=-16)
01:16:50 kernel: [1844473.226355] ata1: hard resetting port
01:16:56 kernel: [1844479.173709] ata1: port is slow to respond, please 
be patient (Status 0xff)
01:17:00 kernel: [1844483.252279] ata1: COMRESET failed (errno=-16)
01:17:00 kernel: [1844483.252289] ata1: hard resetting port
01:17:06 kernel: [1844489.199287] ata1: port is slow to respond, please 
be patient (Status 0xff)
01:17:35 kernel: [1844518.245767] ata1: COMRESET failed (errno=-16)
01:17:35 kernel: [1844518.245778] ata1: limiting SATA link speed to 1.5 Gbps
01:17:35 kernel: [1844518.245782] ata1: hard resetting port
01:17:40 kernel: [1844523.293460] ata1: COMRESET failed (errno=-16)
01:17:40 kernel: [1844523.293469] ata1: reset failed, giving up
01:17:40 kernel: [1844523.293476] ata1: EH pending after completion, 
repeating EH (cnt=3)
01:17:40 kernel: [1844523.293485] ata1: exception Emask 0x10 SAct 0x0 
SErr 0x1390002 action 0x2 frozen
01:17:40 kernel: [1844523.293488] ata1: hotplug_status 0x80
01:17:40 kernel: [1844523.293500] ata1: hard resetting port
01:17:46 kernel: [1844529.240746] ata1: port is slow to respond, please 
be patient (Status 0xff)
01:17:50 kernel: [1844533.319339] ata1: COMRESET failed (errno=-16)
01:17:50 kernel: [1844533.319349] ata1: hard resetting port
01:17:56 kernel: [1844539.266172] ata1: port is slow to respond, please 
be patient (Status 0xff)
01:18:00 kernel: [1844543.344817] ata1: COMRESET failed (errno=-16)
01:18:00 kernel: [1844543.344827] ata1: hard resetting port
01:18:06 kernel: [1844549.291715] ata1: port is slow to respond, please 
be patient (Status 0xff)
01:18:35 kernel: [1844578.338834] ata1: COMRESET failed (errno=-16)
01:18:35 kernel: [1844578.338846] ata1: limiting SATA link speed to 1.5 Gbps
01:18:35 kernel: [1844578.338849] ata1: hard resetting port
01:18:41 kernel: [1844583.385996] ata1: COMRESET failed (errno=-16)
01:18:41 kernel: [1844583.386006] ata1: reset failed, giving up
01:18:41 kernel: [1844583.386012] ata1: EH pending after completion, 
repeating EH (cnt=2)
01:18:41 kernel: [1844583.386021] ata1: exception Emask 0x10 SAct 0x0 
SErr 0x1390002 action 0x2 frozen
01:18:41 kernel: [1844583.386024] ata1: hotplug_status 0x80
01:18:41 kernel: [1844583.386036] ata1: hard resetting port
01:18:46 kernel: [1844589.333287] ata1: port is slow to respond, please 
be patient (Status 0xff)
01:18:51 kernel: [1844593.411414] ata1: COMRESET failed (errno=-16)
01:18:51 kernel: [1844593.411424] ata1: hard resetting port
01:18:56 kernel: [1844599.358702] ata1: port is slow to respond, please 
be patient (Status 0xff)
01:19:01 kernel: [1844603.436851] ata1: COMRESET failed (errno=-16)
01:19:01 kernel: [1844603.436862] ata1: hard resetting port
01:19:07 kernel: [1844609.384125] ata1: port is slow to respond, please 
be patient (Status 0xff)
01:19:36 kernel: [1844638.430836] ata1: COMRESET failed (errno=-16)
01:19:36 kernel: [1844638.430848] ata1: limiting SATA link speed to 1.5 Gbps
01:19:36 kernel: [1844638.430851] ata1: hard resetting port
01:20:41 kernel: [1844703.571175] ata1: COMRESET failed (errno=-16)
01:20:41 kernel: [1844703.571185] ata1: reset failed, giving up
01:20:41 kernel: [1844703.571192] ata1: EH pending after 5 tries, giving up
01:20:41 kernel: [1844703.571245] sd 0:0:0:0: [sda] Result: 
hostbyte=0x00 driverbyte=0x08
01:20:41 kernel: [1844703.571249] sd 0:0:0:0: [sda] Sense Key : 0xb 
[current] [descriptor]
01:20:41 kernel: [1844703.571255] Descriptor sense data with sense 
descriptors (in hex):
01:20:41 kernel: [1844703.571258]         72 0b 00 00 00 00 00 0c 00 0a 
80 00 00 00 00 00
01:20:41 kernel: [1844703.571265]         00 00 00 00
01:20:41 kernel: [1844703.571268] sd 0:0:0:0: [sda] ASC=0x0 ASCQ=0x0
01:20:41 kernel: [1844703.571271] end_request: I/O error, dev sda, 
sector 31858176
01:20:41 kernel: [1844703.571343] sd 0:0:0:0: rejecting I/O to offline 
device
01:20:41 kernel: [1844703.571349] sd 0:0:0:0: rejecting I/O to offline 
device
01:20:41 kernel: [1844703.571413] ata1: EH complete
01:20:41 kernel: [1844703.572352] sd 0:0:0:0: [sda] Result: 
hostbyte=0x01 driverbyte=0x00
01:20:41 kernel: [1844703.572358] end_request: I/O error, dev sda, 
sector 31859200
01:20:41 kernel: [1844703.572375] sd 0:0:0:0: rejecting I/O to offline 
device
01:20:41 kernel: [1844703.572378] sd 0:0:0:0: rejecting I/O to offline 
device
01:20:41 kernel: [1844703.572381] sd 0:0:0:0: rejecting I/O to offline 
device
01:20:41 kernel: [1844703.572387] md: super_written gets error=-5, 
uptodate=0
01:20:41 kernel: [1844703.572390] raid5: Disk failure on sda, disabling 
device. Operation continuing on 3 devices
01:20:41 kernel: [1844703.572827] ata1.00: detaching (SCSI 0:0:0:0)
01:20:41 kernel: [1844703.573155] sd 0:0:0:0: [sda] Synchronizing SCSI cache
01:20:41 kernel: [1844703.573347] sd 0:0:0:0: [sda] Result: 
hostbyte=0x04 driverbyte=0x00
01:20:41 kernel: [1844703.573353] sd 0:0:0:0: [sda] Stopping disk
01:20:41 kernel: [1844703.573519] sd 0:0:0:0: [sda] START_STOP FAILED
01:20:41 kernel: [1844703.573522] sd 0:0:0:0: [sda] Result: 
hostbyte=0x04 driverbyte=0x00
01:20:48 kernel: [1844711.027697] md: md0: data-check done.
01:20:48 kernel: [1844711.128915] RAID5 conf printout:
01:20:48 kernel: [1844711.128924]  --- rd:4 wd:3
01:20:48 kernel: [1844711.128927]  disk 0, o:0, dev:sda
01:20:48 kernel: [1844711.128930]  disk 1, o:1, dev:sdd
01:20:48 kernel: [1844711.128933]  disk 2, o:1, dev:sdc
01:20:48 kernel: [1844711.128935]  disk 3, o:1, dev:sdb
01:20:48 kernel: [1844711.157764] RAID5 conf printout:
01:20:48 kernel: [1844711.157774]  --- rd:4 wd:3
01:20:48 kernel: [1844711.157778]  disk 1, o:1, dev:sdd
01:20:48 kernel: [1844711.157782]  disk 2, o:1, dev:sdc
01:20:48 kernel: [1844711.157784]  disk 3, o:1, dev:sdb
01:20:49 mdadm: Fail event detected on md device /dev/md0, component 
device /dev/sda
01:20:49 mdadm: RebuildFinished event detected on md device /dev/md0

Best regards,

Peter



Peter Favrholdt wrote:
> The problem is solved in 2.6.23.1 regarding the "port slow to respond" 
> issue.
> 
> I'm using sata_promise on Promise Technology, Inc. PDC40718 (SATA 300 
> TX4) (rev 02) and 4 Seagate 500GB ES drives.
> 
> Using 2.6.23.1 it is possible to run
> 
> dd if=/dev/sda of=/dev/null bs=1M &
> dd if=/dev/sdb of=/dev/null bs=1M &
> dd if=/dev/sdc of=/dev/null bs=1M &
> dd if=/dev/sdd of=/dev/null bs=1M &
> 
> And it just runs perfectly to the end with no hickups :-)
> 
> Thank you very much :-)
> 
> Best regards,
> 
> Peter


^ permalink raw reply	[flat|nested] 3+ messages in thread

end of thread, other threads:[~2007-11-09 23:58 UTC | newest]

Thread overview: 3+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2007-09-02 11:12 sata_promise: port is slow to respond, reset failed Peter Favrholdt
2007-10-14  9:21 ` Bug is fixed in 2.6.23.1: " Peter Favrholdt
2007-11-09 23:58   ` Correction " Peter Favrholdt

This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for NNTP newsgroup(s).