Re: 2.6.29 regression: ATA bus errors on resume

From: Niel Lambrechts
Date: Wed Mar 25 2009 - 17:40:37 EST

Next message: Eric Anholt: "DRM lock ordering fix series"
Previous message: Christian Thaeter: "[PATCH] hpfall.c improvements, thoughts"
In reply to: Jeff Garzik: "Re: 2.6.29 regression: ATA bus errors on resume"
Next in thread: James Bottomley: "Re: 2.6.29 regression: ATA bus errors on resume"
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]

On 03/25/2009 08:06 AM, Jeff Garzik wrote:
>
> So from this, a few observations and open questions:
>
> Why didn't these SCSI commands get retried?
>
> Were they left over from prior to resume?
>
> Did SCSI error out the commands too soon? The probing is not complete
> until AFTER the sector errors, I note.
>
> Did the system resume before ACPI resume and SCSI resume completed?
> It sure looks that way, from the log.
>
> Does the asynchronous probing play a role here?
It looks like there are a few different scenarios in what could happen:

I had another go at hibernating on 2.6.29 running with and without X,
and this time round it resumed with a *clean* file-system. Without X, it
looked to be better off as there were not even any ATA errors logged,
but I'm not sure if this is just because of the system being almost
entirely idle.

Going on your comments of earlier, when hibernating in X it looks like
this time the SCSI initialization completes normally before any errors,
being 'ATA bus error' messages for a while until a hard reset occurs.

I've attached the messages from both, hopefully this gives someone more
of a clue of what is happening.

