Seagate SATA 3GB/s drive connected to via vt8251 using ahci module

linux-ide.vger.kernel.org archive mirror
 help / color / mirror / Atom feed

* Seagate SATA 3GB/s drive connected to via vt8251 using ahci module
@ 2006-06-08 17:30 Aalderd Bouwman
  2006-06-09  5:44 ` Tejun Heo
       [not found] ` <1150745738.4302.1.camel@anja>
  0 siblings, 2 replies; 14+ messages in thread
From: Aalderd Bouwman @ 2006-06-08 17:30 UTC (permalink / raw)
  To: linux-ide

Hello,

I am running Gentoo Linux on a Asus A8V-MX mainboard with the Via VT8251
southbridge chipset. The sata-controller is configured as AHCI.
Bios version is 0210.

The drive is working properly with the drivers of VIA.

A Maxtor drive works correct on this controller but this seagate drive
(ST3808110AS) fails on both modes (1.5Gb/s or 3.0Gb/s).

Here you can find the log-results after doing some actions on this
drive. After the sector errors the drive could not be accessed by fdisk.

# uname -a
Linux server 2.6.17-rc5-mm2 #2 Wed Jun 7 22:18:57 CEST 2006 i686 AMD
Opteron(tm) Processor 144 AuthenticAMD GNU/Linux

Kernel log:

Jun  8 18:38:54 server 800000 type: 2
Jun  8 18:38:54 server add_memory_region(000000001f7e0000, 0000000000020000, 2)
Jun  8 18:38:54 server copy_e820_map() start: 00000000e0000000 size: 0000000010000000 end: 00000000f0000000 type: 2
Jun  8 18:38:54 server add_memory_region(00000000e0000000, 0000000010000000, 2)
Jun  8 18:38:54 server copy_e820_map() start: 00000000fec00000 size: 0000000000001000 end: 00000000fec01000 type: 2
Jun  8 18:38:54 server add_memory_region(00000000fec00000, 0000000000001000, 2)
Jun  8 18:38:54 server copy_e820_map() start: 00000000ff7c0000 size: 0000000000840000 end: 0000000100000000 type: 2
Jun  8 18:38:54 server add_memory_region(00000000ff7c0000, 0000000000840000, 2)
Jun  8 18:38:54 server BIOS-e820: 0000000000000000 - 000000000009fc00 (usable)
Jun  8 18:38:54 server BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved)
Jun  8 18:38:54 server BIOS-e820: 00000000000e0000 - 0000000000100000 (reserved)
Jun  8 18:38:54 server BIOS-e820: 0000000000100000 - 000000001f7b0000 (usable)
Jun  8 18:38:54 server BIOS-e820: 000000001f7b0000 - 000000001f7be000 (ACPI data)
Jun  8 18:38:54 server BIOS-e820: 000000001f7be000 - 000000001f7e0000 (ACPI NVS)
Jun  8 18:38:54 server BIOS-e820: 000000001f7e0000 - 000000001f800000 (reserved)
Jun  8 18:38:54 server BIOS-e820: 00000000e0000000 - 00000000f0000000 (reserved)
Jun  8 18:38:54 server BIOS-e820: 00000000fec00000 - 00000000fec01000 (reserved)
Jun  8 18:38:54 server BIOS-e820: 00000000ff7c0000 - 0000000100000000 (reserved)
Jun  8 18:38:54 server 503MB LOWMEM available.
Jun  8 18:38:54 server On node 0 totalpages: 128944
Jun  8 18:38:54 server DMA zone: 4096 pages, LIFO batch:0
Jun  8 18:38:54 server Normal zone: 124848 pages, LIFO batch:31
Jun  8 18:38:54 server DMI 2.3 present.
Jun  8 18:38:54 server ACPI: RSDP (v002 ACPIAM                                ) @ 0x000fac60
Jun  8 18:38:54 server ACPI: XSDT (v001 A M I  OEMXSDT  0x09000505 MSFT 0x00000097) @ 0x1f7b0100
Jun  8 18:38:54 server ACPI: FADT (v003 A M I  OEMFACP  0x09000505 MSFT 0x00000097) @ 0x1f7b0290
Jun  8 18:38:54 server ACPI: MADT (v001 A M I  OEMAPIC  0x09000505 MSFT 0x00000097) @ 0x1f7b0390
Jun  8 18:38:54 server ACPI: OEMB (v001 A M I  AMI_OEM  0x09000505 MSFT 0x00000097) @ 0x1f7be040
Jun  8 18:38:54 server ACPI: DSDT (v001  A0347 A0347001 0x00000001 INTL 0x02002026) @ 0x00000000
Jun  8 18:38:54 server ACPI: PM-Timer IO Port: 0x808
Jun  8 18:38:54 server Allocating PCI resources starting at 20000000 (gap: 1f800000:c0800000)
Jun  8 18:38:54 server Detected 1800.296 MHz processor.
Jun  8 18:38:54 server Built 1 zonelists
Jun  8 18:38:54 server Kernel command line: root=/dev/hda3
Jun  8 18:38:54 server Enabling fast FPU save and restore... done.
Jun  8 18:38:54 server Enabling unmasked SIMD FPU exception support... done.
Jun  8 18:38:54 server Initializing CPU#0
Jun  8 18:38:54 server CPU 0 irqstacks, hard=c03d9000 soft=c03d8000
Jun  8 18:38:54 server PID hash table entries: 2048 (order: 11, 8192 bytes)
Jun  8 18:38:54 server Console: colour VGA+ 80x25
Jun  8 18:38:54 server Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
Jun  8 18:38:54 server Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
Jun  8 18:38:54 server Memory: 507748k/515776k available (1920k kernel code, 7540k reserved, 800k data, 164k init, 0k highmem)
Jun  8 18:38:54 server Checking if this processor honours the WP bit even in supervisor mode... Ok.
Jun  8 18:38:54 server Calibrating delay using timer specific routine.. 3602.51 BogoMIPS (lpj=7205030)
Jun  8 18:38:54 server Mount-cache hash table entries: 512
Jun  8 18:38:54 server CPU: After generic identify, caps: 078bfbff e3d3fbff 00000000 00000000 00000001 00000000 00000001
Jun  8 18:38:54 server CPU: After vendor identify, caps: 078bfbff e3d3fbff 00000000 00000000 00000001 00000000 00000001
Jun  8 18:38:54 server CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line)
Jun  8 18:38:54 server CPU: L2 Cache: 1024K (64 bytes/line)
Jun  8 18:38:54 server CPU: After all inits, caps: 078bfbff e3d3fbff 00000000 00000410 00000001 00000000 00000001
Jun  8 18:38:54 server Intel machine check architecture supported.
Jun  8 18:38:54 server Intel machine check reporting enabled on CPU#0.
Jun  8 18:38:54 server Compat vDSO mapped to ffffe000.
Jun  8 18:38:54 server CPU: AMD Opteron(tm) Processor 144 stepping 01
Jun  8 18:38:54 server Checking 'hlt' instruction... OK.
Jun  8 18:38:54 server SMP alternatives: switching to UP code
Jun  8 18:38:54 server Freeing SMP alternatives: 0k freed
Jun  8 18:38:54 server ACPI: setting ELCR to 0200 (from 0c28)
Jun  8 18:38:54 server NET: Registered protocol family 16
Jun  8 18:38:54 server ACPI: bus type pci registered
Jun  8 18:38:54 server PCI: PCI BIOS revision 3.00 entry at 0xf0031, last bus=4
Jun  8 18:38:54 server Setting up standard PCI resources
Jun  8 18:38:54 server ACPI: Subsystem revision 20060310
Jun  8 18:38:54 server ACPI: Interpreter enabled
Jun  8 18:38:54 server ACPI: Using PIC for interrupt routing
Jun  8 18:38:54 server ACPI: PCI Root Bridge [PCI0] (0000:00)
Jun  8 18:38:54 server PCI: Probing PCI hardware (bus 00)
Jun  8 18:38:54 server Boot video device is 0000:01:00.0
Jun  8 18:38:54 server ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
Jun  8 18:38:54 server ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P1._PRT]
Jun  8 18:38:54 server ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P7._PRT]
Jun  8 18:38:54 server ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P7.P7P9._PRT]
Jun  8 18:38:54 server ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P7.P7P8._PRT]
Jun  8 18:38:54 server ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 7 10 *11 12 14 15)
Jun  8 18:38:54 server ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 7 *10 11 12 14 15)
Jun  8 18:38:54 server ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 *5 6 7 10 11 12 14 15)
Jun  8 18:38:54 server ACPI: PCI Interrupt Link [LNKD] (IRQs *3 4 5 6 7 10 11 12 14 15)
Jun  8 18:38:54 server ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
Jun  8 18:38:54 server ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
Jun  8 18:38:54 server ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
Jun  8 18:38:54 server ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
Jun  8 18:38:54 server Linux Plug and Play Support v0.97 (c) Adam Belay
Jun  8 18:38:54 server pnp: PnP ACPI init
Jun  8 18:38:54 server pnp: PnP ACPI: found 15 devices
Jun  8 18:38:54 server SCSI subsystem initialized
Jun  8 18:38:54 server usbcore: registered new driver usbfs
Jun  8 18:38:54 server usbcore: registered new driver hub
Jun  8 18:38:54 server PCI: Using ACPI for IRQ routing
Jun  8 18:38:54 server PCI: If a device doesn't work, try "pci=routeirq".  If it helps, post a report
Jun  8 18:38:54 server TC classifier action (bugs to netdev@vger.kernel.org cc hadi@cyberus.ca)
Jun  8 18:38:54 server pnp: 00:07: ioport range 0x290-0x297 has been reserved
Jun  8 18:38:54 server PCI: Bridge: 0000:00:01.0
Jun  8 18:38:54 server IO window: disabled.
Jun  8 18:38:54 server MEM window: fca00000-feafffff
Jun  8 18:38:54 server PREFETCH window: cff00000-d7efffff
Jun  8 18:38:54 server PCI: Bridge: 0000:02:00.0
Jun  8 18:38:54 server IO window: disabled.
Jun  8 18:38:54 server MEM window: disabled.
Jun  8 18:38:54 server PREFETCH window: disabled.
Jun  8 18:38:54 server PCI: Bridge: 0000:02:00.1
Jun  8 18:38:54 server IO window: disabled.
Jun  8 18:38:54 server MEM window: disabled.
Jun  8 18:38:54 server PREFETCH window: disabled.
Jun  8 18:38:54 server PCI: Bridge: 0000:00:13.0
Jun  8 18:38:54 server IO window: disabled.
Jun  8 18:38:54 server MEM window: disabled.
Jun  8 18:38:54 server PREFETCH window: disabled.
Jun  8 18:38:54 server ACPI (acpi_bus-0192): Device `P0P1]is not power manageable [20060310]
Jun  8 18:38:54 server PCI: Setting latency timer of device 0000:00:01.0 to 64
Jun  8 18:38:54 server PCI: Setting latency timer of device 0000:00:13.0 to 64
Jun  8 18:38:54 server ACPI (acpi_bus-0192): Device `P7P8]is not power manageable [20060310]
Jun  8 18:38:54 server PCI: Setting latency timer of device 0000:02:00.0 to 64
Jun  8 18:38:54 server ACPI (acpi_bus-0192): Device `P7P9]is not power manageable [20060310]
Jun  8 18:38:54 server PCI: Setting latency timer of device 0000:02:00.1 to 64
Jun  8 18:38:54 server NET: Registered protocol family 2
Jun  8 18:38:54 server IP route cache hash table entries: 4096 (order: 2, 16384 bytes)
Jun  8 18:38:54 server TCP established hash table entries: 16384 (order: 4, 65536 bytes)
Jun  8 18:38:54 server TCP bind hash table entries: 8192 (order: 3, 32768 bytes)
Jun  8 18:38:54 server TCP: Hash tables configured (established 16384 bind 8192)
Jun  8 18:38:54 server TCP reno registered
Jun  8 18:38:54 server Machine check exception polling timer started.
Jun  8 18:38:54 server audit: initializing netlink socket (disabled)
Jun  8 18:38:54 server audit(1149791899.396:1): initialized
Jun  8 18:38:54 server Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
Jun  8 18:38:54 server io scheduler noop registered
Jun  8 18:38:54 server io scheduler anticipatory registered (default)
Jun  8 18:38:54 server io scheduler deadline registered
Jun  8 18:38:54 server io scheduler cfq registered
Jun  8 18:38:54 server ACPI: Power Button (FF) [PWRF]
Jun  8 18:38:54 server ACPI: Sleep Button (CM) [SLPB]
Jun  8 18:38:54 server ACPI: Power Button (CM) [PWRB]
Jun  8 18:38:54 server ACPI: Processor [CPU1] (supports 16 throttling states)
Jun  8 18:38:54 server ACPI Error (acpi_processor-0474): Getting cpuindex for acpiid 0x2 [20060310]
Jun  8 18:38:54 server Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing disabled
Jun  8 18:38:54 server serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
Jun  8 18:38:54 server 00:0c: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
Jun  8 18:38:54 server Floppy drive(s): fd0 is 1.44M
Jun  8 18:38:54 server FDC 0 is a post-1991 82077
Jun  8 18:38:54 server via-rhine.c:v1.10-LK1.2.0-2.6 June-10-2004 Written by Donald Becker
Jun  8 18:38:54 server ACPI (acpi_bus-0192): Device `ILAN]is not power manageable [20060310]
Jun  8 18:38:54 server ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 11
Jun  8 18:38:54 server PCI: setting IRQ 11 as level-triggered
Jun  8 18:38:54 server ACPI: PCI Interrupt 0000:00:12.0[A] -> Link [LNKA] -> GSI 11 (level, low) -> IRQ 11
Jun  8 18:38:54 server eth0: VIA Rhine II at 0xfebff000, 00:13:d4:a7:82:e5, IRQ 11.
Jun  8 18:38:54 server eth0: MII PHY found at address 1, status 0x786d advertising 01e1 Link 45e1.
Jun  8 18:38:54 server Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
Jun  8 18:38:54 server ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
Jun  8 18:38:54 server VP_IDE: IDE controller at PCI slot 0000:00:0f.1
Jun  8 18:38:54 server ACPI (acpi_bus-0192): Device `IDEC]is not power manageable [20060310]
Jun  8 18:38:54 server PCI: VIA IRQ fixup for 0000:00:0f.1, from 255 to 0
Jun  8 18:38:54 server VP_IDE: chipset revision 7
Jun  8 18:38:54 server VP_IDE: not 100% native mode: will probe irqs later
Jun  8 18:38:54 server VP_IDE: VIA vt8251 (rev 00) IDE UDMA133 controller on pci0000:00:0f.1
Jun  8 18:38:54 server ide0: BM-DMA at 0xfc00-0xfc07, BIOS settings: hda:DMA, hdb:DMA
Jun  8 18:38:54 server ide1: BM-DMA at 0xfc08-0xfc0f, BIOS settings: hdc:DMA, hdd:pio
Jun  8 18:38:54 server Probing IDE interface ide0...
Jun  8 18:38:54 server hda: QUANTUM BIGFOOT TS6.4A, ATA DISK drive
Jun  8 18:38:54 server hdb: Pioneer DVD-ROM ATAPIModel DVD-104S 012, ATAPI CD/DVD-ROM drive
Jun  8 18:38:54 server ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
Jun  8 18:38:54 server Probing IDE interface ide1...
Jun  8 18:38:54 server hdc: ST3160021A, ATA DISK drive
Jun  8 18:38:54 server ide1 at 0x170-0x177,0x376 on irq 15
Jun  8 18:38:54 server hda: max request size: 128KiB
Jun  8 18:38:54 server hda: 12536370 sectors (6418 MB) w/418KiB Cache, CHS=13266/15/63, UDMA(33)
Jun  8 18:38:54 server hda: cache flushes not supported
Jun  8 18:38:54 server hda: hda1 hda2 hda3 hda4 < hda5 >
Jun  8 18:38:54 server hdc: max request size: 512KiB
Jun  8 18:38:54 server hdc: 312581808 sectors (160041 MB) w/2048KiB Cache, CHS=19457/255/63, UDMA(100)
Jun  8 18:38:54 server hdc: cache flushes supported
Jun  8 18:38:54 server hdc: hdc1 hdc2 < hdc5 hdc6 hdc7 hdc8 hdc9 >
Jun  8 18:38:54 server hdb: ATAPI DVD-ROM drive, 512kB Cache, UDMA(33)
Jun  8 18:38:54 server Uniform CD-ROM driver Revision: 3.20
Jun  8 18:38:54 server ACPI (acpi_bus-0192): Device `EHCI]is not power manageable [20060310]
Jun  8 18:38:54 server ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 5
Jun  8 18:38:54 server PCI: setting IRQ 5 as level-triggered
Jun  8 18:38:54 server ACPI: PCI Interrupt 0000:00:10.4[C] -> Link [LNKC] -> GSI 5 (level, low) -> IRQ 5
Jun  8 18:38:54 server ehci_hcd 0000:00:10.4: EHCI Host Controller
Jun  8 18:38:54 server ehci_hcd 0000:00:10.4: new USB bus registered, assigned bus number 1
Jun  8 18:38:54 server ehci_hcd 0000:00:10.4: debug port 1
Jun  8 18:38:54 server ehci_hcd 0000:00:10.4: irq 5, io mem 0xfebff400
Jun  8 18:38:54 server ehci_hcd 0000:00:10.4: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
Jun  8 18:38:54 server usb usb1: new device found, idVendor=0000, idProduct=0000
Jun  8 18:38:54 server usb usb1: new device strings: Mfr=3, Product=2, SerialNumber=1
Jun  8 18:38:54 server usb usb1: Product: EHCI Host Controller
Jun  8 18:38:54 server usb usb1: Manufacturer: Linux 2.6.17-rc5-mm2 ehci_hcd
Jun  8 18:38:54 server usb usb1: SerialNumber: 0000:00:10.4
Jun  8 18:38:54 server usb usb1: configuration #1 chosen from 1 choice
Jun  8 18:38:54 server hub 1-0:1.0: USB hub found
Jun  8 18:38:54 server hub 1-0:1.0: 8 ports detected
Jun  8 18:38:54 server USB Universal Host Controller Interface driver v3.0
Jun  8 18:38:54 server ACPI (acpi_bus-0192): Device `USB1]is not power manageable [20060310]
Jun  8 18:38:54 server ACPI: PCI Interrupt 0000:00:10.0[A] -> Link [LNKA] -> GSI 11 (level, low) -> IRQ 11
Jun  8 18:38:54 server uhci_hcd 0000:00:10.0: UHCI Host Controller
Jun  8 18:38:54 server uhci_hcd 0000:00:10.0: new USB bus registered, assigned bus number 2
Jun  8 18:38:54 server uhci_hcd 0000:00:10.0: irq 11, io base 0x0000e000
Jun  8 18:38:54 server usb usb2: new device found, idVendor=0000, idProduct=0000
Jun  8 18:38:54 server usb usb2: new device strings: Mfr=3, Product=2, SerialNumber=1
Jun  8 18:38:54 server usb usb2: Product: UHCI Host Controller
Jun  8 18:38:54 server usb usb2: Manufacturer: Linux 2.6.17-rc5-mm2 uhci_hcd
Jun  8 18:38:54 server usb usb2: SerialNumber: 0000:00:10.0
Jun  8 18:38:54 server usb usb2: configuration #1 chosen from 1 choice
Jun  8 18:38:54 server hub 2-0:1.0: USB hub found
Jun  8 18:38:54 server hub 2-0:1.0: 2 ports detected
Jun  8 18:38:54 server ACPI (acpi_bus-0192): Device `USB2]is not power manageable [20060310]
Jun  8 18:38:54 server ACPI: PCI Interrupt 0000:00:10.1[C] -> Link [LNKC] -> GSI 5 (level, low) -> IRQ 5
Jun  8 18:38:54 server uhci_hcd 0000:00:10.1: UHCI Host Controller
Jun  8 18:38:54 server uhci_hcd 0000:00:10.1: new USB bus registered, assigned bus number 3
Jun  8 18:38:54 server uhci_hcd 0000:00:10.1: irq 5, io base 0x0000dc00
Jun  8 18:38:54 server usb usb3: new device found, idVendor=0000, idProduct=0000
Jun  8 18:38:54 server usb usb3: new device strings: Mfr=3, Product=2, SerialNumber=1
Jun  8 18:38:54 server usb usb3: Product: UHCI Host Controller
Jun  8 18:38:54 server usb usb3: Manufacturer: Linux 2.6.17-rc5-mm2 uhci_hcd
Jun  8 18:38:54 server usb usb3: SerialNumber: 0000:00:10.1
Jun  8 18:38:54 server usb usb3: configuration #1 chosen from 1 choice
Jun  8 18:38:54 server hub 3-0:1.0: USB hub found
Jun  8 18:38:54 server hub 3-0:1.0: 2 ports detected
Jun  8 18:38:54 server ACPI (acpi_bus-0192): Device `USB3]is not power manageable [20060310]
Jun  8 18:38:54 server ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 10
Jun  8 18:38:54 server PCI: setting IRQ 10 as level-triggered
Jun  8 18:38:54 server ACPI: PCI Interrupt 0000:00:10.2[B] -> Link [LNKB] -> GSI 10 (level, low) -> IRQ 10
Jun  8 18:38:54 server uhci_hcd 0000:00:10.2: UHCI Host Controller
Jun  8 18:38:54 server uhci_hcd 0000:00:10.2: new USB bus registered, assigned bus number 4
Jun  8 18:38:54 server uhci_hcd 0000:00:10.2: irq 10, io base 0x0000d880
Jun  8 18:38:54 server usb usb4: new device found, idVendor=0000, idProduct=0000
Jun  8 18:38:54 server usb usb4: new device strings: Mfr=3, Product=2, SerialNumber=1
Jun  8 18:38:54 server usb usb4: Product: UHCI Host Controller
Jun  8 18:38:54 server usb usb4: Manufacturer: Linux 2.6.17-rc5-mm2 uhci_hcd
Jun  8 18:38:54 server usb usb4: SerialNumber: 0000:00:10.2
Jun  8 18:38:54 server usb usb4: configuration #1 chosen from 1 choice
Jun  8 18:38:54 server hub 4-0:1.0: USB hub found
Jun  8 18:38:54 server hub 4-0:1.0: 2 ports detected
Jun  8 18:38:54 server ACPI (acpi_bus-0192): Device `USB4]is not power manageable [20060310]
Jun  8 18:38:54 server ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 3
Jun  8 18:38:54 server PCI: setting IRQ 3 as level-triggered
Jun  8 18:38:54 server ACPI: PCI Interrupt 0000:00:10.3[D] -> Link [LNKD] -> GSI 3 (level, low) -> IRQ 3
Jun  8 18:38:54 server uhci_hcd 0000:00:10.3: UHCI Host Controller
Jun  8 18:38:54 server uhci_hcd 0000:00:10.3: new USB bus registered, assigned bus number 5
Jun  8 18:38:54 server uhci_hcd 0000:00:10.3: irq 3, io base 0x0000d800
Jun  8 18:38:54 server usb usb5: new device found, idVendor=0000, idProduct=0000
Jun  8 18:38:54 server usb usb5: new device strings: Mfr=3, Product=2, SerialNumber=1
Jun  8 18:38:54 server usb usb5: Product: UHCI Host Controller
Jun  8 18:38:54 server usb usb5: Manufacturer: Linux 2.6.17-rc5-mm2 uhci_hcd
Jun  8 18:38:54 server usb usb5: SerialNumber: 0000:00:10.3
Jun  8 18:38:54 server usb usb5: configuration #1 chosen from 1 choice
Jun  8 18:38:54 server hub 5-0:1.0: USB hub found
Jun  8 18:38:54 server hub 5-0:1.0: 2 ports detected
Jun  8 18:38:54 server Initializing USB Mass Storage driver...
Jun  8 18:38:54 server usbcore: registered new driver usb-storage
Jun  8 18:38:54 server USB Mass Storage support registered.
Jun  8 18:38:54 server PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12
Jun  8 18:38:54 server serio: i8042 AUX port at 0x60,0x64 irq 12
Jun  8 18:38:54 server serio: i8042 KBD port at 0x60,0x64 irq 1
Jun  8 18:38:54 server mice: PS/2 mouse device common for all mice
Jun  8 18:38:54 server input: PC Speaker as /class/input/input0
Jun  8 18:38:54 server ip_conntrack version 2.4 (4029 buckets, 32232 max) - 208 bytes per conntrack
Jun  8 18:38:54 server input: AT Translated Set 2 keyboard as /class/input/input1
Jun  8 18:38:54 server TCP bic registered
Jun  8 18:38:54 server NET: Registered protocol family 1
Jun  8 18:38:54 server NET: Registered protocol family 17
Jun  8 18:38:54 server Using IPI Shortcut mode
Jun  8 18:38:54 server ACPI: (supports S0 S1 S3 S4 S5)
Jun  8 18:38:54 server Freeing unused kernel memory: 164k freed
Jun  8 18:38:54 server Time: tsc clocksource has been installed.
Jun  8 18:38:54 server ReiserFS: hda3: found reiserfs format "3.6" with standard journal
Jun  8 18:38:54 server ReiserFS: hda3: using ordered data mode
Jun  8 18:38:54 server ReiserFS: hda3: journal params: device hda3, size 8192, journal first block 18, max trans len 1024, max batch 900, max commit age 30, max trans age 30
Jun  8 18:38:54 server ReiserFS: hda3: checking transaction log (hda3)
Jun  8 18:38:54 server ReiserFS: hda3: Using r5 hash to sort names
Jun  8 18:38:54 server Adding 192772k swap on /dev/hda2.  Priority:-1 extents:1 across:192772k
Jun  8 18:38:54 server ACPI: PCI Interrupt 0000:00:0c.0[A] -> Link [LNKB] -> GSI 10 (level, low) -> IRQ 10
Jun  8 18:38:54 server 3c59x: Donald Becker and others. www.scyld.com/network/vortex.html
Jun  8 18:38:54 server 0000:00:0c.0: 3Com PCI 3c905B Cyclone 100baseTx at e00d6800.
Jun  8 18:38:54 server ReiserFS: hdc5: found reiserfs format "3.6" with standard journal
Jun  8 18:38:54 server ReiserFS: hdc5: using ordered data mode
Jun  8 18:38:54 server ReiserFS: hdc5: journal params: device hdc5, size 8192, journal first block 18, max trans len 1024, max batch 900, max commit age 30, max trans age 30
Jun  8 18:38:54 server ReiserFS: hdc5: checking transaction log (hdc5)
Jun  8 18:38:54 server ReiserFS: hdc5: Using r5 hash to sort names
Jun  8 18:38:54 server ReiserFS: hdc6: found reiserfs format "3.6" with standard journal
Jun  8 18:38:54 server ReiserFS: hdc6: using ordered data mode
Jun  8 18:38:54 server ReiserFS: hdc6: journal params: device hdc6, size 8192, journal first block 18, max trans len 1024, max batch 900, max commit age 30, max trans age 30
Jun  8 18:38:54 server ReiserFS: hdc6: checking transaction log (hdc6)
Jun  8 18:38:54 server ReiserFS: hdc6: Using r5 hash to sort names
Jun  8 18:38:54 server ReiserFS: hdc7: found reiserfs format "3.6" with standard journal
Jun  8 18:38:54 server ReiserFS: hdc7: using ordered data mode
Jun  8 18:38:54 server ReiserFS: hdc7: journal params: device hdc7, size 8192, journal first block 18, max trans len 1024, max batch 900, max commit age 30, max trans age 30
Jun  8 18:38:54 server ReiserFS: hdc7: checking transaction log (hdc7)
Jun  8 18:38:54 server ReiserFS: hdc7: Using r5 hash to sort names
Jun  8 18:38:54 server ReiserFS: hdc8: found reiserfs format "3.6" with standard journal
Jun  8 18:38:54 server ReiserFS: hdc8: using ordered data mode
Jun  8 18:38:54 server ReiserFS: hdc8: journal params: device hdc8, size 8192, journal first block 18, max trans len 1024, max batch 900, max commit age 30, max trans age 30
Jun  8 18:38:54 server ReiserFS: hdc8: checking transaction log (hdc8)
Jun  8 18:38:54 server ReiserFS: hdc8: Using r5 hash to sort names
Jun  8 18:38:54 server ReiserFS: hdc9: found reiserfs format "3.6" with standard journal
Jun  8 18:38:54 server ReiserFS: hdc9: using ordered data mode
Jun  8 18:38:54 server ReiserFS: hdc9: journal params: device hdc9, size 8192, journal first block 18, max trans len 1024, max batch 900, max commit age 30, max trans age 30
Jun  8 18:38:54 server ReiserFS: hdc9: checking transaction log (hdc9)
Jun  8 18:38:54 server ReiserFS: hdc9: Using r5 hash to sort names
Jun  8 18:38:55 server eth0: link up, 100Mbps, full-duplex, lpa 0x45E1
Jun  8 18:38:55 server ACPI: PCI Interrupt 0000:00:0c.0[A] -> Link [LNKB] -> GSI 10 (level, low) -> IRQ 10
Jun  8 18:38:55 server eth1:  setting full-duplex.

