Re: 2.6.29 regression: ATA bus errors on resume (output with debugpatch)

From: Niel Lambrechts
Date: Sat May 23 2009 - 06:39:12 EST

Next message: Michael S. Zick: "Re: [BUG FIX] Make x86_32 uni-processor Atomic ops, Atomic"
Previous message: Zhenwen Xu: "[PATCH] forget a blankspace before 'name'"
In reply to: Niel Lambrechts: "Re: 2.6.29 regression: ATA bus errors on resume"
Next in thread: Niel Lambrechts: "Re: 2.6.29 regression: ATA bus errors on resume"
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]

On 05/23/2009 11:17 AM, Niel Lambrechts wrote:

On 04/09/2009 08:18 PM, Tejun Heo wrote:
Hmm... none of the debugging messages got triggered, so there should
be no noticeable timing change. It seems like you're being lucky for
the time being. Can you please keep testing and report back?

Hi Tejun,

Just to keep you up informed - this issue is still happening, I've been testing the latest 2.6.30-git kernels the last couple of weeks and just checked my logs after having an unexpected fsck upon system boot - the freeze seems to trigger very infrequently though, since I use s2disk at least twice a day and the error only seems to have happened twice since May 2.

I had a look at your 2.6.29 debug patch, it does "Hunk #6 succeeded at 3368" so it should still be good (I hope), I'll try and run with it for a while.

Hi Tejun

Bug triggered with your patch! I played audio while suspending to try and increase activity (I also removed a CD on boot), and the filesystem came up dirty! This was on attempt nr. 3 or 4.

