Re: ata2: translated ATA stat/err 0x51/0c to SCSI SK/ASC/ASCQ 0xb/00/00 status=0x51 { DriveReady SeekComplete Error }

[David Greaves <david@dgreaves.com>]

Soeren Sonnenburg wrote:

>Hi all,
>
>does anyone know what this could sata error message could mean ?
>
>ata2: translated ATA stat/err 0x51/0c to SCSI SK/ASC/ASCQ 0xb/00/00
>ata2: status=0x51 { DriveReady SeekComplete Error }
>ata2: error=0x0c { DriveStatusError }
>
>I get *lots* of this when I copy files from the sata disk to some
>ieee1394 device and it seems they are sharing interrupt 16:
>
> 16:     430796   IO-APIC-level  ide2, ide3, libata, ohci1394
>
>Happens with kernel 2.6.15, asus a7v8x, sata_promise tx2/4 ...
>  
>
FYI

I'm getting similar issues.

I'm assuming that since the problems span 4 disks on two controllers
(more counting Soeren's problems) that it's unlikely to be a hardware
fault. Of course I could be wrong!1

kernel 2.6.15, mb: ASUS A7V600-X (KT600 chipset I think)
4 sata disks (Maxtor + Seagate). SMART is showing them OK

sata_sil for a cheap PCI-plugin sata controller:
0000:00:0a.0 Unknown mass storage controller: Silicon Image, Inc.
(formerly CMD Technology Inc) SiI 3112 [SATALink/SATARaid] SerialATA
Controller (rev 02)

sata_via for the onboard VIA sata:
0000:00:0f.0 RAID bus controller: VIA Technologies, Inc. VIA VT6420 SATA
RAID Controller (rev 80)

during boot I get:
...
ACPI: RSDP (v000 ASUS                                  ) @ 0x000f5e30
ACPI: RSDT (v001 ASUS   A7V600-X 0x42302e31 MSFT 0x31313031) @ 0x3fffb000
ACPI: FADT (v001 ASUS   A7V600-X 0x42302e31 MSFT 0x31313031) @ 0x3fffb0b2
ACPI: BOOT (v001 ASUS   A7V600-X 0x42302e31 MSFT 0x31313031) @ 0x3fffb030
ACPI: MADT (v001 ASUS   A7V600-X 0x42302e31 MSFT 0x31313031) @ 0x3fffb058
ACPI: DSDT (v001   ASUS A7V600-X 0x00001000 MSFT 0x0100000b) @ 0x00000000
ACPI: Local APIC address 0xfee00000
ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
Processor #0 6:10 APIC version 16
ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
ACPI: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0])
IOAPIC[0]: apic_id 2, version 3, address 0xfec00000, GSI 0-23
ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl edge)
ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 low level)
ACPI: IRQ0 used by override.
ACPI: IRQ2 used by override.
ACPI: IRQ9 used by override.
Enabling APIC mode:  Flat.  Using 1 I/O APICs
Allocating PCI resources starting at 50000000 (gap: 40000000:bec00000)
Built 1 zonelists
Kernel command line: root=/dev/md0 ro
mapped APIC to ffffd000 (fee00000)
mapped IOAPIC to ffffc000 (fec00000)
...