After inserting ahci module:
Kernel log:
Jun  8 18:52:12 server libata version 1.30 loaded.
Jun  8 18:52:12 server ahci 0000:00:0f.0: version 1.3
Jun  8 18:52:12 server ACPI (acpi_bus-0192): Device `SATA]is not power manageable [20060310]
Jun  8 18:52:12 server ACPI: PCI Interrupt 0000:00:0f.0[B] -> Link [LNKB] -> GSI 10 (level, low) -> IRQ 10
Jun  8 18:52:18 server ahci 0000:00:0f.0: AHCI 0001.0000 32 slots 4 ports 3 Gbps 0xf impl SATA mode
Jun  8 18:52:18 server ahci 0000:00:0f.0: flags: 64bit ncq pm led clo pmp pio slum part 
Jun  8 18:52:18 server ata1: SATA max UDMA/133 cmd 0xE0488D00 ctl 0x0 bmdma 0x0 irq 10
Jun  8 18:52:18 server ata2: SATA max UDMA/133 cmd 0xE0488D80 ctl 0x0 bmdma 0x0 irq 10
Jun  8 18:52:18 server ata3: SATA max UDMA/133 cmd 0xE0488E00 ctl 0x0 bmdma 0x0 irq 10
Jun  8 18:52:18 server ata4: SATA max UDMA/133 cmd 0xE0488E80 ctl 0x0 bmdma 0x0 irq 10
Jun  8 18:52:18 server ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Jun  8 18:52:18 server ata1.00: cfg 49:2f00 82:346b 83:7d01 84:4023 85:3469 86:3c01 87:4023 88:407f
Jun  8 18:52:18 server ata1.00: ATA-7, max UDMA/133, 156301488 sectors: LBA48 NCQ (depth 31/32)
Jun  8 18:52:18 server ata1.00: configured for UDMA/133
Jun  8 18:52:18 server scsi0 : ahci
Jun  8 18:52:18 server ata2: SATA link down (SStatus 0 SControl 300)
Jun  8 18:52:18 server scsi1 : ahci
Jun  8 18:52:19 server ata3: SATA link down (SStatus 0 SControl 300)
Jun  8 18:52:19 server scsi2 : ahci
Jun  8 18:52:19 server ata4: SATA link down (SStatus 0 SControl 300)
Jun  8 18:52:19 server scsi3 : ahci
Jun  8 18:52:19 server Vendor: ATA       Model: ST3808110AS       Rev: 3.AA
Jun  8 18:52:19 server Type:   Direct-Access                      ANSI SCSI revision: 05
Jun  8 18:52:19 server SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
Jun  8 18:52:19 server sda: Write Protect is off
Jun  8 18:52:19 server sda: Mode Sense: 00 3a 00 00
Jun  8 18:52:19 server SCSI device sda: drive cache: write back
Jun  8 18:52:19 server SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
Jun  8 18:52:19 server sda: Write Protect is off
Jun  8 18:52:19 server sda: Mode Sense: 00 3a 00 00
Jun  8 18:52:19 server SCSI device sda: drive cache: write back
Jun  8 18:52:19 server sda: sda1 sda2 sda3 sda4 < sda5 >
Jun  8 18:52:19 server sd 0:0:0:0: Attached scsi disk sda
Jun  8 18:52:19 server sd 0:0:0:0: Attached scsi generic sg0 type 0

Now doing fdisk /dev/sda or mke2fs /dev/sda1:
Kernel log:
Jun  8 18:52:19 server ata1: spurious interrupt (irq_stat 0x8 active_tag -84148995 sactive 0x2)
Jun  8 18:53:09 server ata1.00: qc timeout (cmd 0x2f)
Jun  8 18:53:09 server ata1: failed to read log page 10h (errno=-5)
Jun  8 18:53:09 server ata1.00: exception Emask 0x1 SAct 0x7fffffff SErr 0x0 action 0x2 frozen
Jun  8 18:53:09 server ata1.00: (irq_stat 0x40000000)
Jun  8 18:53:09 server ata1.00: tag 0 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 1 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 2 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 3 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 4 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 5 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 6 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 7 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 8 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 9 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 10 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 11 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 12 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 13 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 14 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 15 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 16 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 17 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 18 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 19 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 20 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 21 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 22 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 23 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 24 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 25 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 26 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 27 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 28 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 29 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1.00: tag 30 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun  8 18:53:09 server ata1: soft resetting port
Jun  8 18:53:09 server ata1: softreset failed (1st FIS failed)
Jun  8 18:53:09 server ata1: softreset failed, retrying in 5 secs

Jun  8 18:53:14 server ata1: hard resetting port
Jun  8 18:53:22 server ata1: port is slow to respond, please be patient
Jun  8 18:53:45 server ata1: port failed to respond (30 secs)
Jun  8 18:53:45 server ata1: COMRESET failed (device not ready)
Jun  8 18:53:45 server ata1: hardreset failed, retrying in 5 secs
Jun  8 18:53:50 server ata1: hard resetting port
Jun  8 18:53:57 server ata1: port is slow to respond, please be patient

Jun  8 18:54:20 server ata1: port failed to respond (30 secs)
Jun  8 18:54:20 server ata1: COMRESET failed (device not ready)
Jun  8 18:54:20 server ata1: reset failed, giving up
Jun  8 18:54:20 server ata1.00: disabled
Jun  8 18:54:20 server ata1: EH complete
Jun  8 18:54:20 server CSI error: return code = 0x40000

Very much scsi errors for different sectors: 
(I use syslog-ng, maybe the corruptoins are generated by suppressing messages)

Jun  8 18:54:20 server end_request: I/O error, dev sda, sector 98375
Jun  8 18:54:20 server sd 0:0:0:0: SCSI error: return code = 0x40000
..
Jun  8 18:54:20 server sd 0:0:0:0: SCSI errCSI error: return code = 0x40000
Jun  8 18:54:20 server end_request: I/O error, dev sda, sector 98395
Jun  8 18:54:20 server sd 0:0:0:0: SCSI error: return code = 0x40000
..
Jun  8 18:54:20 server end_request: I/CSI error: return code = 0x40000
Jun  8 18:54:20 server end_request: I/O error, dev sda, sector 98415
Jun  8 18:54:20 server sd 0:0:0:0: SCSI error: return code = 0x40000
..
Jun  8 18:54:20 server sd 0:0:0:0: SCSI errSCSI error: return code = 0x40000
Jun  8 18:54:20 server end_request: I/O error, dev sda, sector 98691
Jun  8 18:54:20 server sd 0:0:0:0: SCSI error: return code = 0x40000
..
Jun  8 18:54:20 server unparseable log message: "<ror: return code = 0x40000"
Jun  8 18:54:20 server end_request: I/O error, dev sda, sector 196759
..
Jun  8 18:54:20 server end_request: I/O error, dev sda, sector 273051
Jun  8 18:54:20 server sd 0:0:0:0: SCSI error: return code = 0x40000
Jun  8 18:54:20 server end_request: I/O errror: return code = 0x40000
Jun  8 18:54:20 server end_request: I/O error, dev sda, sector 196779
Jun  8 18:54:20 server sd 0:0:0:0: SCSI error: return code = 0x40000
Jun  8 18:54:20 server end_request: I/O error, dev sda, sector 180653
Jun  8 18:54:20 server sd 0:0:0:0: SCSI error: return code = 0x40000
Jun  8 18:54:20 server end_request: I/O error, dev sda, sector 273063
..
Jun  8 18:54:20 server end_request: I/O e: return code = 0x40000
Jun  8 18:54:20 server end_request: I/O error, dev sda, sector 131559
Jun  8 18:54:20 server sd 0:0:0:0: SCSI error: return code = 0x40000
Jun  8 18:54:20 server end_request: I/O error, dev sda, sector 131301
Jun  8 18:54:20 server sd 0:0:0:0: SCSI error: return code = 0x40000
..
Jun  8 18:54:20 server sd 0:0:0:0: SCSI e return code = 0x40000
Jun  8 18:54:20 server end_request: I/O error, dev sda, sector 180473
Jun  8 18:54:20 server sd 0:0:0:0: SCSI error: return code = 0x40000
..
Jun  8 18:54:20 server sd 0:0:0:0: SCSI error: return code = 0x40000
Jun  8 18:54:20 server end_request: I/O error, dev sda, sector 65
Jun  8 18:54:41 server sd 0:0:0:0: SCSI error: return code = 0x40000
Jun  8 18:54:41 server end_request: I/O error, dev sda, sector 0
Jun  8 18:54:41 server printk: 3105 messages suppressed.
Jun  8 18:54:41 server Buffer I/O error on device sda, logical block 0
Jun  8 18:54:41 server sd 0:0:0:0: SCSI error: return code = 0x40000
Jun  8 18:54:41 server end_request: I/O error, dev sda, sector 8
Jun  8 18:54:41 server Buffer I/O error on device sda, logical block 1
Jun  8 18:54:41 server sd 0:0:0:0: SCSI error: return code = 0x40000
Jun  8 18:54:41 server end_request: I/O error, dev sda, sector 16
Jun  8 18:54:41 server Buffer I/O error on device sda, logical block 2
Jun  8 18:54:41 server sd 0:0:0:0: SCSI error: return code = 0x40000
Jun  8 18:54:41 server end_request: I/O error, dev sda, sector 24
Jun  8 18:54:41 server Buffer I/O error on device sda, logical block 3
Jun  8 18:54:41 server sd 0:0:0:0: SCSI error: return code = 0x40000
Jun  8 18:54:41 server end_request: I/O error, dev sda, sector 0

unloading ahci:
Jun  8 18:54:47 server ACPI: PCI interrupt for device 0000:00:0f.0 disabled

Thanks Tejun for finding this list ;-)

Aalderd.


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

* Re: Seagate SATA 3GB/s drive connected to via vt8251 using ahci module
  2006-06-08 17:30 Seagate SATA 3GB/s drive connected to via vt8251 using ahci module Aalderd Bouwman
@ 2006-06-09  5:44 ` Tejun Heo
  2006-06-12 10:42   ` Aalderd Bouwman
       [not found] ` <1150745738.4302.1.camel@anja>
  1 sibling, 1 reply; 14+ messages in thread
