* Port multiplier resets
@ 2008-11-02 15:10 Yan Seiner
2008-11-11 9:06 ` Tejun Heo
0 siblings, 1 reply; 11+ messages in thread
From: Yan Seiner @ 2008-11-02 15:10 UTC (permalink / raw)
To: linux-ide
I'm seeing errors on a new port multiplier install.
The mobo has a J-Micron SATA controller: JMicron Technologies, Inc. JMicron 20360/20363 AHCI Controller (rev 03)
The port multiplier has a SIL 3726 chipset. AFAICT, this is a supported comnbination. The kernel recognizes the chipset.
The system has an adaptec SCSI controller w/ 2 15K SCSI drives, 6 internal SATA drives, and 3 external SATA drives using the port multiplier. Because of a broken bios (ARGH!) I have to power up the external drives after the system scans the SCSI bus. This seems to cause no problems. Here's a log of a recent boot (scroll down for the error I see after the system is booted):
Nov 2 05:26:07 selene kernel: [ 28.691524] ata8: SATA max UDMA/133 abar m8192@0xfdcfe000 port 0xfdcfe180 irq 16
Nov 2 05:26:07 selene kernel: [ 34.104580] ata8: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 2 05:26:07 selene kernel: [ 34.104580] ata8.15: Port Multiplier 1.1, 0x1095:0x3726 r23, 6 ports, feat 0x1/0x9
Nov 2 05:26:07 selene kernel: [ 34.104580] ata8.00: hard resetting link
Nov 2 05:26:07 selene kernel: [ 34.425000] ata8.00: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 2 05:26:07 selene kernel: [ 34.425003] ata8.01: hard resetting link
Nov 2 05:26:07 selene kernel: [ 35.001232] ata8.01: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 2 05:26:07 selene kernel: [ 35.001234] ata8.02: hard resetting link
Nov 2 05:26:07 selene kernel: [ 35.321389] ata8.02: SATA link down (SStatus 0 SControl 320)
Nov 2 05:26:07 selene kernel: [ 35.321419] ata8.03: hard resetting link
Nov 2 05:26:07 selene kernel: [ 35.869739] ata8.03: SATA link down (SStatus 0 SControl 320)
Nov 2 05:26:07 selene kernel: [ 35.869769] ata8.04: hard resetting link
Nov 2 05:26:07 selene kernel: [ 36.621491] ata8.04: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 2 05:26:07 selene kernel: [ 36.621493] ata8.05: hard resetting link
Nov 2 05:26:07 selene kernel: [ 36.941025] ata8.05: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 2 05:26:07 selene kernel: [ 36.942522] ata8.00: ATA-8: ST31000340AS, SD15, max UDMA/133
Nov 2 05:26:07 selene kernel: [ 36.942525] ata8.00: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32)
Nov 2 05:26:07 selene kernel: [ 36.944479] ata8.00: configured for UDMA/133
Nov 2 05:26:07 selene kernel: [ 53.613888] ata8.01: failed to IDENTIFY (INIT_DEV_PARAMS failed, err_mask=0x80)
Nov 2 05:26:07 selene kernel: [ 53.613888] ata8.04: failed to IDENTIFY (I/O error, err_mask=0x100)
Nov 2 05:26:07 selene kernel: [ 53.613888] ata8: failed to recover some devices, retrying in 5 secs
Nov 2 05:26:07 selene kernel: [ 61.420677] ata8.01: hard resetting link
Nov 2 05:26:07 selene kernel: [ 66.420763] ata8.15: qc timeout (cmd 0xe4)
Nov 2 05:26:07 selene kernel: [ 66.420770] ata8.01: failed to read SCR 2 (Emask=0x4)
Nov 2 05:26:07 selene kernel: [ 66.420773] ata8.01: failed to read SCR 2 (Emask=0x40)
Nov 2 05:26:07 selene kernel: [ 66.420775] ata8.01: COMRESET failed (errno=-5)
Nov 2 05:26:07 selene kernel: [ 66.420804] ata8.01: failed to read SCR 0 (Emask=0x40)
Nov 2 05:26:07 selene kernel: [ 66.420806] ata8.01: reset failed, giving up
Nov 2 05:26:07 selene kernel: [ 66.420835] ata8.15: hard resetting link
Nov 2 05:26:07 selene kernel: [ 68.284883] ata8.15: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 2 05:26:07 selene kernel: [ 68.285116] ata8.00: hard resetting link
Nov 2 05:26:07 selene kernel: [ 68.604947] ata8.00: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 2 05:26:07 selene kernel: [ 68.604949] ata8.01: hard resetting link
Nov 2 05:26:07 selene kernel: [ 69.329438] ata8.01: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 2 05:26:07 selene kernel: [ 69.329440] ata8.02: hard resetting link
Nov 2 05:26:07 selene kernel: [ 69.649777] ata8.02: SATA link down (SStatus 0 SControl 320)
Nov 2 05:26:07 selene kernel: [ 69.649807] ata8.03: hard resetting link
Nov 2 05:26:07 selene kernel: [ 69.969439] ata8.03: SATA link down (SStatus 0 SControl 320)
Nov 2 05:26:07 selene kernel: [ 69.969469] ata8.04: hard resetting link
Nov 2 05:26:07 selene kernel: [ 70.813852] ata8.04: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 2 05:26:07 selene kernel: [ 70.813855] ata8.05: hard resetting link
Nov 2 05:26:07 selene kernel: [ 71.377665] ata8.05: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 2 05:26:07 selene kernel: [ 71.381475] ata8.00: configured for UDMA/133
Nov 2 05:26:07 selene kernel: [ 71.381475] ata8.01: ATA-8: ST31000340AS, SD15, max UDMA/133
Nov 2 05:26:07 selene kernel: [ 71.381475] ata8.01: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32)
Nov 2 05:26:07 selene kernel: [ 71.381879] ata8.01: configured for UDMA/133
Nov 2 05:26:07 selene kernel: [ 71.381879] ata8.04: ATA-8: ST31000340AS, SD15, max UDMA/133
Nov 2 05:26:07 selene kernel: [ 71.381879] ata8.04: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32)
Nov 2 05:26:07 selene kernel: [ 71.381890] ata8.04: configured for UDMA/133
Nov 2 05:26:07 selene kernel: [ 71.389769] ata8: EH complete
Once the system is up and running, I get these errors. They appear anywhere from several hours to a few minutes apart. What exactly does this mean? It doesn't seem to have any impact on the performance of the drives. Typically, these drives are very heavily loaded; I've been dumping > 1TB of data to them while rebuilding the RAID-5 array, so they're pretty well maxed out.
Nov 2 05:40:13 selene kernel: [ 984.254543] ata8.15: exception Emask 0x10 SAct 0x0 SErr 0x780101 action 0x7
Nov 2 05:40:13 selene kernel: [ 984.254549] ata8.15: irq_stat 0x0c000000
Nov 2 05:40:13 selene kernel: [ 984.254552] ata8: SError: { RecovData UnrecovData 10B8B Dispar BadCRC Handshk }
Nov 2 05:40:13 selene kernel: [ 984.254557] ata8.01: exception Emask 0x0 SAct 0x3 SErr 0x0 action 0x0
Nov 2 05:40:13 selene kernel: [ 984.254563] ata8.15: hard resetting link
Nov 2 05:40:14 selene kernel: [ 985.018050] ata8.15: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 2 05:40:14 selene kernel: [ 985.018332] ata8.00: hard resetting link
Nov 2 05:40:14 selene kernel: [ 985.338351] ata8.00: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 2 05:40:14 selene kernel: [ 985.338351] ata8.01: hard resetting link
Nov 2 05:40:15 selene kernel: [ 985.658050] ata8.01: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 2 05:40:15 selene kernel: [ 985.658050] ata8.02: hard resetting link
Nov 2 05:40:15 selene kernel: [ 985.978141] ata8.02: SATA link down (SStatus 0 SControl 320)
Nov 2 05:40:15 selene kernel: [ 985.978227] ata8.03: hard resetting link
Nov 2 05:40:15 selene kernel: [ 986.299067] ata8.03: SATA link down (SStatus 0 SControl 320)
Nov 2 05:40:15 selene kernel: [ 986.299155] ata8.04: hard resetting link
Nov 2 05:40:16 selene kernel: [ 986.619270] ata8.04: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 2 05:40:16 selene kernel: [ 986.619278] ata8.05: hard resetting link
Nov 2 05:40:16 selene kernel: [ 986.939112] ata8.05: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 2 05:40:16 selene kernel: [ 986.943256] ata8.00: configured for UDMA/133
Nov 2 05:40:16 selene kernel: [ 986.946950] ata8.01: configured for UDMA/133
Nov 2 05:40:16 selene kernel: [ 986.950647] ata8.04: configured for UDMA/133
Nov 2 05:40:16 selene kernel: [ 986.950647] ata8: EH complete
^ permalink raw reply [flat|nested] 11+ messages in thread
* Re: Port multiplier resets
2008-11-02 15:10 Port multiplier resets Yan Seiner
@ 2008-11-11 9:06 ` Tejun Heo
2008-11-19 15:15 ` Yan Seiner
2008-11-20 3:19 ` Yan Seiner
0 siblings, 2 replies; 11+ messages in thread
From: Tejun Heo @ 2008-11-11 9:06 UTC (permalink / raw)
To: Yan Seiner; +Cc: linux-ide
Yan Seiner wrote:
> I'm seeing errors on a new port multiplier install. The mobo has a
> J-Micron SATA controller: JMicron Technologies, Inc. JMicron
> 20360/20363 AHCI Controller (rev 03) The port multiplier has a SIL
> 3726 chipset. AFAICT, this is a supported comnbination. The kernel
> recognizes the chipset. The system has an adaptec SCSI controller
> w/ 2 15K SCSI drives, 6 internal SATA drives, and 3 external SATA
> drives using the port multiplier. Because of a broken bios (ARGH!) I
> have to power up the external drives after the system scans the SCSI
> bus. This seems to cause no problems. Here's a log of a recent
> boot (scroll down for the error I see after the system is booted):
>
> Nov 2 05:26:07 selene kernel: [ 28.691524] ata8: SATA max UDMA/133 abar m8192@0xfdcfe000 port 0xfdcfe180 irq 16
> Nov 2 05:26:07 selene kernel: [ 34.104580] ata8: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> Nov 2 05:26:07 selene kernel: [ 34.104580] ata8.15: Port Multiplier 1.1, 0x1095:0x3726 r23, 6 ports, feat 0x1/0x9
> Nov 2 05:26:07 selene kernel: [ 34.104580] ata8.00: hard resetting link
> Nov 2 05:26:07 selene kernel: [ 34.425000] ata8.00: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
> Nov 2 05:26:07 selene kernel: [ 34.425003] ata8.01: hard resetting link
> Nov 2 05:26:07 selene kernel: [ 35.001232] ata8.01: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
> Nov 2 05:26:07 selene kernel: [ 35.001234] ata8.02: hard resetting link
> Nov 2 05:26:07 selene kernel: [ 35.321389] ata8.02: SATA link down (SStatus 0 SControl 320)
> Nov 2 05:26:07 selene kernel: [ 35.321419] ata8.03: hard resetting link
> Nov 2 05:26:07 selene kernel: [ 35.869739] ata8.03: SATA link down (SStatus 0 SControl 320)
> Nov 2 05:26:07 selene kernel: [ 35.869769] ata8.04: hard resetting link
> Nov 2 05:26:07 selene kernel: [ 36.621491] ata8.04: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
> Nov 2 05:26:07 selene kernel: [ 36.621493] ata8.05: hard resetting link
> Nov 2 05:26:07 selene kernel: [ 36.941025] ata8.05: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
> Nov 2 05:26:07 selene kernel: [ 36.942522] ata8.00: ATA-8: ST31000340AS, SD15, max UDMA/133
> Nov 2 05:26:07 selene kernel: [ 36.942525] ata8.00: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32)
> Nov 2 05:26:07 selene kernel: [ 36.944479] ata8.00: configured for UDMA/133
> Nov 2 05:26:07 selene kernel: [ 53.613888] ata8.01: failed to IDENTIFY (INIT_DEV_PARAMS failed, err_mask=0x80)
> Nov 2 05:26:07 selene kernel: [ 53.613888] ata8.04: failed to IDENTIFY (I/O error, err_mask=0x100)
> Nov 2 05:26:07 selene kernel: [ 53.613888] ata8: failed to recover some devices, retrying in 5 secs
> Nov 2 05:26:07 selene kernel: [ 61.420677] ata8.01: hard resetting link
> Nov 2 05:26:07 selene kernel: [ 66.420763] ata8.15: qc timeout (cmd 0xe4)
> Nov 2 05:26:07 selene kernel: [ 66.420770] ata8.01: failed to read SCR 2 (Emask=0x4)
> Nov 2 05:26:07 selene kernel: [ 66.420773] ata8.01: failed to read SCR 2 (Emask=0x40)
> Nov 2 05:26:07 selene kernel: [ 66.420775] ata8.01: COMRESET failed (errno=-5)
> Nov 2 05:26:07 selene kernel: [ 66.420804] ata8.01: failed to read SCR 0 (Emask=0x40)
> Nov 2 05:26:07 selene kernel: [ 66.420806] ata8.01: reset failed, giving up
> Nov 2 05:26:07 selene kernel: [ 66.420835] ata8.15: hard resetting link
> Nov 2 05:26:07 selene kernel: [ 68.284883] ata8.15: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> Nov 2 05:26:07 selene kernel: [ 68.285116] ata8.00: hard resetting link
> Nov 2 05:26:07 selene kernel: [ 68.604947] ata8.00: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
> Nov 2 05:26:07 selene kernel: [ 68.604949] ata8.01: hard resetting link
> Nov 2 05:26:07 selene kernel: [ 69.329438] ata8.01: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
> Nov 2 05:26:07 selene kernel: [ 69.329440] ata8.02: hard resetting link
> Nov 2 05:26:07 selene kernel: [ 69.649777] ata8.02: SATA link down (SStatus 0 SControl 320)
> Nov 2 05:26:07 selene kernel: [ 69.649807] ata8.03: hard resetting link
> Nov 2 05:26:07 selene kernel: [ 69.969439] ata8.03: SATA link down (SStatus 0 SControl 320)
> Nov 2 05:26:07 selene kernel: [ 69.969469] ata8.04: hard resetting link
> Nov 2 05:26:07 selene kernel: [ 70.813852] ata8.04: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
> Nov 2 05:26:07 selene kernel: [ 70.813855] ata8.05: hard resetting link
> Nov 2 05:26:07 selene kernel: [ 71.377665] ata8.05: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
> Nov 2 05:26:07 selene kernel: [ 71.381475] ata8.00: configured for UDMA/133
> Nov 2 05:26:07 selene kernel: [ 71.381475] ata8.01: ATA-8: ST31000340AS, SD15, max UDMA/133
> Nov 2 05:26:07 selene kernel: [ 71.381475] ata8.01: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32)
> Nov 2 05:26:07 selene kernel: [ 71.381879] ata8.01: configured for UDMA/133
> Nov 2 05:26:07 selene kernel: [ 71.381879] ata8.04: ATA-8: ST31000340AS, SD15, max UDMA/133
> Nov 2 05:26:07 selene kernel: [ 71.381879] ata8.04: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32)
> Nov 2 05:26:07 selene kernel: [ 71.381890] ata8.04: configured for UDMA/133
> Nov 2 05:26:07 selene kernel: [ 71.389769] ata8: EH complete
The link probably got a PHY event after reset sequence is complete.
Maybe our timinig is too aggressive or the PMP is just quirky but as
long as detection succeeds in the end, it should be okay.
> Once the system is up and running, I get these errors. They appear
> anywhere from several hours to a few minutes apart. What exactly
> does this mean? It doesn't seem to have any impact on the
> performance of the drives. Typically, these drives are very heavily
> loaded; I've been dumping > 1TB of data to them while rebuilding the
> RAID-5 array, so they're pretty well maxed out.
>
> Nov 2 05:40:13 selene kernel: [ 984.254543] ata8.15: exception Emask 0x10 SAct 0x0 SErr 0x780101 action 0x7
> Nov 2 05:40:13 selene kernel: [ 984.254549] ata8.15: irq_stat 0x0c000000
> Nov 2 05:40:13 selene kernel: [ 984.254552] ata8: SError: { RecovData UnrecovData 10B8B Dispar BadCRC Handshk }
> Nov 2 05:40:13 selene kernel: [ 984.254557] ata8.01: exception Emask 0x0 SAct 0x3 SErr 0x0 action 0x0
It indicates that the link experienced ATA bus transmission failure.
Heh... Not only that the controller turned on all possible link error
bits. Hmmm... The second device has active commands and it's the same
device which caused problem during boot too. Can you please try the
followings?
1. Swap the second drive with another one and see whether the error
follows the drive or stays with the slot.
2. Specify libata.force=8.15:1.5Gbps
Thanks.
--
tejun
^ permalink raw reply [flat|nested] 11+ messages in thread
* Re: Port multiplier resets
2008-11-11 9:06 ` Tejun Heo
@ 2008-11-19 15:15 ` Yan Seiner
2008-11-20 2:27 ` Tejun Heo
2008-11-20 3:19 ` Yan Seiner
1 sibling, 1 reply; 11+ messages in thread
From: Yan Seiner @ 2008-11-19 15:15 UTC (permalink / raw)
To: Tejun Heo; +Cc: linux-ide
Tejun Heo wrote:
> Yan Seiner wrote:
>
>> I'm seeing errors on a new port multiplier install. The mobo has a
>> J-Micron SATA controller: JMicron Technologies, Inc. JMicron
>> 20360/20363 AHCI Controller (rev 03) The port multiplier has a SIL
>> 3726 chipset. AFAICT, this is a supported comnbination. The kernel
>> recognizes the chipset. The system has an adaptec SCSI controller
>> w/ 2 15K SCSI drives, 6 internal SATA drives, and 3 external SATA
>> drives using the port multiplier. Because of a broken bios (ARGH!) I
>> have to power up the external drives after the system scans the SCSI
>> bus. This seems to cause no problems. Here's a log of a recent
>> boot (scroll down for the error I see after the system is booted):
>>
>> Nov 2 05:26:07 selene kernel: [ 28.691524] ata8: SATA max UDMA/133 abar m8192@0xfdcfe000 port 0xfdcfe180 irq 16
>> Nov 2 05:26:07 selene kernel: [ 34.104580] ata8: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
>> Nov 2 05:26:07 selene kernel: [ 34.104580] ata8.15: Port Multiplier 1.1, 0x1095:0x3726 r23, 6 ports, feat 0x1/0x9
>> Nov 2 05:26:07 selene kernel: [ 34.104580] ata8.00: hard resetting link
>> Nov 2 05:26:07 selene kernel: [ 34.425000] ata8.00: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
>> Nov 2 05:26:07 selene kernel: [ 34.425003] ata8.01: hard resetting link
>> Nov 2 05:26:07 selene kernel: [ 35.001232] ata8.01: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
>> Nov 2 05:26:07 selene kernel: [ 35.001234] ata8.02: hard resetting link
>> Nov 2 05:26:07 selene kernel: [ 35.321389] ata8.02: SATA link down (SStatus 0 SControl 320)
>> Nov 2 05:26:07 selene kernel: [ 35.321419] ata8.03: hard resetting link
>> Nov 2 05:26:07 selene kernel: [ 35.869739] ata8.03: SATA link down (SStatus 0 SControl 320)
>> Nov 2 05:26:07 selene kernel: [ 35.869769] ata8.04: hard resetting link
>> Nov 2 05:26:07 selene kernel: [ 36.621491] ata8.04: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
>> Nov 2 05:26:07 selene kernel: [ 36.621493] ata8.05: hard resetting link
>> Nov 2 05:26:07 selene kernel: [ 36.941025] ata8.05: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
>> Nov 2 05:26:07 selene kernel: [ 36.942522] ata8.00: ATA-8: ST31000340AS, SD15, max UDMA/133
>> Nov 2 05:26:07 selene kernel: [ 36.942525] ata8.00: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32)
>> Nov 2 05:26:07 selene kernel: [ 36.944479] ata8.00: configured for UDMA/133
>> Nov 2 05:26:07 selene kernel: [ 53.613888] ata8.01: failed to IDENTIFY (INIT_DEV_PARAMS failed, err_mask=0x80)
>> Nov 2 05:26:07 selene kernel: [ 53.613888] ata8.04: failed to IDENTIFY (I/O error, err_mask=0x100)
>> Nov 2 05:26:07 selene kernel: [ 53.613888] ata8: failed to recover some devices, retrying in 5 secs
>> Nov 2 05:26:07 selene kernel: [ 61.420677] ata8.01: hard resetting link
>> Nov 2 05:26:07 selene kernel: [ 66.420763] ata8.15: qc timeout (cmd 0xe4)
>> Nov 2 05:26:07 selene kernel: [ 66.420770] ata8.01: failed to read SCR 2 (Emask=0x4)
>> Nov 2 05:26:07 selene kernel: [ 66.420773] ata8.01: failed to read SCR 2 (Emask=0x40)
>> Nov 2 05:26:07 selene kernel: [ 66.420775] ata8.01: COMRESET failed (errno=-5)
>> Nov 2 05:26:07 selene kernel: [ 66.420804] ata8.01: failed to read SCR 0 (Emask=0x40)
>> Nov 2 05:26:07 selene kernel: [ 66.420806] ata8.01: reset failed, giving up
>> Nov 2 05:26:07 selene kernel: [ 66.420835] ata8.15: hard resetting link
>> Nov 2 05:26:07 selene kernel: [ 68.284883] ata8.15: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
>> Nov 2 05:26:07 selene kernel: [ 68.285116] ata8.00: hard resetting link
>> Nov 2 05:26:07 selene kernel: [ 68.604947] ata8.00: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
>> Nov 2 05:26:07 selene kernel: [ 68.604949] ata8.01: hard resetting link
>> Nov 2 05:26:07 selene kernel: [ 69.329438] ata8.01: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
>> Nov 2 05:26:07 selene kernel: [ 69.329440] ata8.02: hard resetting link
>> Nov 2 05:26:07 selene kernel: [ 69.649777] ata8.02: SATA link down (SStatus 0 SControl 320)
>> Nov 2 05:26:07 selene kernel: [ 69.649807] ata8.03: hard resetting link
>> Nov 2 05:26:07 selene kernel: [ 69.969439] ata8.03: SATA link down (SStatus 0 SControl 320)
>> Nov 2 05:26:07 selene kernel: [ 69.969469] ata8.04: hard resetting link
>> Nov 2 05:26:07 selene kernel: [ 70.813852] ata8.04: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
>> Nov 2 05:26:07 selene kernel: [ 70.813855] ata8.05: hard resetting link
>> Nov 2 05:26:07 selene kernel: [ 71.377665] ata8.05: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
>> Nov 2 05:26:07 selene kernel: [ 71.381475] ata8.00: configured for UDMA/133
>> Nov 2 05:26:07 selene kernel: [ 71.381475] ata8.01: ATA-8: ST31000340AS, SD15, max UDMA/133
>> Nov 2 05:26:07 selene kernel: [ 71.381475] ata8.01: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32)
>> Nov 2 05:26:07 selene kernel: [ 71.381879] ata8.01: configured for UDMA/133
>> Nov 2 05:26:07 selene kernel: [ 71.381879] ata8.04: ATA-8: ST31000340AS, SD15, max UDMA/133
>> Nov 2 05:26:07 selene kernel: [ 71.381879] ata8.04: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32)
>> Nov 2 05:26:07 selene kernel: [ 71.381890] ata8.04: configured for UDMA/133
>> Nov 2 05:26:07 selene kernel: [ 71.389769] ata8: EH complete
>>
>
> The link probably got a PHY event after reset sequence is complete.
> Maybe our timinig is too aggressive or the PMP is just quirky but as
> long as detection succeeds in the end, it should be okay.
>
>
>> Once the system is up and running, I get these errors. They appear
>> anywhere from several hours to a few minutes apart. What exactly
>> does this mean? It doesn't seem to have any impact on the
>> performance of the drives. Typically, these drives are very heavily
>> loaded; I've been dumping > 1TB of data to them while rebuilding the
>> RAID-5 array, so they're pretty well maxed out.
>>
>> Nov 2 05:40:13 selene kernel: [ 984.254543] ata8.15: exception Emask 0x10 SAct 0x0 SErr 0x780101 action 0x7
>> Nov 2 05:40:13 selene kernel: [ 984.254549] ata8.15: irq_stat 0x0c000000
>> Nov 2 05:40:13 selene kernel: [ 984.254552] ata8: SError: { RecovData UnrecovData 10B8B Dispar BadCRC Handshk }
>> Nov 2 05:40:13 selene kernel: [ 984.254557] ata8.01: exception Emask 0x0 SAct 0x3 SErr 0x0 action 0x0
>>
>
> It indicates that the link experienced ATA bus transmission failure.
> Heh... Not only that the controller turned on all possible link error
> bits. Hmmm... The second device has active commands and it's the same
> device which caused problem during boot too. Can you please try the
> followings?
>
> 1. Swap the second drive with another one and see whether the error
> follows the drive or stays with the slot.
>
> 2. Specify libata.force=8.15:1.5Gbps
>
Sorry for the delayed response. I've installed another controller based
on the SiI 3132 chipset. The esata array is the same; I've simply moved
the esata cables to the new controller. My observations:
1. The JMicron 20360/20363 AHCI Controller (rev 03) controller is far,
far slower. hdparm clocks it at 20 mb/sec and a raid check takes about
15 hours.
2. The JMicron controller experiences the above errors.
3. The Sil 3132 controller is faster. hdparm says 110 mb/sec and a
raid check takes about 5 hours. This is confirmed by usage - I can
stream 2 videos, record 2 videos, and commercial flag a video in myth
with no visible stuttering. The JMicron controller stutters even with a
single video streaming and recording.
4. The Sil 3132 controller doesn't have any errors even under high load.
Google says the rev 3 version of the JMicron controller has these
issues, and to turn off NCQ on it. It also indicates that these
problems should have been resolved in my kernel - version 2.6.27.4 - but
apparently not. :-(
Is there a way to turn off NCQ on that controller without impacting the
Sil 3132 controller? Any other things to try?
--Yan
^ permalink raw reply [flat|nested] 11+ messages in thread
* Re: Port multiplier resets
2008-11-19 15:15 ` Yan Seiner
@ 2008-11-20 2:27 ` Tejun Heo
2008-11-20 3:05 ` Yan Seiner
0 siblings, 1 reply; 11+ messages in thread
From: Tejun Heo @ 2008-11-20 2:27 UTC (permalink / raw)
To: Yan Seiner; +Cc: linux-ide
Yan Seiner wrote:
> Sorry for the delayed response. I've installed another controller based
> on the SiI 3132 chipset. The esata array is the same; I've simply moved
> the esata cables to the new controller. My observations:
>
> 1. The JMicron 20360/20363 AHCI Controller (rev 03) controller is far,
> far slower. hdparm clocks it at 20 mb/sec and a raid check takes about
> 15 hours.
Hmm... That's probably due to lack of FIS based switching support.
The hardware (20360/3) can't do it and ahci doesn't support it yet
either. So, only commands to one drive can be in flight at any given
moment which is usually pretty bad for RAID.
> 2. The JMicron controller experiences the above errors.
Have you tried forcing the link speed?
> 3. The Sil 3132 controller is faster. hdparm says 110 mb/sec and a
> raid check takes about 5 hours. This is confirmed by usage - I can
> stream 2 videos, record 2 videos, and commercial flag a video in myth
> with no visible stuttering. The JMicron controller stutters even with a
> single video streaming and recording.
As said above, if you're running RAID on top of it, command based
switching will hurt bad. And 110Mb/s is about the limit of sil3132.
> 4. The Sil 3132 controller doesn't have any errors even under high load.
>
> Google says the rev 3 version of the JMicron controller has these
> issues, and to turn off NCQ on it. It also indicates that these
> problems should have been resolved in my kernel - version 2.6.27.4 - but
> apparently not. :-(
Nah.. I'm not aware of any such problem and it will work fine w/o PMP.
JMB ahci's are actually pretty good. Lack of FIS switching support
hurts when you use PMP tho.
> Is there a way to turn off NCQ on that controller without impacting the
> Sil 3132 controller? Any other things to try?
You can do that with libata.force param but if you wanna put parallel
high load to the drives connected through PMP, no matter what you do,
the performance is gonna be bad if FIS switching is not supported.
--
tejun
^ permalink raw reply [flat|nested] 11+ messages in thread
* Re: Port multiplier resets
2008-11-20 2:27 ` Tejun Heo
@ 2008-11-20 3:05 ` Yan Seiner
2008-11-20 3:23 ` Tejun Heo
0 siblings, 1 reply; 11+ messages in thread
From: Yan Seiner @ 2008-11-20 3:05 UTC (permalink / raw)
To: Tejun Heo; +Cc: linux-ide
Tejun Heo wrote:
> Yan Seiner wrote:
>
>> Sorry for the delayed response. I've installed another controller based
>> on the SiI 3132 chipset. The esata array is the same; I've simply moved
>> the esata cables to the new controller. My observations:
>>
>> 1. The JMicron 20360/20363 AHCI Controller (rev 03) controller is far,
>> far slower. hdparm clocks it at 20 mb/sec and a raid check takes about
>> 15 hours.
>>
>
> Hmm... That's probably due to lack of FIS based switching support.
> The hardware (20360/3) can't do it and ahci doesn't support it yet
> either. So, only commands to one drive can be in flight at any given
> moment which is usually pretty bad for RAID.
>
So is there hope that FIS switching will make it into the driver or is
this a hardware limitation? I have some time (months if not a year or
two) before I really start to populate my external disk pack. (I have
20 slots with 4 - 5 port multipliers.)
>
>> 2. The JMicron controller experiences the above errors.
>>
>
> Have you tried forcing the link speed?
>
Not yet. I spent the weekend building a remote switch and driver for
the esata drive box. (Long story; I need to be able to shut the
external drive pack off via software.)
I need to check the drives as well make sure that the drives are not
jumper-limited to 1.5 gb/sec. (one of those DUH! moments...)
>
>> 3. The Sil 3132 controller is faster. hdparm says 110 mb/sec and a
>> raid check takes about 5 hours. This is confirmed by usage - I can
>> stream 2 videos, record 2 videos, and commercial flag a video in myth
>> with no visible stuttering. The JMicron controller stutters even with a
>> single video streaming and recording.
>>
>
> As said above, if you're running RAID on top of it, command based
> switching will hurt bad. And 110Mb/s is about the limit of sil3132.
>
I'm OK with the speed of the sil controller. I'm concerned about the
slow speed of the jmicron controller - it's about 1/3 to 1/5 as fast.
>
>> 4. The Sil 3132 controller doesn't have any errors even under high load.
>>
>> Google says the rev 3 version of the JMicron controller has these
>> issues, and to turn off NCQ on it. It also indicates that these
>> problems should have been resolved in my kernel - version 2.6.27.4 - but
>> apparently not. :-(
>>
>
> Nah.. I'm not aware of any such problem and it will work fine w/o PMP.
> JMB ahci's are actually pretty good. Lack of FIS switching support
> hurts when you use PMP tho.
>
>
>> Is there a way to turn off NCQ on that controller without impacting the
>> Sil 3132 controller? Any other things to try?
>>
>
> You can do that with libata.force param but if you wanna put parallel
> high load to the drives connected through PMP, no matter what you do,
> the performance is gonna be bad if FIS switching is not supported.
>
>
OK, thanks. I'll check the drive jumpers and see about setting the
lower speed.
The drives are used for storing and streaming videos. The idea is to
have 4 esata channels, eventually with 5 sets of raid-5 arrays. Each
raid-5 array consists of 4 drives, and each drive is on its own esata
controller. The likelihood of multiple raid arrays being used at the
same time is small, so I hope I won't get hit too bad performance-wise.
Even if they get hit, the bandwidth is small; each video stream is about
8gb/hour.
I'm waiting on more hardware (the esata cables, of all things) before I
can test this out fully.
^ permalink raw reply [flat|nested] 11+ messages in thread
* Re: Port multiplier resets
2008-11-11 9:06 ` Tejun Heo
2008-11-19 15:15 ` Yan Seiner
@ 2008-11-20 3:19 ` Yan Seiner
2008-11-20 3:24 ` Tejun Heo
1 sibling, 1 reply; 11+ messages in thread
From: Yan Seiner @ 2008-11-20 3:19 UTC (permalink / raw)
To: Tejun Heo; +Cc: linux-ide
Tejun Heo wrote:
>
> 2. Specify libata.force=8.15:1.5Gbps
>
dumb question:
Does this go on the kernel parameter line or can I change it without
rebooting the system?
I don't see it in /sys/module/libata/parameters so I'm guessing it needs
a reboot?
--Yan
^ permalink raw reply [flat|nested] 11+ messages in thread
* Re: Port multiplier resets
2008-11-20 3:05 ` Yan Seiner
@ 2008-11-20 3:23 ` Tejun Heo
0 siblings, 0 replies; 11+ messages in thread
From: Tejun Heo @ 2008-11-20 3:23 UTC (permalink / raw)
To: Yan Seiner; +Cc: linux-ide
Yan Seiner wrote:
> Tejun Heo wrote:
>> Yan Seiner wrote:
>>
>>> Sorry for the delayed response. I've installed another controller based
>>> on the SiI 3132 chipset. The esata array is the same; I've simply moved
>>> the esata cables to the new controller. My observations:
>>>
>>> 1. The JMicron 20360/20363 AHCI Controller (rev 03) controller is far,
>>> far slower. hdparm clocks it at 20 mb/sec and a raid check takes about
>>> 15 hours.
>>>
>>
>> Hmm... That's probably due to lack of FIS based switching support.
>> The hardware (20360/3) can't do it and ahci doesn't support it yet
>> either. So, only commands to one drive can be in flight at any given
>> moment which is usually pretty bad for RAID.
>>
> So is there hope that FIS switching will make it into the driver or is
> this a hardware limitation? I have some time (months if not a year or
> two) before I really start to populate my external disk pack. (I have
> 20 slots with 4 - 5 port multipliers.)
20360/3 don't support them in hardware but there are controllers which
do and ahci driver will be updated to support them.
>> As said above, if you're running RAID on top of it, command based
>> switching will hurt bad. And 110Mb/s is about the limit of sil3132.
>>
> I'm OK with the speed of the sil controller. I'm concerned about the
> slow speed of the jmicron controller - it's about 1/3 to 1/5 as fast.
Yeah, that's the difference coming from FIS and command based switching.
> OK, thanks. I'll check the drive jumpers and see about setting the
> lower speed.
No, you don't have to do that. You can just use libata.force parameter.
ie. if the JMB one is ATA host 3, you can say libata.force=3:1.5Gbps.
--
tejun
^ permalink raw reply [flat|nested] 11+ messages in thread
* Re: Port multiplier resets
2008-11-20 3:19 ` Yan Seiner
@ 2008-11-20 3:24 ` Tejun Heo
2008-11-22 16:48 ` Yan Seiner
0 siblings, 1 reply; 11+ messages in thread
From: Tejun Heo @ 2008-11-20 3:24 UTC (permalink / raw)
To: Yan Seiner; +Cc: linux-ide
Yan Seiner wrote:
> Tejun Heo wrote:
>>
>> 2. Specify libata.force=8.15:1.5Gbps
>>
> dumb question:
>
> Does this go on the kernel parameter line or can I change it without
> rebooting the system?
>
> I don't see it in /sys/module/libata/parameters so I'm guessing it needs
> a reboot?
Yeah, you will need to reboot or reload libata module. Note that if
libata.ko is being loaded from initrd, different distros use different
methods to pass parameters to them.
--
tejun
^ permalink raw reply [flat|nested] 11+ messages in thread
* Re: Port multiplier resets
2008-11-20 3:24 ` Tejun Heo
@ 2008-11-22 16:48 ` Yan Seiner
2008-11-23 0:53 ` Tejun Heo
0 siblings, 1 reply; 11+ messages in thread
From: Yan Seiner @ 2008-11-22 16:48 UTC (permalink / raw)
To: Tejun Heo; +Cc: linux-ide
[-- Attachment #1: Type: text/plain, Size: 1874 bytes --]
Tejun Heo wrote:
> Yan Seiner wrote:
>
>> Tejun Heo wrote:
>>
>>> 2. Specify libata.force=8.15:1.5Gbps
>>>
>>>
>> dumb question:
>>
>> Does this go on the kernel parameter line or can I change it without
>> rebooting the system?
>>
>> I don't see it in /sys/module/libata/parameters so I'm guessing it needs
>> a reboot?
>>
>
> Yeah, you will need to reboot or reload libata module. Note that if
> libata.ko is being loaded from initrd, different distros use different
> methods to pass parameters to them.
>
>
Thanks for all your help. Unfortunately I'm still dead in the water
with the jmicron controller.... :-(
If anything, things have gotten worse. I suspect that the hardware is
broken, but I'm hoping there's still something I can do.
The jmicron controller is ata3 and ata4. Here's my kernel line from grub:
title Debian GNU/Linux, kernel 2.6.27.4
root (hd0,1)
kernel /vmlinuz-2.6.27.4 root=/dev/md0 ro quiet
libata.force=3.15:1.5Gbps,4.15:1.5Gbps
initrd /initrd.img-2.6.27.4
I'm trying to limit the link from the controller to the PMP to 1.5
Gbps. That doesn't seem to be happening. Worse, now the drives
connected to that controller aren't being recognized.
I get "link online but device misclassified, retrying" errors. It's not
the drive or the caddy; I've swapped drives, slots, and cables and the
errors remain with the jmicron ports.
I'm attaching a log which takes you from boot, to powerup of the
diskpack, and then a power cycle of the diskpack. Unfortunately I
cannot boot with the external drives powered up; the jmicron controller
hangs (it has a conflict with the scsi controller if any drives are
attached at boot.)
I'd appreciate any help at this point. My only alternative is to
disable the onboard jmicron and get a PCI-based esata controller.
--Yan
[-- Attachment #2: syslog --]
[-- Type: text/plain, Size: 66317 bytes --]
Nov 22 06:53:20 selene kernel: [ 8.076344] ata3: SATA max UDMA/133 abar m8192@0xfdbfe000 port 0xfdbfe100 irq 16
Nov 22 06:53:20 selene kernel: [ 8.076347] ata4: SATA max UDMA/133 abar m8192@0xfdbfe000 port 0xfdbfe180 irq 16
Nov 22 06:53:20 selene kernel: [ 8.396027] ata3: SATA link down (SStatus 0 SControl 300)
Nov 22 06:53:20 selene kernel: [ 8.732025] ata4: SATA link down (SStatus 0 SControl 300)
Nov 22 06:53:43 selene kernel: [ 103.726812] ata3: exception Emask 0x10 SAct 0x0 SErr 0x4050000 action 0xe frozen
Nov 22 06:53:43 selene kernel: [ 103.726821] ata3: irq_stat 0x00400040, connection status changed
Nov 22 06:53:43 selene kernel: [ 103.726824] ata3: SError: { PHYRdyChg CommWake DevExch }
Nov 22 06:53:43 selene kernel: [ 103.726832] ata3: hard resetting link
Nov 22 06:53:43 selene kernel: [ 103.730085] ata4: exception Emask 0x10 SAct 0x0 SErr 0x4050000 action 0xe frozen
Nov 22 06:53:43 selene kernel: [ 103.730091] ata4: irq_stat 0x00400040, connection status changed
Nov 22 06:53:43 selene kernel: [ 103.730094] ata4: SError: { PHYRdyChg CommWake DevExch }
Nov 22 06:53:43 selene kernel: [ 103.730097] ata4: hard resetting link
Nov 22 06:53:45 selene kernel: [ 105.620028] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 06:53:45 selene kernel: [ 105.620296] ata3.15: Port Multiplier 1.1, 0x1095:0x3726 r23, 6 ports, feat 0x1/0x9
Nov 22 06:53:45 selene kernel: [ 105.620577] ata3.00: hard resetting link
Nov 22 06:53:45 selene kernel: [ 105.624020] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 06:53:45 selene kernel: [ 105.624027] ata4: link online but device misclassified, retrying
Nov 22 06:53:45 selene kernel: [ 105.624030] ata4: reset failed (errno=-11), retrying in 9 secs
Nov 22 06:53:45 selene kernel: [ 105.936981] ata3.00: SATA link down (SStatus 0 SControl 310)
Nov 22 06:53:45 selene kernel: [ 105.937081] ata3.01: hard resetting link
Nov 22 06:53:45 selene kernel: [ 106.257020] ata3.01: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 22 06:53:45 selene kernel: [ 106.257026] ata3.02: hard resetting link
Nov 22 06:53:45 selene kernel: [ 106.577025] ata3.02: SATA link down (SStatus 0 SControl 320)
Nov 22 06:53:45 selene kernel: [ 106.577124] ata3.03: hard resetting link
Nov 22 06:53:46 selene kernel: [ 106.897024] ata3.03: SATA link down (SStatus 0 SControl 320)
Nov 22 06:53:46 selene kernel: [ 106.897122] ata3.04: hard resetting link
Nov 22 06:53:46 selene kernel: [ 107.217022] ata3.04: SATA link down (SStatus 0 SControl 320)
Nov 22 06:53:46 selene kernel: [ 107.217120] ata3.05: hard resetting link
Nov 22 06:53:46 selene kernel: [ 107.536976] ata3.05: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 22 06:53:51 selene kernel: [ 112.536522] ata3.01: qc timeout (cmd 0xec)
Nov 22 06:53:51 selene kernel: [ 112.536534] ata3.01: failed to IDENTIFY (I/O error, err_mask=0x4)
Nov 22 06:53:51 selene kernel: [ 112.536539] ata3.15: hard resetting link
Nov 22 06:53:53 selene kernel: [ 113.728023] ata4: hard resetting link
Nov 22 06:53:53 selene kernel: [ 114.028522] ata3.15: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 06:53:53 selene kernel: [ 114.029004] ata3.00: hard resetting link
Nov 22 06:53:53 selene kernel: [ 114.348497] ata3.00: SATA link down (SStatus 0 SControl 310)
Nov 22 06:53:53 selene kernel: [ 114.348676] ata3.01: hard resetting link
Nov 22 06:53:54 selene kernel: [ 114.668526] ata3.01: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 22 06:53:54 selene kernel: [ 114.668534] ata3.05: hard resetting link
Nov 22 06:53:54 selene kernel: [ 114.997984] ata3.05: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 22 06:53:55 selene kernel: [ 115.624029] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 06:53:55 selene kernel: [ 115.624037] ata4: link online but device misclassified, retrying
Nov 22 06:53:55 selene kernel: [ 115.624040] ata4: reset failed (errno=-11), retrying in 9 secs
Nov 22 06:53:57 selene kernel: [ 118.123857] ata3.01: failed to IDENTIFY (INIT_DEV_PARAMS failed, err_mask=0x80)
Nov 22 06:53:57 selene kernel: [ 118.124935] ata3.01: hard resetting link
Nov 22 06:54:00 selene kernel: [ 121.124529] ata3.15: qc timeout (cmd 0xe4)
Nov 22 06:54:00 selene kernel: [ 121.124541] ata3.01: failed to read SCR 2 (Emask=0x4)
Nov 22 06:54:00 selene kernel: [ 121.124544] ata3.01: failed to read SCR 2 (Emask=0x40)
Nov 22 06:54:00 selene kernel: [ 121.124546] ata3.01: COMRESET failed (errno=-5)
Nov 22 06:54:00 selene kernel: [ 121.124549] ata3.01: failed to read SCR 0 (Emask=0x40)
Nov 22 06:54:00 selene kernel: [ 121.124551] ata3.01: reset failed, giving up
Nov 22 06:54:00 selene kernel: [ 121.124555] ata3.15: hard resetting link
Nov 22 06:54:01 selene kernel: [ 122.337060] sd 0:1:0:0: [sdj] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 06:54:01 selene kernel: [ 122.337083] sd 0:1:0:0: [sdj] Write Protect is off
Nov 22 06:54:01 selene kernel: [ 122.337086] sd 0:1:0:0: [sdj] Mode Sense: 00 3a 00 00
Nov 22 06:54:01 selene kernel: [ 122.337123] sd 0:1:0:0: [sdj] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 06:54:01 selene kernel: [ 122.337210] sd 0:1:0:0: [sdj] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 06:54:01 selene kernel: [ 122.337231] sd 0:1:0:0: [sdj] Write Protect is off
Nov 22 06:54:01 selene kernel: [ 122.337233] sd 0:1:0:0: [sdj] Mode Sense: 00 3a 00 00
Nov 22 06:54:01 selene kernel: [ 122.337268] sd 0:1:0:0: [sdj] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 06:54:01 selene kernel: [ 122.337272] sdj: sdj1
Nov 22 06:54:01 selene kernel: [ 122.364839] sd 0:1:0:0: [sdj] Attached SCSI disk
Nov 22 06:54:02 selene kernel: [ 122.628030] ata3.15: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 06:54:02 selene kernel: [ 122.628303] ata3.00: hard resetting link
Nov 22 06:54:02 selene kernel: [ 122.948958] ata3.00: SATA link down (SStatus 0 SControl 310)
Nov 22 06:54:02 selene kernel: [ 123.124525] ata3.01: hard resetting link
Nov 22 06:54:02 selene kernel: [ 123.444997] ata3.01: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 22 06:54:02 selene kernel: [ 123.445006] ata3.02: hard resetting link
Nov 22 06:54:03 selene kernel: [ 123.732027] ata4: hard resetting link
Nov 22 06:54:03 selene kernel: [ 123.760975] ata3.02: SATA link down (SStatus 0 SControl 320)
Nov 22 06:54:03 selene kernel: [ 123.761146] ata3.03: hard resetting link
Nov 22 06:54:03 selene kernel: [ 124.081013] ata3.03: SATA link down (SStatus 0 SControl 320)
Nov 22 06:54:03 selene kernel: [ 124.081180] ata3.04: hard resetting link
Nov 22 06:54:03 selene kernel: [ 124.401013] ata3.04: SATA link down (SStatus 0 SControl 320)
Nov 22 06:54:03 selene kernel: [ 124.401187] ata3.05: hard resetting link
Nov 22 06:54:04 selene kernel: [ 124.725187] ata3.05: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 22 06:54:04 selene kernel: [ 124.726676] ata3.01: ATA-8: ST31000340AS, SD15, max UDMA/133
Nov 22 06:54:04 selene kernel: [ 124.726681] ata3.01: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32)
Nov 22 06:54:04 selene kernel: [ 124.728615] ata3.01: configured for UDMA/133
Nov 22 06:54:04 selene kernel: [ 124.731287] ata3: EH complete
Nov 22 06:54:04 selene kernel: [ 124.731696] sd 4:1:0:0: [sdk] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 06:54:04 selene kernel: [ 124.731721] sd 4:1:0:0: [sdk] Write Protect is off
Nov 22 06:54:04 selene kernel: [ 124.731724] sd 4:1:0:0: [sdk] Mode Sense: 00 3a 00 00
Nov 22 06:54:04 selene kernel: [ 124.731761] sd 4:1:0:0: [sdk] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 06:54:04 selene kernel: [ 124.731844] sd 4:1:0:0: [sdk] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 06:54:04 selene kernel: [ 124.731869] sd 4:1:0:0: [sdk] Write Protect is off
Nov 22 06:54:04 selene kernel: [ 124.731871] sd 4:1:0:0: [sdk] Mode Sense: 00 3a 00 00
Nov 22 06:54:04 selene kernel: [ 124.731908] sd 4:1:0:0: [sdk] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 06:54:04 selene kernel: [ 124.731915] sdk: sdk1
Nov 22 06:54:04 selene kernel: [ 124.761321] sd 4:1:0:0: [sdk] Attached SCSI disk
Nov 22 06:54:04 selene kernel: [ 124.813130] sd 1:1:0:0: [sdl] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 06:54:04 selene kernel: [ 124.813153] sd 1:1:0:0: [sdl] Write Protect is off
Nov 22 06:54:04 selene kernel: [ 124.813155] sd 1:1:0:0: [sdl] Mode Sense: 00 3a 00 00
Nov 22 06:54:04 selene kernel: [ 124.813190] sd 1:1:0:0: [sdl] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 06:54:04 selene kernel: [ 124.813266] sd 1:1:0:0: [sdl] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 06:54:04 selene kernel: [ 124.813286] sd 1:1:0:0: [sdl] Write Protect is off
Nov 22 06:54:04 selene kernel: [ 124.813288] sd 1:1:0:0: [sdl] Mode Sense: 00 3a 00 00
Nov 22 06:54:04 selene kernel: [ 124.813322] sd 1:1:0:0: [sdl] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 06:54:04 selene kernel: [ 124.813325] sdl: sdl1
Nov 22 06:54:04 selene kernel: [ 124.838253] sd 1:1:0:0: [sdl] Attached SCSI disk
Nov 22 06:54:05 selene kernel: [ 125.624028] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 06:54:05 selene kernel: [ 125.624037] ata4: link online but device misclassified, retrying
Nov 22 06:54:05 selene kernel: [ 125.624039] ata4: reset failed (errno=-11), retrying in 34 secs
Nov 22 06:54:38 selene kernel: [ 158.728020] ata4: limiting SATA link speed to 1.5 Gbps
Nov 22 06:54:38 selene kernel: [ 158.728026] ata4: hard resetting link
Nov 22 06:54:40 selene kernel: [ 160.624025] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 06:54:40 selene kernel: [ 160.624035] ata4: link online but device misclassified, device detection might fail
Nov 22 06:54:40 selene kernel: [ 160.624041] ata4: EH complete
Nov 22 06:57:09 selene kernel: [ 309.921890] ata3.01: exception Emask 0x10 SAct 0x0 SErr 0x4010000 action 0xf
Nov 22 06:57:09 selene kernel: [ 309.921898] ata3.01: SError: { PHYRdyChg DevExch }
Nov 22 06:57:09 selene kernel: [ 309.923261] ata3.01: hard resetting link
Nov 22 06:57:09 selene kernel: [ 309.930585] ata4: exception Emask 0x10 SAct 0x0 SErr 0x1990001 action 0xe frozen
Nov 22 06:57:09 selene kernel: [ 309.930593] ata4: irq_stat 0x04400000, PHY RDY changed
Nov 22 06:57:09 selene kernel: [ 309.930596] ata4: SError: { RecovData PHYRdyChg 10B8B Dispar LinkSeq TrStaTrns }
Nov 22 06:57:09 selene kernel: [ 309.930603] ata4: hard resetting link
Nov 22 06:57:09 selene kernel: [ 309.940560] ata3.01: failed to read SCR 2 (Emask=0x40)
Nov 22 06:57:09 selene kernel: [ 309.940566] ata3.01: COMRESET failed (errno=-5)
Nov 22 06:57:09 selene kernel: [ 309.940569] ata3.01: failed to read SCR 0 (Emask=0x40)
Nov 22 06:57:09 selene kernel: [ 309.940573] ata3.01: reset failed, giving up
Nov 22 06:57:09 selene kernel: [ 309.940578] ata3.15: hard resetting link
Nov 22 06:57:09 selene kernel: [ 310.260528] ata3.15: SATA link down (SStatus 0 SControl 300)
Nov 22 06:57:10 selene kernel: [ 310.656032] ata4: SATA link down (SStatus 0 SControl 300)
Nov 22 06:57:10 selene kernel: [ 310.656044] ata4: EH complete
Nov 22 06:57:12 selene kernel: [ 313.260520] ata3.15: qc timeout (cmd 0xe4)
Nov 22 06:57:12 selene kernel: [ 313.260536] ata3.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 06:57:12 selene kernel: [ 313.260539] ata3.15: PMP revalidation failed (errno=-5)
Nov 22 06:57:14 selene kernel: [ 315.260518] ata3.15: hard resetting link
Nov 22 06:57:14 selene kernel: [ 315.580460] ata3.15: SATA link down (SStatus 0 SControl 300)
Nov 22 06:57:17 selene kernel: [ 318.580021] ata3.15: qc timeout (cmd 0xe4)
Nov 22 06:57:17 selene kernel: [ 318.580034] ata3.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 06:57:17 selene kernel: [ 318.580038] ata3.15: PMP revalidation failed (errno=-5)
Nov 22 06:57:17 selene kernel: [ 318.580041] ata3.15: limiting SATA link speed to 1.5 Gbps
Nov 22 06:57:19 selene kernel: [ 320.580020] ata3.15: hard resetting link
Nov 22 06:57:20 selene kernel: [ 320.904034] ata3.15: SATA link down (SStatus 0 SControl 310)
Nov 22 06:57:23 selene kernel: [ 323.904061] ata3.15: qc timeout (cmd 0xe4)
Nov 22 06:57:23 selene kernel: [ 323.904075] ata3.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 06:57:23 selene kernel: [ 323.904078] ata3.15: PMP revalidation failed (errno=-5)
Nov 22 06:57:25 selene kernel: [ 325.908015] ata3.15: hard resetting link
Nov 22 06:57:25 selene kernel: [ 326.224027] ata3.15: SATA link down (SStatus 0 SControl 310)
Nov 22 06:57:28 selene kernel: [ 329.224022] ata3.15: qc timeout (cmd 0xe4)
Nov 22 06:57:28 selene kernel: [ 329.224037] ata3.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 06:57:28 selene kernel: [ 329.224040] ata3.15: PMP revalidation failed (errno=-5)
Nov 22 06:57:30 selene kernel: [ 331.224018] ata3.15: hard resetting link
Nov 22 06:57:30 selene kernel: [ 331.331974] ata4: exception Emask 0x10 SAct 0x0 SErr 0x4050000 action 0xe frozen
Nov 22 06:57:30 selene kernel: [ 331.331980] ata4: irq_stat 0x00400040, connection status changed
Nov 22 06:57:30 selene kernel: [ 331.331983] ata4: SError: { PHYRdyChg CommWake DevExch }
Nov 22 06:57:30 selene kernel: [ 331.331990] ata4: hard resetting link
Nov 22 06:57:32 selene kernel: [ 332.724037] ata3.15: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 06:57:32 selene kernel: [ 332.724398] ata3.00: hard resetting link
Nov 22 06:57:32 selene kernel: [ 333.045051] ata3.00: SATA link down (SStatus 0 SControl 10)
Nov 22 06:57:32 selene kernel: [ 333.045100] ata3.01: hard resetting link
Nov 22 06:57:32 selene kernel: [ 333.224524] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 06:57:32 selene kernel: [ 333.224532] ata4: link online but device misclassified, retrying
Nov 22 06:57:32 selene kernel: [ 333.224535] ata4: reset failed (errno=-11), retrying in 9 secs
Nov 22 06:57:32 selene kernel: [ 333.364520] ata3.01: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 06:57:32 selene kernel: [ 333.364527] ata3.02: hard resetting link
Nov 22 06:57:33 selene kernel: [ 333.684462] ata3.02: SATA link down (SStatus 0 SControl 310)
Nov 22 06:57:33 selene kernel: [ 333.684516] ata3.03: hard resetting link
Nov 22 06:57:33 selene kernel: [ 334.004998] ata3.03: SATA link down (SStatus 0 SControl 310)
Nov 22 06:57:33 selene kernel: [ 334.005047] ata3.04: hard resetting link
Nov 22 06:57:33 selene kernel: [ 334.324966] ata3.04: SATA link down (SStatus 0 SControl 310)
Nov 22 06:57:33 selene kernel: [ 334.325017] ata3.05: hard resetting link
Nov 22 06:57:34 selene kernel: [ 334.645009] ata3.05: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 06:57:39 selene kernel: [ 339.644023] ata3.01: qc timeout (cmd 0xec)
Nov 22 06:57:39 selene kernel: [ 339.644038] ata3.01: failed to IDENTIFY (I/O error, err_mask=0x4)
Nov 22 06:57:39 selene kernel: [ 339.644042] ata3.01: revalidation failed (errno=-5)
Nov 22 06:57:39 selene kernel: [ 339.644049] ata3.15: hard resetting link
Nov 22 06:57:40 selene kernel: [ 341.140530] ata3.15: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 06:57:40 selene kernel: [ 341.145868] ata3.00: hard resetting link
Nov 22 06:57:40 selene kernel: [ 341.328513] ata4: hard resetting link
Nov 22 06:57:40 selene kernel: [ 341.464990] ata3.00: SATA link down (SStatus 0 SControl 310)
Nov 22 06:57:40 selene kernel: [ 341.465039] ata3.01: hard resetting link
Nov 22 06:57:41 selene kernel: [ 341.784962] ata3.01: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 06:57:41 selene kernel: [ 341.784968] ata3.05: hard resetting link
Nov 22 06:57:41 selene kernel: [ 342.104964] ata3.05: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 06:57:42 selene kernel: [ 343.228525] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 06:57:42 selene kernel: [ 343.228534] ata4: link online but device misclassified, retrying
Nov 22 06:57:42 selene kernel: [ 343.228536] ata4: reset failed (errno=-11), retrying in 9 secs
Nov 22 06:57:44 selene kernel: [ 345.260263] ata3.01: failed to read native max address (err_mask=0x1)
Nov 22 06:57:44 selene kernel: [ 345.260269] ata3.01: HPA support seems broken, skipping HPA handling
Nov 22 06:57:44 selene kernel: [ 345.261783] ata3.01: configured for UDMA/133
Nov 22 06:57:44 selene kernel: [ 345.262129] ata3.15: exception Emask 0x10 SAct 0x0 SErr 0x400001 action 0x9 t4
Nov 22 06:57:44 selene kernel: [ 345.262134] ata3.15: irq_stat 0x48000000
Nov 22 06:57:44 selene kernel: [ 345.262136] ata3.15: SError: { RecovData Handshk }
Nov 22 06:57:44 selene kernel: [ 345.262597] ata3: EH complete
Nov 22 06:57:44 selene kernel: [ 345.262701] sd 4:1:0:0: [sdk] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 06:57:44 selene kernel: [ 345.262725] sd 4:1:0:0: [sdk] Write Protect is off
Nov 22 06:57:44 selene kernel: [ 345.262727] sd 4:1:0:0: [sdk] Mode Sense: 00 3a 00 00
Nov 22 06:57:44 selene kernel: [ 345.262764] sd 4:1:0:0: [sdk] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 06:57:44 selene kernel: [ 345.581894] sd 1:1:0:0: [sdl] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 06:57:44 selene kernel: [ 345.581918] sd 1:1:0:0: [sdl] Write Protect is off
Nov 22 06:57:44 selene kernel: [ 345.581920] sd 1:1:0:0: [sdl] Mode Sense: 00 3a 00 00
Nov 22 06:57:44 selene kernel: [ 345.581958] sd 1:1:0:0: [sdl] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 06:57:49 selene kernel: [ 350.289017] Buffer I/O error on device sdj1, logical block 976759936
Nov 22 06:57:49 selene kernel: [ 350.289021] Buffer I/O error on device sdj1, logical block 976759937
Nov 22 06:57:49 selene kernel: [ 350.289024] Buffer I/O error on device sdj1, logical block 976759938
Nov 22 06:57:49 selene kernel: [ 350.289026] Buffer I/O error on device sdj1, logical block 976759939
Nov 22 06:57:49 selene kernel: [ 350.289038] Buffer I/O error on device sdj1, logical block 976759936
Nov 22 06:57:49 selene kernel: [ 350.289046] Buffer I/O error on device sdj1, logical block 976759937
Nov 22 06:57:49 selene kernel: [ 350.289052] Buffer I/O error on device sdj1, logical block 976759938
Nov 22 06:57:49 selene kernel: [ 350.289059] Buffer I/O error on device sdj1, logical block 976759939
Nov 22 06:57:49 selene kernel: [ 350.289115] Buffer I/O error on device sdj, logical block 244190624
Nov 22 06:57:49 selene kernel: [ 350.289122] Buffer I/O error on device sdj, logical block 244190624
Nov 22 06:57:49 selene kernel: [ 350.289322] sd 0:1:0:0: [sdj] Synchronizing SCSI cache
Nov 22 06:57:49 selene kernel: [ 350.289383] sd 0:1:0:0: [sdj] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Nov 22 06:57:49 selene kernel: [ 350.289386] sd 0:1:0:0: [sdj] Stopping disk
Nov 22 06:57:49 selene kernel: [ 350.289402] sd 0:1:0:0: [sdj] START_STOP FAILED
Nov 22 06:57:49 selene kernel: [ 350.289404] sd 0:1:0:0: [sdj] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Nov 22 06:57:49 selene kernel: [ 350.304884] md: bind<sdk1>
Nov 22 06:57:49 selene kernel: [ 350.305427] md: bind<sdl1>
Nov 22 06:57:49 selene kernel: [ 350.367343] raid5: device sdl1 operational as raid disk 1
Nov 22 06:57:49 selene kernel: [ 350.367349] raid5: device sdk1 operational as raid disk 2
Nov 22 06:57:49 selene kernel: [ 350.367901] disk 1, o:1, dev:sdl1
Nov 22 06:57:49 selene kernel: [ 350.367903] disk 2, o:1, dev:sdk1
Nov 22 06:57:50 selene kernel: [ 351.328525] ata4: hard resetting link
Nov 22 06:57:52 selene kernel: [ 353.224540] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 06:57:52 selene kernel: [ 353.224549] ata4: link online but device misclassified, retrying
Nov 22 06:57:52 selene kernel: [ 353.224551] ata4: reset failed (errno=-11), retrying in 34 secs
Nov 22 06:58:25 selene kernel: [ 386.328516] ata4: limiting SATA link speed to 1.5 Gbps
Nov 22 06:58:25 selene kernel: [ 386.328522] ata4: hard resetting link
Nov 22 06:58:27 selene kernel: [ 388.224025] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 06:58:27 selene kernel: [ 388.224034] ata4: link online but device misclassified, device detection might fail
Nov 22 06:58:27 selene kernel: [ 388.224039] ata4: EH complete
Nov 22 07:03:37 selene kernel: [ 698.104956] ata3.01: exception Emask 0x10 SAct 0x0 SErr 0x4010000 action 0xf
Nov 22 07:03:37 selene kernel: [ 698.104962] ata3.01: SError: { PHYRdyChg DevExch }
Nov 22 07:03:37 selene kernel: [ 698.105257] ata3.01: hard resetting link
Nov 22 07:03:37 selene kernel: [ 698.115302] ata4: exception Emask 0x10 SAct 0x0 SErr 0x1990001 action 0xe frozen
Nov 22 07:03:37 selene kernel: [ 698.115308] ata4: irq_stat 0x04400000, PHY RDY changed
Nov 22 07:03:37 selene kernel: [ 698.115311] ata4: SError: { RecovData PHYRdyChg 10B8B Dispar LinkSeq TrStaTrns }
Nov 22 07:03:37 selene kernel: [ 698.115320] ata4: hard resetting link
Nov 22 07:03:37 selene kernel: [ 698.316431] ata3.01: failed to read SCR 0 (Emask=0x40)
Nov 22 07:03:37 selene kernel: [ 698.316437] ata3.01: COMRESET failed (errno=-5)
Nov 22 07:03:37 selene kernel: [ 698.316440] ata3.01: failed to read SCR 0 (Emask=0x40)
Nov 22 07:03:37 selene kernel: [ 698.316442] ata3.01: reset failed, giving up
Nov 22 07:03:37 selene kernel: [ 698.316446] ata3.15: hard resetting link
Nov 22 07:03:38 selene kernel: [ 698.636530] ata3.15: SATA link down (SStatus 0 SControl 310)
Nov 22 07:03:38 selene kernel: [ 698.844031] ata4: SATA link down (SStatus 0 SControl 300)
Nov 22 07:03:38 selene kernel: [ 698.844046] ata4: EH complete
Nov 22 07:03:41 selene kernel: [ 701.636517] ata3.15: qc timeout (cmd 0xe4)
Nov 22 07:03:41 selene kernel: [ 701.636532] ata3.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:03:41 selene kernel: [ 701.636534] ata3.15: PMP revalidation failed (errno=-5)
Nov 22 07:03:43 selene kernel: [ 703.636514] ata3.15: hard resetting link
Nov 22 07:03:43 selene kernel: [ 703.956030] ata3.15: SATA link down (SStatus 0 SControl 310)
Nov 22 07:03:46 selene kernel: [ 706.956033] ata3.15: qc timeout (cmd 0xe4)
Nov 22 07:03:46 selene kernel: [ 706.956048] ata3.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:03:46 selene kernel: [ 706.956050] ata3.15: PMP revalidation failed (errno=-5)
Nov 22 07:03:48 selene kernel: [ 708.956519] ata3.15: hard resetting link
Nov 22 07:03:48 selene kernel: [ 709.283160] ata3.15: SATA link down (SStatus 0 SControl 310)
Nov 22 07:03:51 selene kernel: [ 712.280517] ata3.15: qc timeout (cmd 0xe4)
Nov 22 07:03:51 selene kernel: [ 712.280532] ata3.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:03:51 selene kernel: [ 712.280535] ata3.15: PMP revalidation failed (errno=-5)
Nov 22 07:03:53 selene kernel: [ 714.280551] ata3.15: hard resetting link
Nov 22 07:03:54 selene kernel: [ 714.600527] ata3.15: SATA link down (SStatus 0 SControl 310)
Nov 22 07:03:57 selene kernel: [ 717.600514] ata3.15: qc timeout (cmd 0xe4)
Nov 22 07:03:57 selene kernel: [ 717.600527] ata3.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:03:57 selene kernel: [ 717.600528] ata3.15: PMP revalidation failed (errno=-5)
Nov 22 07:03:59 selene kernel: [ 719.600513] ata3.15: hard resetting link
Nov 22 07:03:59 selene kernel: [ 719.920527] ata3.15: SATA link down (SStatus 0 SControl 310)
Nov 22 07:04:02 selene kernel: [ 722.920521] ata3.15: qc timeout (cmd 0xe4)
Nov 22 07:04:02 selene kernel: [ 722.920536] ata3.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:04:02 selene kernel: [ 722.920539] ata3.15: PMP revalidation failed (errno=-5)
Nov 22 07:04:02 selene kernel: [ 722.920541] ata3.15: failed to recover PMP after 5 tries, giving up
Nov 22 07:04:02 selene kernel: [ 722.920543] ata3.15: Port Multiplier detaching
Nov 22 07:04:02 selene kernel: [ 722.920547] ata3.01: disabled
Nov 22 07:04:02 selene kernel: [ 722.920551] ata3.00: disabled
Nov 22 07:04:02 selene kernel: [ 722.920560] ata3: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0xe frozen t4
Nov 22 07:04:02 selene kernel: [ 722.920562] ata3: irq_stat 0x00400000, PHY RDY changed
Nov 22 07:04:02 selene kernel: [ 722.920567] ata3: hard resetting link
Nov 22 07:04:03 selene kernel: [ 723.644527] ata3: SATA link down (SStatus 0 SControl 300)
Nov 22 07:04:03 selene kernel: [ 723.644542] ata3: EH complete
Nov 22 07:04:03 selene kernel: [ 723.644556] ata3.01: detaching (SCSI 4:1:0:0)
Nov 22 07:04:03 selene kernel: [ 723.644934] sd 4:1:0:0: [sdk] Synchronizing SCSI cache
Nov 22 07:04:03 selene kernel: [ 723.645001] sd 4:1:0:0: [sdk] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Nov 22 07:04:03 selene kernel: [ 723.645008] sd 4:1:0:0: [sdk] Stopping disk
Nov 22 07:04:03 selene kernel: [ 723.645025] sd 4:1:0:0: [sdk] START_STOP FAILED
Nov 22 07:04:03 selene kernel: [ 723.645027] sd 4:1:0:0: [sdk] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Nov 22 07:04:13 selene kernel: [ 733.799297] sd 1:1:0:0: [sdl] Synchronizing SCSI cache
Nov 22 07:04:13 selene kernel: [ 733.799370] sd 1:1:0:0: [sdl] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Nov 22 07:04:13 selene kernel: [ 733.799374] sd 1:1:0:0: [sdl] Stopping disk
Nov 22 07:04:13 selene kernel: [ 733.799390] sd 1:1:0:0: [sdl] START_STOP FAILED
Nov 22 07:04:13 selene kernel: [ 733.799392] sd 1:1:0:0: [sdl] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Nov 22 07:04:33 selene kernel: [ 754.464361] ata4: exception Emask 0x10 SAct 0x0 SErr 0x4050000 action 0xe frozen
Nov 22 07:04:33 selene kernel: [ 754.464370] ata4: irq_stat 0x00400040, connection status changed
Nov 22 07:04:33 selene kernel: [ 754.464373] ata4: SError: { PHYRdyChg CommWake DevExch }
Nov 22 07:04:33 selene kernel: [ 754.464382] ata4: hard resetting link
Nov 22 07:04:33 selene kernel: [ 754.468066] ata3: exception Emask 0x10 SAct 0x0 SErr 0x4050000 action 0xe frozen
Nov 22 07:04:33 selene kernel: [ 754.468068] ata3: irq_stat 0x00000040, connection status changed
Nov 22 07:04:33 selene kernel: [ 754.468071] ata3: SError: { PHYRdyChg CommWake DevExch }
Nov 22 07:04:33 selene kernel: [ 754.468075] ata3: hard resetting link
Nov 22 07:04:35 selene kernel: [ 756.360026] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 07:04:35 selene kernel: [ 756.360468] ata4.15: Port Multiplier 1.1, 0x1095:0x3726 r23, 6 ports, feat 0x1/0x9
Nov 22 07:04:35 selene kernel: [ 756.360836] ata4.00: hard resetting link
Nov 22 07:04:35 selene kernel: [ 756.364526] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 07:04:35 selene kernel: [ 756.364533] ata3: link online but device misclassified, retrying
Nov 22 07:04:35 selene kernel: [ 756.364535] ata3: reset failed (errno=-11), retrying in 9 secs
Nov 22 07:04:36 selene kernel: [ 756.680967] ata4.00: SATA link down (SStatus 0 SControl 310)
Nov 22 07:04:36 selene kernel: [ 756.681016] ata4.01: hard resetting link
Nov 22 07:04:36 selene kernel: [ 757.000950] ata4.01: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 22 07:04:36 selene kernel: [ 757.000955] ata4.02: hard resetting link
Nov 22 07:04:36 selene kernel: [ 757.328368] ata4.02: SATA link down (SStatus 0 SControl 320)
Nov 22 07:04:36 selene kernel: [ 757.328443] ata4.03: hard resetting link
Nov 22 07:04:37 selene kernel: [ 757.648994] ata4.03: SATA link down (SStatus 0 SControl 320)
Nov 22 07:04:37 selene kernel: [ 757.649047] ata4.04: hard resetting link
Nov 22 07:04:37 selene kernel: [ 757.968984] ata4.04: SATA link down (SStatus 0 SControl 320)
Nov 22 07:04:37 selene kernel: [ 757.969036] ata4.05: hard resetting link
Nov 22 07:04:37 selene kernel: [ 758.289096] ata4.05: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 22 07:04:42 selene kernel: [ 763.288112] ata4.01: qc timeout (cmd 0xec)
Nov 22 07:04:42 selene kernel: [ 763.288127] ata4.01: failed to IDENTIFY (I/O error, err_mask=0x4)
Nov 22 07:04:42 selene kernel: [ 763.288132] ata4.15: hard resetting link
Nov 22 07:04:43 selene kernel: [ 764.468518] ata3: hard resetting link
Nov 22 07:04:44 selene kernel: [ 764.780523] ata4.15: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 07:04:44 selene kernel: [ 764.780858] ata4.00: hard resetting link
Nov 22 07:04:44 selene kernel: [ 765.101008] ata4.00: SATA link down (SStatus 0 SControl 310)
Nov 22 07:04:44 selene kernel: [ 765.101061] ata4.01: hard resetting link
Nov 22 07:04:44 selene kernel: [ 765.420999] ata4.01: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 22 07:04:44 selene kernel: [ 765.421008] ata4.05: hard resetting link
Nov 22 07:04:45 selene kernel: [ 765.745006] ata4.05: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 22 07:04:45 selene kernel: [ 766.364526] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 07:04:45 selene kernel: [ 766.364534] ata3: link online but device misclassified, retrying
Nov 22 07:04:45 selene kernel: [ 766.364537] ata3: reset failed (errno=-11), retrying in 9 secs
Nov 22 07:04:48 selene kernel: [ 768.836668] ata4.01: failed to IDENTIFY (INIT_DEV_PARAMS failed, err_mask=0x80)
Nov 22 07:04:48 selene kernel: [ 768.837766] ata4.01: hard resetting link
Nov 22 07:04:51 selene kernel: [ 771.836600] ata4.15: qc timeout (cmd 0xe4)
Nov 22 07:04:51 selene kernel: [ 771.836615] ata4.01: failed to read SCR 2 (Emask=0x4)
Nov 22 07:04:51 selene kernel: [ 771.836619] ata4.01: failed to read SCR 2 (Emask=0x40)
Nov 22 07:04:51 selene kernel: [ 771.836622] ata4.01: COMRESET failed (errno=-5)
Nov 22 07:04:51 selene kernel: [ 771.836626] ata4.01: failed to read SCR 0 (Emask=0x40)
Nov 22 07:04:51 selene kernel: [ 771.836629] ata4.01: reset failed, giving up
Nov 22 07:04:51 selene kernel: [ 771.836634] ata4.15: hard resetting link
Nov 22 07:04:52 selene kernel: [ 773.073029] sd 0:1:0:0: [sdj] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 07:04:52 selene kernel: [ 773.073051] sd 0:1:0:0: [sdj] Write Protect is off
Nov 22 07:04:52 selene kernel: [ 773.073054] sd 0:1:0:0: [sdj] Mode Sense: 00 3a 00 00
Nov 22 07:04:52 selene kernel: [ 773.073091] sd 0:1:0:0: [sdj] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 07:04:52 selene kernel: [ 773.073171] sd 0:1:0:0: [sdj] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 07:04:52 selene kernel: [ 773.073191] sd 0:1:0:0: [sdj] Write Protect is off
Nov 22 07:04:52 selene kernel: [ 773.073193] sd 0:1:0:0: [sdj] Mode Sense: 00 3a 00 00
Nov 22 07:04:52 selene kernel: [ 773.073230] sd 0:1:0:0: [sdj] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 07:04:52 selene kernel: [ 773.073233] sdj: sdj1
Nov 22 07:04:52 selene kernel: [ 773.102273] sd 0:1:0:0: [sdj] Attached SCSI disk
Nov 22 07:04:52 selene kernel: [ 773.328357] ata4.15: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 07:04:52 selene kernel: [ 773.328751] ata4.00: hard resetting link
Nov 22 07:04:53 selene kernel: [ 773.649004] ata4.00: SATA link down (SStatus 0 SControl 310)
Nov 22 07:04:53 selene kernel: [ 773.836516] ata4.01: hard resetting link
Nov 22 07:04:53 selene kernel: [ 774.157012] ata4.01: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 22 07:04:53 selene kernel: [ 774.157021] ata4.02: hard resetting link
Nov 22 07:04:53 selene kernel: [ 774.468515] ata3: hard resetting link
Nov 22 07:04:53 selene kernel: [ 774.477006] ata4.02: SATA link down (SStatus 0 SControl 320)
Nov 22 07:04:53 selene kernel: [ 774.477058] ata4.03: hard resetting link
Nov 22 07:04:54 selene kernel: [ 774.797002] ata4.03: SATA link down (SStatus 0 SControl 320)
Nov 22 07:04:54 selene kernel: [ 774.797052] ata4.04: hard resetting link
Nov 22 07:04:54 selene kernel: [ 775.116995] ata4.04: SATA link down (SStatus 0 SControl 320)
Nov 22 07:04:54 selene kernel: [ 775.117043] ata4.05: hard resetting link
Nov 22 07:04:54 selene kernel: [ 775.436960] ata4.05: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 22 07:04:54 selene kernel: [ 775.438449] ata4.01: ATA-8: ST31000340AS, SD15, max UDMA/133
Nov 22 07:04:54 selene kernel: [ 775.438454] ata4.01: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32)
Nov 22 07:04:54 selene kernel: [ 775.440398] ata4.01: configured for UDMA/133
Nov 22 07:04:54 selene kernel: [ 775.441019] ata4: EH complete
Nov 22 07:04:54 selene kernel: [ 775.441251] sd 5:1:0:0: [sdm] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 07:04:54 selene kernel: [ 775.441273] sd 5:1:0:0: [sdm] Write Protect is off
Nov 22 07:04:54 selene kernel: [ 775.441276] sd 5:1:0:0: [sdm] Mode Sense: 00 3a 00 00
Nov 22 07:04:54 selene kernel: [ 775.441313] sd 5:1:0:0: [sdm] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 07:04:54 selene kernel: [ 775.441392] sd 5:1:0:0: [sdm] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 07:04:54 selene kernel: [ 775.441412] sd 5:1:0:0: [sdm] Write Protect is off
Nov 22 07:04:54 selene kernel: [ 775.441415] sd 5:1:0:0: [sdm] Mode Sense: 00 3a 00 00
Nov 22 07:04:54 selene kernel: [ 775.441451] sd 5:1:0:0: [sdm] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 07:04:54 selene kernel: [ 775.441454] sdm: sdm1
Nov 22 07:04:54 selene kernel: [ 775.469127] sd 5:1:0:0: [sdm] Attached SCSI disk
Nov 22 07:04:55 selene kernel: [ 775.816885] sd 1:1:0:0: [sdn] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 07:04:55 selene kernel: [ 775.816902] sd 1:1:0:0: [sdn] Write Protect is off
Nov 22 07:04:55 selene kernel: [ 775.816905] sd 1:1:0:0: [sdn] Mode Sense: 00 3a 00 00
Nov 22 07:04:55 selene kernel: [ 775.816932] sd 1:1:0:0: [sdn] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 07:04:55 selene kernel: [ 775.817045] sd 1:1:0:0: [sdn] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 07:04:55 selene kernel: [ 775.817061] sd 1:1:0:0: [sdn] Write Protect is off
Nov 22 07:04:55 selene kernel: [ 775.817063] sd 1:1:0:0: [sdn] Mode Sense: 00 3a 00 00
Nov 22 07:04:55 selene kernel: [ 775.817089] sd 1:1:0:0: [sdn] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 07:04:55 selene kernel: [ 775.817092] sdn: sdn1
Nov 22 07:04:55 selene kernel: [ 775.845659] sd 1:1:0:0: [sdn] Attached SCSI disk
Nov 22 07:04:55 selene kernel: [ 776.364526] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 07:04:55 selene kernel: [ 776.364535] ata3: link online but device misclassified, retrying
Nov 22 07:04:55 selene kernel: [ 776.364538] ata3: reset failed (errno=-11), retrying in 34 secs
Nov 22 07:05:28 selene kernel: [ 809.468157] ata3: limiting SATA link speed to 1.5 Gbps
Nov 22 07:05:28 selene kernel: [ 809.468164] ata3: hard resetting link
Nov 22 07:05:30 selene kernel: [ 811.364522] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 07:05:30 selene kernel: [ 811.364531] ata3: link online but device misclassified, device detection might fail
Nov 22 07:05:30 selene kernel: [ 811.364538] ata3: EH complete
Nov 22 07:08:08 selene kernel: [ 968.627099] ata4.01: exception Emask 0x10 SAct 0x0 SErr 0x4010000 action 0xf
Nov 22 07:08:08 selene kernel: [ 968.627144] ata4.01: SError: { PHYRdyChg DevExch }
Nov 22 07:08:08 selene kernel: [ 968.627313] ata4.01: hard resetting link
Nov 22 07:08:08 selene kernel: [ 968.637576] ata3: exception Emask 0x10 SAct 0x0 SErr 0x1990001 action 0xe frozen
Nov 22 07:08:08 selene kernel: [ 968.637585] ata3: irq_stat 0x04400000, PHY RDY changed
Nov 22 07:08:08 selene kernel: [ 968.637588] ata3: SError: { RecovData PHYRdyChg 10B8B Dispar LinkSeq TrStaTrns }
Nov 22 07:08:08 selene kernel: [ 968.637596] ata3: hard resetting link
Nov 22 07:08:08 selene kernel: [ 968.836453] ata4.01: failed to read SCR 0 (Emask=0x40)
Nov 22 07:08:08 selene kernel: [ 968.836455] ata4.01: COMRESET failed (errno=-5)
Nov 22 07:08:08 selene kernel: [ 968.836457] ata4.01: failed to read SCR 0 (Emask=0x40)
Nov 22 07:08:08 selene kernel: [ 968.836458] ata4.01: reset failed, giving up
Nov 22 07:08:08 selene kernel: [ 968.836461] ata4.15: hard resetting link
Nov 22 07:08:08 selene kernel: [ 969.156541] ata4.15: SATA link down (SStatus 0 SControl 300)
Nov 22 07:08:08 selene kernel: [ 969.360528] ata3: SATA link down (SStatus 0 SControl 300)
Nov 22 07:08:08 selene kernel: [ 969.360541] ata3: EH complete
Nov 22 07:08:11 selene kernel: [ 972.156133] ata4.15: qc timeout (cmd 0xe4)
Nov 22 07:08:11 selene kernel: [ 972.156149] ata4.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:08:11 selene kernel: [ 972.156152] ata4.15: PMP revalidation failed (errno=-5)
Nov 22 07:08:13 selene kernel: [ 974.156233] ata4.15: hard resetting link
Nov 22 07:08:13 selene kernel: [ 974.476527] ata4.15: SATA link down (SStatus 0 SControl 300)
Nov 22 07:08:16 selene kernel: [ 977.476523] ata4.15: qc timeout (cmd 0xe4)
Nov 22 07:08:16 selene kernel: [ 977.476542] ata4.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:08:16 selene kernel: [ 977.476546] ata4.15: PMP revalidation failed (errno=-5)
Nov 22 07:08:16 selene kernel: [ 977.476551] ata4.15: limiting SATA link speed to 1.5 Gbps
Nov 22 07:08:18 selene kernel: [ 979.476515] ata4.15: hard resetting link
Nov 22 07:08:19 selene kernel: [ 979.796528] ata4.15: SATA link down (SStatus 0 SControl 310)
Nov 22 07:08:22 selene kernel: [ 982.796518] ata4.15: qc timeout (cmd 0xe4)
Nov 22 07:08:22 selene kernel: [ 982.796536] ata4.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:08:22 selene kernel: [ 982.796540] ata4.15: PMP revalidation failed (errno=-5)
Nov 22 07:08:24 selene kernel: [ 984.796517] ata4.15: hard resetting link
Nov 22 07:08:24 selene kernel: [ 985.116525] ata4.15: SATA link down (SStatus 0 SControl 310)
Nov 22 07:08:27 selene kernel: [ 988.116016] ata4.15: qc timeout (cmd 0xe4)
Nov 22 07:08:27 selene kernel: [ 988.116032] ata4.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:08:27 selene kernel: [ 988.116034] ata4.15: PMP revalidation failed (errno=-5)
Nov 22 07:08:29 selene kernel: [ 990.116015] ata4.15: hard resetting link
Nov 22 07:08:29 selene kernel: [ 990.436527] ata4.15: SATA link down (SStatus 0 SControl 310)
Nov 22 07:08:32 selene kernel: [ 993.436526] ata4.15: qc timeout (cmd 0xe4)
Nov 22 07:08:32 selene kernel: [ 993.436543] ata4.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:08:32 selene kernel: [ 993.436546] ata4.15: PMP revalidation failed (errno=-5)
Nov 22 07:08:32 selene kernel: [ 993.436549] ata4.15: failed to recover PMP after 5 tries, giving up
Nov 22 07:08:32 selene kernel: [ 993.436551] ata4.15: Port Multiplier detaching
Nov 22 07:08:32 selene kernel: [ 993.436555] ata4.01: disabled
Nov 22 07:08:32 selene kernel: [ 993.436560] ata4.00: disabled
Nov 22 07:08:32 selene kernel: [ 993.436572] ata4: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0xe frozen t4
Nov 22 07:08:32 selene kernel: [ 993.436574] ata4: irq_stat 0x00400000, PHY RDY changed
Nov 22 07:08:32 selene kernel: [ 993.436580] ata4: hard resetting link
Nov 22 07:08:33 selene kernel: [ 994.160164] ata4: SATA link down (SStatus 0 SControl 300)
Nov 22 07:08:33 selene kernel: [ 994.160181] ata4: EH complete
Nov 22 07:08:33 selene kernel: [ 994.160196] ata4.01: detaching (SCSI 5:1:0:0)
Nov 22 07:08:33 selene kernel: [ 994.160579] sd 5:1:0:0: [sdm] Synchronizing SCSI cache
Nov 22 07:08:33 selene kernel: [ 994.164349] sd 5:1:0:0: [sdm] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Nov 22 07:08:33 selene kernel: [ 994.164360] sd 5:1:0:0: [sdm] Stopping disk
Nov 22 07:08:33 selene kernel: [ 994.164374] sd 5:1:0:0: [sdm] START_STOP FAILED
Nov 22 07:08:33 selene kernel: [ 994.164376] sd 5:1:0:0: [sdm] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Nov 22 07:08:39 selene kernel: [ 1000.442715] ata4: exception Emask 0x10 SAct 0x0 SErr 0x4050000 action 0xe frozen
Nov 22 07:08:39 selene kernel: [ 1000.442721] ata4: irq_stat 0x00000040, connection status changed
Nov 22 07:08:39 selene kernel: [ 1000.442724] ata4: SError: { PHYRdyChg CommWake DevExch }
Nov 22 07:08:39 selene kernel: [ 1000.442732] ata4: hard resetting link
Nov 22 07:08:39 selene kernel: [ 1000.446407] ata3: exception Emask 0x10 SAct 0x0 SErr 0x4050000 action 0xe frozen
Nov 22 07:08:39 selene kernel: [ 1000.446412] ata3: irq_stat 0x00400040, connection status changed
Nov 22 07:08:39 selene kernel: [ 1000.446414] ata3: SError: { PHYRdyChg CommWake DevExch }
Nov 22 07:08:39 selene kernel: [ 1000.446418] ata3: hard resetting link
Nov 22 07:08:41 selene kernel: [ 1002.340528] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Nov 22 07:08:41 selene kernel: [ 1002.340585] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 07:08:41 selene kernel: [ 1002.340590] ata3: link online but device misclassified, retrying
Nov 22 07:08:41 selene kernel: [ 1002.340592] ata3: reset failed (errno=-11), retrying in 9 secs
Nov 22 07:08:41 selene kernel: [ 1002.340891] ata4.15: Port Multiplier 1.1, 0x1095:0x3726 r23, 6 ports, feat 0x1/0x9
Nov 22 07:08:41 selene kernel: [ 1002.341193] ata4.00: hard resetting link
Nov 22 07:08:42 selene kernel: [ 1002.660957] ata4.00: SATA link down (SStatus 0 SControl 310)
Nov 22 07:08:42 selene kernel: [ 1002.661004] ata4.01: hard resetting link
Nov 22 07:08:42 selene kernel: [ 1002.981069] ata4.01: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 07:08:42 selene kernel: [ 1002.981075] ata4.02: hard resetting link
Nov 22 07:08:42 selene kernel: [ 1003.301010] ata4.02: SATA link down (SStatus 0 SControl 310)
Nov 22 07:08:42 selene kernel: [ 1003.301060] ata4.03: hard resetting link
Nov 22 07:08:43 selene kernel: [ 1003.620966] ata4.03: SATA link down (SStatus 0 SControl 310)
Nov 22 07:08:43 selene kernel: [ 1003.621013] ata4.04: hard resetting link
Nov 22 07:08:43 selene kernel: [ 1003.941012] ata4.04: SATA link down (SStatus 0 SControl 310)
Nov 22 07:08:43 selene kernel: [ 1003.941065] ata4.05: hard resetting link
Nov 22 07:08:43 selene kernel: [ 1004.261017] ata4.05: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 07:08:43 selene kernel: [ 1004.565590] sd 0:1:0:0: [sdj] Synchronizing SCSI cache
Nov 22 07:08:43 selene kernel: [ 1004.568808] sd 0:1:0:0: [sdj] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Nov 22 07:08:43 selene kernel: [ 1004.568817] sd 0:1:0:0: [sdj] Stopping disk
Nov 22 07:08:43 selene kernel: [ 1004.568832] sd 0:1:0:0: [sdj] START_STOP FAILED
Nov 22 07:08:43 selene kernel: [ 1004.568834] sd 0:1:0:0: [sdj] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Nov 22 07:08:48 selene kernel: [ 1009.260518] ata4.01: qc timeout (cmd 0xec)
Nov 22 07:08:48 selene kernel: [ 1009.260534] ata4.01: failed to IDENTIFY (I/O error, err_mask=0x4)
Nov 22 07:08:48 selene kernel: [ 1009.260540] ata4.15: hard resetting link
Nov 22 07:08:49 selene kernel: [ 1010.444524] ata3: hard resetting link
Nov 22 07:08:50 selene kernel: [ 1010.752528] ata4.15: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 07:08:50 selene kernel: [ 1010.752884] ata4.00: hard resetting link
Nov 22 07:08:50 selene kernel: [ 1011.073022] ata4.00: SATA link down (SStatus 0 SControl 310)
Nov 22 07:08:50 selene kernel: [ 1011.073071] ata4.01: hard resetting link
Nov 22 07:08:50 selene kernel: [ 1011.392956] ata4.01: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 22 07:08:50 selene kernel: [ 1011.392965] ata4.05: hard resetting link
Nov 22 07:08:51 selene kernel: [ 1011.712955] ata4.05: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Nov 22 07:08:51 selene kernel: [ 1012.348525] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 07:08:51 selene kernel: [ 1012.348534] ata3: link online but device misclassified, retrying
Nov 22 07:08:51 selene kernel: [ 1012.348537] ata3: reset failed (errno=-11), retrying in 9 secs
Nov 22 07:08:54 selene kernel: [ 1014.829116] ata4.01: failed to IDENTIFY (INIT_DEV_PARAMS failed, err_mask=0x80)
Nov 22 07:08:54 selene kernel: [ 1014.830187] ata4.01: hard resetting link
Nov 22 07:08:57 selene kernel: [ 1017.828519] ata4.15: qc timeout (cmd 0xe4)
Nov 22 07:08:57 selene kernel: [ 1017.828534] ata4.01: failed to read SCR 2 (Emask=0x4)
Nov 22 07:08:57 selene kernel: [ 1017.828538] ata4.01: failed to read SCR 2 (Emask=0x40)
Nov 22 07:08:57 selene kernel: [ 1017.828541] ata4.01: COMRESET failed (errno=-5)
Nov 22 07:08:57 selene kernel: [ 1017.828545] ata4.01: failed to read SCR 0 (Emask=0x40)
Nov 22 07:08:57 selene kernel: [ 1017.828548] ata4.01: reset failed, giving up
Nov 22 07:08:57 selene kernel: [ 1017.828553] ata4.15: hard resetting link
Nov 22 07:08:58 selene kernel: [ 1019.320523] ata4.15: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Nov 22 07:08:58 selene kernel: [ 1019.320894] ata4.00: hard resetting link
Nov 22 07:08:59 selene kernel: [ 1019.641025] ata4.00: SATA link down (SStatus 0 SControl 310)
Nov 22 07:08:59 selene kernel: [ 1019.828514] ata4.01: hard resetting link
Nov 22 07:08:59 selene kernel: [ 1020.148964] ata4.01: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 07:08:59 selene kernel: [ 1020.148972] ata4.02: hard resetting link
Nov 22 07:08:59 selene kernel: [ 1020.444529] ata3: hard resetting link
Nov 22 07:08:59 selene kernel: [ 1020.468964] ata4.02: SATA link down (SStatus 0 SControl 310)
Nov 22 07:08:59 selene kernel: [ 1020.469013] ata4.03: hard resetting link
Nov 22 07:09:00 selene kernel: [ 1020.788998] ata4.03: SATA link down (SStatus 0 SControl 310)
Nov 22 07:09:00 selene kernel: [ 1020.789050] ata4.04: hard resetting link
Nov 22 07:09:00 selene kernel: [ 1021.108595] ata4.04: SATA link down (SStatus 0 SControl 310)
Nov 22 07:09:00 selene kernel: [ 1021.108646] ata4.05: hard resetting link
Nov 22 07:09:00 selene kernel: [ 1021.429010] ata4.05: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 07:09:00 selene kernel: [ 1021.430499] ata4.01: ATA-8: ST31000340AS, SD15, max UDMA/133
Nov 22 07:09:00 selene kernel: [ 1021.430504] ata4.01: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32)
Nov 22 07:09:00 selene kernel: [ 1021.432445] ata4.01: configured for UDMA/133
Nov 22 07:09:00 selene kernel: [ 1021.432988] ata4: EH complete
Nov 22 07:09:00 selene kernel: [ 1021.434110] sd 5:1:0:0: [sdj] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 07:09:00 selene kernel: [ 1021.434137] sd 5:1:0:0: [sdj] Write Protect is off
Nov 22 07:09:00 selene kernel: [ 1021.434139] sd 5:1:0:0: [sdj] Mode Sense: 00 3a 00 00
Nov 22 07:09:00 selene kernel: [ 1021.434172] sd 5:1:0:0: [sdj] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 07:09:00 selene kernel: [ 1021.434260] sd 5:1:0:0: [sdj] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 07:09:00 selene kernel: [ 1021.434279] sd 5:1:0:0: [sdj] Write Protect is off
Nov 22 07:09:00 selene kernel: [ 1021.434281] sd 5:1:0:0: [sdj] Mode Sense: 00 3a 00 00
Nov 22 07:09:00 selene kernel: [ 1021.434313] sd 5:1:0:0: [sdj] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 07:09:00 selene kernel: [ 1021.434316] sdj: sdj1
Nov 22 07:09:00 selene kernel: [ 1021.461616] sd 5:1:0:0: [sdj] Attached SCSI disk
Nov 22 07:09:00 selene kernel: [ 1021.549438] sd 1:1:0:0: [sdn] Synchronizing SCSI cache
Nov 22 07:09:00 selene kernel: [ 1021.551432] sd 1:1:0:0: [sdn] Stopping disk
Nov 22 07:09:00 selene kernel: [ 1021.552344] sd 1:1:0:0: [sdm] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 07:09:00 selene kernel: [ 1021.552371] sd 1:1:0:0: [sdm] Write Protect is off
Nov 22 07:09:00 selene kernel: [ 1021.552374] sd 1:1:0:0: [sdm] Mode Sense: 00 3a 00 00
Nov 22 07:09:00 selene kernel: [ 1021.552413] sd 1:1:0:0: [sdm] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 07:09:00 selene kernel: [ 1021.552493] sd 1:1:0:0: [sdm] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 07:09:00 selene kernel: [ 1021.552536] sd 1:1:0:0: [sdm] Write Protect is off
Nov 22 07:09:00 selene kernel: [ 1021.552539] sd 1:1:0:0: [sdm] Mode Sense: 00 3a 00 00
Nov 22 07:09:00 selene kernel: [ 1021.552577] sd 1:1:0:0: [sdm] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 07:09:01 selene kernel: [ 1021.552581] sdm:<6>ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 07:09:01 selene kernel: [ 1022.340533] ata3: link online but device misclassified, retrying
Nov 22 07:09:01 selene kernel: [ 1022.340536] ata3: reset failed (errno=-11), retrying in 34 secs
Nov 22 07:09:02 selene kernel: [ 1023.384882] sdm1
Nov 22 07:09:02 selene kernel: [ 1023.385014] sd 1:1:0:0: [sdm] Attached SCSI disk
Nov 22 07:09:34 selene kernel: [ 1055.444515] ata3: limiting SATA link speed to 1.5 Gbps
Nov 22 07:09:34 selene kernel: [ 1055.444522] ata3: hard resetting link
Nov 22 07:09:36 selene kernel: [ 1057.340525] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 07:09:36 selene kernel: [ 1057.340533] ata3: link online but device misclassified, device detection might fail
Nov 22 07:09:36 selene kernel: [ 1057.340539] ata3: EH complete
Nov 22 07:10:26 selene kernel: [ 1107.565495] ata4.01: exception Emask 0x10 SAct 0x0 SErr 0x4010000 action 0xf
Nov 22 07:10:26 selene kernel: [ 1107.565505] ata4.01: SError: { PHYRdyChg DevExch }
Nov 22 07:10:26 selene kernel: [ 1107.565688] ata4.01: hard resetting link
Nov 22 07:10:26 selene kernel: [ 1107.573114] ata3: exception Emask 0x10 SAct 0x0 SErr 0x1990001 action 0xe frozen
Nov 22 07:10:26 selene kernel: [ 1107.573124] ata3: irq_stat 0x04400000, PHY RDY changed
Nov 22 07:10:26 selene kernel: [ 1107.573127] ata3: SError: { RecovData PHYRdyChg 10B8B Dispar LinkSeq TrStaTrns }
Nov 22 07:10:26 selene kernel: [ 1107.573134] ata3: hard resetting link
Nov 22 07:10:27 selene kernel: [ 1107.780520] ata4.01: failed to read SCR 0 (Emask=0x40)
Nov 22 07:10:27 selene kernel: [ 1107.780524] ata4.01: COMRESET failed (errno=-5)
Nov 22 07:10:27 selene kernel: [ 1107.780526] ata4.01: failed to read SCR 0 (Emask=0x40)
Nov 22 07:10:27 selene kernel: [ 1107.780529] ata4.01: reset failed, giving up
Nov 22 07:10:27 selene kernel: [ 1107.780533] ata4.15: hard resetting link
Nov 22 07:10:27 selene kernel: [ 1108.100528] ata4.15: SATA link down (SStatus 0 SControl 300)
Nov 22 07:10:27 selene kernel: [ 1108.300538] ata3: SATA link down (SStatus 0 SControl 300)
Nov 22 07:10:27 selene kernel: [ 1108.300551] ata3: EH complete
Nov 22 07:10:30 selene kernel: [ 1111.100523] ata4.15: qc timeout (cmd 0xe4)
Nov 22 07:10:30 selene kernel: [ 1111.100542] ata4.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:10:30 selene kernel: [ 1111.100545] ata4.15: PMP revalidation failed (errno=-5)
Nov 22 07:10:32 selene kernel: [ 1113.100548] ata4.15: hard resetting link
Nov 22 07:10:32 selene kernel: [ 1113.420531] ata4.15: SATA link down (SStatus 0 SControl 300)
Nov 22 07:10:35 selene kernel: [ 1116.420033] ata4.15: qc timeout (cmd 0xe4)
Nov 22 07:10:35 selene kernel: [ 1116.420051] ata4.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:10:35 selene kernel: [ 1116.420054] ata4.15: PMP revalidation failed (errno=-5)
Nov 22 07:10:35 selene kernel: [ 1116.420058] ata4.15: limiting SATA link speed to 1.5 Gbps
Nov 22 07:10:37 selene kernel: [ 1118.420516] ata4.15: hard resetting link
Nov 22 07:10:38 selene kernel: [ 1118.740030] ata4.15: SATA link down (SStatus 0 SControl 310)
Nov 22 07:10:41 selene kernel: [ 1121.740515] ata4.15: qc timeout (cmd 0xe4)
Nov 22 07:10:41 selene kernel: [ 1121.740532] ata4.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:10:41 selene kernel: [ 1121.740535] ata4.15: PMP revalidation failed (errno=-5)
Nov 22 07:10:43 selene kernel: [ 1123.740516] ata4.15: hard resetting link
Nov 22 07:10:43 selene kernel: [ 1124.060526] ata4.15: SATA link down (SStatus 0 SControl 310)
Nov 22 07:10:46 selene kernel: [ 1127.060515] ata4.15: qc timeout (cmd 0xe4)
Nov 22 07:10:46 selene kernel: [ 1127.060531] ata4.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:10:46 selene kernel: [ 1127.060534] ata4.15: PMP revalidation failed (errno=-5)
Nov 22 07:10:48 selene kernel: [ 1129.060514] ata4.15: hard resetting link
Nov 22 07:10:48 selene kernel: [ 1129.380566] ata4.15: SATA link down (SStatus 0 SControl 310)
Nov 22 07:10:51 selene kernel: [ 1132.380556] ata4.15: qc timeout (cmd 0xe4)
Nov 22 07:10:51 selene kernel: [ 1132.380571] ata4.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:10:51 selene kernel: [ 1132.380574] ata4.15: PMP revalidation failed (errno=-5)
Nov 22 07:10:51 selene kernel: [ 1132.380576] ata4.15: failed to recover PMP after 5 tries, giving up
Nov 22 07:10:51 selene kernel: [ 1132.380578] ata4.15: Port Multiplier detaching
Nov 22 07:10:51 selene kernel: [ 1132.380582] ata4.01: disabled
Nov 22 07:10:51 selene kernel: [ 1132.380586] ata4.00: disabled
Nov 22 07:10:51 selene kernel: [ 1132.380594] ata4: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0xe frozen t4
Nov 22 07:10:51 selene kernel: [ 1132.380596] ata4: irq_stat 0x00400000, PHY RDY changed
Nov 22 07:10:51 selene kernel: [ 1132.380601] ata4: hard resetting link
Nov 22 07:10:52 selene kernel: [ 1133.104527] ata4: SATA link down (SStatus 0 SControl 300)
Nov 22 07:10:52 selene kernel: [ 1133.104543] ata4: EH complete
Nov 22 07:10:52 selene kernel: [ 1133.104557] ata4.01: detaching (SCSI 5:1:0:0)
Nov 22 07:10:52 selene kernel: [ 1133.104910] sd 5:1:0:0: [sdj] Synchronizing SCSI cache
Nov 22 07:10:52 selene kernel: [ 1133.108553] sd 5:1:0:0: [sdj] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Nov 22 07:10:52 selene kernel: [ 1133.108559] sd 5:1:0:0: [sdj] Stopping disk
Nov 22 07:10:52 selene kernel: [ 1133.110747] sd 5:1:0:0: [sdj] START_STOP FAILED
Nov 22 07:10:52 selene kernel: [ 1133.110753] sd 5:1:0:0: [sdj] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Nov 22 07:11:02 selene kernel: [ 1143.260916] sd 1:1:0:0: [sdm] Synchronizing SCSI cache
Nov 22 07:11:02 selene kernel: [ 1143.261213] sd 1:1:0:0: [sdm] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Nov 22 07:11:02 selene kernel: [ 1143.261220] sd 1:1:0:0: [sdm] Stopping disk
Nov 22 07:11:02 selene kernel: [ 1143.261330] sd 1:1:0:0: [sdm] START_STOP FAILED
Nov 22 07:11:02 selene kernel: [ 1143.261335] sd 1:1:0:0: [sdm] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Nov 22 07:11:16 selene kernel: [ 1157.059760] ata4: exception Emask 0x10 SAct 0x0 SErr 0x4050000 action 0xe frozen
Nov 22 07:11:16 selene kernel: [ 1157.059766] ata4: irq_stat 0x00400040, connection status changed
Nov 22 07:11:16 selene kernel: [ 1157.059770] ata4: SError: { PHYRdyChg CommWake DevExch }
Nov 22 07:11:16 selene kernel: [ 1157.059776] ata4: hard resetting link
Nov 22 07:11:16 selene kernel: [ 1157.063394] ata3: exception Emask 0x10 SAct 0x0 SErr 0x4050000 action 0xe frozen
Nov 22 07:11:16 selene kernel: [ 1157.063398] ata3: irq_stat 0x00400040, connection status changed
Nov 22 07:11:16 selene kernel: [ 1157.063401] ata3: SError: { PHYRdyChg CommWake DevExch }
Nov 22 07:11:16 selene kernel: [ 1157.063405] ata3: hard resetting link
Nov 22 07:11:18 selene kernel: [ 1158.956528] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 07:11:18 selene kernel: [ 1158.956538] ata3: link online but device misclassified, retrying
Nov 22 07:11:18 selene kernel: [ 1158.956540] ata3: reset failed (errno=-11), retrying in 9 secs
Nov 22 07:11:18 selene kernel: [ 1158.956758] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Nov 22 07:11:18 selene kernel: [ 1158.957209] ata4.15: Port Multiplier 1.1, 0x1095:0x3726 r23, 6 ports, feat 0x1/0x9
Nov 22 07:11:18 selene kernel: [ 1158.957620] ata4.00: hard resetting link
Nov 22 07:11:18 selene kernel: [ 1159.276967] ata4.00: SATA link down (SStatus 0 SControl 310)
Nov 22 07:11:18 selene kernel: [ 1159.277120] ata4.01: hard resetting link
Nov 22 07:11:19 selene kernel: [ 1159.596515] ata4.01: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 07:11:19 selene kernel: [ 1159.596522] ata4.02: hard resetting link
Nov 22 07:11:19 selene kernel: [ 1159.917018] ata4.02: SATA link down (SStatus 0 SControl 310)
Nov 22 07:11:19 selene kernel: [ 1159.917123] ata4.03: hard resetting link
Nov 22 07:11:19 selene kernel: [ 1160.236569] ata4.03: SATA link down (SStatus 0 SControl 310)
Nov 22 07:11:19 selene kernel: [ 1160.236672] ata4.04: hard resetting link
Nov 22 07:11:19 selene kernel: [ 1160.557020] ata4.04: SATA link down (SStatus 0 SControl 310)
Nov 22 07:11:19 selene kernel: [ 1160.557187] ata4.05: hard resetting link
Nov 22 07:11:20 selene kernel: [ 1160.877006] ata4.05: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 07:11:25 selene kernel: [ 1165.876519] ata4.01: qc timeout (cmd 0xec)
Nov 22 07:11:25 selene kernel: [ 1165.876533] ata4.01: failed to IDENTIFY (I/O error, err_mask=0x4)
Nov 22 07:11:25 selene kernel: [ 1165.876538] ata4.15: hard resetting link
Nov 22 07:11:26 selene kernel: [ 1167.060514] ata3: hard resetting link
Nov 22 07:11:26 selene kernel: [ 1167.368530] ata4.15: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Nov 22 07:11:26 selene kernel: [ 1167.369080] ata4.00: hard resetting link
Nov 22 07:11:27 selene kernel: [ 1167.689003] ata4.00: SATA link down (SStatus 0 SControl 310)
Nov 22 07:11:27 selene kernel: [ 1167.689167] ata4.01: hard resetting link
Nov 22 07:11:27 selene kernel: [ 1168.009002] ata4.01: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 07:11:27 selene kernel: [ 1168.009012] ata4.05: hard resetting link
Nov 22 07:11:27 selene kernel: [ 1168.328971] ata4.05: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 07:11:28 selene kernel: [ 1168.956524] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 07:11:28 selene kernel: [ 1168.956533] ata3: link online but device misclassified, retrying
Nov 22 07:11:28 selene kernel: [ 1168.956536] ata3: reset failed (errno=-11), retrying in 9 secs
Nov 22 07:11:30 selene kernel: [ 1171.453122] ata4.01: ATA-8: ST31000340AS, SD15, max UDMA/133
Nov 22 07:11:30 selene kernel: [ 1171.453129] ata4.01: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32)
Nov 22 07:11:30 selene kernel: [ 1171.455030] ata4.01: configured for UDMA/133
Nov 22 07:11:30 selene kernel: [ 1171.455651] ata4: EH complete
Nov 22 07:11:30 selene kernel: [ 1171.456002] sd 5:1:0:0: [sdj] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 07:11:30 selene kernel: [ 1171.456085] sd 5:1:0:0: [sdj] Write Protect is off
Nov 22 07:11:30 selene kernel: [ 1171.456090] sd 5:1:0:0: [sdj] Mode Sense: 00 3a 00 00
Nov 22 07:11:30 selene kernel: [ 1171.456224] sd 5:1:0:0: [sdj] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 07:11:30 selene kernel: [ 1171.456401] sd 5:1:0:0: [sdj] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 07:11:30 selene kernel: [ 1171.456481] sd 5:1:0:0: [sdj] Write Protect is off
Nov 22 07:11:30 selene kernel: [ 1171.456487] sd 5:1:0:0: [sdj] Mode Sense: 00 3a 00 00
Nov 22 07:11:30 selene kernel: [ 1171.456628] sd 5:1:0:0: [sdj] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 07:11:30 selene kernel: [ 1171.456636] sdj: sdj1
Nov 22 07:11:30 selene kernel: [ 1171.525631] sd 5:1:0:0: [sdj] Attached SCSI disk
Nov 22 07:11:36 selene kernel: [ 1177.060514] ata3: hard resetting link
Nov 22 07:11:37 selene kernel: [ 1177.836866] sd 1:1:0:0: [sdm] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 07:11:37 selene kernel: [ 1177.836953] sd 1:1:0:0: [sdm] Write Protect is off
Nov 22 07:11:37 selene kernel: [ 1177.836958] sd 1:1:0:0: [sdm] Mode Sense: 00 3a 00 00
Nov 22 07:11:37 selene kernel: [ 1177.837128] sd 1:1:0:0: [sdm] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 07:11:37 selene kernel: [ 1177.837277] sd 1:1:0:0: [sdm] 1953525168 512-byte hardware sectors (1000205 MB)
Nov 22 07:11:37 selene kernel: [ 1177.837364] sd 1:1:0:0: [sdm] Write Protect is off
Nov 22 07:11:37 selene kernel: [ 1177.837369] sd 1:1:0:0: [sdm] Mode Sense: 00 3a 00 00
Nov 22 07:11:37 selene kernel: [ 1177.837501] sd 1:1:0:0: [sdm] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Nov 22 07:11:37 selene kernel: [ 1177.837510] sdm: sdm1
Nov 22 07:11:37 selene kernel: [ 1177.860144] sd 1:1:0:0: [sdm] Attached SCSI disk
Nov 22 07:11:38 selene kernel: [ 1178.956528] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Nov 22 07:11:38 selene kernel: [ 1178.956537] ata3: link online but device misclassified, retrying
Nov 22 07:11:38 selene kernel: [ 1178.956540] ata3: reset failed (errno=-11), retrying in 34 secs
Nov 22 07:12:11 selene kernel: [ 1212.060516] ata3: limiting SATA link speed to 1.5 Gbps
Nov 22 07:12:11 selene kernel: [ 1212.060524] ata3: hard resetting link
Nov 22 07:12:13 selene kernel: [ 1213.956524] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Nov 22 07:12:13 selene kernel: [ 1213.956534] ata3: link online but device misclassified, device detection might fail
Nov 22 07:12:13 selene kernel: [ 1213.956540] ata3: EH complete
Nov 22 07:14:58 selene kernel: [ 1379.388365] ata4.01: exception Emask 0x10 SAct 0x0 SErr 0x4010000 action 0xf
Nov 22 07:14:58 selene kernel: [ 1379.388371] ata4.01: SError: { PHYRdyChg DevExch }
Nov 22 07:14:58 selene kernel: [ 1379.388637] ata4.01: hard resetting link
Nov 22 07:14:58 selene kernel: [ 1379.399867] ata3: exception Emask 0x10 SAct 0x0 SErr 0x1990001 action 0xe frozen
Nov 22 07:14:58 selene kernel: [ 1379.399869] ata3: irq_stat 0x04400000, PHY RDY changed
Nov 22 07:14:58 selene kernel: [ 1379.399872] ata3: SError: { RecovData PHYRdyChg 10B8B Dispar LinkSeq TrStaTrns }
Nov 22 07:14:58 selene kernel: [ 1379.399878] ata3: hard resetting link
Nov 22 07:14:58 selene kernel: [ 1379.403579] ata4.01: failed to read SCR 2 (Emask=0x40)
Nov 22 07:14:58 selene kernel: [ 1379.403584] ata4.01: COMRESET failed (errno=-5)
Nov 22 07:14:58 selene kernel: [ 1379.403586] ata4.01: failed to read SCR 0 (Emask=0x40)
Nov 22 07:14:58 selene kernel: [ 1379.403589] ata4.01: reset failed, giving up
Nov 22 07:14:58 selene kernel: [ 1379.403593] ata4.15: hard resetting link
Nov 22 07:14:59 selene kernel: [ 1379.720527] ata4.15: SATA link down (SStatus 0 SControl 300)
Nov 22 07:14:59 selene kernel: [ 1380.120033] ata3: SATA link down (SStatus 0 SControl 300)
Nov 22 07:14:59 selene kernel: [ 1380.120045] ata3: EH complete
Nov 22 07:15:02 selene kernel: [ 1382.720358] ata4.15: qc timeout (cmd 0xe4)
Nov 22 07:15:02 selene kernel: [ 1382.720374] ata4.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:15:02 selene kernel: [ 1382.720377] ata4.15: PMP revalidation failed (errno=-5)
Nov 22 07:15:04 selene kernel: [ 1384.720515] ata4.15: hard resetting link
Nov 22 07:15:04 selene kernel: [ 1385.040528] ata4.15: SATA link down (SStatus 0 SControl 300)
Nov 22 07:15:07 selene kernel: [ 1388.040049] ata4.15: qc timeout (cmd 0xe4)
Nov 22 07:15:07 selene kernel: [ 1388.040066] ata4.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:15:07 selene kernel: [ 1388.040069] ata4.15: PMP revalidation failed (errno=-5)
Nov 22 07:15:07 selene kernel: [ 1388.040073] ata4.15: limiting SATA link speed to 1.5 Gbps
Nov 22 07:15:09 selene kernel: [ 1390.040517] ata4.15: hard resetting link
Nov 22 07:15:09 selene kernel: [ 1390.360527] ata4.15: SATA link down (SStatus 0 SControl 310)
Nov 22 07:15:12 selene kernel: [ 1393.360517] ata4.15: qc timeout (cmd 0xe4)
Nov 22 07:15:12 selene kernel: [ 1393.360534] ata4.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:15:12 selene kernel: [ 1393.360537] ata4.15: PMP revalidation failed (errno=-5)
Nov 22 07:15:14 selene kernel: [ 1395.360516] ata4.15: hard resetting link
Nov 22 07:15:15 selene kernel: [ 1395.684572] ata4.15: SATA link down (SStatus 0 SControl 310)
Nov 22 07:15:18 selene kernel: [ 1398.684521] ata4.15: qc timeout (cmd 0xe4)
Nov 22 07:15:18 selene kernel: [ 1398.684537] ata4.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:15:18 selene kernel: [ 1398.684540] ata4.15: PMP revalidation failed (errno=-5)
Nov 22 07:15:20 selene kernel: [ 1400.684520] ata4.15: hard resetting link
Nov 22 07:15:20 selene kernel: [ 1401.004527] ata4.15: SATA link down (SStatus 0 SControl 310)
Nov 22 07:15:23 selene kernel: [ 1404.004518] ata4.15: qc timeout (cmd 0xe4)
Nov 22 07:15:23 selene kernel: [ 1404.004535] ata4.15: failed to read PMP GSCR[0] (Emask=0x4)
Nov 22 07:15:23 selene kernel: [ 1404.004537] ata4.15: PMP revalidation failed (errno=-5)
Nov 22 07:15:23 selene kernel: [ 1404.004540] ata4.15: failed to recover PMP after 5 tries, giving up
Nov 22 07:15:23 selene kernel: [ 1404.004542] ata4.15: Port Multiplier detaching
Nov 22 07:15:23 selene kernel: [ 1404.004546] ata4.01: disabled
Nov 22 07:15:23 selene kernel: [ 1404.004550] ata4.00: disabled
Nov 22 07:15:23 selene kernel: [ 1404.004560] ata4: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0xe frozen t4
Nov 22 07:15:23 selene kernel: [ 1404.004563] ata4: irq_stat 0x00400000, PHY RDY changed
Nov 22 07:15:23 selene kernel: [ 1404.004568] ata4: hard resetting link
Nov 22 07:15:24 selene kernel: [ 1404.728532] ata4: SATA link down (SStatus 0 SControl 300)
Nov 22 07:15:24 selene kernel: [ 1404.728549] ata4: EH complete
Nov 22 07:15:24 selene kernel: [ 1404.728568] ata4.01: detaching (SCSI 5:1:0:0)
Nov 22 07:15:24 selene kernel: [ 1404.731605] sd 5:1:0:0: [sdj] Synchronizing SCSI cache
Nov 22 07:15:24 selene kernel: [ 1404.733338] sd 5:1:0:0: [sdj] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Nov 22 07:15:24 selene kernel: [ 1404.733350] sd 5:1:0:0: [sdj] Stopping disk
Nov 22 07:15:24 selene kernel: [ 1404.733519] sd 5:1:0:0: [sdj] START_STOP FAILED
Nov 22 07:15:24 selene kernel: [ 1404.733526] sd 5:1:0:0: [sdj] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Nov 22 07:15:34 selene kernel: [ 1415.086522] sd 1:1:0:0: [sdm] Synchronizing SCSI cache
Nov 22 07:15:34 selene kernel: [ 1415.086586] sd 1:1:0:0: [sdm] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Nov 22 07:15:34 selene kernel: [ 1415.086590] sd 1:1:0:0: [sdm] Stopping disk
Nov 22 07:15:34 selene kernel: [ 1415.086603] sd 1:1:0:0: [sdm] START_STOP FAILED
Nov 22 07:15:34 selene kernel: [ 1415.086605] sd 1:1:0:0: [sdm] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
^ permalink raw reply [flat|nested] 11+ messages in thread
* Re: Port multiplier resets
2008-11-22 16:48 ` Yan Seiner
@ 2008-11-23 0:53 ` Tejun Heo
2008-11-25 4:44 ` Yan Seiner
0 siblings, 1 reply; 11+ messages in thread
From: Tejun Heo @ 2008-11-23 0:53 UTC (permalink / raw)
To: Yan Seiner; +Cc: linux-ide
Yan Seiner wrote:
> Tejun Heo wrote:
>> Yan Seiner wrote:
>>
>>> Tejun Heo wrote:
>>>
>>>> 2. Specify libata.force=8.15:1.5Gbps
>>>>
>>> dumb question:
>>>
>>> Does this go on the kernel parameter line or can I change it without
>>> rebooting the system?
>>>
>>> I don't see it in /sys/module/libata/parameters so I'm guessing it needs
>>> a reboot?
>>>
>>
>> Yeah, you will need to reboot or reload libata module. Note that if
>> libata.ko is being loaded from initrd, different distros use different
>> methods to pass parameters to them.
>>
>>
> Thanks for all your help. Unfortunately I'm still dead in the water
> with the jmicron controller.... :-(
>
> If anything, things have gotten worse. I suspect that the hardware is
> broken, but I'm hoping there's still something I can do.
>
> The jmicron controller is ata3 and ata4. Here's my kernel line from grub:
>
> title Debian GNU/Linux, kernel 2.6.27.4
> root (hd0,1)
> kernel /vmlinuz-2.6.27.4 root=/dev/md0 ro quiet
> libata.force=3.15:1.5Gbps,4.15:1.5Gbps
> initrd /initrd.img-2.6.27.4
>
> I'm trying to limit the link from the controller to the PMP to 1.5
> Gbps. That doesn't seem to be happening. Worse, now the drives
> connected to that controller aren't being recognized.
>
> I get "link online but device misclassified, retrying" errors. It's not
> the drive or the caddy; I've swapped drives, slots, and cables and the
> errors remain with the jmicron ports.
>
> I'm attaching a log which takes you from boot, to powerup of the
> diskpack, and then a power cycle of the diskpack. Unfortunately I
> cannot boot with the external drives powered up; the jmicron controller
> hangs (it has a conflict with the scsi controller if any drives are
> attached at boot.)
>
> I'd appreciate any help at this point. My only alternative is to
> disable the onboard jmicron and get a PCI-based esata controller.
The parameter isn't getting through. Please make that work first. Ask
your distro how to do that and can you try a shorter cable?
--
tejun
^ permalink raw reply [flat|nested] 11+ messages in thread
* Re: Port multiplier resets
2008-11-23 0:53 ` Tejun Heo
@ 2008-11-25 4:44 ` Yan Seiner
0 siblings, 0 replies; 11+ messages in thread
From: Yan Seiner @ 2008-11-25 4:44 UTC (permalink / raw)
To: Tejun Heo; +Cc: linux-ide
Tejun Heo wrote:
>
>
> The parameter isn't getting through. Please make that work first. Ask
> your distro how to do that and can you try a shorter cable?
>
>
OK, that fixed it. Turned out that with debian, you have to rebuild
initrd to get the module parameters to work.
I've got everything limited to 1.5 Gbps, and the system is stable. :-)
Thanks!
--Yan
^ permalink raw reply [flat|nested] 11+ messages in thread
end of thread, other threads:[~2008-11-25 4:44 UTC | newest]
Thread overview: 11+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2008-11-02 15:10 Port multiplier resets Yan Seiner
2008-11-11 9:06 ` Tejun Heo
2008-11-19 15:15 ` Yan Seiner
2008-11-20 2:27 ` Tejun Heo
2008-11-20 3:05 ` Yan Seiner
2008-11-20 3:23 ` Tejun Heo
2008-11-20 3:19 ` Yan Seiner
2008-11-20 3:24 ` Tejun Heo
2008-11-22 16:48 ` Yan Seiner
2008-11-23 0:53 ` Tejun Heo
2008-11-25 4:44 ` Yan Seiner
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).