Re: 2.6.29 regression: ATA bus errors on resume

From: Niel Lambrechts
Date: Thu Apr 02 2009 - 02:20:31 EST

Next message: Lai Jiangshan: "[PATCH] tracing: mark get_parent_ip() notrace"
Previous message: Herbert Xu: "Re: Crypto Update for 2.6.30"
In reply to: Tejun Heo: "Re: 2.6.29 regression: ATA bus errors on resume"
Next in thread: Tejun Heo: "Re: 2.6.29 regression: ATA bus errors on resume"
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]

On 04/02/2009 03:50 AM, Tejun Heo wrote:
> Hello,
>
> Sorry about the delay.
>
> Niel Lambrechts wrote:
>
>> I think I should be able to reproduce when actively using X with 2.6.29,
>> and I have an external disk where I could backup to / boot from if the
>> corruption became a problem.
>>
>> These issues are keeping me from 2.6.29 so I'll gladly help where I can,
>> if you can please provide me the patches and the .config settings that
>> may be required?
>>
>
> Attached is the debug patch. Please reproduce the problem with the
> patch applied and post full kernel log.
>
Hi there,

Here is the output, it took 2 attempts to reproduce the EXT4 corruption
(clean with errors).

Niel

Apr 2 08:13:00 linux-7vph kernel: Syncing filesystems ... done.
Apr 2 08:13:01 linux-7vph kernel: Freezing user space processes ... (elapsed 0.00 seconds) done.
Apr 2 08:13:01 linux-7vph kernel: Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
Apr 2 08:13:01 linux-7vph kernel: PM: Shrinking memory... done (48421 pages freed)
Apr 2 08:13:01 linux-7vph kernel: PM: Freed 193684 kbytes in 3.08 seconds (62.88 MB/s)
Apr 2 08:13:01 linux-7vph kernel: Suspending console(s) (use no_console_suspend to debug)
Apr 2 08:13:01 linux-7vph kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Apr 2 08:13:01 linux-7vph kernel: ACPI handle has no context!
Apr 2 08:13:01 linux-7vph kernel: iwlagn 0000:03:00.0: PCI INT A disabled
Apr 2 08:13:01 linux-7vph kernel: ata1: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
Apr 2 08:13:01 linux-7vph kernel: ata2: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
Apr 2 08:13:02 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PCI INT D disabled
Apr 2 08:13:02 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
Apr 2 08:13:02 linux-7vph kernel: uhci_hcd 0000:00:1d.2: PCI INT C disabled
Apr 2 08:13:02 linux-7vph kernel: uhci_hcd 0000:00:1d.1: PCI INT B disabled
Apr 2 08:13:02 linux-7vph kernel: uhci_hcd 0000:00:1d.0: PCI INT A disabled
Apr 2 08:13:02 linux-7vph kernel: HDA Intel 0000:00:1b.0: PCI INT B disabled
Apr 2 08:13:02 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PCI INT D disabled
Apr 2 08:13:02 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
Apr 2 08:13:02 linux-7vph kernel: uhci_hcd 0000:00:1a.2: PCI INT C disabled
Apr 2 08:13:02 linux-7vph kernel: uhci_hcd 0000:00:1a.1: PCI INT B disabled
Apr 2 08:13:04 linux-7vph kernel: uhci_hcd 0000:00:1a.0: PCI INT A disabled
Apr 2 08:13:04 linux-7vph kernel: e1000e 0000:00:19.0: PME# enabled
Apr 2 08:13:04 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Apr 2 08:13:04 linux-7vph kernel: e1000e 0000:00:19.0: PME# enabled
Apr 2 08:13:04 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Apr 2 08:13:04 linux-7vph kernel: e1000e 0000:00:19.0: PCI INT A disabled
Apr 2 08:13:04 linux-7vph kernel: ACPI: Preparing to enter system sleep state S4
Apr 2 08:13:04 linux-7vph kernel: PM: Saving platform NVS memory
Apr 2 08:13:04 linux-7vph kernel: Disabling non-boot CPUs ...
Apr 2 08:13:04 linux-7vph kernel: CPU 1 is now offline
Apr 2 08:13:04 linux-7vph kernel: SMP alternatives: switching to UP code
Apr 2 08:13:04 linux-7vph kernel: CPU0 attaching NULL sched-domain.
Apr 2 08:13:04 linux-7vph kernel: CPU1 attaching NULL sched-domain.
Apr 2 08:13:04 linux-7vph kernel: CPU0 attaching NULL sched-domain.
Apr 2 08:13:04 linux-7vph kernel: CPU1 is down
Apr 2 08:13:04 linux-7vph kernel: Extended CMOS year: 2000
Apr 2 08:13:04 linux-7vph kernel: PM: Creating hibernation image:
Apr 2 08:13:04 linux-7vph kernel: PM: Need to copy 125230 pages
Apr 2 08:13:04 linux-7vph kernel: x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
Apr 2 08:13:04 linux-7vph kernel: Intel machine check architecture supported.
Apr 2 08:13:04 linux-7vph kernel: Intel machine check reporting enabled on CPU#0.
Apr 2 08:13:04 linux-7vph kernel: PM: Restoring platform NVS memory
Apr 2 08:13:04 linux-7vph kernel: Extended CMOS year: 2000
Apr 2 08:13:04 linux-7vph kernel: pci 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900403)
Apr 2 08:13:04 linux-7vph kernel: HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102)
Apr 2 08:13:04 linux-7vph kernel: ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00403, writing 0x2b00407)
Apr 2 08:13:04 linux-7vph kernel: Enabling non-boot CPUs ...
Apr 2 08:13:04 linux-7vph kernel: SMP alternatives: switching to SMP code
Apr 2 08:13:04 linux-7vph kernel: Booting processor 1 APIC 0x1 ip 0x6000
Apr 2 08:13:04 linux-7vph kernel: Initializing CPU#1
Apr 2 08:13:04 linux-7vph kernel: Calibrating delay using timer specific routine.. 5054.19 BogoMIPS (lpj=10108381)
Apr 2 08:13:04 linux-7vph kernel: CPU: L1 I cache: 32K, L1 D cache: 32K
Apr 2 08:13:04 linux-7vph kernel: CPU: L2 cache: 6144K
Apr 2 08:13:04 linux-7vph kernel: [ds] using Core 2/Atom configuration
Apr 2 08:13:04 linux-7vph kernel: CPU: Physical Processor ID: 0
Apr 2 08:13:04 linux-7vph kernel: CPU: Processor Core ID: 1
Apr 2 08:13:04 linux-7vph kernel: Intel machine check architecture supported.
Apr 2 08:13:04 linux-7vph kernel: Intel machine check reporting enabled on CPU#1.
Apr 2 08:13:04 linux-7vph kernel: x86 PAT enabled: cpu 1, old 0x7040600070406, new 0x7010600070106
Apr 2 08:13:04 linux-7vph kernel: CPU1: Intel(R) Core(TM)2 Duo CPU T9400 @ 2.53GHz stepping 06
Apr 2 08:13:04 linux-7vph kernel: CPU0 attaching NULL sched-domain.
Apr 2 08:13:04 linux-7vph kernel: Switched to high resolution mode on CPU 1
Apr 2 08:13:04 linux-7vph kernel: CPU0 attaching sched-domain:
Apr 2 08:13:04 linux-7vph kernel: domain 0: span 0-1 level MC
Apr 2 08:13:04 linux-7vph kernel: groups: 0 1
Apr 2 08:13:04 linux-7vph kernel: domain 1: span 0-1 level CPU
Apr 2 08:13:04 linux-7vph kernel: groups: 0-1
Apr 2 08:13:04 linux-7vph kernel: CPU1 attaching sched-domain:
Apr 2 08:13:04 linux-7vph kernel: domain 0: span 0-1 level MC
Apr 2 08:13:04 linux-7vph kernel: groups: 1 0
Apr 2 08:13:04 linux-7vph kernel: domain 1: span 0-1 level CPU
Apr 2 08:13:04 linux-7vph kernel: groups: 0-1
Apr 2 08:13:04 linux-7vph kernel: CPU1 is up
Apr 2 08:13:04 linux-7vph kernel: ACPI: Waking up from system sleep state S4
Apr 2 08:13:04 linux-7vph kernel: ACPI: EC: non-query interrupt received, switching to interrupt mode
Apr 2 08:13:04 linux-7vph kernel: pci 0000:00:02.0: PME# disabled
Apr 2 08:13:04 linux-7vph kernel: pci 0000:00:02.0: power state changed by ACPI to D0
Apr 2 08:13:04 linux-7vph kernel: pci 0000:00:02.0: setting latency timer to 64
Apr 2 08:13:04 linux-7vph kernel: pci 0000:00:02.1: PME# disabled
Apr 2 08:13:04 linux-7vph kernel: pci 0000:00:03.0: PME# disabled
Apr 2 08:13:04 linux-7vph kernel: e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
Apr 2 08:13:04 linux-7vph kernel: e1000e 0000:00:19.0: setting latency timer to 64
Apr 2 08:13:04 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Apr 2 08:13:04 linux-7vph kernel: e1000e 0000:00:19.0: PME# disabled
Apr 2 08:13:04 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Apr 2 08:13:04 linux-7vph kernel: e1000e 0000:00:19.0: PME# disabled
Apr 2 08:13:04 linux-7vph kernel: e1000e 0000:00:19.0: irq 29 for MSI/MSI-X
Apr 2 08:13:04 linux-7vph kernel: uhci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Apr 2 08:13:04 linux-7vph kernel: uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
Apr 2 08:13:04 linux-7vph kernel: uhci_hcd 0000:00:1a.0: setting latency timer to 64
Apr 2 08:13:04 linux-7vph kernel: uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21
Apr 2 08:13:04 linux-7vph kernel: uhci_hcd 0000:00:1a.1: setting latency timer to 64
Apr 2 08:13:04 linux-7vph kernel: uhci_hcd 0000:00:1a.2: power state changed by ACPI to D0
Apr 2 08:13:04 linux-7vph kernel: uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 22 (level, low) -> IRQ 22
Apr 2 08:13:04 linux-7vph kernel: uhci_hcd 0000:00:1a.2: setting latency timer to 64
Apr 2 08:13:04 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
Apr 2 08:13:04 linux-7vph kernel: ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0
Apr 2 08:13:04 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PCI INT D -> GSI 23 (level, low) -> IRQ 23
Apr 2 08:13:04 linux-7vph kernel: ehci_hcd 0000:00:1a.7: setting latency timer to 64
Apr 2 08:13:04 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
Apr 2 08:13:04 linux-7vph kernel: HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Apr 2 08:13:04 linux-7vph kernel: HDA Intel 0000:00:1b.0: setting latency timer to 64
Apr 2 08:13:04 linux-7vph kernel: pcieport-driver 0000:00:1c.0: setting latency timer to 64
Apr 2 08:13:04 linux-7vph kernel: pcieport-driver 0000:00:1c.1: setting latency timer to 64
Apr 2 08:13:04 linux-7vph kernel: pcieport-driver 0000:00:1c.3: setting latency timer to 64
Apr 2 08:13:04 linux-7vph kernel: pcieport-driver 0000:00:1c.4: setting latency timer to 64
Apr 2 08:13:04 linux-7vph kernel: uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Apr 2 08:13:04 linux-7vph kernel: uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Apr 2 08:13:04 linux-7vph kernel: uhci_hcd 0000:00:1d.0: setting latency timer to 64
Apr 2 08:13:04 linux-7vph kernel: uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Apr 2 08:13:04 linux-7vph kernel: uhci_hcd 0000:00:1d.1: setting latency timer to 64
Apr 2 08:13:04 linux-7vph kernel: uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
Apr 2 08:13:04 linux-7vph kernel: uhci_hcd 0000:00:1d.2: setting latency timer to 64
Apr 2 08:13:04 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
Apr 2 08:13:04 linux-7vph kernel: ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0
Apr 2 08:13:04 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19
Apr 2 08:13:04 linux-7vph kernel: ehci_hcd 0000:00:1d.7: setting latency timer to 64
Apr 2 08:13:04 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
Apr 2 08:13:04 linux-7vph kernel: pci 0000:00:1e.0: setting latency timer to 64
Apr 2 08:13:04 linux-7vph kernel: ahci 0000:00:1f.2: setting latency timer to 64
Apr 2 08:13:04 linux-7vph kernel: ata1: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x16 frozen
Apr 2 08:13:04 linux-7vph kernel: ata2: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x16 frozen
Apr 2 08:13:04 linux-7vph kernel: iwlagn 0000:03:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
Apr 2 08:13:04 linux-7vph kernel: iwlagn 0000:03:00.0: irq 30 for MSI/MSI-X
Apr 2 08:13:04 linux-7vph kernel: pci 0000:15:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Apr 2 08:13:04 linux-7vph kernel: Registered led device: iwl-phy0:radio
Apr 2 08:13:04 linux-7vph kernel: Registered led device: iwl-phy0:assoc
Apr 2 08:13:04 linux-7vph kernel: Registered led device: iwl-phy0:RX
Apr 2 08:13:04 linux-7vph kernel: Registered led device: iwl-phy0:TX
Apr 2 08:13:04 linux-7vph kernel: ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f4801000-f48017ff] Max Packet=[2048] IR/IT contexts=[4/4]
Apr 2 08:13:04 linux-7vph kernel: pci 0000:15:00.2: PME# disabled
Apr 2 08:13:04 linux-7vph kernel: pci 0000:15:00.3: PME# disabled
Apr 2 08:13:04 linux-7vph kernel: pci 0000:15:00.4: PME# disabled
Apr 2 08:13:04 linux-7vph kernel: pci 0000:15:00.5: PME# disabled
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Starting disk
Apr 2 08:13:04 linux-7vph kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Apr 2 08:13:04 linux-7vph kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Apr 2 08:13:04 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Apr 2 08:13:04 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Apr 2 08:13:04 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Apr 2 08:13:04 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Apr 2 08:13:04 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Apr 2 08:13:04 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Apr 2 08:13:04 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Apr 2 08:13:04 linux-7vph kernel: ata1.00: configured for UDMA/133
Apr 2 08:13:04 linux-7vph kernel: ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
Apr 2 08:13:04 linux-7vph kernel: ata1: irq_stat 0x00400040, connection status changed
Apr 2 08:13:04 linux-7vph kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded
Apr 2 08:13:04 linux-7vph kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded
Apr 2 08:13:04 linux-7vph kernel: ata1.00: configured for UDMA/133
Apr 2 08:13:04 linux-7vph kernel: ata1: EH complete
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Apr 2 08:13:04 linux-7vph kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded
Apr 2 08:13:04 linux-7vph kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded
Apr 2 08:13:04 linux-7vph kernel: ata2.00: configured for UDMA/133
Apr 2 08:13:04 linux-7vph kernel: ata2: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
Apr 2 08:13:04 linux-7vph kernel: ata2: irq_stat 0x40000001
Apr 2 08:13:04 linux-7vph kernel: ata2.00: configured for UDMA/133
Apr 2 08:13:04 linux-7vph kernel: ata2: EH complete
Apr 2 08:13:04 linux-7vph kernel: pci 0000:00:02.0: power state changed by ACPI to D0
Apr 2 08:13:04 linux-7vph kernel: pci 0000:00:02.0: restoring config space at offset 0x1 (was 0x900407, writing 0x900403)
Apr 2 08:13:04 linux-7vph kernel: pci 0000:00:02.0: setting latency timer to 64
Apr 2 08:13:04 linux-7vph kernel: Restarting tasks ... done.
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc1
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc2
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc3
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc4
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc5
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc6
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc7
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc8
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc9
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc10
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc11
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc12
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc13
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc14
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc15
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc16
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc17
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc18
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc19
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc20
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc21
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc22
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc23
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc24
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc25
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc26
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX setting retry on qc27
Apr 2 08:13:04 linux-7vph kernel: ata1: limiting SATA link speed to 1.5 Gbps
Apr 2 08:13:04 linux-7vph kernel: ata1.00: exception Emask 0x10 SAct 0xfffffff SErr 0x50000 action 0xe frozen
Apr 2 08:13:04 linux-7vph kernel: ata1.00: irq_stat 0x00400008, PHY RDY changed
Apr 2 08:13:04 linux-7vph kernel: ata1: SError: { PHYRdyChg CommWake }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/18:00:6f:b1:68/00:00:0c:00:00/40 tag 0 ncq 12288 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/08:08:8f:b1:68/00:00:0c:00:00/40 tag 1 ncq 4096 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/38:10:5f:89:67/00:00:0e:00:00/40 tag 2 ncq 28672 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/c8:18:9f:89:67/00:00:0e:00:00/40 tag 3 ncq 102400 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/18:20:8f:9b:14/00:00:0f:00:00/40 tag 4 ncq 12288 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/08:28:ef:9b:14/00:00:0f:00:00/40 tag 5 ncq 4096 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/08:30:5f:08:c8/00:00:0c:00:00/40 tag 6 ncq 4096 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/30:38:b7:64:9e/00:00:0b:00:00/40 tag 7 ncq 24576 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/68:40:ef:64:9e/00:00:0b:00:00/40 tag 8 ncq 53248 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/58:48:5f:65:9e/00:00:0b:00:00/40 tag 9 ncq 45056 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/40:50:d1:95:b6/00:00:09:00:00/40 tag 10 ncq 32768 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/40:58:51:7c:b6/00:00:09:00:00/40 tag 11 ncq 32768 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/28:60:07:55:45/00:00:0c:00:00/40 tag 12 ncq 20480 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/38:68:37:55:45/00:00:0c:00:00/40 tag 13 ncq 28672 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/28:70:77:84:0f/00:00:0c:00:00/40 tag 14 ncq 20480 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/18:78:a7:84:0f/00:00:0c:00:00/40 tag 15 ncq 12288 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/20:80:57:73:98/00:00:0d:00:00/40 tag 16 ncq 16384 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/08:88:7f:73:98/00:00:0d:00:00/40 tag 17 ncq 4096 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/18:90:8f:73:98/00:00:0d:00:00/40 tag 18 ncq 12288 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/30:98:af:73:98/00:00:0d:00:00/40 tag 19 ncq 24576 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/20:a0:ef:73:98/00:00:0d:00:00/40 tag 20 ncq 16384 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/28:a8:17:74:98/00:00:0d:00:00/40 tag 21 ncq 20480 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/08:b0:e7:cd:11/00:00:0f:00:00/40 tag 22 ncq 4096 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/f0:b8:5f:e4:16/00:00:0f:00:00/40 tag 23 ncq 122880 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/38:c0:5f:64:9e/00:00:0b:00:00/40 tag 24 ncq 28672 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/18:c8:9f:64:9e/00:00:0b:00:00/40 tag 25 ncq 12288 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/c0:d0:17:02:3a/00:00:0d:00:00/40 tag 26 ncq 98304 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1.00: cmd 60/38:d8:df:02:3a/00:00:0d:00:00/40 tag 27 ncq 28672 in
Apr 2 08:13:04 linux-7vph kernel: res 50/00:38:df:02:3a/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: status: { DRDY }
Apr 2 08:13:04 linux-7vph kernel: ata1: hard resetting link
Apr 2 08:13:04 linux-7vph kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Apr 2 08:13:04 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Apr 2 08:13:04 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Apr 2 08:13:04 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Apr 2 08:13:04 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Apr 2 08:13:04 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Apr 2 08:13:04 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Apr 2 08:13:04 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Apr 2 08:13:04 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Apr 2 08:13:04 linux-7vph kernel: ata1.00: configured for UDMA/133
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc0, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc1, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc2, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc3, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc4, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc5, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc6, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc7, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc8, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc9, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc10, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc11, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc12, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc13, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc14, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc15, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc16, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc17, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc18, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc19, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc20, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc21, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc22, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc23, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc24, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc25, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc26, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1.00: XXX retrying qc27, retries=0
Apr 2 08:13:04 linux-7vph kernel: ata1: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 t4
Apr 2 08:13:04 linux-7vph kernel: ata1.00: configured for UDMA/133
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE,SUGGEST_OK
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Sense Key : Aborted Command [current] [descriptor]
Apr 2 08:13:04 linux-7vph kernel: Descriptor sense data with sense descriptors (in hex):
Apr 2 08:13:04 linux-7vph kernel: 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
Apr 2 08:13:04 linux-7vph kernel: 0d 3a 02 df
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Add. Sense: No additional sense information
Apr 2 08:13:04 linux-7vph kernel: end_request: I/O error, dev sda, sector 241666399
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE,SUGGEST_OK
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Sense Key : Aborted Command [current] [descriptor]
Apr 2 08:13:04 linux-7vph kernel: Descriptor sense data with sense descriptors (in hex):
Apr 2 08:13:04 linux-7vph kernel: 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
Apr 2 08:13:04 linux-7vph kernel: 0d 3a 02 df
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Add. Sense: No additional sense information
Apr 2 08:13:04 linux-7vph kernel: end_request: I/O error, dev sda, sector 241666463
Apr 2 08:13:04 linux-7vph kernel: ata1: EH complete
Apr 2 08:13:04 linux-7vph kernel: EXT4-fs error (device sda6): __ext4_get_inode_loc: unable to read inode block - inode=2330983, block=9338933
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Apr 2 08:13:04 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Apr 2 08:13:04 linux-7vph kernel: EXT4-fs error (device sda6) in ext4_reserve_inode_write: IO failure

Next message: Lai Jiangshan: "[PATCH] tracing: mark get_parent_ip() notrace"
Previous message: Herbert Xu: "Re: Crypto Update for 2.6.30"
In reply to: Tejun Heo: "Re: 2.6.29 regression: ATA bus errors on resume"
Next in thread: Tejun Heo: "Re: 2.6.29 regression: ATA bus errors on resume"
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]