From: Tejun Heo @ 2006-06-09  5:44 UTC (permalink / raw)
  To: aalderd.boac; +Cc: linux-ide

Hello, Aalderd.

Aalderd Bouwman wrote:
> Hello,
> 
> I am running Gentoo Linux on a Asus A8V-MX mainboard with the Via VT8251
> southbridge chipset. The sata-controller is configured as AHCI.
> Bios version is 0210.
> 
> The drive is working properly with the drivers of VIA.
> 
> A Maxtor drive works correct on this controller but this seagate drive
> (ST3808110AS) fails on both modes (1.5Gb/s or 3.0Gb/s).
> 
> Here you can find the log-results after doing some actions on this
> drive. After the sector errors the drive could not be accessed by fdisk.
> 
> # uname -a
> Linux server 2.6.17-rc5-mm2 #2 Wed Jun 7 22:18:57 CEST 2006 i686 AMD
> Opteron(tm) Processor 144 AuthenticAMD GNU/Linux
> 
> Kernel log:
[--snip--]
> Jun  8 18:52:19 server ata1: spurious interrupt (irq_stat 0x8 active_tag -84148995 sactive 0x2)

One spurious interrupt here and then device reports error after ~20 secs 
which triggers libata to read log page 10h.

> Jun  8 18:53:09 server ata1.00: qc timeout (cmd 0x2f)
> Jun  8 18:53:09 server ata1: failed to read log page 10h (errno=-5)

Which timed out here.

> Jun  8 18:53:09 server ata1.00: exception Emask 0x1 SAct 0x7fffffff SErr 0x0 action 0x2 frozen
> Jun  8 18:53:09 server ata1.00: (irq_stat 0x40000000)
> Jun  8 18:53:09 server ata1.00: tag 0 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
[--snip--]
> Jun  8 18:53:09 server ata1.00: tag 30 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
> Jun  8 18:53:09 server ata1: soft resetting port
> Jun  8 18:53:09 server ata1: softreset failed (1st FIS failed)
> Jun  8 18:53:09 server ata1: softreset failed, retrying in 5 secs
> 
> Jun  8 18:53:14 server ata1: hard resetting port
> Jun  8 18:53:22 server ata1: port is slow to respond, please be patient
> Jun  8 18:53:45 server ata1: port failed to respond (30 secs)
> Jun  8 18:53:45 server ata1: COMRESET failed (device not ready)
> Jun  8 18:53:45 server ata1: hardreset failed, retrying in 5 secs
> Jun  8 18:53:50 server ata1: hard resetting port
> Jun  8 18:53:57 server ata1: port is slow to respond, please be patient
> 
> Jun  8 18:54:20 server ata1: port failed to respond (30 secs)
> Jun  8 18:54:20 server ata1: COMRESET failed (device not ready)
> Jun  8 18:54:20 server ata1: reset failed, giving up
> Jun  8 18:54:20 server ata1.00: disabled
> Jun  8 18:54:20 server ata1: EH complete

And the drive and/or port is completely deaf.

Can you please unplug power to the drive and replug it while EH is in 
progress, umm.. say during the first hardreset?

-- 
tejun

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

* Re: Seagate SATA 3GB/s drive connected to via vt8251 using ahci module
  2006-06-09  5:44 ` Tejun Heo
@ 2006-06-12 10:42   ` Aalderd Bouwman
  2006-06-12 10:51     ` Tejun Heo
  0 siblings, 1 reply; 14+ messages in thread
From: Aalderd Bouwman @ 2006-06-12 10:42 UTC (permalink / raw)
  To: Tejun Heo; +Cc: linux-ide

Hello Tejun,

#modprobe achi:
Jun 12 12:26:47 server ahci 0000:00:0f.0: version 1.3
Jun 12 12:26:47 server ACPI: PCI Interrupt 0000:00:0f.0[B] -> Link [LNKB] -> GSI 10 (level, low) -> IRQ 10
Jun 12 12:26:53 server ahci 0000:00:0f.0: AHCI 0001.0000 32 slots 4 ports 3 Gbps 0xf impl SATA mode
Jun 12 12:26:53 server ahci 0000:00:0f.0: flags: 64bit ncq pm led clo pmp pio slum part 
Jun 12 12:26:53 server ata13: SATA max UDMA/133 cmd 0xE048AD00 ctl 0x0 bmdma 0x0 irq 10
Jun 12 12:26:53 server ata14: SATA max UDMA/133 cmd 0xE048AD80 ctl 0x0 bmdma 0x0 irq 10
Jun 12 12:26:53 server ata15: SATA max UDMA/133 cmd 0xE048AE00 ctl 0x0 bmdma 0x0 irq 10
Jun 12 12:26:53 server ata16: SATA max UDMA/133 cmd 0xE048AE80 ctl 0x0 bmdma 0x0 irq 10
Jun 12 12:26:53 server ata13: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Jun 12 12:26:53 server ata13.00: cfg 49:2f00 82:346b 83:7d01 84:4023 85:3469 86:3c01 87:4023 88:007f
Jun 12 12:26:53 server ata13.00: ATA-7, max UDMA/133, 156301488 sectors: LBA48 NCQ (depth 31/32)
Jun 12 12:26:53 server ata13.00: configured for UDMA/133
Jun 12 12:26:53 server scsi12 : ahci
Jun 12 12:26:53 server ata14: SATA link down (SStatus 0 SControl 300)
Jun 12 12:26:53 server scsi13 : ahci
Jun 12 12:26:54 server ata15: SATA link down (SStatus 0 SControl 300)
Jun 12 12:26:54 server scsi14 : ahci
Jun 12 12:26:54 server ata16: SATA link down (SStatus 0 SControl 300)
Jun 12 12:26:54 server scsi15 : ahci
Jun 12 12:26:54 server Vendor: ATA       Model: ST3808110AS       Rev: 3.AA
Jun 12 12:26:54 server Type:   Direct-Access                      ANSI SCSI revision: 05
Jun 12 12:26:54 server SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
Jun 12 12:26:54 server sda: Write Protect is off
Jun 12 12:26:54 server sda: Mode Sense: 00 3a 00 00
Jun 12 12:26:54 server SCSI device sda: drive cache: write back
Jun 12 12:26:54 server SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
Jun 12 12:26:54 server sda: Write Protect is off
Jun 12 12:26:54 server sda: Mode Sense: 00 3a 00 00
Jun 12 12:26:54 server SCSI device sda: drive cache: write back
Jun 12 12:26:54 server sda: sda1 sda2 sda3 sda4 < sda5 >
Jun 12 12:26:54 server sd 12:0:0:0: Attached scsi disk sda
Jun 12 12:26:54 server sd 12:0:0:0: Attached scsi generic sg0 type 0
Jun 12 12:27:24 server ata13.00: qc timeout (cmd 0x2f)
Jun 12 12:27:24 server ata13: failed to read log page 10h (errno=-5)
Jun 12 12:27:24 server ata13.00: exception Emask 0x1 SAct 0xb SErr 0x0 action 0x2 frozen
Jun 12 12:27:24 server ata13.00: (irq_stat 0x40000000)
Jun 12 12:27:24 server ata13.00: tag 0 cmd 0x60 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun 12 12:27:24 server ata13.00: tag 1 cmd 0x60 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun 12 12:27:24 server ata13.00: tag 3 cmd 0x60 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun 12 12:27:24 server ata13: soft resetting port
Jun 12 12:27:24 server ata13: softreset failed (1st FIS failed)
Jun 12 12:27:24 server ata13: softreset failed, retrying in 5 secs
Jun 12 12:27:29 server ata13: hard resetting port

Remove sata-connector and then the power-connector of the Seagate drive.
(Note that I also have problems with hotplug the Maxtor-drive.
 I will post that problem later this day.)

Jun 12 12:27:37 server ata13: port is slow to respond, please be patient
Jun 12 12:28:00 server ata13: port failed to respond (30 secs)
Jun 12 12:28:00 server ata13: COMRESET failed (device not ready)
Jun 12 12:28:00 server ata13: hardreset failed, retrying in 5 secs
Jun 12 12:28:05 server ata13: hard resetting port
Jun 12 12:28:12 server ata13: port is slow to respond, please be patient
Jun 12 12:28:35 server ata13: port failed to respond (30 secs)
Jun 12 12:28:35 server ata13: COMRESET failed (device not ready)
Jun 12 12:28:35 server ata13: reset failed, giving up
Jun 12 12:28:35 server ata13.00: disabled
Jun 12 12:28:35 server ata13: EH complete
Jun 12 12:28:35 server sd 12:0:0:0: SCSI error: return code = 0x40000
Jun 12 12:28:35 server end_request: I/O error, dev sda, sector 20836305
Jun 12 12:28:35 server printk: 971 messages suppressed.
Jun 12 12:28:35 server Buffer I/O error on device sda4, logical block 0
Jun 12 12:28:35 server sd 12:0:0:0: SCSI error: return code = 0x40000
Jun 12 12:28:35 server end_request: I/O error, dev sda, sector 156296208
Jun 12 12:28:35 server Buffer I/O error on device sda5, logical block 135459840
Jun 12 12:28:35 server sd 12:0:0:0: SCSI error: return code = 0x40000
Jun 12 12:28:35 server end_request: I/O error, dev sda, sector 20836296
Jun 12 12:28:35 server Buffer I/O error on device sda3, logical block 19551096
Jun 12 12:28:35 server sd 12:0:0:0: SCSI error: return code = 0x40000
Jun 12 12:28:35 server end_request: I/O error, dev sda, sector 156296209
Jun 12 12:28:35 server Buffer I/O error on device sda5, logical block 135459841
Jun 12 12:28:35 server sd 12:0:0:0: SCSI error: return code = 0x40000
Jun 12 12:28:35 server end_request: I/O error, dev sda, sector 20836297
Jun 12 12:28:35 server Buffer I/O error on device sda3, logical block 19551097
Jun 12 12:28:35 server sd 12:0:0:0: SCSI error: return code = 0x40000
Jun 12 12:28:35 server end_request: I/O error, dev sda, sector 156296210
Jun 12 12:28:35 server Buffer I/O error on device sda5, logical block 135459842
Jun 12 12:28:35 server sd 12:0:0:0: SCSI error: return code = 0x40000
Jun 12 12:28:35 server end_request: I/O error, dev sda, sector 20836298
Jun 12 12:28:35 server Buffer I/O error on device sda3, logical block 19551098
Jun 12 12:28:35 server sd 12:0:0:0: SCSI error: return code = 0x40000
Jun 12 12:28:35 server end_request: I/O error, dev sda, sector 156296211
Jun 12 12:28:35 server Buffer I/O error on device sda5, logical block 135459843
Jun 12 12:28:35 server sd 12:0:0:0: SCSI error: return code = 0x40000
Jun 12 12:28:35 server end_request: I/O error, dev sda, sector 20836299
Jun 12 12:28:35 server Buffer I/O error on device sda3, logical block 19551099
Jun 12 12:28:35 server sd 12:0:0:0: SCSI error: return code = 0x40000
Jun 12 12:28:35 server end_request: I/O error, dev sda, sector 156296212
Jun 12 12:28:35 server Buffer I/O error on device sda5, logical block 135459844
Jun 12 12:28:35 server sd 12:0:0:0: SCSI error: return code = 0x40000
Jun 12 12:28:35 server end_request: I/O error, dev sda, sector 20836300
Jun 12 12:28:35 server sd 12:0:0:0: SCSI error: return code = 0x40000

...
A lot of SCSI errors...

#rmmod ahci:
Jun 12 12:29:13 server ACPI: PCI interrupt for device 0000:00:0f.0 disabled

No other commands I perform during this test.

Aalderd Bouwman.

On Fri, 2006-06-09 at 14:44 +0900, Tejun Heo wrote:
> Hello, Aalderd.
> 
> Aalderd Bouwman wrote:
> > Hello,
> > 
> > I am running Gentoo Linux on a Asus A8V-MX mainboard with the Via VT8251
> > southbridge chipset. The sata-controller is configured as AHCI.
> > Bios version is 0210.
> > 
> > The drive is working properly with the drivers of VIA.
> > 
> > A Maxtor drive works correct on this controller but this seagate drive
> > (ST3808110AS) fails on both modes (1.5Gb/s or 3.0Gb/s).
> > 
> > Here you can find the log-results after doing some actions on this
> > drive. After the sector errors the drive could not be accessed by fdisk.
> > 
> > # uname -a
> > Linux server 2.6.17-rc5-mm2 #2 Wed Jun 7 22:18:57 CEST 2006 i686 AMD
> > Opteron(tm) Processor 144 AuthenticAMD GNU/Linux
> > 
> > Kernel log:
> [--snip--]
> > Jun  8 18:52:19 server ata1: spurious interrupt (irq_stat 0x8 active_tag -84148995 sactive 0x2)
> 
> One spurious interrupt here and then device reports error after ~20 secs 
> which triggers libata to read log page 10h.
> 
> > Jun  8 18:53:09 server ata1.00: qc timeout (cmd 0x2f)
> > Jun  8 18:53:09 server ata1: failed to read log page 10h (errno=-5)
> 
> Which timed out here.
> 
> > Jun  8 18:53:09 server ata1.00: exception Emask 0x1 SAct 0x7fffffff SErr 0x0 action 0x2 frozen
> > Jun  8 18:53:09 server ata1.00: (irq_stat 0x40000000)
> > Jun  8 18:53:09 server ata1.00: tag 0 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
> [--snip--]
> > Jun  8 18:53:09 server ata1.00: tag 30 cmd 0x61 Emask 0x1 stat 0x41 err 0x4 (device error)
> > Jun  8 18:53:09 server ata1: soft resetting port
> > Jun  8 18:53:09 server ata1: softreset failed (1st FIS failed)
> > Jun  8 18:53:09 server ata1: softreset failed, retrying in 5 secs
> > 
> > Jun  8 18:53:14 server ata1: hard resetting port
> > Jun  8 18:53:22 server ata1: port is slow to respond, please be patient
> > Jun  8 18:53:45 server ata1: port failed to respond (30 secs)
> > Jun  8 18:53:45 server ata1: COMRESET failed (device not ready)
> > Jun  8 18:53:45 server ata1: hardreset failed, retrying in 5 secs
> > Jun  8 18:53:50 server ata1: hard resetting port
> > Jun  8 18:53:57 server ata1: port is slow to respond, please be patient
> > 
> > Jun  8 18:54:20 server ata1: port failed to respond (30 secs)
> > Jun  8 18:54:20 server ata1: COMRESET failed (device not ready)
> > Jun  8 18:54:20 server ata1: reset failed, giving up
> > Jun  8 18:54:20 server ata1.00: disabled
> > Jun  8 18:54:20 server ata1: EH complete
> 
> And the drive and/or port is completely deaf.
> 
> Can you please unplug power to the drive and replug it while EH is in 
> progress, umm.. say during the first hardreset?
> 


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

* Re: Seagate SATA 3GB/s drive connected to via vt8251 using ahci module
  2006-06-12 10:42   ` Aalderd Bouwman