Here's the ata/scsi bit:
Jan 10 21:48:31 localhost kernel: ata1: SATA max UDMA/100 cmd 0xF881F080
ctl 0xF881F08A bmdma 0xF881F000 irq 169
Jan 10 21:48:31 localhost kernel: ata2: SATA max UDMA/100 cmd 0xF881F0C0
ctl 0xF881F0CA bmdma 0xF881F008 irq 169
Jan 10 21:48:31 localhost kernel: ata1: dev 0 ATA, max UDMA/100,
390721968 sectors: lba48
Jan 10 21:48:31 localhost kernel: ata1: dev 0 configured for UDMA/100
Jan 10 21:48:31 localhost kernel: scsi0 : sata_sil
Jan 10 21:48:31 localhost kernel: ata2: dev 0 ATA, max UDMA/133,
398297088 sectors: lba48
Jan 10 21:48:31 localhost kernel: ata2: dev 0 configured for UDMA/100
Jan 10 21:48:31 localhost kernel: scsi1 : sata_sil
Jan 10 21:48:31 localhost kernel: Using anticipatory io scheduler
Jan 10 21:48:31 localhost kernel:   Vendor: ATA       Model: Maxtor
6B200M0    Rev: BANC
Jan 10 21:48:31 localhost kernel:   Type:  
Direct-Access                      ANSI SCSI revision: 05
Jan 10 21:48:31 localhost kernel:   Vendor: ATA       Model: Maxtor
6B200M0    Rev: BANC
Jan 10 21:48:31 localhost kernel:   Type:  
Direct-Access                      ANSI SCSI revision: 05
Jan 10 21:48:31 localhost kernel: ACPI: PCI interrupt 0000:00:0f.0[B] ->
GSI 20 (level, low) -> IRQ 185
Jan 10 21:48:31 localhost kernel: sata_via(0000:00:0f.0): routed to hard
irq line 0
Jan 10 21:48:31 localhost kernel: ata3: SATA max UDMA/133 cmd 0x9800 ctl
0x9402 bmdma 0x8400 irq 185
Jan 10 21:48:31 localhost kernel: ata4: SATA max UDMA/133 cmd 0x9000 ctl
0x8802 bmdma 0x8408 irq 185
Jan 10 21:48:31 localhost kernel: ata3: dev 0 ATA, max UDMA/133,
312581808 sectors: lba48
Jan 10 21:48:31 localhost kernel: ata3: dev 0 configured for UDMA/133
Jan 10 21:48:31 localhost kernel: scsi2 : sata_via
Jan 10 21:48:31 localhost kernel: ata4: dev 0 ATA, max UDMA/133,
398297088 sectors: lba48
Jan 10 21:48:31 localhost kernel: ata4: dev 0 configured for UDMA/133
Jan 10 21:48:31 localhost kernel: scsi3 : sata_via

Then, during udev initialisation I get a timeout and eventually:
ata2: command 0x25 timeout, stat 0x51 host_stat 0x0
ata2: no sense translation for status: 0x51
ata2: translated ATA stat/err 0x51/00 to SCSI SK/ASC/ASCQ 0x3/11/04
ata2: status=0x51 { DriveReady SeekComplete Error }
sd 1:0:0:0: SCSI error: return code = 0x8000002
sdb: Current: sense key: Medium Error
    Additional sense: Unrecovered read error - auto reallocate failed
end_request: I/O error, dev sdb, sector 393554465
raid1: Disk failure on sdb2, disabling device.
        Operation continuing on 1 devices
raid1: sdb2: rescheduling sector 2837600
RAID1 conf printout:
 --- wd:1 rd:2
 disk 0, wo:0, o:1, dev:sdd2
 disk 1, wo:1, o:0, dev:sdb2
RAID1 conf printout:
 --- wd:1 rd:2
 disk 0, wo:0, o:1, dev:sdd2
raid1: sdd2: redirecting sector 2837600 to another mirror

(BTW, badblocks shows no bad blocks and mdadm --remove/--add returns
just fine)