Here is the /var/log/messages output - i hope this is sufficient to trace down the cause:
May 23 12:15:08 linux-7vph kernel: PM: Shrinking memory... done (98443 pages freed)
May 23 12:15:07 linux-7vph pulseaudio[3781]: alsa-util.c: period_time : 185759
May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: tstamp_mode : ENABLE
May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: period_step : 1
May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: avail_min : 15503
May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: period_event : 0
May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: start_threshold : -1
May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: stop_threshold : 1073741824
May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: silence_threshold: 0
May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: silence_size : 0
May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: boundary : 1073741824
May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: Slave: Hardware PCM card 0 'HDA Intel' device 0 subdevice 0
May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: Its setup is:
May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: stream : PLAYBACK
May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: access : MMAP_INTERLEAVED
May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: format : S16_LE
May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: subformat : STD
May 23 12:15:08 linux-7vph kernel: PM: Freed 393772 kbytes in 3.23 seconds (121.91 MB/s)
May 23 12:15:09 linux-7vph kernel: Suspending console(s) (use no_console_suspend to debug)
May 23 12:15:09 linux-7vph kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
May 23 12:15:09 linux-7vph kernel: ACPI handle has no context!
May 23 12:15:09 linux-7vph kernel: ata1: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
May 23 12:15:09 linux-7vph kernel: ata2: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
May 23 12:15:09 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PCI INT D disabled
May 23 12:15:09 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
May 23 12:15:09 linux-7vph kernel: uhci_hcd 0000:00:1d.2: PCI INT C disabled
May 23 12:15:09 linux-7vph kernel: uhci_hcd 0000:00:1d.1: PCI INT B disabled
May 23 12:15:09 linux-7vph kernel: uhci_hcd 0000:00:1d.0: PCI INT A disabled
May 23 12:15:09 linux-7vph kernel: HDA Intel 0000:00:1b.0: PCI INT B disabled
May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PCI INT D disabled
May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.2: PCI INT C disabled
May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.1: PCI INT B disabled
May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: channels : 2
May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: rate : 44100
May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: exact rate : 44100 (44100/1)
May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.0: PCI INT A disabled
May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: msbits : 16
May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: PCI INT A disabled
May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: buffer_size : 16384
May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: period_size : 8192
May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: period_time : 185759
May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: tstamp_mode : ENABLE
May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: period_step : 1
May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: avail_min : 15503
May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: period_event : 0
May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: PME# enabled
May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: start_threshold : -1
May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability enabled by ACPI
May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: stop_threshold : 1073741824
May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: silence_threshold: 0
May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: silence_size : 0
May 23 12:15:10 linux-7vph kernel: ACPI: Preparing to enter system sleep state S4
May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: boundary : 1073741824
May 23 12:15:10 linux-7vph kernel: PM: Saving platform NVS memory
May 23 12:15:10 linux-7vph kernel: Disabling non-boot CPUs ...
May 23 12:15:10 linux-7vph kernel: CPU 1 is now offline
May 23 12:15:10 linux-7vph kernel: SMP alternatives: switching to UP code
May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-sink.c: Increasing wakeup watermark to 40.00 ms
May 23 12:15:10 linux-7vph kernel: CPU0 attaching NULL sched-domain.
May 23 12:15:10 linux-7vph kernel: CPU1 attaching NULL sched-domain.
May 23 12:15:10 linux-7vph kernel: CPU0 attaching NULL sched-domain.
May 23 12:15:10 linux-7vph kernel: CPU1 is down
May 23 12:15:10 linux-7vph kernel: Extended CMOS year: 2000
May 23 12:15:10 linux-7vph kernel: PM: Creating hibernation image:
May 23 12:15:10 linux-7vph kernel: PM: Need to copy 124750 pages
May 23 12:15:10 linux-7vph kernel: x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
May 23 12:15:10 linux-7vph kernel: Intel machine check architecture supported.
May 23 12:15:10 linux-7vph kernel: Intel machine check reporting enabled on CPU#0.
May 23 12:15:10 linux-7vph kernel: PM: Restoring platform NVS memory
May 23 12:15:10 linux-7vph kernel: Extended CMOS year: 2000
May 23 12:15:10 linux-7vph kernel: Enabling non-boot CPUs ...
May 23 12:15:10 linux-7vph kernel: SMP alternatives: switching to SMP code
May 23 12:15:10 linux-7vph kernel: Booting processor 1 APIC 0x1 ip 0x6000
May 23 12:15:10 linux-7vph kernel: Initializing CPU#1
May 23 12:15:10 linux-7vph kernel: Calibrating delay using timer specific routine.. 5054.05 BogoMIPS (lpj=10108117)
May 23 12:15:10 linux-7vph kernel: CPU: L1 I cache: 32K, L1 D cache: 32K
May 23 12:15:10 linux-7vph kernel: CPU: L2 cache: 6144K
May 23 12:15:10 linux-7vph kernel: CPU: Physical Processor ID: 0
May 23 12:15:10 linux-7vph kernel: CPU: Processor Core ID: 1
May 23 12:15:10 linux-7vph kernel: Intel machine check architecture supported.
May 23 12:15:10 linux-7vph kernel: Intel machine check reporting enabled on CPU#1.
May 23 12:15:10 linux-7vph kernel: x86 PAT enabled: cpu 1, old 0x7040600070406, new 0x7010600070106
May 23 12:15:10 linux-7vph kernel: CPU1: Intel(R) Core(TM)2 Duo CPU T9400 @ 2.53GHz stepping 06
May 23 12:15:10 linux-7vph kernel: CPU0 attaching NULL sched-domain.
May 23 12:15:10 linux-7vph kernel: Switched to high resolution mode on CPU 1
May 23 12:15:10 linux-7vph kernel: CPU0 attaching sched-domain:
May 23 12:15:10 linux-7vph kernel: domain 0: span 0-1 level MC
May 23 12:15:10 linux-7vph kernel: groups: 0 1
May 23 12:15:10 linux-7vph kernel: domain 1: span 0-1 level CPU
May 23 12:15:10 linux-7vph kernel: groups: 0-1 (__cpu_power = 2048)
May 23 12:15:10 linux-7vph kernel: CPU1 attaching sched-domain:
May 23 12:15:10 linux-7vph kernel: domain 0: span 0-1 level MC
May 23 12:15:10 linux-7vph kernel: groups: 1 0
May 23 12:15:10 linux-7vph kernel: domain 1: span 0-1 level CPU
May 23 12:15:10 linux-7vph kernel: groups: 0-1 (__cpu_power = 2048)
May 23 12:15:10 linux-7vph kernel: microcode: failed to init CPU1
May 23 12:15:10 linux-7vph kernel: CPU1 is up
May 23 12:15:10 linux-7vph kernel: ACPI: Waking up from system sleep state S4
May 23 12:15:10 linux-7vph kernel: pci 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900403)
May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.0: power state changed by ACPI to D0
May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.2: power state changed by ACPI to D0
May 23 12:15:10 linux-7vph kernel: HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102)
May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
May 23 12:15:10 linux-7vph kernel: ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00403, writing 0x2b00407)
May 23 12:15:10 linux-7vph kernel: iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506)
May 23 12:15:10 linux-7vph kernel: pci 0000:00:02.0: PME# disabled
May 23 12:15:10 linux-7vph kernel: pci 0000:00:02.0: setting latency timer to 64
May 23 12:15:10 linux-7vph kernel: pci 0000:00:02.1: PME# disabled
May 23 12:15:10 linux-7vph kernel: pci 0000:00:03.0: PME# disabled
May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: pci_enable_pcie_error_reporting failed 0xfffffffb
May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: setting latency timer to 64
May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability disabled by ACPI
May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: PME# disabled
May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability disabled by ACPI
May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: PME# disabled
May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: irq 29 for MSI/MSI-X
May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.0: setting latency timer to 64
May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21
May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.1: setting latency timer to 64
May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 22 (level, low) -> IRQ 22
May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.2: setting latency timer to 64
May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PCI INT D -> GSI 23 (level, low) -> IRQ 23
May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1a.7: setting latency timer to 64
May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
May 23 12:15:10 linux-7vph kernel: HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17
May 23 12:15:10 linux-7vph kernel: HDA Intel 0000:00:1b.0: setting latency timer to 64
May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1d.0: setting latency timer to 64
May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17
May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1d.1: setting latency timer to 64
May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1d.2: setting latency timer to 64
May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19
May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1d.7: setting latency timer to 64
May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
May 23 12:15:10 linux-7vph kernel: pci 0000:00:1e.0: setting latency timer to 64
May 23 12:15:10 linux-7vph kernel: ahci 0000:00:1f.2: setting latency timer to 64
May 23 12:15:10 linux-7vph kernel: ata1: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x16 frozen
May 23 12:15:10 linux-7vph kernel: ata2: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x16 frozen
May 23 12:15:10 linux-7vph kernel: pci 0000:15:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
May 23 12:15:10 linux-7vph kernel: ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f4801000-f48017ff] Max Packet=[2048] IR/IT contexts=[4/4]
May 23 12:15:10 linux-7vph kernel: pci 0000:15:00.2: PME# disabled
May 23 12:15:10 linux-7vph kernel: pci 0000:15:00.3: PME# disabled
May 23 12:15:10 linux-7vph kernel: pci 0000:15:00.4: PME# disabled
May 23 12:15:10 linux-7vph kernel: pci 0000:15:00.5: PME# disabled
May 23 12:15:10 linux-7vph kernel: sd 0:0:0:0: [sda] Starting disk
May 23 12:15:10 linux-7vph kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 23 12:15:10 linux-7vph kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 23 12:15:10 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
May 23 12:15:10 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
May 23 12:15:10 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
May 23 12:15:10 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
May 23 12:15:10 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
May 23 12:15:10 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
May 23 12:15:10 linux-7vph kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded
May 23 12:15:10 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
May 23 12:15:10 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
May 23 12:15:10 linux-7vph kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded
May 23 12:15:10 linux-7vph kernel: ata1.00: configured for UDMA/133
May 23 12:15:10 linux-7vph kernel: ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
May 23 12:15:10 linux-7vph kernel: ata1: irq_stat 0x00400040, connection status changed
May 23 12:15:10 linux-7vph kernel: ata1.00: configured for UDMA/133
May 23 12:15:10 linux-7vph kernel: ata1: EH complete
May 23 12:15:10 linux-7vph kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded
May 23 12:15:10 linux-7vph kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded
May 23 12:15:10 linux-7vph kernel: ata2.00: configured for UDMA/133
May 23 12:15:10 linux-7vph kernel: ata2: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
May 23 12:15:10 linux-7vph kernel: ata2: irq_stat 0x40000001
May 23 12:15:10 linux-7vph kernel: ata2.00: configured for UDMA/133
May 23 12:15:10 linux-7vph kernel: ata2: EH complete
May 23 12:15:10 linux-7vph kernel: Registered led device: iwl-phy0::radio
May 23 12:15:10 linux-7vph kernel: Registered led device: iwl-phy0::assoc
May 23 12:15:10 linux-7vph kernel: Registered led device: iwl-phy0::RX
May 23 12:15:10 linux-7vph kernel: Registered led device: iwl-phy0::TX
May 23 12:15:10 linux-7vph kernel: mac80211-phy0: failed to set key (0, 00:1d:92:1d:1e:8e) to hardware (-22)
May 23 12:15:10 linux-7vph kernel: pci 0000:00:02.0: restoring config space at offset 0x1 (was 0x900407, writing 0x900403)
May 23 12:15:10 linux-7vph kernel: pci 0000:00:02.0: setting latency timer to 64
May 23 12:15:10 linux-7vph kernel: Restarting tasks ... <3>ata1.00: exception Emask 0x10 SAct 0x1f SErr 0x50000 action 0xe frozen
May 23 12:15:10 linux-7vph kernel: ata1.00: irq_stat 0x00400008, PHY RDY changed
May 23 12:15:10 linux-7vph kernel: ata1: SError: { PHYRdyChg CommWake }
May 23 12:15:10 linux-7vph kernel: ata1.00: cmd 60/08:00:5f:89:67/01:00:0e:00:00/40 tag 0 ncq 135168 in
May 23 12:15:10 linux-7vph kernel: res 50/00:08:5f:08:c8/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
May 23 12:15:10 linux-7vph kernel: ata1.00: status: { DRDY }
May 23 12:15:10 linux-7vph kernel: ata1.00: cmd 60/08:08:6f:88:6f/01:00:0e:00:00/40 tag 1 ncq 135168 in
May 23 12:15:10 linux-7vph kernel: res 50/00:08:5f:08:c8/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
May 23 12:15:10 linux-7vph kernel: ata1.00: status: { DRDY }
May 23 12:15:10 linux-7vph kernel: ata1.00: cmd 60/08:10:5f:08:c8/00:00:0c:00:00/40 tag 2 ncq 4096 in
May 23 12:15:10 linux-7vph kernel: res 50/00:08:5f:08:c8/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
May 23 12:15:10 linux-7vph kernel: ata1.00: status: { DRDY }
May 23 12:15:10 linux-7vph kernel: ata1.00: cmd 60/08:18:9f:98:6d/00:00:0e:00:00/40 tag 3 ncq 4096 in
May 23 12:15:10 linux-7vph kernel: res 50/00:08:5f:08:c8/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
May 23 12:15:11 linux-7vph kernel: ata1.00: status: { DRDY }
May 23 12:15:11 linux-7vph kernel: ata1.00: cmd 60/e8:20:5f:8e:2b/00:00:0b:00:00/40 tag 4 ncq 118784 in
May 23 12:15:11 linux-7vph kernel: res 50/00:08:5f:08:c8/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
May 23 12:15:11 linux-7vph kernel: ata1.00: status: { DRDY }
May 23 12:15:11 linux-7vph kernel: ata1: hard resetting link
May 23 12:15:11 linux-7vph kernel: done.
May 23 12:15:11 linux-7vph kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 23 12:15:11 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
May 23 12:15:11 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
May 23 12:15:11 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
May 23 12:15:11 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
May 23 12:15:11 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
May 23 12:15:11 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
May 23 12:15:11 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
May 23 12:15:11 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
May 23 12:15:11 linux-7vph kernel: ata1.00: configured for UDMA/133
May 23 12:15:11 linux-7vph kernel: ata1: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 t4
May 23 12:15:11 linux-7vph kernel: ata1.00: configured for UDMA/133
May 23 12:15:11 linux-7vph kernel: XXX scsi_eh_flush_done_q: online=1(2) noretry=2 retries=0 allowed=5
May 23 12:15:11 linux-7vph kernel: scsi_eh_0: flush finish cmd: f6838ec0
May 23 12:15:11 linux-7vph kernel: sd 0:0:0:0: [sda] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
May 23 12:15:11 linux-7vph kernel: sd 0:0:0:0: [sda] Sense Key : Aborted Command [current] [descriptor]
May 23 12:15:11 linux-7vph kernel: Descriptor sense data with sense descriptors (in hex):
May 23 12:15:11 linux-7vph kernel: 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
May 23 12:15:11 linux-7vph kernel: 0c c8 08 5f
May 23 12:15:11 linux-7vph kernel: sd 0:0:0:0: [sda] Add. Sense: No additional sense information
May 23 12:15:11 linux-7vph kernel: end_request: I/O error, dev sda, sector 241666399
May 23 12:15:11 linux-7vph kernel: XXX scsi_eh_flush_done_q: online=1(2) noretry=2 retries=0 allowed=5
May 23 12:15:11 linux-7vph kernel: scsi_eh_0: flush finish cmd: f6838680
May 23 12:15:11 linux-7vph kernel: sd 0:0:0:0: [sda] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
May 23 12:15:11 linux-7vph kernel: sd 0:0:0:0: [sda] Sense Key : Aborted Command <2>EXT4-fs error (device sda6): __ext4_get_inode_loc: unable to read inode block - inode=2330983, block=9338933
May 23 12:15:11 linux-7vph kernel: [current] [descriptor]
May 23 12:15:11 linux-7vph kernel: Descriptor sense data with sense descriptors (in hex):
May 23 12:15:11 linux-7vph kernel: 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
May 23 12:15:11 linux-7vph kernel: 0c c8 08 5f
May 23 12:15:11 linux-7vph kernel: sd 0:0:0:0: [sda] Add. Sense: No additional sense information
May 23 12:15:11 linux-7vph kernel: end_request: I/O error, dev sda, sector 242190447
May 23 12:15:11 linux-7vph kernel: EXT4-fs error (device sda6): __ext4_get_inode_loc: XXX scsi_eh_flush_done_q: online=1(2) noretry=0 retries=0 allowed=5
May 23 12:15:11 linux-7vph kernel: unable to read inode block - inode=2346524, block=9404418
May 23 12:15:11 linux-7vph kernel: scsi_eh_0: flush retry cmd: f6838800
May 23 12:15:11 linux-7vph kernel: XXX scsi_eh_flush_done_q: online=1(2) noretry=0 retries=0 allowed=5
May 23 12:15:11 linux-7vph kernel: scsi_eh_0: flush retry cmd: f403de00
May 23 12:15:11 linux-7vph kernel: XXX scsi_eh_flush_done_q: online=1(2) noretry=2 retries=0 allowed=5
May 23 12:15:11 linux-7vph kernel: scsi_eh_0: flush finish cmd: f403d200
May 23 12:15:11 linux-7vph kernel: sd 0:0:0:0: [sda] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
May 23 12:15:11 linux-7vph kernel: sd 0:0:0:0: [sda] Sense Key : Aborted Command [current] [descriptor]
May 23 12:15:11 linux-7vph kernel: Descriptor sense data with sense descriptors (in hex):
May 23 12:15:11 linux-7vph kernel: 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
May 23 12:15:11 linux-7vph kernel: 0c c8 08 5f
May 23 12:15:11 linux-7vph kernel: sd 0:0:0:0: [sda] Add. Sense: No additional sense information
May 23 12:15:11 linux-7vph kernel: end_request: I/O error, dev sda, sector 187403871
May 23 12:15:11 linux-7vph kernel: ata1: EH complete
May 23 12:15:11 linux-7vph kernel: EXT4-fs error (device sda6): __ext4_get_inode_loc: unable to read inode block - inode=641187, block=2556122
May 23 12:15:11 linux-7vph kernel: EXT4-fs error (device sda6) in ext4_reserve_inode_write: IO failure
May 23 12:15:11 linux-7vph kernel: EXT4-fs error (device sda6) in ext4_reserve_inode_write: IO failure
May 23 12:15:11 linux-7vph kernel: EXT4-fs error (device sda6) in ext4_reserve_inode_write: IO failure
May 23 12:16:50 linux-7vph kernel: ata1: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x10
May 23 12:16:50 linux-7vph kernel: CPU0 attaching NULL sched-domain.
May 23 12:16:50 linux-7vph kernel: CPU1 attaching NULL sched-domain.
May 23 12:16:50 linux-7vph kernel: CPU0 attaching sched-domain:
May 23 12:16:50 linux-7vph kernel: domain 0: span 0-1 level MC
May 23 12:16:50 linux-7vph kernel: groups: 0 1
May 23 12:16:50 linux-7vph kernel: domain 1: span 0-1 level CPU
May 23 12:16:50 linux-7vph kernel: groups: 0-1 (__cpu_power = 2048)
May 23 12:16:50 linux-7vph kernel: CPU1 attaching sched-domain:
May 23 12:16:50 linux-7vph kernel: domain 0: span 0-1 level MC
May 23 12:16:50 linux-7vph kernel: groups: 1 0
May 23 12:16:50 linux-7vph kernel: domain 1: span 0-1 level CPU
May 23 12:16:50 linux-7vph kernel: groups: 0-1 (__cpu_power = 2048)

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

Next message: Michael S. Zick: "Re: [BUG FIX] Make x86_32 uni-processor Atomic ops, Atomic"
Previous message: Zhenwen Xu: "[PATCH] forget a blankspace before 'name'"
In reply to: Niel Lambrechts: "Re: 2.6.29 regression: ATA bus errors on resume"
Next in thread: Niel Lambrechts: "Re: 2.6.29 regression: ATA bus errors on resume"
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]