@ 2006-06-12 10:51     ` Tejun Heo
  2006-06-12 11:24       ` Aalderd Bouwman
  0 siblings, 1 reply; 14+ messages in thread
From: Tejun Heo @ 2006-06-12 10:51 UTC (permalink / raw)
  To: aalderd.boac; +Cc: linux-ide

Aalderd Bouwman wrote:
> Jun 12 12:27:24 server ata13.00: (irq_stat 0x40000000)
> Jun 12 12:27:24 server ata13.00: tag 0 cmd 0x60 Emask 0x1 stat 0x41 err 0x4 (device error)
> Jun 12 12:27:24 server ata13.00: tag 1 cmd 0x60 Emask 0x1 stat 0x41 err 0x4 (device error)
> Jun 12 12:27:24 server ata13.00: tag 3 cmd 0x60 Emask 0x1 stat 0x41 err 0x4 (device error)
> Jun 12 12:27:24 server ata13: soft resetting port
> Jun 12 12:27:24 server ata13: softreset failed (1st FIS failed)
> Jun 12 12:27:24 server ata13: softreset failed, retrying in 5 secs
> Jun 12 12:27:29 server ata13: hard resetting port
> 
> Remove sata-connector and then the power-connector of the Seagate drive.
> (Note that I also have problems with hotplug the Maxtor-drive.
>  I will post that problem later this day.)

Can you remove and then replug those?  I wanna see whether the 
controller is locked up or the drive.

-- 
tejun

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

* Re: Seagate SATA 3GB/s drive connected to via vt8251 using ahci module
  2006-06-12 10:51     ` Tejun Heo
@ 2006-06-12 11:24       ` Aalderd Bouwman
  2006-06-18  2:56         ` Tejun Heo
  0 siblings, 1 reply; 14+ messages in thread
From: Aalderd Bouwman @ 2006-06-12 11:24 UTC (permalink / raw)
  To: Tejun Heo; +Cc: linux-ide

Tejun,

#modprobe ahci:
Jun 12 13:13:25 server ahci 0000:00:0f.0: version 1.3
Jun 12 13:13:25 server ACPI: PCI Interrupt 0000:00:0f.0[B] -> Link [LNKB] -> GSI 10 (level, low) -> IRQ 10
Jun 12 13:13:32 server ahci 0000:00:0f.0: AHCI 0001.0000 32 slots 4 ports 3 Gbps 0xf impl SATA mode
Jun 12 13:13:32 server ahci 0000:00:0f.0: flags: 64bit ncq pm led clo pmp pio slum part 
Jun 12 13:13:32 server ata17: SATA max UDMA/133 cmd 0xE048AD00 ctl 0x0 bmdma 0x0 irq 10
Jun 12 13:13:32 server ata18: SATA max UDMA/133 cmd 0xE048AD80 ctl 0x0 bmdma 0x0 irq 10
Jun 12 13:13:32 server ata19: SATA max UDMA/133 cmd 0xE048AE00 ctl 0x0 bmdma 0x0 irq 10
Jun 12 13:13:32 server ata20: SATA max UDMA/133 cmd 0xE048AE80 ctl 0x0 bmdma 0x0 irq 10
Jun 12 13:13:33 server ata17: SATA link down (SStatus 0 SControl 300)
Jun 12 13:13:33 server scsi16 : ahci
Jun 12 13:13:33 server ata18: SATA link down (SStatus 0 SControl 300)
Jun 12 13:13:33 server scsi17 : ahci
Jun 12 13:13:33 server ata19: SATA link down (SStatus 0 SControl 300)
Jun 12 13:13:33 server scsi18 : ahci
Jun 12 13:13:33 server ata20: SATA link down (SStatus 0 SControl 300)
Jun 12 13:13:33 server scsi19 : ahci

Turning on sata-drive:
Jun 12 13:13:38 server ata18: exception Emask 0x10 SAct 0x0 SErr 0x4060000 action 0x2 frozen
Jun 12 13:13:38 server ata18: (irq_stat 0x00000040, connection status changed)
Jun 12 13:13:38 server ata18: soft resetting port
Jun 12 13:13:38 server ata18: SATA link down (SStatus 1 SControl 300)
Jun 12 13:13:38 server ata18: EH complete
Jun 12 13:13:38 server ata18: exception Emask 0x10 SAct 0x0 SErr 0x4060000 action 0x2 frozen
Jun 12 13:13:38 server ata18: (irq_stat 0x00000040, connection status changed)
Jun 12 13:13:38 server ata18: soft resetting port
Jun 12 13:13:38 server ata18: SATA link down (SStatus 1 SControl 300)
Jun 12 13:13:38 server ata18: EH complete

Turing off sata-drive:
no messages

Turning on sata-drive:
Jun 12 13:13:58 server ata18: exception Emask 0x10 SAct 0x0 SErr 0x4070000 action 0x2 frozen
Jun 12 13:13:58 server ata18: (irq_stat 0x04400040, connection status changed)
Jun 12 13:13:58 server ata18: soft resetting port
Jun 12 13:13:58 server ata18: SATA link down (SStatus 1 SControl 300)
Jun 12 13:13:58 server ata18: EH complete

Turning off sata-drive:
no messages

Turning on sata-drive:
Jun 12 13:14:13 server ata18: exception Emask 0x10 SAct 0x0 SErr 0x40f0000 action 0x2 frozen
Jun 12 13:14:13 server ata18: (irq_stat 0x04400040, connection status changed)
Jun 12 13:14:13 server ata18: soft resetting port
Jun 12 13:14:13 server ata18: SATA link down (SStatus 1 SControl 300)
Jun 12 13:14:13 server ata18: EH complete

Turning off sata-drive:
no messages

#rmmod ahci:
Jun 12 13:14:23 server ACPI: PCI interrupt for device 0000:00:0f.0 disabled

Loading the ahci-driver while the drive is turned on, the drive is working properly.
I have copied a lot of data to/from this drive and compared this data to the orginal with diff.

Aalderd Bouwman.

On Mon, 2006-06-12 at 19:51 +0900, Tejun Heo wrote:
> Aalderd Bouwman wrote:
> > Jun 12 12:27:24 server ata13.00: (irq_stat 0x40000000)
> > Jun 12 12:27:24 server ata13.00: tag 0 cmd 0x60 Emask 0x1 stat 0x41 err 0x4 (device error)
> > Jun 12 12:27:24 server ata13.00: tag 1 cmd 0x60 Emask 0x1 stat 0x41 err 0x4 (device error)
> > Jun 12 12:27:24 server ata13.00: tag 3 cmd 0x60 Emask 0x1 stat 0x41 err 0x4 (device error)
> > Jun 12 12:27:24 server ata13: soft resetting port
> > Jun 12 12:27:24 server ata13: softreset failed (1st FIS failed)
> > Jun 12 12:27:24 server ata13: softreset failed, retrying in 5 secs
> > Jun 12 12:27:29 server ata13: hard resetting port
> > 
> > Remove sata-connector and then the power-connector of the Seagate drive.
> > (Note that I also have problems with hotplug the Maxtor-drive.
> >  I will post that problem later this day.)
> 
> Can you remove and then replug those?  I wanna see whether the 
> controller is locked up or the drive.
> 


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

* Re: Seagate SATA 3GB/s drive connected to via vt8251 using ahci module
  2006-06-12 11:24       ` Aalderd Bouwman