Then later:
Jan 19 15:23:05 haze kernel: ata1: PIO error
Jan 19 15:23:05 haze kernel: ata1: status=0x50 { DriveReady SeekComplete }
Jan 19 15:23:05 haze kernel: ata1: PIO error
Jan 19 15:23:05 haze kernel: ata1: status=0x50 { DriveReady SeekComplete }
Jan 19 15:23:05 haze kernel: ata1: PIO error
...
Jan 19 15:23:06 haze kernel: ata1: status=0x51 { DriveReady SeekComplete
Error }
Jan 19 15:23:06 haze kernel: ata1: error=0x04 { DriveStatusError }
Jan 19 15:23:06 haze kernel: ata1: PIO error
Jan 19 15:23:06 haze kernel: ata1: status=0x51 { DriveReady SeekComplete
Error }
Jan 19 15:23:06 haze kernel: ata1: error=0x04 { DriveStatusError }
Jan 19 15:23:06 haze kernel: ata1: PIO error
...
Jan 19 15:23:06 haze kernel: ata2: PIO error
Jan 19 15:23:06 haze kernel: ata2: status=0x50 { DriveReady SeekComplete }
Jan 19 15:23:06 haze kernel: ata2: PIO error
Jan 19 15:23:06 haze kernel: ata2: status=0x50 { DriveReady SeekComplete }
...
Jan 19 15:23:08 haze kernel: ata2: status=0x51 { DriveReady SeekComplete
Error }
Jan 19 15:23:08 haze kernel: ata2: error=0x04 { DriveStatusError }
Jan 19 15:23:08 haze kernel: ata2: PIO error
Jan 19 15:23:08 haze kernel: ata2: status=0x51 { DriveReady SeekComplete
Error }
Jan 19 15:23:08 haze kernel: ata2: error=0x04 { DriveStatusError }
Jan 19 15:23:08 haze kernel: ata2: PIO error
...
Jan 19 15:23:08 haze kernel: ata3: PIO error
Jan 19 15:23:08 haze kernel: ata3: status=0x50 { DriveReady SeekComplete }
Jan 19 15:23:08 haze kernel: ata3: PIO error
Jan 19 15:23:08 haze kernel: ata3: status=0x50 { DriveReady SeekComplete }
...
Jan 19 15:23:09 haze kernel: ata4: PIO error
Jan 19 15:23:09 haze kernel: ata4: status=0x50 { DriveReady SeekComplete }
Jan 19 15:23:09 haze kernel: ata4: PIO error
Jan 19 15:23:09 haze kernel: ata4: status=0x50 { DriveReady SeekComplete }
Jan 19 15:23:09 haze kernel: ata4: PIO error
...



smartctl -a -data /dev/sd[abcd] shows all is well.

and thereafter it all seems to work fine.

config: (grep APIC|SATA|IDE)
CONFIG_X86_GOOD_APIC=y
CONFIG_X86_UP_APIC=y
CONFIG_X86_UP_IOAPIC=y
CONFIG_X86_LOCAL_APIC=y
CONFIG_X86_IO_APIC=y

CONFIG_SCSI_SATA=y
CONFIG_SCSI_SATA_SIL=y
CONFIG_SCSI_SATA_VIA=y

CONFIG_IDE=y
CONFIG_BLK_DEV_IDE=y
# Please see Documentation/ide.txt for help/info on IDE drives
# CONFIG_BLK_DEV_IDE_SATA is not set
# CONFIG_BLK_DEV_HD_IDE is not set
CONFIG_BLK_DEV_IDEDISK=m
CONFIG_IDEDISK_MULTI_MODE=y
CONFIG_BLK_DEV_IDECD=y
# CONFIG_BLK_DEV_IDETAPE is not set
# CONFIG_BLK_DEV_IDEFLOPPY is not set
CONFIG_BLK_DEV_IDESCSI=y
# CONFIG_IDE_TASK_IOCTL is not set
# IDE chipset support/bugfixes
# CONFIG_IDE_GENERIC is not set
CONFIG_BLK_DEV_IDEPCI=y
CONFIG_IDEPCI_SHARE_IRQ=y
CONFIG_BLK_DEV_IDEDMA_PCI=y
# CONFIG_BLK_DEV_IDEDMA_FORCED is not set
CONFIG_IDEDMA_PCI_AUTO=y
# CONFIG_IDEDMA_ONLYDISK is not set
# CONFIG_IDE_ARM is not set
CONFIG_BLK_DEV_IDEDMA=y
# CONFIG_IDEDMA_IVB is not set
CONFIG_IDEDMA_AUTO=y


          CPU0
  0:    2480491    IO-APIC-edge  timer
  1:       7431    IO-APIC-edge  i8042
  9:          0   IO-APIC-level  acpi
 12:     165332    IO-APIC-edge  i8042
 14:     177275    IO-APIC-edge  ide0
 16:      56964   IO-APIC-level  libata
 17:    2842493   IO-APIC-level  libata
 18:          0   IO-APIC-level  uhci_hcd:usb1, uhci_hcd:usb2,
uhci_hcd:usb3, uhci_hcd:usb4, ehci_hcd:usb5
 19:     275949   IO-APIC-level  skge
 21:       4031   IO-APIC-level  VIA8237
NMI:          0
LOC:    2480522
ERR:          0
MIS:          0

Any other diagnostics I can provide? Any patches to try?

David

-- 

-
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@vger.kernel.org
More majordomo info at  http://vger.kernel.org/majordomo-info.html
Please read the FAQ at  http://www.tux.org/lkml/