cheers
Niel
Mar 25 20:04:27 linux-7vph kernel: [drm:gm45_get_vblank_counter] *ERROR* trying to get vblank count for disabled pipe 0
Mar 25 20:05:32 linux-7vph kernel: Syncing filesystems ... done.
Mar 25 20:05:32 linux-7vph kernel: Freezing user space processes ... (elapsed 0.00 seconds) done.
Mar 25 20:05:32 linux-7vph kernel: Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
Mar 25 20:05:32 linux-7vph kernel: PM: Shrinking memory... done (49943 pages freed)
Mar 25 20:05:32 linux-7vph kernel: PM: Freed 199772 kbytes in 1.05 seconds (190.25 MB/s)
Mar 25 20:05:32 linux-7vph kernel: Suspending console(s) (use no_console_suspend to debug)
Mar 25 20:05:32 linux-7vph kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Mar 25 20:05:32 linux-7vph kernel: ACPI handle has no context!
Mar 25 20:05:32 linux-7vph kernel: iwlagn 0000:03:00.0: PCI INT A disabled
Mar 25 20:05:32 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PCI INT D disabled
Mar 25 20:05:32 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
Mar 25 20:05:32 linux-7vph kernel: uhci_hcd 0000:00:1d.2: PCI INT C disabled
Mar 25 20:05:35 linux-7vph kernel: uhci_hcd 0000:00:1d.1: PCI INT B disabled
Mar 25 20:05:35 linux-7vph kernel: uhci_hcd 0000:00:1d.0: PCI INT A disabled
Mar 25 20:05:35 linux-7vph kernel: HDA Intel 0000:00:1b.0: PCI INT B disabled
Mar 25 20:05:36 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PCI INT D disabled
Mar 25 20:05:36 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
Mar 25 20:05:36 linux-7vph kernel: uhci_hcd 0000:00:1a.2: PCI INT C disabled
Mar 25 20:05:36 linux-7vph kernel: uhci_hcd 0000:00:1a.1: PCI INT B disabled
Mar 25 20:05:36 linux-7vph kernel: uhci_hcd 0000:00:1a.0: PCI INT A disabled
Mar 25 20:05:36 linux-7vph kernel: e1000e 0000:00:19.0: PME# enabled
Mar 25 20:05:36 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Mar 25 20:05:36 linux-7vph kernel: e1000e 0000:00:19.0: PME# enabled
Mar 25 20:05:36 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Mar 25 20:05:36 linux-7vph kernel: e1000e 0000:00:19.0: PCI INT A disabled
Mar 25 20:05:36 linux-7vph kernel: ACPI: Preparing to enter system sleep state S4
Mar 25 20:05:36 linux-7vph kernel: PM: Saving platform NVS memory
Mar 25 20:05:36 linux-7vph kernel: Disabling non-boot CPUs ...
Mar 25 20:05:36 linux-7vph kernel: CPU 1 is now offline
Mar 25 20:05:36 linux-7vph kernel: SMP alternatives: switching to UP code
Mar 25 20:05:36 linux-7vph kernel: CPU0 attaching NULL sched-domain.
Mar 25 20:05:36 linux-7vph kernel: CPU1 attaching NULL sched-domain.
Mar 25 20:05:36 linux-7vph kernel: CPU0 attaching NULL sched-domain.
Mar 25 20:05:36 linux-7vph kernel: CPU1 is down
Mar 25 20:05:36 linux-7vph kernel: Extended CMOS year: 2000
Mar 25 20:05:36 linux-7vph kernel: PM: Creating hibernation image:
Mar 25 20:05:36 linux-7vph kernel: PM: Need to copy 125019 pages
Mar 25 20:05:36 linux-7vph kernel: x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
Mar 25 20:05:36 linux-7vph kernel: Intel machine check architecture supported.
Mar 25 20:05:36 linux-7vph kernel: Intel machine check reporting enabled on CPU#0.
Mar 25 20:05:36 linux-7vph kernel: PM: Restoring platform NVS memory
Mar 25 20:05:36 linux-7vph kernel: Extended CMOS year: 2000
Mar 25 20:05:36 linux-7vph kernel: pci 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900403)
Mar 25 20:05:36 linux-7vph kernel: HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102)
Mar 25 20:05:36 linux-7vph kernel: ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00403, writing 0x2b00407)
Mar 25 20:05:36 linux-7vph kernel: Enabling non-boot CPUs ...
Mar 25 20:05:36 linux-7vph kernel: SMP alternatives: switching to SMP code
Mar 25 20:05:36 linux-7vph kernel: Booting processor 1 APIC 0x1 ip 0x6000
Mar 25 20:05:36 linux-7vph kernel: Initializing CPU#1
Mar 25 20:05:36 linux-7vph kernel: Calibrating delay using timer specific routine.. 5054.04 BogoMIPS (lpj=10108098)
Mar 25 20:05:36 linux-7vph kernel: CPU: L1 I cache: 32K, L1 D cache: 32K
Mar 25 20:05:36 linux-7vph kernel: CPU: L2 cache: 6144K
Mar 25 20:05:36 linux-7vph kernel: [ds] using Core 2/Atom configuration
Mar 25 20:05:36 linux-7vph kernel: CPU: Physical Processor ID: 0
Mar 25 20:05:36 linux-7vph kernel: CPU: Processor Core ID: 1
Mar 25 20:05:36 linux-7vph kernel: Intel machine check architecture supported.
Mar 25 20:05:36 linux-7vph kernel: Intel machine check reporting enabled on CPU#1.
Mar 25 20:05:36 linux-7vph kernel: x86 PAT enabled: cpu 1, old 0x7040600070406, new 0x7010600070106
Mar 25 20:05:36 linux-7vph kernel: CPU1: Intel(R) Core(TM)2 Duo CPU T9400 @ 2.53GHz stepping 06
Mar 25 20:05:36 linux-7vph kernel: CPU0 attaching NULL sched-domain.
Mar 25 20:05:36 linux-7vph kernel: Switched to high resolution mode on CPU 1
Mar 25 20:05:36 linux-7vph kernel: CPU0 attaching sched-domain:
Mar 25 20:05:36 linux-7vph kernel: domain 0: span 0-1 level MC
Mar 25 20:05:36 linux-7vph kernel: groups: 0 1
Mar 25 20:05:36 linux-7vph kernel: domain 1: span 0-1 level CPU
Mar 25 20:05:36 linux-7vph kernel: groups: 0-1
Mar 25 20:05:36 linux-7vph kernel: CPU1 attaching sched-domain:
Mar 25 20:05:36 linux-7vph kernel: domain 0: span 0-1 level MC
Mar 25 20:05:36 linux-7vph kernel: groups: 1 0
Mar 25 20:05:36 linux-7vph kernel: domain 1: span 0-1 level CPU
Mar 25 20:05:36 linux-7vph kernel: groups: 0-1
Mar 25 20:05:36 linux-7vph kernel: CPU1 is up
Mar 25 20:05:36 linux-7vph kernel: ACPI: Waking up from system sleep state S4
Mar 25 20:05:36 linux-7vph kernel: ACPI: EC: non-query interrupt received, switching to interrupt mode
Mar 25 20:05:36 linux-7vph kernel: pci 0000:00:02.0: PME# disabled
Mar 25 20:05:36 linux-7vph kernel: pci 0000:00:02.0: power state changed by ACPI to D0
Mar 25 20:05:36 linux-7vph kernel: pci 0000:00:02.0: setting latency timer to 64
Mar 25 20:05:36 linux-7vph kernel: pci 0000:00:02.1: PME# disabled
Mar 25 20:05:36 linux-7vph kernel: pci 0000:00:03.0: PME# disabled
Mar 25 20:05:36 linux-7vph kernel: e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
Mar 25 20:05:36 linux-7vph kernel: e1000e 0000:00:19.0: setting latency timer to 64
Mar 25 20:05:36 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Mar 25 20:05:36 linux-7vph kernel: e1000e 0000:00:19.0: PME# disabled
Mar 25 20:05:36 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Mar 25 20:05:36 linux-7vph kernel: e1000e 0000:00:19.0: PME# disabled
Mar 25 20:05:36 linux-7vph kernel: e1000e 0000:00:19.0: irq 29 for MSI/MSI-X
Mar 25 20:05:36 linux-7vph kernel: uhci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 25 20:05:36 linux-7vph kernel: uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
Mar 25 20:05:36 linux-7vph kernel: uhci_hcd 0000:00:1a.0: setting latency timer to 64
Mar 25 20:05:36 linux-7vph kernel: uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21
Mar 25 20:05:36 linux-7vph kernel: uhci_hcd 0000:00:1a.1: setting latency timer to 64
Mar 25 20:05:36 linux-7vph kernel: uhci_hcd 0000:00:1a.2: power state changed by ACPI to D0
Mar 25 20:05:36 linux-7vph kernel: uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 22 (level, low) -> IRQ 22
Mar 25 20:05:36 linux-7vph kernel: uhci_hcd 0000:00:1a.2: setting latency timer to 64
Mar 25 20:05:36 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
Mar 25 20:05:36 linux-7vph kernel: ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0
Mar 25 20:05:36 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PCI INT D -> GSI 23 (level, low) -> IRQ 23
Mar 25 20:05:36 linux-7vph kernel: ehci_hcd 0000:00:1a.7: setting latency timer to 64
Mar 25 20:05:36 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
Mar 25 20:05:36 linux-7vph kernel: HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Mar 25 20:05:36 linux-7vph kernel: HDA Intel 0000:00:1b.0: setting latency timer to 64
Mar 25 20:05:36 linux-7vph kernel: pcieport-driver 0000:00:1c.0: setting latency timer to 64
Mar 25 20:05:36 linux-7vph kernel: pcieport-driver 0000:00:1c.1: setting latency timer to 64
Mar 25 20:05:36 linux-7vph kernel: pcieport-driver 0000:00:1c.3: setting latency timer to 64
Mar 25 20:05:36 linux-7vph kernel: pcieport-driver 0000:00:1c.4: setting latency timer to 64
Mar 25 20:05:36 linux-7vph kernel: uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 25 20:05:36 linux-7vph kernel: uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Mar 25 20:05:36 linux-7vph kernel: uhci_hcd 0000:00:1d.0: setting latency timer to 64
Mar 25 20:05:36 linux-7vph kernel: uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Mar 25 20:05:36 linux-7vph kernel: uhci_hcd 0000:00:1d.1: setting latency timer to 64
Mar 25 20:05:36 linux-7vph kernel: uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
Mar 25 20:05:36 linux-7vph kernel: uhci_hcd 0000:00:1d.2: setting latency timer to 64
Mar 25 20:05:36 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
Mar 25 20:05:36 linux-7vph kernel: ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0
Mar 25 20:05:36 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19
Mar 25 20:05:36 linux-7vph kernel: ehci_hcd 0000:00:1d.7: setting latency timer to 64
Mar 25 20:05:36 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
Mar 25 20:05:36 linux-7vph kernel: pci 0000:00:1e.0: setting latency timer to 64
Mar 25 20:05:36 linux-7vph kernel: ahci 0000:00:1f.2: setting latency timer to 64
Mar 25 20:05:36 linux-7vph kernel: iwlagn 0000:03:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
Mar 25 20:05:36 linux-7vph kernel: iwlagn 0000:03:00.0: irq 30 for MSI/MSI-X
Mar 25 20:05:36 linux-7vph kernel: pci 0000:15:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Mar 25 20:05:36 linux-7vph kernel: Registered led device: iwl-phy0:radio
Mar 25 20:05:36 linux-7vph kernel: Registered led device: iwl-phy0:assoc
Mar 25 20:05:36 linux-7vph kernel: Registered led device: iwl-phy0:RX
Mar 25 20:05:36 linux-7vph kernel: Registered led device: iwl-phy0:TX
Mar 25 20:05:36 linux-7vph kernel: ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f4801000-f48017ff] Max Packet=[2048] IR/IT contexts=[4/4]
Mar 25 20:05:36 linux-7vph kernel: pci 0000:15:00.2: PME# disabled
Mar 25 20:05:36 linux-7vph kernel: pci 0000:15:00.3: PME# disabled
Mar 25 20:05:36 linux-7vph kernel: pci 0000:15:00.4: PME# disabled
Mar 25 20:05:36 linux-7vph kernel: pci 0000:15:00.5: PME# disabled
Mar 25 20:05:36 linux-7vph kernel: sd 0:0:0:0: [sda] Starting disk
Mar 25 20:05:36 linux-7vph kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 25 20:05:36 linux-7vph kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 25 20:05:36 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Mar 25 20:05:36 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Mar 25 20:05:36 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Mar 25 20:05:36 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Mar 25 20:05:36 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Mar 25 20:05:36 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Mar 25 20:05:36 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Mar 25 20:05:36 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Mar 25 20:05:36 linux-7vph kernel: ata1.00: configured for UDMA/133
Mar 25 20:05:36 linux-7vph kernel: ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
Mar 25 20:05:36 linux-7vph kernel: ata1: irq_stat 0x00400040, connection status changed
Mar 25 20:05:36 linux-7vph kernel: ata1.00: configured for UDMA/133
Mar 25 20:05:36 linux-7vph kernel: ata1: EH complete
Mar 25 20:05:36 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Mar 25 20:05:36 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Mar 25 20:05:36 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Mar 25 20:05:36 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 25 20:05:36 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Mar 25 20:05:36 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Mar 25 20:05:36 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Mar 25 20:05:36 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 25 20:05:36 linux-7vph kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded
Mar 25 20:05:36 linux-7vph kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded
Mar 25 20:05:36 linux-7vph kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded
Mar 25 20:05:36 linux-7vph kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded
Mar 25 20:05:36 linux-7vph kernel: ata2.00: configured for UDMA/133
Mar 25 20:05:36 linux-7vph kernel: pci 0000:00:02.0: power state changed by ACPI to D0
Mar 25 20:05:36 linux-7vph kernel: pci 0000:00:02.0: restoring config space at offset 0x1 (was 0x900407, writing 0x900403)
Mar 25 20:05:36 linux-7vph kernel: pci 0000:00:02.0: setting latency timer to 64
Mar 25 20:05:36 linux-7vph kernel: ata2: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
Mar 25 20:05:36 linux-7vph kernel: ata2: irq_stat 0x40000001
Mar 25 20:05:36 linux-7vph kernel: ata2.00: configured for UDMA/133
Mar 25 20:05:36 linux-7vph kernel: ata2: EH complete
Mar 25 20:05:36 linux-7vph kernel: Restarting tasks ... done.
Mar 25 20:05:36 linux-7vph kernel: ata1.00: exception Emask 0x10 SAct 0x3f SErr 0x50000 action 0xe frozen
Mar 25 20:05:36 linux-7vph kernel: ata1.00: irq_stat 0x00400008, PHY RDY changed
Mar 25 20:05:36 linux-7vph kernel: ata1: SError: { PHYRdyChg CommWake }
Mar 25 20:05:36 linux-7vph kernel: ata1.00: cmd 60/10:00:af:e1:19/00:00:0f:00:00/40 tag 0 ncq 8192 in
Mar 25 20:05:36 linux-7vph kernel: res 50/00:40:ff:38:1e/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Mar 25 20:05:36 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 25 20:05:36 linux-7vph kernel: ata1.00: cmd 60/08:08:97:d6:ab/00:00:0e:00:00/40 tag 1 ncq 4096 in
Mar 25 20:05:36 linux-7vph kernel: res 50/00:40:ff:38:1e/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Mar 25 20:05:36 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 25 20:05:36 linux-7vph kernel: ata1.00: cmd 60/08:10:17:5a:03/00:00:0b:00:00/40 tag 2 ncq 4096 in
Mar 25 20:05:36 linux-7vph kernel: res 50/00:40:ff:38:1e/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Mar 25 20:05:36 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 25 20:05:36 linux-7vph kernel: ata1.00: cmd 60/08:18:a7:38:1e/00:00:0d:00:00/40 tag 3 ncq 4096 in
Mar 25 20:05:37 linux-7vph kernel: res 50/00:40:ff:38:1e/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Mar 25 20:05:37 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 25 20:05:37 linux-7vph kernel: ata1.00: cmd 60/08:20:c7:38:1e/00:00:0d:00:00/40 tag 4 ncq 4096 in
Mar 25 20:05:37 linux-7vph kernel: res 50/00:40:ff:38:1e/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Mar 25 20:05:37 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 25 20:05:37 linux-7vph kernel: ata1.00: cmd 60/40:28:ff:38:1e/00:00:0d:00:00/40 tag 5 ncq 32768 in
Mar 25 20:05:37 linux-7vph kernel: res 50/00:40:ff:38:1e/00:00:0d:00:00/40 Emask 0x10 (ATA bus error)
Mar 25 20:05:37 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 25 20:05:37 linux-7vph kernel: ata1: hard resetting link
Mar 25 20:05:37 linux-7vph kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 25 20:05:37 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Mar 25 20:05:37 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Mar 25 20:05:37 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Mar 25 20:05:37 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Mar 25 20:05:37 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Mar 25 20:05:37 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Mar 25 20:05:37 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Mar 25 20:05:37 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Mar 25 20:05:37 linux-7vph kernel: ata1.00: configured for UDMA/133
Mar 25 20:05:37 linux-7vph kernel: ata1.00: configured for UDMA/133
Mar 25 20:05:37 linux-7vph kernel: ata1: EH complete
Mar 25 20:05:37 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Mar 25 20:05:37 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Mar 25 20:05:37 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Mar 25 20:05:37 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 25 20:05:37 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Mar 25 20:05:37 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Mar 25 20:05:37 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Mar 25 20:05:37 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA

Mar 25 20:01:51 linux-7vph kernel: Syncing filesystems ... done.
Mar 25 20:01:51 linux-7vph kernel: Freezing user space processes ... (elapsed 0.00 seconds) done.
Mar 25 20:01:51 linux-7vph kernel: Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
Mar 25 20:01:51 linux-7vph kernel: PM: Shrinking memory... done (0 pages freed)
Mar 25 20:01:51 linux-7vph kernel: PM: Freed 0 kbytes in 0.14 seconds (0.00 MB/s)
Mar 25 20:01:51 linux-7vph kernel: Suspending console(s) (use no_console_suspend to debug)
Mar 25 20:01:51 linux-7vph kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Mar 25 20:01:51 linux-7vph kernel: ACPI handle has no context!
Mar 25 20:01:51 linux-7vph kernel: iwlagn 0000:03:00.0: PCI INT A disabled
Mar 25 20:01:51 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PCI INT D disabled
Mar 25 20:01:51 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1d.2: PCI INT C disabled
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1d.1: PCI INT B disabled
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1d.0: PCI INT A disabled
Mar 25 20:01:51 linux-7vph kernel: HDA Intel 0000:00:1b.0: PCI INT B disabled
Mar 25 20:01:51 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PCI INT D disabled
Mar 25 20:01:51 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1a.2: PCI INT C disabled
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1a.1: PCI INT B disabled
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1a.0: PCI INT A disabled
Mar 25 20:01:51 linux-7vph kernel: e1000e 0000:00:19.0: PME# enabled
Mar 25 20:01:51 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Mar 25 20:01:51 linux-7vph kernel: e1000e 0000:00:19.0: PME# enabled
Mar 25 20:01:51 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Mar 25 20:01:51 linux-7vph kernel: e1000e 0000:00:19.0: PCI INT A disabled
Mar 25 20:01:51 linux-7vph kernel: ACPI: Preparing to enter system sleep state S4
Mar 25 20:01:51 linux-7vph kernel: PM: Saving platform NVS memory
Mar 25 20:01:51 linux-7vph kernel: Disabling non-boot CPUs ...
Mar 25 20:01:51 linux-7vph kernel: CPU 1 is now offline
Mar 25 20:01:51 linux-7vph kernel: SMP alternatives: switching to UP code
Mar 25 20:01:51 linux-7vph kernel: CPU0 attaching NULL sched-domain.
Mar 25 20:01:51 linux-7vph kernel: CPU1 attaching NULL sched-domain.
Mar 25 20:01:51 linux-7vph kernel: CPU0 attaching NULL sched-domain.
Mar 25 20:01:51 linux-7vph kernel: CPU1 is down
Mar 25 20:01:51 linux-7vph kernel: Extended CMOS year: 2000
Mar 25 20:01:51 linux-7vph kernel: PM: Creating hibernation image:
Mar 25 20:01:51 linux-7vph kernel: PM: Need to copy 37796 pages
Mar 25 20:01:51 linux-7vph kernel: x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
Mar 25 20:01:51 linux-7vph kernel: Intel machine check architecture supported.
Mar 25 20:01:51 linux-7vph kernel: Intel machine check reporting enabled on CPU#0.
Mar 25 20:01:51 linux-7vph kernel: PM: Restoring platform NVS memory
Mar 25 20:01:51 linux-7vph kernel: Extended CMOS year: 2000
Mar 25 20:01:51 linux-7vph kernel: HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102)
Mar 25 20:01:51 linux-7vph kernel: ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00403, writing 0x2b00407)
Mar 25 20:01:51 linux-7vph kernel: Enabling non-boot CPUs ...
Mar 25 20:01:51 linux-7vph kernel: SMP alternatives: switching to SMP code
Mar 25 20:01:51 linux-7vph kernel: Booting processor 1 APIC 0x1 ip 0x6000
Mar 25 20:01:51 linux-7vph kernel: Initializing CPU#1
Mar 25 20:01:51 linux-7vph kernel: Calibrating delay using timer specific routine.. 5054.04 BogoMIPS (lpj=10108082)
Mar 25 20:01:51 linux-7vph kernel: CPU: L1 I cache: 32K, L1 D cache: 32K
Mar 25 20:01:51 linux-7vph kernel: CPU: L2 cache: 6144K
Mar 25 20:01:51 linux-7vph kernel: [ds] using Core 2/Atom configuration
Mar 25 20:01:51 linux-7vph kernel: CPU: Physical Processor ID: 0
Mar 25 20:01:51 linux-7vph kernel: CPU: Processor Core ID: 1
Mar 25 20:01:51 linux-7vph kernel: Intel machine check architecture supported.
Mar 25 20:01:51 linux-7vph kernel: Intel machine check reporting enabled on CPU#1.
Mar 25 20:01:51 linux-7vph kernel: x86 PAT enabled: cpu 1, old 0x7040600070406, new 0x7010600070106
Mar 25 20:01:51 linux-7vph kernel: CPU1: Intel(R) Core(TM)2 Duo CPU T9400 @ 2.53GHz stepping 06
Mar 25 20:01:51 linux-7vph kernel: CPU0 attaching NULL sched-domain.
Mar 25 20:01:51 linux-7vph kernel: Switched to high resolution mode on CPU 1
Mar 25 20:01:51 linux-7vph kernel: CPU0 attaching sched-domain:
Mar 25 20:01:51 linux-7vph kernel: domain 0: span 0-1 level MC
Mar 25 20:01:51 linux-7vph kernel: groups: 0 1
Mar 25 20:01:51 linux-7vph kernel: CPU1 attaching sched-domain:
Mar 25 20:01:51 linux-7vph kernel: domain 0: span 0-1 level MC
Mar 25 20:01:51 linux-7vph kernel: groups: 1 0
Mar 25 20:01:51 linux-7vph kernel: CPU1 is up
Mar 25 20:01:51 linux-7vph kernel: ACPI: Waking up from system sleep state S4
Mar 25 20:01:51 linux-7vph kernel: ACPI: EC: non-query interrupt received, switching to interrupt mode
Mar 25 20:01:51 linux-7vph kernel: pci 0000:00:02.0: PME# disabled
Mar 25 20:01:51 linux-7vph kernel: pci 0000:00:02.1: PME# disabled
Mar 25 20:01:51 linux-7vph kernel: pci 0000:00:03.0: PME# disabled
Mar 25 20:01:51 linux-7vph kernel: e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
Mar 25 20:01:51 linux-7vph kernel: e1000e 0000:00:19.0: setting latency timer to 64
Mar 25 20:01:51 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Mar 25 20:01:51 linux-7vph kernel: e1000e 0000:00:19.0: PME# disabled
Mar 25 20:01:51 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Mar 25 20:01:51 linux-7vph kernel: e1000e 0000:00:19.0: PME# disabled
Mar 25 20:01:51 linux-7vph kernel: e1000e 0000:00:19.0: irq 29 for MSI/MSI-X
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1a.0: setting latency timer to 64
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1a.1: setting latency timer to 64
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1a.2: power state changed by ACPI to D0
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 22 (level, low) -> IRQ 22
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1a.2: setting latency timer to 64
Mar 25 20:01:51 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
Mar 25 20:01:51 linux-7vph kernel: ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0
Mar 25 20:01:51 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PCI INT D -> GSI 23 (level, low) -> IRQ 23
Mar 25 20:01:51 linux-7vph kernel: ehci_hcd 0000:00:1a.7: setting latency timer to 64
Mar 25 20:01:51 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
Mar 25 20:01:51 linux-7vph kernel: HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Mar 25 20:01:51 linux-7vph kernel: HDA Intel 0000:00:1b.0: setting latency timer to 64
Mar 25 20:01:51 linux-7vph kernel: pcieport-driver 0000:00:1c.0: setting latency timer to 64
Mar 25 20:01:51 linux-7vph kernel: pcieport-driver 0000:00:1c.1: setting latency timer to 64
Mar 25 20:01:51 linux-7vph kernel: pcieport-driver 0000:00:1c.3: setting latency timer to 64
Mar 25 20:01:51 linux-7vph kernel: pcieport-driver 0000:00:1c.4: setting latency timer to 64
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1d.0: setting latency timer to 64
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1d.1: setting latency timer to 64
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
Mar 25 20:01:51 linux-7vph kernel: uhci_hcd 0000:00:1d.2: setting latency timer to 64
Mar 25 20:01:51 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
Mar 25 20:01:51 linux-7vph kernel: ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0
Mar 25 20:01:51 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19
Mar 25 20:01:51 linux-7vph kernel: ehci_hcd 0000:00:1d.7: setting latency timer to 64
Mar 25 20:01:51 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
Mar 25 20:01:51 linux-7vph kernel: pci 0000:00:1e.0: setting latency timer to 64
Mar 25 20:01:51 linux-7vph kernel: ahci 0000:00:1f.2: setting latency timer to 64
Mar 25 20:01:51 linux-7vph kernel: iwlagn 0000:03:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
Mar 25 20:01:51 linux-7vph kernel: iwlagn 0000:03:00.0: irq 30 for MSI/MSI-X
Mar 25 20:01:51 linux-7vph kernel: pci 0000:15:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Mar 25 20:01:51 linux-7vph kernel: Registered led device: iwl-phy0:radio
Mar 25 20:01:51 linux-7vph kernel: Registered led device: iwl-phy0:assoc
Mar 25 20:01:51 linux-7vph kernel: Registered led device: iwl-phy0:RX
Mar 25 20:01:51 linux-7vph kernel: Registered led device: iwl-phy0:TX
Mar 25 20:01:51 linux-7vph kernel: ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f4801000-f48017ff] Max Packet=[2048] IR/IT contexts=[4/4]
Mar 25 20:01:51 linux-7vph kernel: pci 0000:15:00.2: PME# disabled
Mar 25 20:01:51 linux-7vph kernel: pci 0000:15:00.3: PME# disabled
Mar 25 20:01:51 linux-7vph kernel: pci 0000:15:00.4: PME# disabled
Mar 25 20:01:51 linux-7vph kernel: pci 0000:15:00.5: PME# disabled
Mar 25 20:01:51 linux-7vph kernel: sd 0:0:0:0: [sda] Starting disk
Mar 25 20:01:51 linux-7vph kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 25 20:01:51 linux-7vph kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 25 20:01:51 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Mar 25 20:01:51 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Mar 25 20:01:51 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Mar 25 20:01:51 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Mar 25 20:01:51 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Mar 25 20:01:51 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Mar 25 20:01:51 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Mar 25 20:01:51 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Mar 25 20:01:51 linux-7vph kernel: ata1.00: configured for UDMA/133
Mar 25 20:01:51 linux-7vph kernel: ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
Mar 25 20:01:51 linux-7vph kernel: ata1: irq_stat 0x00400040, connection status changed
Mar 25 20:01:51 linux-7vph kernel: ata1.00: configured for UDMA/133
Mar 25 20:01:51 linux-7vph kernel: ata1: EH complete
Mar 25 20:01:51 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Mar 25 20:01:51 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Mar 25 20:01:51 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Mar 25 20:01:51 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 25 20:01:51 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Mar 25 20:01:51 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Mar 25 20:01:51 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Mar 25 20:01:51 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 25 20:01:51 linux-7vph kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded
Mar 25 20:01:51 linux-7vph kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded
Mar 25 20:01:51 linux-7vph kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded
Mar 25 20:01:51 linux-7vph kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded
Mar 25 20:01:51 linux-7vph kernel: ata2.00: configured for UDMA/133
Mar 25 20:01:51 linux-7vph kernel: Restarting tasks ... <3>ata2: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
Mar 25 20:01:51 linux-7vph kernel: ata2: irq_stat 0x40000001
Mar 25 20:01:51 linux-7vph kernel: done.
Mar 25 20:01:51 linux-7vph kernel: ata2.00: configured for UDMA/133
Mar 25 20:01:51 linux-7vph kernel: ata2: EH complete

Next message: Eric Anholt: "DRM lock ordering fix series"
Previous message: Christian Thaeter: "[PATCH] hpfall.c improvements, thoughts"
In reply to: Jeff Garzik: "Re: 2.6.29 regression: ATA bus errors on resume"
Next in thread: James Bottomley: "Re: 2.6.29 regression: ATA bus errors on resume"
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]