@ 2006-06-18  2:56         ` Tejun Heo
  2006-06-19 17:10           ` Aalderd Bouwman
  0 siblings, 1 reply; 14+ messages in thread
From: Tejun Heo @ 2006-06-18  2:56 UTC (permalink / raw)
  To: Aalderd Bouwman; +Cc: linux-ide

Can you apply the following patch and report back what the kernel
says?  The following might generate quite a bit of log messages, but
if your boot drive doesn't generate spurious interrupts, it should be
bearable.

diff --git a/drivers/scsi/ahci.c b/drivers/scsi/ahci.c
index e261b37..be3ee0d 100644
--- a/drivers/scsi/ahci.c
+++ b/drivers/scsi/ahci.c
@@ -909,25 +909,18 @@ static void ahci_host_intr(struct ata_po
 	}
 
 	/* hmmm... a spurious interupt */
+	ata_port_printk(ap, KERN_INFO, "spurious interrupt "
+			"(irq_stat 0x%x active_tag %x sactive 0x%x)\n",
+			status, ap->active_tag, ap->sactive);
 
-	/* some devices send D2H reg with I bit set during NCQ command phase */
-	if (ap->sactive && status & PORT_IRQ_D2H_REG_FIS)
-		return;
-
-	/* ignore interim PIO setup fis interrupts */
-	if (ata_tag_valid(ap->active_tag)) {
-		struct ata_queued_cmd *qc =
-			ata_qc_from_tag(ap, ap->active_tag);
+	if (status & PORT_IRQ_SDB_FIS) {
+		struct ahci_port_priv *pp = ap->private_data;
+		u32 *sdb_fis = pp->rx_fis + 0x58;
 
-		if (qc && qc->tf.protocol == ATA_PROT_PIO &&
-		    (status & PORT_IRQ_PIOS_FIS))
-			return;
+		ata_port_printk(ap, KERN_INFO, "spurious SDB FIS "
+				"%08x:%08x ap->qc_active=%08x qc_active=%08x\n",
+				sdb_fis[0], sdb_fis[1], ap->qc_active, qc_active);
 	}
-
-	if (ata_ratelimit())
-		ata_port_printk(ap, KERN_INFO, "spurious interrupt "
-				"(irq_stat 0x%x active_tag %d sactive 0x%x)\n",
-				status, ap->active_tag, ap->sactive);
 }
 
 static void ahci_irq_clear(struct ata_port *ap)

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

* Re: Seagate SATA 3GB/s drive connected to via vt8251 using ahci module
  2006-06-18  2:56         ` Tejun Heo
@ 2006-06-19 17:10           ` Aalderd Bouwman
  2006-06-19 17:18             ` Tejun Heo
  0 siblings, 1 reply; 14+ messages in thread
From: Aalderd Bouwman @ 2006-06-19 17:10 UTC (permalink / raw)
  To: Tejun Heo; +Cc: linux-ide

Hm, I only have seen spurious interrupts when I have executed fdisk:

Now doing fdisk /dev/sda or mke2fs /dev/sda1: 
Kernel log:
Jun  8 18:52:19 server ata1: spurious interrupt (irq_stat 0x8 active_tag -84148995 sactive 0x2)
Jun  8 18:53:09 server ata1.00: qc timeout (cmd 0x2f)
Jun  8 18:53:09 server ata1: failed to read log page 10h (errno=-5)
Jun  8 18:53:09 server ata1.00: exception Emask 0x1 SAct 0x7fffffff SErr 0x0 action 0x2 frozen
Jun  8 18:53:09 server ata1.00: (irq_stat 0x40000000)

Now I am unable to do fdisk because the drive is taken offline:

Jun 19 18:31:36 server ahci 0000:00:0f.0: version 1.3
Jun 19 18:31:36 server ACPI: PCI Interrupt 0000:00:0f.0[B] -> Link [LNKB] -> GSI 10 (level, low) -> IRQ 10
Jun 19 18:31:42 server ahci 0000:00:0f.0: AHCI 0001.0000 32 slots 4 ports 3 Gbps 0xf impl SATA mode
Jun 19 18:31:42 server ahci 0000:00:0f.0: flags: 64bit ncq pm led clo pmp pio slum part 
Jun 19 18:31:42 server ata21: SATA max UDMA/133 cmd 0xE0488D00 ctl 0x0 bmdma 0x0 irq 10
Jun 19 18:31:42 server ata22: SATA max UDMA/133 cmd 0xE0488D80 ctl 0x0 bmdma 0x0 irq 10
Jun 19 18:31:42 server ata23: SATA max UDMA/133 cmd 0xE0488E00 ctl 0x0 bmdma 0x0 irq 10
Jun 19 18:31:42 server ata24: SATA max UDMA/133 cmd 0xE0488E80 ctl 0x0 bmdma 0x0 irq 10
Jun 19 18:31:42 server ata21: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Jun 19 18:31:42 server ata21.00: cfg 49:2f00 82:346b 83:7d01 84:4023 85:3469 86:3c01 87:4023 88:007f
Jun 19 18:31:42 server ata21.00: ATA-7, max UDMA/133, 156301488 sectors: LBA48 NCQ (depth 31/32)
Jun 19 18:31:42 server ata21.00: configured for UDMA/133
Jun 19 18:31:42 server scsi20 : ahci
Jun 19 18:31:42 server ata22: SATA link down (SStatus 0 SControl 300)
Jun 19 18:31:42 server scsi21 : ahci
Jun 19 18:31:43 server ata23: SATA link down (SStatus 0 SControl 300)
Jun 19 18:31:43 server scsi22 : ahci
Jun 19 18:31:43 server ata24: SATA link down (SStatus 0 SControl 300)
Jun 19 18:31:43 server scsi23 : ahci
Jun 19 18:31:43 server Vendor: ATA       Model: ST3808110AS       Rev: 3.AA
Jun 19 18:31:43 server Type:   Direct-Access                      ANSI SCSI revision: 05
Jun 19 18:31:43 server SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
Jun 19 18:31:43 server sda: Write Protect is off
Jun 19 18:31:43 server sda: Mode Sense: 00 3a 00 00
Jun 19 18:31:43 server SCSI device sda: drive cache: write back
Jun 19 18:31:43 server SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
Jun 19 18:31:43 server sda: Write Protect is off
Jun 19 18:31:43 server sda: Mode Sense: 00 3a 00 00
Jun 19 18:31:43 server SCSI device sda: drive cache: write back
Jun 19 18:31:43 server sda: sda1 sda2 sda3 sda4 < sda5 >
Jun 19 18:31:43 server sd 20:0:0:0: Attached scsi disk sda
Jun 19 18:31:43 server sd 20:0:0:0: Attached scsi generic sg0 type 0
Jun 19 18:32:13 server ata21.00: qc timeout (cmd 0x2f)
Jun 19 18:32:13 server ata21: failed to read log page 10h (errno=-5)
Jun 19 18:32:13 server ata21.00: exception Emask 0x1 SAct 0x7 SErr 0x0 action 0x2 frozen
Jun 19 18:32:13 server ata21.00: (irq_stat 0x40000000)
Jun 19 18:32:13 server ata21.00: tag 0 cmd 0x60 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun 19 18:32:13 server ata21.00: tag 1 cmd 0x60 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun 19 18:32:13 server ata21.00: tag 2 cmd 0x60 Emask 0x1 stat 0x41 err 0x4 (device error)
Jun 19 18:32:13 server ata21: soft resetting port
Jun 19 18:32:13 server ata21: softreset failed (1st FIS failed)
Jun 19 18:32:13 server ata21: softreset failed, retrying in 5 secs
Jun 19 18:32:18 server ata21: hard resetting port
Jun 19 18:32:26 server ata21: port is slow to respond, please be patient
Jun 19 18:32:49 server ata21: port failed to respond (30 secs)
Jun 19 18:32:49 server ata21: COMRESET failed (device not ready)
Jun 19 18:32:49 server ata21: hardreset failed, retrying in 5 secs
Jun 19 18:32:54 server ata21: hard resetting port
Jun 19 18:33:01 server ata21: port is slow to respond, please be patient
Jun 19 18:33:24 server ata21: port failed to respond (30 secs)
Jun 19 18:33:24 server ata21: COMRESET failed (device not ready)
Jun 19 18:33:24 server ata21: reset failed, giving up
Jun 19 18:33:24 server ata21.00: disabled
Jun 19 18:33:24 server ata21: EH complete
Jun 19 18:33:24 server sd 20:0:0:0: SCSI error: return code = 0x40000
Jun 19 18:33:24 server end_request: I/O error, dev sda, sector 20836243
Jun 19 18:33:24 server printk: 971 messages suppressed.
Jun 19 18:33:24 server Buffer I/O error on device sda3, logical block 19551043
Jun 19 18:33:24 server sd 20:0:0:0: SCSI error: return code = 0x40000
Jun 19 18:33:24 server end_request: I/O error, dev sda, sector 273039
Jun 19 18:33:24 server Buffer I/O error on device sda1, logical block 136488
Jun 19 18:33:24 server sd 20:0:0:0: SCSI error: return code = 0x40000

This is a log with the given patch.

Maybe I should reboot the machine? (note ata1 in first given log)

Aalderd.

On Sun, 2006-06-18 at 11:56 +0900, Tejun Heo wrote: 
> Can you apply the following patch and report back what the kernel
> says?  The following might generate quite a bit of log messages, but
> if your boot drive doesn't generate spurious interrupts, it should be
> bearable.
> 
> diff --git a/drivers/scsi/ahci.c b/drivers/scsi/ahci.c
> index e261b37..be3ee0d 100644
> --- a/drivers/scsi/ahci.c
> +++ b/drivers/scsi/ahci.c
> @@ -909,25 +909,18 @@ static void ahci_host_intr(struct ata_po
>  	}
>  
>  	/* hmmm... a spurious interupt */
> +	ata_port_printk(ap, KERN_INFO, "spurious interrupt "
> +			"(irq_stat 0x%x active_tag %x sactive 0x%x)\n",
> +			status, ap->active_tag, ap->sactive);
>  
> -	/* some devices send D2H reg with I bit set during NCQ command phase */
> -	if (ap->sactive && status & PORT_IRQ_D2H_REG_FIS)
> -		return;
> -
> -	/* ignore interim PIO setup fis interrupts */
> -	if (ata_tag_valid(ap->active_tag)) {
> -		struct ata_queued_cmd *qc =
> -			ata_qc_from_tag(ap, ap->active_tag);
> +	if (status & PORT_IRQ_SDB_FIS) {
> +		struct ahci_port_priv *pp = ap->private_data;
> +		u32 *sdb_fis = pp->rx_fis + 0x58;
>  
> -		if (qc && qc->tf.protocol == ATA_PROT_PIO &&
> -		    (status & PORT_IRQ_PIOS_FIS))
> -			return;
> +		ata_port_printk(ap, KERN_INFO, "spurious SDB FIS "
> +				"%08x:%08x ap->qc_active=%08x qc_active=%08x\n",
> +				sdb_fis[0], sdb_fis[1], ap->qc_active, qc_active);
>  	}
> -
> -	if (ata_ratelimit())
> -		ata_port_printk(ap, KERN_INFO, "spurious interrupt "
> -				"(irq_stat 0x%x active_tag %d sactive 0x%x)\n",
> -				status, ap->active_tag, ap->sactive);
>  }
>  
>  static void ahci_irq_clear(struct ata_port *ap)
> 


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

* Re: Seagate SATA 3GB/s drive connected to via vt8251 using ahci module
  2006-06-19 17:10           ` Aalderd Bouwman
