sata_sil24 on libata driver issue

sata_sil24 on libata driver issue

[Dave Pitts]

[-- Attachment #1: Type: text/plain, Size: 770 bytes --]

Hello:

I didn't know who to contact for the sata_sil24 driver as there is no e-mail 
address for the developer...

Anyway, I'm working with a client that is running CentOS 5.5 with the sata_sil24 
and libata drivers. The application does async I/O using the sg layer 
(/dev/sgxx) and gets the errors shown in the attachment. The messages from 
"Engine" are the errors that the application is seeing. It appears that the I/O 
is timing out with the aborted command status. The question is why?? What's 
going on??

Any ideas on this would be great. Also, maybe fixed in newer kernels?

Thanks in advance...

-- 
Dave Pitts             PULLMAN: Travel and sleep in safety and comfort.
dpitts@cozx.com        My other RV IS a Pullman (Colorado Pine).
http://www.cozx.com


[-- Attachment #2: messages.sil24 --]
[-- Type: text/plain, Size: 6084 bytes --]

Sep 16 10:16:08 646cf843 kernel: ata3.00: failed to read SCR 1 (Emask=0x40)
Sep 16 10:16:08 646cf843 kernel: ata3.01: failed to read SCR 1 (Emask=0x40)
Sep 16 10:16:08 646cf843 kernel: ata3.02: failed to read SCR 1 (Emask=0x40)
Sep 16 10:16:08 646cf843 kernel: ata3.03: failed to read SCR 1 (Emask=0x40)
Sep 16 10:16:08 646cf843 kernel: ata3.04: failed to read SCR 1 (Emask=0x40)
Sep 16 10:16:08 646cf843 kernel: ata3.05: failed to read SCR 1 (Emask=0x40)
Sep 16 10:16:08 646cf843 kernel: ata3.15: exception Emask 0x4 SAct 0x0 SErr 0x0 action 0x6 frozen
Sep 16 10:16:08 646cf843 kernel: ata3.00: exception Emask 0x100 SAct 0x0 SErr 0x0 action 0x6 frozen
Sep 16 10:16:08 646cf843 kernel: ata3.01: exception Emask 0x100 SAct 0x0 SErr 0x0 action 0x6 frozen
Sep 16 10:16:08 646cf843 kernel: ata3.02: exception Emask 0x100 SAct 0x0 SErr 0x0 action 0x6 frozen
Sep 16 10:16:08 646cf843 kernel: ata3.03: exception Emask 0x100 SAct 0x8 SErr 0x0 action 0x6 frozen
Sep 16 10:16:08 646cf843 kernel: ata3.03: cmd 60/40:18:c0:1e:35/00:00:00:00:00/40 tag 3 ncq 32768 in
Sep 16 10:16:08 646cf843 kernel:          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
Sep 16 10:16:08 646cf843 kernel: ata3.03: status: { DRDY }
Sep 16 10:16:08 646cf843 kernel: ata3.04: exception Emask 0x100 SAct 0x0 SErr 0x0 action 0x6 frozen
Sep 16 10:16:08 646cf843 kernel: ata3.05: exception Emask 0x100 SAct 0x0 SErr 0x0 action 0x6 frozen
Sep 16 10:16:08 646cf843 kernel: ata3.15: hard resetting link
Sep 16 10:16:08 646cf843 kernel: ata3: controller in dubious state, performing PORT_RST
Sep 16 10:16:10 646cf843 kernel: ata3.15: SATA link up 3.0 Gbps (SStatus 123 SControl 0)
Sep 16 10:16:10 646cf843 kernel: ata3.00: hard resetting link
Sep 16 10:16:10 646cf843 kernel: ata3.00: SATA link up 3.0 Gbps (SStatus 123 SControl 320)
Sep 16 10:16:10 646cf843 kernel: ata3.01: hard resetting link
Sep 16 10:16:11 646cf843 kernel: ata3.01: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Sep 16 10:16:11 646cf843 kernel: ata3.02: hard resetting link
Sep 16 10:16:11 646cf843 kernel: ata3.02: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Sep 16 10:16:11 646cf843 kernel: ata3.03: hard resetting link
Sep 16 10:16:11 646cf843 kernel: ata3.03: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Sep 16 10:16:11 646cf843 kernel: ata3.04: hard resetting link
Sep 16 10:16:11 646cf843 kernel: ata3.04: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Sep 16 10:16:11 646cf843 kernel: ata3.05: hard resetting link
Sep 16 10:16:12 646cf843 kernel: ata3.05: SATA link up 1.5 Gbps (SStatus 113 SControl 320)
Sep 16 10:16:12 646cf843 kernel: ata3.00: configured for UDMA/100
Sep 16 10:16:12 646cf843 kernel: ata3.01: configured for UDMA/100
Sep 16 10:16:12 646cf843 kernel: ata3.02: configured for UDMA/100
Sep 16 10:16:12 646cf843 kernel: ata3.03: configured for UDMA/100
Sep 16 10:16:12 646cf843 kernel: ata3.04: configured for UDMA/100
Sep 16 10:16:12 646cf843 Engine[5067]: [41C25940]<04> SgInt  [sg_err_category             (sg_int.c  :1284)]: masked_status=0x01, host_status=0x00, driver_status=0x0E
Sep 16 10:16:12 646cf843 Engine[5067]: [41C25940]<04> SgInt  [sg_err_category             (sg_int.c  :1309)]: SENSE INFO: response code=72 SK=B ASC=0 ASCQ=0 
Sep 16 10:16:12 646cf843 Engine[5067]: [40F97940]<04> BE     [BE_IO_Complete         (be_perform_io.c: 493)]:  IO ERROR: op=READ, drive=00.00.01.05 (0x500108600008D00E) lba=0x351EC0(0x40), code=-16, retryLevel=0, timeout=1000 ms, resp_time=5121(sg says 5121) ms 
Sep 16 10:16:12 646cf843 kernel: sd 6:3:0:0: timing out command, waited 1s
Sep 16 10:16:12 646cf843 kernel: ata3: EH complete
Sep 16 10:16:12 646cf843 kernel: SCSI device sdl: 3907029168 512-byte hdwr sectors (2000399 MB)
Sep 16 10:16:12 646cf843 kernel: sdl: Write Protect is off
Sep 16 10:16:12 646cf843 kernel: SCSI device sdl: drive cache: write back
Sep 16 10:16:12 646cf843 kernel: SCSI device sdm: 3907029168 512-byte hdwr sectors (2000399 MB)
Sep 16 10:16:12 646cf843 kernel: sdm: Write Protect is off
Sep 16 10:16:12 646cf843 kernel: SCSI device sdm: drive cache: write back
Sep 16 10:16:12 646cf843 kernel: SCSI device sdn: 3907029168 512-byte hdwr sectors (2000399 MB)
Sep 16 10:16:12 646cf843 kernel: sdn: Write Protect is off
Sep 16 10:16:12 646cf843 kernel: SCSI device sdn: drive cache: write back
Sep 16 10:16:12 646cf843 kernel: SCSI device sdo: 3907029168 512-byte hdwr sectors (2000399 MB)
Sep 16 10:16:12 646cf843 kernel: sdo: Write Protect is off
Sep 16 10:16:12 646cf843 kernel: SCSI device sdo: drive cache: write back
Sep 16 10:16:12 646cf843 kernel: SCSI device sdp: 3907029168 512-byte hdwr sectors (2000399 MB)
Sep 16 10:16:12 646cf843 kernel: sdp: Write Protect is off
Sep 16 10:16:12 646cf843 kernel: SCSI device sdp: drive cache: write back
Sep 16 10:16:12 646cf843 kernel: SCSI device sdl: 3907029168 512-byte hdwr sectors (2000399 MB)
Sep 16 10:16:12 646cf843 kernel: sdl: Write Protect is off
Sep 16 10:16:12 646cf843 kernel: SCSI device sdl: drive cache: write back
Sep 16 10:16:12 646cf843 kernel: SCSI device sdm: 3907029168 512-byte hdwr sectors (2000399 MB)
Sep 16 10:16:12 646cf843 kernel: sdm: Write Protect is off
Sep 16 10:16:12 646cf843 kernel: SCSI device sdm: drive cache: write back
Sep 16 10:16:12 646cf843 kernel: SCSI device sdn: 3907029168 512-byte hdwr sectors (2000399 MB)
Sep 16 10:16:12 646cf843 kernel: sdn: Write Protect is off
Sep 16 10:16:12 646cf843 kernel: SCSI device sdn: drive cache: write back
Sep 16 10:16:12 646cf843 kernel: SCSI device sdo: 3907029168 512-byte hdwr sectors (2000399 MB)
Sep 16 10:16:12 646cf843 kernel: sdo: Write Protect is off
Sep 16 10:16:12 646cf843 kernel: SCSI device sdo: drive cache: write back
Sep 16 10:16:12 646cf843 kernel: SCSI device sdp: 3907029168 512-byte hdwr sectors (2000399 MB)
Sep 16 10:16:12 646cf843 kernel: sdp: Write Protect is off
Sep 16 10:16:12 646cf843 kernel: SCSI device sdp: drive cache: write back
Sep 16 10:16:13 646cf843 Engine[5067]: [42C6C940]<05> Scrub  [Scrub_HandleMsg_Response              (scrub_task.c   : 439)]: FAILED read: beCmd=0x83e728, lba=3481280, error=-16