@ 2006-06-19 17:18             ` Tejun Heo
  0 siblings, 0 replies; 14+ messages in thread
From: Tejun Heo @ 2006-06-19 17:18 UTC (permalink / raw)
  To: aalderd.boac; +Cc: linux-ide

Aalderd Bouwman wrote:
> Hm, I only have seen spurious interrupts when I have executed fdisk:
> 
> Now doing fdisk /dev/sda or mke2fs /dev/sda1: 
> Kernel log:
> Jun  8 18:52:19 server ata1: spurious interrupt (irq_stat 0x8 active_tag -84148995 sactive 0x2)

Can you double check the patch is actually applied.  irq_stat 0x8 is 
PORT_IRQ_SDB_FIS and if the patch were applied, it should have printed a 
message starting with 'spurious SDB FIS...'

> Jun  8 18:53:09 server ata1.00: qc timeout (cmd 0x2f)
> Jun  8 18:53:09 server ata1: failed to read log page 10h (errno=-5)
> Jun  8 18:53:09 server ata1.00: exception Emask 0x1 SAct 0x7fffffff SErr 0x0 action 0x2 frozen
> Jun  8 18:53:09 server ata1.00: (irq_stat 0x40000000)
> 
> Now I am unable to do fdisk because the drive is taken offline:

The patch only adds some debug messages such that the problem can be 
analyzed better - it doesn't fix anything.

-- 
tejun

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

[parent not found: <1150745738.4302.1.camel@anja>]

[parent not found: <20060620023312.GA1250@htj.dyndns.org>]

* Re: Seagate SATA 3GB/s drive connected to via vt8251 using ahci module
       [not found]   ` <20060620023312.GA1250@htj.dyndns.org>
@ 2006-06-20 15:21     ` Aalderd Bouwman
  2006-06-20 15:37       ` Tejun Heo
  0 siblings, 1 reply; 14+ messages in thread
From: Aalderd Bouwman @ 2006-06-20 15:21 UTC (permalink / raw)
  To: Tejun Heo; +Cc: linux-ide

Oke,

About the driver I mentioned:
I don't know if it use the NCQ functionality.
It was the Windows binary of VIA.
Sorry for the missing information.

About the given patch:
I have applied the patch by hand :-)

Loading driver ahci:

Jun 20 16:28:04 server ACPI: PCI interrupt for device 0000:00:0f.0 disabled
Jun 20 16:28:07 server ahci 0000:00:0f.0: version 1.3
Jun 20 16:28:07 server ACPI: PCI Interrupt 0000:00:0f.0[B] -> Link [LNKB] -> 
GSI 10 (level, low) -> IRQ 10
Jun 20 16:28:13 server ahci 0000:00:0f.0: AHCI 0001.0000 32 slots 4 ports 3 
Gbps 0xf impl SATA mode
Jun 20 16:28:13 server ahci 0000:00:0f.0: flags: 64bit ncq pm led clo pmp pio 
slum part 
Jun 20 16:28:13 server ata29: SATA max UDMA/133 cmd 0xE048AD00 ctl 0x0 bmdma 
0x0 irq 10
Jun 20 16:28:13 server ata30: SATA max UDMA/133 cmd 0xE048AD80 ctl 0x0 bmdma 
0x0 irq 10
Jun 20 16:28:13 server ata31: SATA max UDMA/133 cmd 0xE048AE00 ctl 0x0 bmdma 
0x0 irq 10
Jun 20 16:28:13 server ata32: SATA max UDMA/133 cmd 0xE048AE80 ctl 0x0 bmdma 
0x0 irq 10
Jun 20 16:28:14 server ata29: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Jun 20 16:28:14 server ata29.00: cfg 49:2f00 82:346b 83:7d01 84:4023 85:3469 
86:3c01 87:4023 88:407f
Jun 20 16:28:14 server ata29.00: ATA-7, max UDMA/133, 156301488 sectors: LBA48 
NCQ (depth 0/32)
Jun 20 16:28:14 server ata29.00: configured for UDMA/133
Jun 20 16:28:14 server scsi28 : ahci
Jun 20 16:28:14 server ata30: SATA link down (SStatus 0 SControl 300)
Jun 20 16:28:14 server scsi29 : ahci
Jun 20 16:28:14 server ata31: SATA link down (SStatus 0 SControl 300)
Jun 20 16:28:14 server scsi30 : ahci
Jun 20 16:28:14 server ata32: SATA link down (SStatus 0 SControl 300)
Jun 20 16:28:14 server scsi31 : ahci
Jun 20 16:28:14 server Vendor: ATA       Model: ST3808110AS       Rev: 3.AA
Jun 20 16:28:14 server Type:   Direct-Access                      ANSI SCSI 
revision: 05
Jun 20 16:28:14 server SCSI device sda: 156301488 512-byte hdwr sectors (80026 
MB)
Jun 20 16:28:14 server sda: Write Protect is off
Jun 20 16:28:14 server sda: Mode Sense: 00 3a 00 00
Jun 20 16:28:14 server SCSI device sda: drive cache: write back
Jun 20 16:28:14 server SCSI device sda: 156301488 512-byte hdwr sectors (80026 
MB)
Jun 20 16:28:14 server sda: Write Protect is off
Jun 20 16:28:14 server sda: Mode Sense: 00 3a 00 00
Jun 20 16:28:14 server SCSI device sda: drive cache: write back
Jun 20 16:28:14 server sda: sda1 sda2 sda3 sda4 < sda5 >
Jun 20 16:28:14 server sd 28:0:0:0: Attached scsi disk sda
Jun 20 16:28:14 server sd 28:0:0:0: Attached scsi generic sg0 type 0

make ext2 filesystem succeeds on /dev/sda1 and also fdisk succeeds :-)

Removing driver:
Jun 20 16:29:54 server ACPI: PCI interrupt for device 0000:00:0f.0 disabled

Loading driver:
Jun 20 16:30:00 server ahci 0000:00:0f.0: version 1.3
Jun 20 16:30:00 server ACPI: PCI Interrupt 0000:00:0f.0[B] -> Link [LNKB] -> 
GSI 10 (level, low) -> IRQ 10
Jun 20 16:30:06 server ahci 0000:00:0f.0: AHCI 0001.0000 32 slots 4 ports 3 
Gbps 0xf impl SATA mode
Jun 20 16:30:06 server ahci 0000:00:0f.0: flags: 64bit ncq pm led clo pmp pio 
slum part 
Jun 20 16:30:06 server ata33: SATA max UDMA/133 cmd 0xE048AD00 ctl 0x0 bmdma 
0x0 irq 10
Jun 20 16:30:06 server ata34: SATA max UDMA/133 cmd 0xE048AD80 ctl 0x0 bmdma 
0x0 irq 10
Jun 20 16:30:06 server ata35: SATA max UDMA/133 cmd 0xE048AE00 ctl 0x0 bmdma 
0x0 irq 10
Jun 20 16:30:06 server ata36: SATA max UDMA/133 cmd 0xE048AE80 ctl 0x0 bmdma 
0x0 irq 10
Jun 20 16:30:06 server ata33: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Jun 20 16:30:06 server ata33.00: cfg 49:2f00 82:346b 83:7d01 84:4023 85:3469 
86:3c01 87:4023 88:407f
Jun 20 16:30:06 server ata33.00: ATA-7, max UDMA/133, 156301488 sectors: LBA48 
NCQ (depth 0/32)
Jun 20 16:30:06 server ata33.00: configured for UDMA/133
Jun 20 16:30:06 server scsi32 : ahci
Jun 20 16:30:06 server ata34: SATA link down (SStatus 0 SControl 300)
Jun 20 16:30:06 server scsi33 : ahci
Jun 20 16:30:06 server ata35: SATA link down (SStatus 0 SControl 300)
Jun 20 16:30:06 server scsi34 : ahci
Jun 20 16:30:07 server ata36: SATA link down (SStatus 0 SControl 300)
Jun 20 16:30:07 server scsi35 : ahci
Jun 20 16:30:07 server Vendor: ATA       Model: ST3808110AS       Rev: 3.AA
Jun 20 16:30:07 server Type:   Direct-Access                      ANSI SCSI 
revision: 05
Jun 20 16:30:07 server SCSI device sda: 156301488 512-byte hdwr sectors (80026 
MB)
Jun 20 16:30:07 server sda: Write Protect is off
Jun 20 16:30:07 server sda: Mode Sense: 00 3a 00 00
Jun 20 16:30:07 server SCSI device sda: drive cache: write back
Jun 20 16:30:07 server SCSI device sda: 156301488 512-byte hdwr sectors (80026 
MB)
Jun 20 16:30:07 server sda: Write Protect is off
Jun 20 16:30:07 server sda: Mode Sense: 00 3a 00 00
Jun 20 16:30:07 server SCSI device sda: drive cache: write back
Jun 20 16:30:07 server sda: sda1 sda2 sda3 sda4 < sda5 >
Jun 20 16:30:07 server sd 32:0:0:0: Attached scsi disk sda
Jun 20 16:30:07 server sd 32:0:0:0: Attached scsi generic sg0 type 0
Jun 20 16:30:58 server ACPI: PCI interrupt for device 0000:00:0f.0 disabled

And reloading driver :-)

Jun 20 16:31:04 server ahci 0000:00:0f.0: version 1.3
Jun 20 16:31:04 server ACPI: PCI Interrupt 0000:00:0f.0[B] -> Link [LNKB] -> 
GSI 10 (level, low) -> IRQ 10
Jun 20 16:31:09 server ahci 0000:00:0f.0: AHCI 0001.0000 32 slots 4 ports 3 
Gbps 0xf impl SATA mode
Jun 20 16:31:09 server ahci 0000:00:0f.0: flags: 64bit ncq pm led clo pmp pio 
slum part 
Jun 20 16:31:09 server ata37: SATA max UDMA/133 cmd 0xE0488D00 ctl 0x0 bmdma 
0x0 irq 10
Jun 20 16:31:09 server ata38: SATA max UDMA/133 cmd 0xE0488D80 ctl 0x0 bmdma 
0x0 irq 10
Jun 20 16:31:09 server ata39: SATA max UDMA/133 cmd 0xE0488E00 ctl 0x0 bmdma 
0x0 irq 10
Jun 20 16:31:09 server ata40: SATA max UDMA/133 cmd 0xE0488E80 ctl 0x0 bmdma 
0x0 irq 10
Jun 20 16:31:10 server ata37: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Jun 20 16:31:10 server ata37.00: cfg 49:2f00 82:346b 83:7d01 84:4023 85:3469 
86:3c01 87:4023 88:407f
Jun 20 16:31:10 server ata37.00: ATA-7, max UDMA/133, 156301488 sectors: LBA48 
NCQ (depth 0/32)
Jun 20 16:31:10 server ata37.00: configured for UDMA/133
Jun 20 16:31:10 server scsi36 : ahci
Jun 20 16:31:10 server ata38: SATA link down (SStatus 0 SControl 300)
Jun 20 16:31:10 server scsi37 : ahci
Jun 20 16:31:10 server ata39: SATA link down (SStatus 0 SControl 300)
Jun 20 16:31:10 server scsi38 : ahci
Jun 20 16:31:10 server ata40: SATA link down (SStatus 0 SControl 300)
Jun 20 16:31:10 server scsi39 : ahci
Jun 20 16:31:10 server Vendor: ATA       Model: ST3808110AS       Rev: 3.AA
Jun 20 16:31:10 server Type:   Direct-Access                      ANSI SCSI 
revision: 05
Jun 20 16:31:10 server SCSI device sda: 156301488 512-byte hdwr sectors (80026 
MB)
Jun 20 16:31:10 server sda: Write Protect is off
Jun 20 16:31:10 server sda: Mode Sense: 00 3a 00 00
Jun 20 16:31:10 server SCSI device sda: drive cache: write back
Jun 20 16:31:10 server SCSI device sda: 156301488 512-byte hdwr sectors (80026 
MB)
Jun 20 16:31:10 server sda: Write Protect is off
Jun 20 16:31:10 server sda: Mode Sense: 00 3a 00 00
Jun 20 16:31:10 server SCSI device sda: drive cache: write back
Jun 20 16:31:10 server sda: sda1 sda2 sda3 sda4 < sda5 >

Changing the partition-table on the disk (remove logical and creating 2 
logical partitions):

Jun 20 16:31:10 server sd 36:0:0:0: Attached scsi disk sda
Jun 20 16:31:10 server sd 36:0:0:0: Attached scsi generic sg0 type 0
Jun 20 16:32:49 server SCSI device sda: 156301488 512-byte hdwr sectors (80026 
MB)
Jun 20 16:32:49 server sda: Write Protect is off
Jun 20 16:32:49 server sda: Mode Sense: 00 3a 00 00
Jun 20 16:32:49 server SCSI device sda: drive cache: write back
Jun 20 16:32:49 server sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
Jun 20 16:32:51 server SCSI device sda: 156301488 512-byte hdwr sectors (80026 
MB)
Jun 20 16:32:51 server sda: Write Protect is off
Jun 20 16:32:51 server sda: Mode Sense: 00 3a 00 00
Jun 20 16:32:51 server SCSI device sda: drive cache: write back
Jun 20 16:32:51 server sda: sda1 sda2 sda3 sda4 < sda5 sda6 >

Make reiserfs 3.x filesystem and mount /dev/sda5:

Jun 20 16:34:21 server ReiserFS: sda5: found reiserfs format "3.6" with 
standard journal
Jun 20 16:34:24 server ReiserFS: sda5: using ordered data mode
Jun 20 16:34:24 server ReiserFS: sda5: journal params: device sda5, size 8192, 
journal first block 18, max trans len 1024, max batch 900, max commit age 30, 
max trans age 30
Jun 20 16:34:24 server ReiserFS: sda5: checking transaction log (sda5)
Jun 20 16:34:24 server ReiserFS: sda5: Using r5 hash to sort names

Copy 5gig data and compare it 4 times with success :-)

Unmount and remove driver:
Jun 20 17:06:30 ACPI: PCI interrupt for device 0000:00:0f.0 disabled

As you can see it works.
Now I will check the hotplug functionality and if you have a patch for maybe 
better NCQ usage I want to test him for you.

Aalderd.

On Tuesday 20 June 2006 04:33, Tejun Heo wrote:
> On Mon, Jun 19, 2006 at 09:35:38PM +0200, Aalderd Bouwman wrote:
> > Note the date: jun 8 :)
> >
> > As I said I cannot fdisk because the drive isn't available.
> > So I don't receive spurious message anymore.
> >
> > In all other logs I have posted I don't have the 'spurious message.
>
> I see, so the spurious interrupt isn't related.  Can you try the
> following patch?  It simply disable NCQ on via AHCI.  Does the via
> driver you mentioned do NCQ?  Is the driver open-source?
>
> diff --git a/drivers/scsi/ahci.c b/drivers/scsi/ahci.c
> index e261b37..8208546 100644
> --- a/drivers/scsi/ahci.c
> +++ b/drivers/scsi/ahci.c
> @@ -164,6 +164,7 @@ enum {
>
>  	/* ap->flags bits */
>  	AHCI_FLAG_RESET_NEEDS_CLO	= (1 << 24),
> +	AHCI_FLAG_NO_NCQ		= (1 << 25),
>  };
>
>  struct ahci_cmd_hdr {
> @@ -277,7 +278,7 @@ static const struct ata_port_info ahci_p
>  		.host_flags	= ATA_FLAG_SATA | ATA_FLAG_NO_LEGACY |
>  				  ATA_FLAG_MMIO | ATA_FLAG_PIO_DMA |
>  				  ATA_FLAG_SKIP_D2H_BSY |
> -				  AHCI_FLAG_RESET_NEEDS_CLO,
> +				  AHCI_FLAG_RESET_NEEDS_CLO | AHCI_FLAG_NO_NCQ,
>  		.pio_mask	= 0x1f, /* pio0-4 */
>  		.udma_mask	= 0x7f, /* udma0-6 ; FIXME */
>  		.port_ops	= &ahci_ops,
> @@ -1362,7 +1363,8 @@ static int ahci_init_one (struct pci_dev
>  	if (rc)
>  		goto err_out_hpriv;
>
> -	if (hpriv->cap & HOST_CAP_NCQ)
> +	if (!(probe_ent->host_flags & AHCI_FLAG_NO_NCQ) &&
> +	    (hpriv->cap & HOST_CAP_NCQ))
>  		probe_ent->host_flags |= ATA_FLAG_NCQ;
>
>  	ahci_print_info(probe_ent);

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

* Re: Seagate SATA 3GB/s drive connected to via vt8251 using ahci module
  2006-06-20 15:21     ` Aalderd Bouwman
@ 2006-06-20 15:37       ` Tejun Heo
  2006-06-20 19:31         ` Bastiaan Jacques
  0 siblings, 1 reply; 14+ messages in thread
From: Tejun Heo @ 2006-06-20 15:37 UTC (permalink / raw)
  To: boac; +Cc: linux-ide, b.jacques

[CC'ing Bastiaan Jacques. Hi!]

Aalderd Bouwman wrote:
> Copy 5gig data and compare it 4 times with success :-)
> 
> Unmount and remove driver:
> Jun 20 17:06:30 ACPI: PCI interrupt for device 0000:00:0f.0 disabled
> 
> As you can see it works.
> Now I will check the hotplug functionality and if you have a patch for maybe 
> better NCQ usage I want to test him for you.

Do you happen to have a different drive which can do NCQ?  Preferably, 
one from another vendor?  AFAICS, the ahci driver is doing NCQ as the 
AHCI spec specifies and all ICH controllers seem to be quite happy with 
it.  I wanna know whether the problem is from the specific combination 
of the controller and the drive or the via controller needs a different 
programming sequence for NCQ in general.  Does the Windows driver do NCQ?

Bastiaan, do you know whether we need to do things differently to use 
NCQ on vt8251?

Thanks.

-- 
tejun

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

* Re: Seagate SATA 3GB/s drive connected to via vt8251 using ahci module
  2006-06-20 15:37       ` Tejun Heo
@ 2006-06-20 19:31         ` Bastiaan Jacques
  2006-06-21  7:25           ` Aalderd Bouwman
  2006-06-21 14:12           ` [PATCH] ahci: disable NCQ support on vt8251 Tejun Heo
  0 siblings, 2 replies; 14+ messages in thread
From: Bastiaan Jacques @ 2006-06-20 19:31 UTC (permalink / raw)
  To: Tejun Heo; +Cc: boac, linux-ide

On Tuesday 20 June 2006 17:37, Tejun Heo wrote:
> [CC'ing Bastiaan Jacques. Hi!]

Hey!

> Do you happen to have a different drive which can do NCQ? 
> Preferably, one from another vendor?  

I've just tried it with a Maxtor drive supporting NCQ, and it didn't 
work.

> AFAICS, the ahci driver is 
> doing NCQ as the AHCI spec specifies and all ICH controllers seem to
> be quite happy with it.  I wanna know whether the problem is from the
> specific combination of the controller and the drive or the via
> controller needs a different programming sequence for NCQ in general.

It would seem the problem is in the VIA controller. Surprise, surprise.

> Bastiaan, do you know whether we need to do things differently to use
> NCQ on vt8251?

Apparently we have a hardware or firmware bug to work around, but I have 
no idea what we should be doing differently.

-- 
Bastiaan

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

* Re: Seagate SATA 3GB/s drive connected to via vt8251 using ahci module
  2006-06-20 19:31         ` Bastiaan Jacques
@ 2006-06-21  7:25           ` Aalderd Bouwman
  2006-06-21 14:12           ` [PATCH] ahci: disable NCQ support on vt8251 Tejun Heo
  1 sibling, 0 replies; 14+ messages in thread
From: Aalderd Bouwman @ 2006-06-21  7:25 UTC (permalink / raw)
  To: Bastiaan Jacques; +Cc: Tejun Heo, linux-ide

Maybe it is an idea to disable NCQ for vt8251 compile-time (kernel-option) 
until it works correctly?

On Tuesday 20 June 2006 21:31, Bastiaan Jacques wrote:
> On Tuesday 20 June 2006 17:37, Tejun Heo wrote:
> > [CC'ing Bastiaan Jacques. Hi!]
>
> Hey!
>
> > Do you happen to have a different drive which can do NCQ?
> > Preferably, one from another vendor?
>
> I've just tried it with a Maxtor drive supporting NCQ, and it didn't
> work.
>
> > AFAICS, the ahci driver is
> > doing NCQ as the AHCI spec specifies and all ICH controllers seem to
> > be quite happy with it.  I wanna know whether the problem is from the
> > specific combination of the controller and the drive or the via
> > controller needs a different programming sequence for NCQ in general.
>
> It would seem the problem is in the VIA controller. Surprise, surprise.
>
> > Bastiaan, do you know whether we need to do things differently to use
> > NCQ on vt8251?
>
> Apparently we have a hardware or firmware bug to work around, but I have
> no idea what we should be doing differently.

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

* [PATCH] ahci: disable NCQ support on vt8251
  2006-06-20 19:31         ` Bastiaan Jacques
  2006-06-21  7:25           ` Aalderd Bouwman
@ 2006-06-21 14:12           ` Tejun Heo
  2006-06-23  3:22             ` Jeff Garzik
  1 sibling, 1 reply; 14+ messages in thread
From: Tejun Heo @ 2006-06-21 14:12 UTC (permalink / raw)
  To: jeff; +Cc: boac, b.jacques, linux-ide

vt8251 chokes on NCQ commands.  Two different disks from different
vendors are showing the same symptom and it seems that the windows
driver from via doesn't support NCQ either.  Disable NCQ support on
this controller for the time being.

Signed-off-by: Tejun Heo <htejun@gmail.com>
Cc: Aalderd Bouwman <boac@wanadoo.nl>
Cc: Bastiaan Jacques <b.jacques@planet.nl>
---

diff --git a/drivers/scsi/ahci.c b/drivers/scsi/ahci.c
index e261b37..8208546 100644
--- a/drivers/scsi/ahci.c
+++ b/drivers/scsi/ahci.c
@@ -164,6 +164,7 @@ enum {
 
 	/* ap->flags bits */
 	AHCI_FLAG_RESET_NEEDS_CLO	= (1 << 24),
+	AHCI_FLAG_NO_NCQ		= (1 << 25),
 };
 
 struct ahci_cmd_hdr {
@@ -277,7 +278,7 @@ static const struct ata_port_info ahci_p
 		.host_flags	= ATA_FLAG_SATA | ATA_FLAG_NO_LEGACY |
 				  ATA_FLAG_MMIO | ATA_FLAG_PIO_DMA |
 				  ATA_FLAG_SKIP_D2H_BSY |
-				  AHCI_FLAG_RESET_NEEDS_CLO,
+				  AHCI_FLAG_RESET_NEEDS_CLO | AHCI_FLAG_NO_NCQ,
 		.pio_mask	= 0x1f, /* pio0-4 */
 		.udma_mask	= 0x7f, /* udma0-6 ; FIXME */
 		.port_ops	= &ahci_ops,
@@ -1362,7 +1363,8 @@ static int ahci_init_one (struct pci_dev
 	if (rc)
 		goto err_out_hpriv;
 
-	if (hpriv->cap & HOST_CAP_NCQ)
+	if (!(probe_ent->host_flags & AHCI_FLAG_NO_NCQ) &&
+	    (hpriv->cap & HOST_CAP_NCQ))
 		probe_ent->host_flags |= ATA_FLAG_NCQ;
 
 	ahci_print_info(probe_ent);

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

* Re: [PATCH] ahci: disable NCQ support on vt8251
  2006-06-21 14:12           ` [PATCH] ahci: disable NCQ support on vt8251 Tejun Heo
@ 2006-06-23  3:22             ` Jeff Garzik
  0 siblings, 0 replies; 14+ messages in thread
From: Jeff Garzik @ 2006-06-23  3:22 UTC (permalink / raw)
  To: Tejun Heo; +Cc: boac, b.jacques, linux-ide

applied


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

end of thread, other threads:[~2006-06-23  3:22 UTC | newest]

Thread overview: 14+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2006-06-08 17:30 Seagate SATA 3GB/s drive connected to via vt8251 using ahci module Aalderd Bouwman
2006-06-09  5:44 ` Tejun Heo
2006-06-12 10:42   ` Aalderd Bouwman
2006-06-12 10:51     ` Tejun Heo
2006-06-12 11:24       ` Aalderd Bouwman
2006-06-18  2:56         ` Tejun Heo
2006-06-19 17:10           ` Aalderd Bouwman
2006-06-19 17:18             ` Tejun Heo
     [not found] ` <1150745738.4302.1.camel@anja>
     [not found]   ` <20060620023312.GA1250@htj.dyndns.org>
2006-06-20 15:21     ` Aalderd Bouwman
2006-06-20 15:37       ` Tejun Heo
2006-06-20 19:31         ` Bastiaan Jacques
2006-06-21  7:25           ` Aalderd Bouwman
2006-06-21 14:12           ` [PATCH] ahci: disable NCQ support on vt8251 Tejun Heo
2006-06-23  3:22             ` Jeff Garzik

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).