Re: 2.6.29 regression: ATA bus errors on resume (was: EXT4: __ext4_get_inode

All of lore.kernel.org
 help / color / mirror / Atom feed

* Re: 2.6.29 regression: ATA bus errors on resume (was: EXT4: __ext4_get_inode_loc errors after s2disk)
       [not found] ` <cjq6y-6sq-11@gated-at.bofh.it>
@ 2009-03-25  5:19   ` Niel Lambrechts
  2009-03-25  6:06     ` 2.6.29 regression: ATA bus errors on resume Jeff Garzik
  0 siblings, 1 reply; 5+ messages in thread
From: Niel Lambrechts @ 2009-03-25  5:19 UTC (permalink / raw)
  To: linux.kernel

[-- Attachment #1: Type: text/plain, Size: 1172 bytes --]

On 03/25/2009 03:30 AM, Theodore Tso wrote:
> On Tue, Mar 24, 2009 at 10:25:57PM +0200, Niel Lambrechts wrote:
>> Hi,
>>
>> After upgrading to 2.6.29 I get the below errors after resuming from
>> hibernating with s2disk. I ran fsck and tried doing the same thing again
>> in 2.6.28.9-pae, but do not get any errors there.
> 
> The ext4 errors are interleaved with hardware errors, and the ext4
> errors are about I/O errors.
> 
> EXT4-fs error (device sda6): __ext4_get_inode_loc: unable to read inode block - inode=2346519
> EXT4-fs error (device sda6) in ext4_reserve_inode_write: IO failure
> 
> This looks more like a hibernation problem than an ext4 problem.
> Looks like the hard drive is being left in some inconsistent state
> after resuming from hibernation.
> 
>      	   	       		   	   - Ted

Thanks for the info Theodore, this is definitely looks like some type of
regression in 2.6.29, as the problem is not evident when I s2disk using
2.6.28.9, even after multiple suspend/resume cycles.

I found some 'ATA bus errors' and 'SError' messages in
/var/log/messages, so I've attached the messages from both 2.6.29 and
2.6.28 for comparison.

Regards,
Niel
---

[-- Attachment #2: messages.2.6.29.txt --]
[-- Type: text/plain, Size: 40131 bytes --]

Mar 24 21:29:10 linux-7vph kernel: Syncing filesystems ... done.
Mar 24 21:29:10 linux-7vph kernel: Freezing user space processes ... (elapsed 0.00 seconds) done.
Mar 24 21:29:10 linux-7vph kernel: Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
Mar 24 21:29:10 linux-7vph kernel: PM: Shrinking memory... done (50929 pages freed)
Mar 24 21:29:10 linux-7vph kernel: PM: Freed 203716 kbytes in 1.66 seconds (122.72 MB/s)
Mar 24 21:29:10 linux-7vph kernel: Suspending console(s) (use no_console_suspend to debug)
Mar 24 21:29:10 linux-7vph kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Mar 24 21:29:10 linux-7vph kernel: ACPI handle has no context!
Mar 24 21:29:10 linux-7vph kernel: iwlagn 0000:03:00.0: PCI INT A disabled
Mar 24 21:29:10 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PCI INT D disabled
Mar 24 21:29:10 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
Mar 24 21:29:10 linux-7vph kernel: uhci_hcd 0000:00:1d.2: PCI INT C disabled
Mar 24 21:29:12 linux-7vph kernel: uhci_hcd 0000:00:1d.1: PCI INT B disabled
Mar 24 21:29:12 linux-7vph kernel: uhci_hcd 0000:00:1d.0: PCI INT A disabled
Mar 24 21:29:12 linux-7vph kernel: HDA Intel 0000:00:1b.0: PCI INT B disabled
Mar 24 21:29:12 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PCI INT D disabled
Mar 24 21:29:12 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
Mar 24 21:29:12 linux-7vph kernel: uhci_hcd 0000:00:1a.2: PCI INT C disabled
Mar 24 21:29:12 linux-7vph kernel: uhci_hcd 0000:00:1a.1: PCI INT B disabled
Mar 24 21:29:12 linux-7vph kernel: uhci_hcd 0000:00:1a.0: PCI INT A disabled
Mar 24 21:29:12 linux-7vph kernel: e1000e 0000:00:19.0: PME# enabled
Mar 24 21:29:12 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Mar 24 21:29:13 linux-7vph kernel: e1000e 0000:00:19.0: PME# enabled
Mar 24 21:29:13 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Mar 24 21:29:13 linux-7vph kernel: e1000e 0000:00:19.0: PCI INT A disabled
Mar 24 21:29:13 linux-7vph kernel: ACPI: Preparing to enter system sleep state S4
Mar 24 21:29:13 linux-7vph kernel: PM: Saving platform NVS memory
Mar 24 21:29:13 linux-7vph kernel: Disabling non-boot CPUs ...
Mar 24 21:29:13 linux-7vph kernel: CPU 1 is now offline
Mar 24 21:29:13 linux-7vph kernel: SMP alternatives: switching to UP code
Mar 24 21:29:13 linux-7vph kernel: CPU0 attaching NULL sched-domain.
Mar 24 21:29:13 linux-7vph kernel: CPU1 attaching NULL sched-domain.
Mar 24 21:29:13 linux-7vph kernel: CPU0 attaching NULL sched-domain.
Mar 24 21:29:13 linux-7vph kernel: CPU1 is down
Mar 24 21:29:13 linux-7vph kernel: Extended CMOS year: 2000
Mar 24 21:29:13 linux-7vph kernel: PM: Creating hibernation image: 
Mar 24 21:29:13 linux-7vph kernel: PM: Need to copy 123393 pages
Mar 24 21:29:13 linux-7vph kernel: x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
Mar 24 21:29:13 linux-7vph kernel: Intel machine check architecture supported.
Mar 24 21:29:13 linux-7vph kernel: Intel machine check reporting enabled on CPU#0.
Mar 24 21:29:13 linux-7vph kernel: PM: Restoring platform NVS memory
Mar 24 21:29:13 linux-7vph kernel: Extended CMOS year: 2000
Mar 24 21:29:13 linux-7vph kernel: pci 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900403)
Mar 24 21:29:13 linux-7vph kernel: HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102)
Mar 24 21:29:13 linux-7vph kernel: ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00403, writing 0x2b00407)
Mar 24 21:29:13 linux-7vph kernel: Enabling non-boot CPUs ...
Mar 24 21:29:13 linux-7vph kernel: SMP alternatives: switching to SMP code
Mar 24 21:29:13 linux-7vph kernel: Booting processor 1 APIC 0x1 ip 0x6000
Mar 24 21:29:13 linux-7vph kernel: Initializing CPU#1
Mar 24 21:29:13 linux-7vph kernel: Calibrating delay using timer specific routine.. 5054.02 BogoMIPS (lpj=10108052)
Mar 24 21:29:13 linux-7vph kernel: CPU: L1 I cache: 32K, L1 D cache: 32K
Mar 24 21:29:13 linux-7vph kernel: CPU: L2 cache: 6144K
Mar 24 21:29:13 linux-7vph kernel: [ds] using Core 2/Atom configuration
Mar 24 21:29:13 linux-7vph kernel: CPU: Physical Processor ID: 0
Mar 24 21:29:13 linux-7vph kernel: CPU: Processor Core ID: 1
Mar 24 21:29:13 linux-7vph kernel: Intel machine check architecture supported.
Mar 24 21:29:13 linux-7vph kernel: Intel machine check reporting enabled on CPU#1.
Mar 24 21:29:13 linux-7vph kernel: x86 PAT enabled: cpu 1, old 0x7040600070406, new 0x7010600070106
Mar 24 21:29:13 linux-7vph kernel: CPU1: Intel(R) Core(TM)2 Duo CPU     T9400  @ 2.53GHz stepping 06
Mar 24 21:29:13 linux-7vph kernel: CPU0 attaching NULL sched-domain.
Mar 24 21:29:13 linux-7vph kernel: Switched to high resolution mode on CPU 1
Mar 24 21:29:13 linux-7vph kernel: CPU0 attaching sched-domain:
Mar 24 21:29:13 linux-7vph kernel:  domain 0: span 0-1 level MC
Mar 24 21:29:13 linux-7vph kernel:   groups: 0 1
Mar 24 21:29:13 linux-7vph kernel:   domain 1: span 0-1 level CPU
Mar 24 21:29:13 linux-7vph kernel:    groups: 0-1
Mar 24 21:29:13 linux-7vph kernel: CPU1 attaching sched-domain:
Mar 24 21:29:13 linux-7vph kernel:  domain 0: span 0-1 level MC
Mar 24 21:29:13 linux-7vph kernel:   groups: 1 0
Mar 24 21:29:13 linux-7vph kernel:   domain 1: span 0-1 level CPU
Mar 24 21:29:13 linux-7vph kernel:    groups: 0-1
Mar 24 21:29:13 linux-7vph kernel: CPU1 is up
Mar 24 21:29:13 linux-7vph kernel: ACPI: Waking up from system sleep state S4
Mar 24 21:29:13 linux-7vph kernel: ACPI: EC: non-query interrupt received, switching to interrupt mode
Mar 24 21:29:13 linux-7vph kernel: pci 0000:00:02.0: PME# disabled
Mar 24 21:29:13 linux-7vph kernel: pci 0000:00:02.0: power state changed by ACPI to D0
Mar 24 21:29:13 linux-7vph kernel: pci 0000:00:02.0: setting latency timer to 64
Mar 24 21:29:13 linux-7vph kernel: pci 0000:00:02.1: PME# disabled
Mar 24 21:29:13 linux-7vph kernel: pci 0000:00:03.0: PME# disabled
Mar 24 21:29:13 linux-7vph kernel: e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
Mar 24 21:29:13 linux-7vph kernel: e1000e 0000:00:19.0: setting latency timer to 64
Mar 24 21:29:13 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Mar 24 21:29:13 linux-7vph kernel: e1000e 0000:00:19.0: PME# disabled
Mar 24 21:29:13 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Mar 24 21:29:14 linux-7vph kernel: e1000e 0000:00:19.0: PME# disabled
Mar 24 21:29:14 linux-7vph kernel: e1000e 0000:00:19.0: irq 29 for MSI/MSI-X
Mar 24 21:29:14 linux-7vph kernel: uhci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 24 21:29:14 linux-7vph kernel: uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
Mar 24 21:29:14 linux-7vph kernel: uhci_hcd 0000:00:1a.0: setting latency timer to 64
Mar 24 21:29:14 linux-7vph kernel: uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21
Mar 24 21:29:14 linux-7vph kernel: uhci_hcd 0000:00:1a.1: setting latency timer to 64
Mar 24 21:29:14 linux-7vph kernel: uhci_hcd 0000:00:1a.2: power state changed by ACPI to D0
Mar 24 21:29:14 linux-7vph kernel: uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 22 (level, low) -> IRQ 22
Mar 24 21:29:14 linux-7vph kernel: uhci_hcd 0000:00:1a.2: setting latency timer to 64
Mar 24 21:29:14 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
Mar 24 21:29:14 linux-7vph kernel: ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0
Mar 24 21:29:14 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PCI INT D -> GSI 23 (level, low) -> IRQ 23
Mar 24 21:29:14 linux-7vph kernel: ehci_hcd 0000:00:1a.7: setting latency timer to 64
Mar 24 21:29:14 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
Mar 24 21:29:14 linux-7vph kernel: HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Mar 24 21:29:14 linux-7vph kernel: HDA Intel 0000:00:1b.0: setting latency timer to 64
Mar 24 21:29:14 linux-7vph kernel: pcieport-driver 0000:00:1c.0: setting latency timer to 64
Mar 24 21:29:14 linux-7vph kernel: pcieport-driver 0000:00:1c.1: setting latency timer to 64
Mar 24 21:29:14 linux-7vph kernel: pcieport-driver 0000:00:1c.3: setting latency timer to 64
Mar 24 21:29:14 linux-7vph kernel: pcieport-driver 0000:00:1c.4: setting latency timer to 64
Mar 24 21:29:14 linux-7vph kernel: uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 24 21:29:14 linux-7vph kernel: uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Mar 24 21:29:14 linux-7vph kernel: uhci_hcd 0000:00:1d.0: setting latency timer to 64
Mar 24 21:29:14 linux-7vph kernel: uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Mar 24 21:29:14 linux-7vph kernel: uhci_hcd 0000:00:1d.1: setting latency timer to 64
Mar 24 21:29:14 linux-7vph kernel: uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
Mar 24 21:29:14 linux-7vph kernel: uhci_hcd 0000:00:1d.2: setting latency timer to 64
Mar 24 21:29:14 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
Mar 24 21:29:14 linux-7vph kernel: ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0
Mar 24 21:29:14 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19
Mar 24 21:29:14 linux-7vph kernel: ehci_hcd 0000:00:1d.7: setting latency timer to 64
Mar 24 21:29:14 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
Mar 24 21:29:14 linux-7vph kernel: pci 0000:00:1e.0: setting latency timer to 64
Mar 24 21:29:14 linux-7vph kernel: ahci 0000:00:1f.2: setting latency timer to 64
Mar 24 21:29:14 linux-7vph kernel: iwlagn 0000:03:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
Mar 24 21:29:14 linux-7vph kernel: iwlagn 0000:03:00.0: irq 30 for MSI/MSI-X
Mar 24 21:29:14 linux-7vph kernel: pci 0000:15:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Mar 24 21:29:14 linux-7vph kernel: Registered led device: iwl-phy0:radio
Mar 24 21:29:14 linux-7vph kernel: Registered led device: iwl-phy0:assoc
Mar 24 21:29:14 linux-7vph kernel: Registered led device: iwl-phy0:RX
Mar 24 21:29:14 linux-7vph kernel: Registered led device: iwl-phy0:TX
Mar 24 21:29:14 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 24 21:29:14 linux-7vph kernel: pci 0000:15:00.2: PME# disabled
Mar 24 21:29:14 linux-7vph kernel: pci 0000:15:00.3: PME# disabled
Mar 24 21:29:14 linux-7vph kernel: pci 0000:15:00.4: PME# disabled
Mar 24 21:29:14 linux-7vph kernel: pci 0000:15:00.5: PME# disabled
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Starting disk
Mar 24 21:29:14 linux-7vph kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 24 21:29:14 linux-7vph kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Mar 24 21:29:14 linux-7vph kernel: ata1.00: configured for UDMA/133
Mar 24 21:29:14 linux-7vph kernel: ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
Mar 24 21:29:14 linux-7vph kernel: ata1: irq_stat 0x00400040, connection status changed
Mar 24 21:29:14 linux-7vph kernel: ata1.00: configured for UDMA/133
Mar 24 21:29:14 linux-7vph kernel: ata1: EH complete
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 24 21:29:14 linux-7vph kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded
Mar 24 21:29:14 linux-7vph kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded
Mar 24 21:29:14 linux-7vph kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded
Mar 24 21:29:14 linux-7vph kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded
Mar 24 21:29:14 linux-7vph kernel: ata2.00: configured for UDMA/133
Mar 24 21:29:14 linux-7vph kernel: pci 0000:00:02.0: power state changed by ACPI to D0
Mar 24 21:29:14 linux-7vph kernel: pci 0000:00:02.0: restoring config space at offset 0x1 (was 0x900407, writing 0x900403)
Mar 24 21:29:14 linux-7vph kernel: pci 0000:00:02.0: setting latency timer to 64
Mar 24 21:29:14 linux-7vph kernel: ata2: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
Mar 24 21:29:14 linux-7vph kernel: ata2: irq_stat 0x40000001
Mar 24 21:29:14 linux-7vph kernel: ata2.00: configured for UDMA/133
Mar 24 21:29:14 linux-7vph kernel: ata2: EH complete
Mar 24 21:29:14 linux-7vph kernel: Restarting tasks ... done.
Mar 24 21:29:14 linux-7vph kernel: ata1.00: exception Emask 0x10 SAct 0x3fff SErr 0x50000 action 0xe frozen
Mar 24 21:29:14 linux-7vph kernel: ata1.00: irq_stat 0x00400008, PHY RDY changed
Mar 24 21:29:14 linux-7vph kernel: ata1: SError: { PHYRdyChg CommWake }
Mar 24 21:29:14 linux-7vph kernel: ata1.00: cmd 60/18:00:77:88:6f/00:00:0e:00:00/40 tag 0 ncq 12288 in
Mar 24 21:29:14 linux-7vph kernel:          res 50/00:30:07:b3:10/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
Mar 24 21:29:14 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 24 21:29:14 linux-7vph kernel: ata1.00: cmd 60/08:08:6f:88:ef/01:00:0e:00:00/40 tag 1 ncq 135168 in
Mar 24 21:29:14 linux-7vph kernel:          res 50/00:30:07:b3:10/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
Mar 24 21:29:14 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 24 21:29:14 linux-7vph kernel: ata1.00: cmd 60/20:10:8f:88:6f/00:00:0e:00:00/40 tag 2 ncq 16384 in
Mar 24 21:29:14 linux-7vph kernel:          res 50/00:30:07:b3:10/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
Mar 24 21:29:14 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 24 21:29:14 linux-7vph kernel: ata1.00: cmd 60/80:18:af:88:6f/00:00:0e:00:00/40 tag 3 ncq 65536 in
Mar 24 21:29:14 linux-7vph kernel:          res 50/00:30:07:b3:10/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
Mar 24 21:29:14 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 24 21:29:14 linux-7vph kernel: ata1.00: cmd 60/40:20:37:89:6f/00:00:0e:00:00/40 tag 4 ncq 32768 in
Mar 24 21:29:14 linux-7vph kernel:          res 50/00:30:07:b3:10/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
Mar 24 21:29:14 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 24 21:29:14 linux-7vph kernel: ata1.00: cmd 60/08:28:6f:88:6f/00:00:0e:00:00/40 tag 5 ncq 4096 in
Mar 24 21:29:14 linux-7vph kernel:          res 50/00:30:07:b3:10/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
Mar 24 21:29:14 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 24 21:29:14 linux-7vph kernel: ata1.00: cmd 60/08:30:6f:88:11/00:00:0f:00:00/40 tag 6 ncq 4096 in
Mar 24 21:29:14 linux-7vph kernel:          res 50/00:30:07:b3:10/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
Mar 24 21:29:14 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 24 21:29:14 linux-7vph kernel: ata1.00: cmd 60/08:38:5f:08:2e/00:00:0b:00:00/40 tag 7 ncq 4096 in
Mar 24 21:29:14 linux-7vph kernel:          res 50/00:30:07:b3:10/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
Mar 24 21:29:14 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 24 21:29:14 linux-7vph kernel: ata1.00: cmd 60/08:40:6f:88:67/01:00:0e:00:00/40 tag 8 ncq 135168 in
Mar 24 21:29:14 linux-7vph kernel:          res 50/00:30:07:b3:10/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
Mar 24 21:29:14 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 24 21:29:14 linux-7vph kernel: ata1.00: cmd 60/30:48:8f:a2:56/00:00:0f:00:00/40 tag 9 ncq 24576 in
Mar 24 21:29:14 linux-7vph kernel:          res 50/00:30:07:b3:10/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
Mar 24 21:29:14 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 24 21:29:14 linux-7vph kernel: ata1.00: cmd 60/10:50:c7:a2:56/00:00:0f:00:00/40 tag 10 ncq 8192 in
Mar 24 21:29:14 linux-7vph kernel:          res 50/00:30:07:b3:10/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
Mar 24 21:29:14 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 24 21:29:14 linux-7vph kernel: ata1.00: cmd 60/10:58:ff:a2:56/00:00:0f:00:00/40 tag 11 ncq 8192 in
Mar 24 21:29:14 linux-7vph kernel:          res 50/00:30:07:b3:10/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
Mar 24 21:29:14 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 24 21:29:14 linux-7vph kernel: ata1.00: cmd 60/08:60:af:fb:48/00:00:0b:00:00/40 tag 12 ncq 4096 in
Mar 24 21:29:14 linux-7vph kernel:          res 50/00:30:07:b3:10/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
Mar 24 21:29:14 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 24 21:29:14 linux-7vph kernel: ata1.00: cmd 60/30:68:07:b3:10/00:00:0c:00:00/40 tag 13 ncq 24576 in
Mar 24 21:29:14 linux-7vph kernel:          res 50/00:30:07:b3:10/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
Mar 24 21:29:14 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 24 21:29:14 linux-7vph kernel: ata1: hard resetting link
Mar 24 21:29:14 linux-7vph kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Mar 24 21:29:14 linux-7vph kernel: ata1.00: configured for UDMA/133
Mar 24 21:29:14 linux-7vph kernel: ata1.00: configured for UDMA/133
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE,SUGGEST_OK
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Sense Key : Aborted Command [current] [descriptor]
Mar 24 21:29:14 linux-7vph kernel: Descriptor sense data with sense descriptors (in hex):
Mar 24 21:29:14 linux-7vph kernel:         72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00 
Mar 24 21:29:14 linux-7vph kernel:         0c 10 b3 07 
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Add. Sense: No additional sense information
Mar 24 21:29:14 linux-7vph kernel: end_request: I/O error, dev sda, sector 242190455
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE,SUGGEST_OK
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Sense Key : Aborted Command [current] [descriptor]
Mar 24 21:29:14 linux-7vph kernel: Descriptor sense data with sense descriptors (in hex):
Mar 24 21:29:14 linux-7vph kernel:         72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00 
Mar 24 21:29:14 linux-7vph kernel:         0c 10 b3 07 
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Add. Sense: No additional sense information
Mar 24 21:29:14 linux-7vph kernel: end_request: I/O error, dev sda, sector 250579055
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE,SUGGEST_OK
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Sense Key : Aborted Command [current] [descriptor]
Mar 24 21:29:14 linux-7vph kernel: Descriptor sense data with sense descriptors (in hex):
Mar 24 21:29:14 linux-7vph kernel: EXT4-fs error (device sda6): __ext4_get_inode_loc: <6>        72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00 
Mar 24 21:29:14 linux-7vph kernel:         0c 10 b3 07 
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] unable to read inode block - inode=2608204, block=10452997Add. Sense: No additional sense information
Mar 24 21:29:14 linux-7vph kernel: end_request: I/O error, dev sda, sector 242190479
Mar 24 21:29:14 linux-7vph kernel: 
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE,SUGGEST_OK
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Sense Key : Aborted Command [current] [descriptor]
Mar 24 21:29:14 linux-7vph kernel: Descriptor sense data with sense descriptors (in hex):
Mar 24 21:29:14 linux-7vph kernel:         72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00 
Mar 24 21:29:14 linux-7vph kernel:         0c 10 b3 07 
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Add. Sense: No additional sense information
Mar 24 21:29:14 linux-7vph kernel: end_request: I/O error, dev sda, sector 242190511
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE,SUGGEST_OK
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Sense Key : Aborted Command [current] [descriptor]
Mar 24 21:29:14 linux-7vph kernel: Descriptor sense data with sense descriptors (in hex):
Mar 24 21:29:14 linux-7vph kernel:         72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00 
Mar 24 21:29:14 linux-7vph kernel:         0c 10 b3 07 
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Add. Sense: No additional sense information
Mar 24 21:29:14 linux-7vph kernel: end_request: I/O error, dev sda, sector 242190647
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE,SUGGEST_OK
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Sense Key : Aborted Command [current] [descriptor]
Mar 24 21:29:14 linux-7vph kernel: Descriptor sense data with sense descriptors (in hex):
Mar 24 21:29:14 linux-7vph kernel:         72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00 
Mar 24 21:29:14 linux-7vph kernel:         0c 10 b3 07 
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Add. Sense: No additional sense information
Mar 24 21:29:14 linux-7vph kernel: end_request: I/O error, dev sda, sector 241666159
Mar 24 21:29:14 linux-7vph kernel: ata1: EH complete
Mar 24 21:29:14 linux-7vph kernel: EXT4-fs error (device sda6): __ext4_get_inode_loc: unable to read inode block - inode=2330178, block=9338883
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 24 21:29:14 linux-7vph kernel: EXT4-fs error (device sda6) in ext4_reserve_inode_write: IO failure
Mar 24 21:29:14 linux-7vph kernel: EXT4-fs error (device sda6) in ext4_reserve_inode_write: IO failure
Mar 24 21:32:33 linux-7vph dbus-daemon: Rejected send message, 3 matched rules; type="method_call", sender=":1.38" (uid=1000 pid=4196 comm="/usr/lib/firefox/firefox ") interface="org.freedesktop.NetworkManager" member="state" error name="(unset)" requested_reply=0 destination="org.freedesktop.NetworkManager" (uid=0 pid=2823 comm="/usr/sbin/NetworkManager "))
Mar 24 21:34:01 linux-7vph kernel: [drm:gm45_get_vblank_counter] *ERROR* trying to get vblank count for disabled pipe 0
Mar 24 21:34:18 linux-7vph kernel: ehci_hcd 0000:00:1d.7: remove, state 4
Mar 24 21:34:18 linux-7vph kernel: usb usb8: USB disconnect, address 1
Mar 24 21:34:18 linux-7vph kernel: ehci_hcd 0000:00:1d.7: USB bus 8 deregistered
Mar 24 21:34:18 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PCI INT D disabled
Mar 24 21:34:18 linux-7vph kernel: ehci_hcd 0000:00:1a.7: remove, state 4
Mar 24 21:34:18 linux-7vph kernel: usb usb7: USB disconnect, address 1
Mar 24 21:34:18 linux-7vph kernel: ehci_hcd 0000:00:1a.7: USB bus 7 deregistered
Mar 24 21:34:18 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PCI INT D disabled
Mar 24 21:34:20 linux-7vph kernel: uhci_hcd 0000:00:1d.2: remove, state 4
Mar 24 21:34:20 linux-7vph kernel: usb usb6: USB disconnect, address 1
Mar 24 21:34:20 linux-7vph kernel: uhci_hcd 0000:00:1d.2: USB bus 6 deregistered
Mar 24 21:34:20 linux-7vph kernel: uhci_hcd 0000:00:1d.2: PCI INT C disabled
Mar 24 21:34:20 linux-7vph kernel: uhci_hcd 0000:00:1d.1: remove, state 4
Mar 24 21:34:20 linux-7vph kernel: usb usb5: USB disconnect, address 1
Mar 24 21:34:20 linux-7vph kernel: uhci_hcd 0000:00:1d.1: USB bus 5 deregistered
Mar 24 21:34:20 linux-7vph kernel: uhci_hcd 0000:00:1d.1: PCI INT B disabled
Mar 24 21:34:20 linux-7vph kernel: uhci_hcd 0000:00:1d.0: remove, state 4
Mar 24 21:34:20 linux-7vph kernel: usb usb4: USB disconnect, address 1
Mar 24 21:34:20 linux-7vph kernel: uhci_hcd 0000:00:1d.0: USB bus 4 deregistered
Mar 24 21:34:20 linux-7vph kernel: uhci_hcd 0000:00:1d.0: PCI INT A disabled
Mar 24 21:34:20 linux-7vph kernel: uhci_hcd 0000:00:1a.2: remove, state 4
Mar 24 21:34:20 linux-7vph kernel: usb usb3: USB disconnect, address 1
Mar 24 21:34:20 linux-7vph kernel: uhci_hcd 0000:00:1a.2: USB bus 3 deregistered
Mar 24 21:34:20 linux-7vph kernel: uhci_hcd 0000:00:1a.2: PCI INT C disabled
Mar 24 21:34:20 linux-7vph kernel: uhci_hcd 0000:00:1a.1: remove, state 1
Mar 24 21:34:20 linux-7vph kernel: usb usb2: USB disconnect, address 1
Mar 24 21:34:20 linux-7vph kernel: usb 2-1: USB disconnect, address 2
Mar 24 21:34:20 linux-7vph kernel: uhci_hcd 0000:00:1a.1: USB bus 2 deregistered
Mar 24 21:34:20 linux-7vph kernel: uhci_hcd 0000:00:1a.1: PCI INT B disabled
Mar 24 21:34:20 linux-7vph kernel: uhci_hcd 0000:00:1a.0: remove, state 4
Mar 24 21:34:20 linux-7vph kernel: usb usb1: USB disconnect, address 1
Mar 24 21:34:20 linux-7vph kernel: uhci_hcd 0000:00:1a.0: USB bus 1 deregistered
Mar 24 21:34:20 linux-7vph kernel: uhci_hcd 0000:00:1a.0: PCI INT A disabled
Mar 24 21:34:26 linux-7vph kernel: [drm:gm45_get_vblank_counter] *ERROR* trying to get vblank count for disabled pipe 0
Mar 24 21:37:36 linux-7vph kernel: Syncing filesystems ... done.
Mar 24 21:37:37 linux-7vph kernel: Freezing user space processes ... (elapsed 0.00 seconds) done.
Mar 24 21:37:37 linux-7vph kernel: Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
Mar 24 21:37:37 linux-7vph kernel: PM: Shrinking memory... done (58007 pages freed)
Mar 24 21:37:37 linux-7vph kernel: PM: Freed 232028 kbytes in 2.98 seconds (77.86 MB/s)
Mar 24 21:37:37 linux-7vph kernel: Suspending console(s) (use no_console_suspend to debug)
Mar 24 21:37:37 linux-7vph kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Mar 24 21:37:37 linux-7vph kernel: ACPI handle has no context!
Mar 24 21:37:37 linux-7vph kernel: iwlagn 0000:03:00.0: PCI INT A disabled
Mar 24 21:37:37 linux-7vph kernel: HDA Intel 0000:00:1b.0: PCI INT B disabled
Mar 24 21:37:37 linux-7vph kernel: e1000e 0000:00:19.0: PME# enabled
Mar 24 21:37:37 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Mar 24 21:37:37 linux-7vph kernel: e1000e 0000:00:19.0: PME# enabled
Mar 24 21:37:37 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Mar 24 21:37:37 linux-7vph kernel: e1000e 0000:00:19.0: PCI INT A disabled
Mar 24 21:37:37 linux-7vph kernel: ACPI: Preparing to enter system sleep state S4
Mar 24 21:37:37 linux-7vph kernel: PM: Saving platform NVS memory
Mar 24 21:37:42 linux-7vph kernel: Disabling non-boot CPUs ...
Mar 24 21:37:42 linux-7vph kernel: CPU 1 is now offline
Mar 24 21:37:42 linux-7vph kernel: SMP alternatives: switching to UP code
Mar 24 21:37:42 linux-7vph kernel: CPU0 attaching NULL sched-domain.
Mar 24 21:37:42 linux-7vph kernel: CPU1 attaching NULL sched-domain.
Mar 24 21:37:42 linux-7vph kernel: CPU0 attaching NULL sched-domain.
Mar 24 21:37:42 linux-7vph kernel: CPU1 is down
Mar 24 21:37:42 linux-7vph kernel: Extended CMOS year: 2000
Mar 24 21:37:42 linux-7vph kernel: PM: Creating hibernation image: 
Mar 24 21:37:42 linux-7vph kernel: PM: Need to copy 124699 pages
Mar 24 21:37:42 linux-7vph kernel: x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
Mar 24 21:37:42 linux-7vph kernel: Intel machine check architecture supported.
Mar 24 21:37:42 linux-7vph kernel: Intel machine check reporting enabled on CPU#0.
Mar 24 21:37:42 linux-7vph kernel: PM: Restoring platform NVS memory
Mar 24 21:37:42 linux-7vph kernel: Extended CMOS year: 2000
Mar 24 21:37:42 linux-7vph kernel: pci 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900403)
Mar 24 21:37:42 linux-7vph kernel: HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102)
Mar 24 21:37:42 linux-7vph kernel: ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00403, writing 0x2b00407)
Mar 24 21:37:42 linux-7vph kernel: Enabling non-boot CPUs ...
Mar 24 21:37:42 linux-7vph kernel: SMP alternatives: switching to SMP code
Mar 24 21:37:43 linux-7vph kernel: Booting processor 1 APIC 0x1 ip 0x6000
Mar 24 21:37:43 linux-7vph kernel: Initializing CPU#1
Mar 24 21:37:43 linux-7vph kernel: Calibrating delay using timer specific routine.. 5053.99 BogoMIPS (lpj=10107989)
Mar 24 21:37:43 linux-7vph kernel: CPU: L1 I cache: 32K, L1 D cache: 32K
Mar 24 21:37:43 linux-7vph kernel: CPU: L2 cache: 6144K
Mar 24 21:37:43 linux-7vph kernel: [ds] using Core 2/Atom configuration
Mar 24 21:37:43 linux-7vph kernel: CPU: Physical Processor ID: 0
Mar 24 21:37:43 linux-7vph kernel: CPU: Processor Core ID: 1
Mar 24 21:37:43 linux-7vph kernel: Intel machine check architecture supported.
Mar 24 21:37:43 linux-7vph kernel: Intel machine check reporting enabled on CPU#1.
Mar 24 21:37:43 linux-7vph kernel: x86 PAT enabled: cpu 1, old 0x7040600070406, new 0x7010600070106
Mar 24 21:37:43 linux-7vph kernel: CPU1: Intel(R) Core(TM)2 Duo CPU     T9400  @ 2.53GHz stepping 06
Mar 24 21:37:43 linux-7vph kernel: CPU0 attaching NULL sched-domain.
Mar 24 21:37:43 linux-7vph kernel: Switched to high resolution mode on CPU 1
Mar 24 21:37:43 linux-7vph kernel: CPU0 attaching sched-domain:
Mar 24 21:37:43 linux-7vph kernel:  domain 0: span 0-1 level MC
Mar 24 21:37:43 linux-7vph kernel:   groups: 0 1
Mar 24 21:37:43 linux-7vph kernel:   domain 1: span 0-1 level CPU
Mar 24 21:37:43 linux-7vph kernel:    groups: 0-1
Mar 24 21:37:43 linux-7vph kernel: CPU1 attaching sched-domain:
Mar 24 21:37:43 linux-7vph kernel:  domain 0: span 0-1 level MC
Mar 24 21:37:43 linux-7vph kernel:   groups: 1 0
Mar 24 21:37:43 linux-7vph kernel:   domain 1: span 0-1 level CPU
Mar 24 21:37:43 linux-7vph kernel:    groups: 0-1
Mar 24 21:37:43 linux-7vph kernel: CPU1 is up
Mar 24 21:37:43 linux-7vph kernel: ACPI: Waking up from system sleep state S4
Mar 24 21:37:43 linux-7vph kernel: ACPI: EC: non-query interrupt received, switching to interrupt mode
Mar 24 21:37:43 linux-7vph kernel: pci 0000:00:02.0: PME# disabled
Mar 24 21:37:43 linux-7vph kernel: pci 0000:00:02.0: power state changed by ACPI to D0
Mar 24 21:37:43 linux-7vph kernel: pci 0000:00:02.0: setting latency timer to 64
Mar 24 21:37:43 linux-7vph kernel: pci 0000:00:02.1: PME# disabled
Mar 24 21:37:43 linux-7vph kernel: pci 0000:00:03.0: PME# disabled
Mar 24 21:37:43 linux-7vph kernel: e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
Mar 24 21:37:43 linux-7vph kernel: e1000e 0000:00:19.0: setting latency timer to 64
Mar 24 21:37:43 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Mar 24 21:37:43 linux-7vph kernel: e1000e 0000:00:19.0: PME# disabled
Mar 24 21:37:43 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Mar 24 21:37:43 linux-7vph kernel: e1000e 0000:00:19.0: PME# disabled
Mar 24 21:37:43 linux-7vph kernel: e1000e 0000:00:19.0: irq 29 for MSI/MSI-X
Mar 24 21:37:43 linux-7vph kernel: pci 0000:00:1a.7: PME# disabled
Mar 24 21:37:43 linux-7vph kernel: HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Mar 24 21:37:43 linux-7vph kernel: HDA Intel 0000:00:1b.0: setting latency timer to 64
Mar 24 21:37:43 linux-7vph kernel: pcieport-driver 0000:00:1c.0: setting latency timer to 64
Mar 24 21:37:43 linux-7vph kernel: pcieport-driver 0000:00:1c.1: setting latency timer to 64
Mar 24 21:37:43 linux-7vph kernel: pcieport-driver 0000:00:1c.3: setting latency timer to 64
Mar 24 21:37:43 linux-7vph kernel: pcieport-driver 0000:00:1c.4: setting latency timer to 64
Mar 24 21:37:43 linux-7vph kernel: pci 0000:00:1d.7: PME# disabled
Mar 24 21:37:43 linux-7vph kernel: pci 0000:00:1e.0: setting latency timer to 64
Mar 24 21:37:43 linux-7vph kernel: ahci 0000:00:1f.2: setting latency timer to 64
Mar 24 21:37:43 linux-7vph kernel: iwlagn 0000:03:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
Mar 24 21:37:43 linux-7vph kernel: iwlagn 0000:03:00.0: irq 30 for MSI/MSI-X
Mar 24 21:37:43 linux-7vph kernel: pci 0000:15:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Mar 24 21:37:43 linux-7vph kernel: Registered led device: iwl-phy0:radio
Mar 24 21:37:43 linux-7vph kernel: Registered led device: iwl-phy0:assoc
Mar 24 21:37:43 linux-7vph kernel: Registered led device: iwl-phy0:RX
Mar 24 21:37:43 linux-7vph kernel: Registered led device: iwl-phy0:TX
Mar 24 21:37:43 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 24 21:37:43 linux-7vph kernel: pci 0000:15:00.2: PME# disabled
Mar 24 21:37:43 linux-7vph kernel: pci 0000:15:00.3: PME# disabled
Mar 24 21:37:43 linux-7vph kernel: pci 0000:15:00.4: PME# disabled
Mar 24 21:37:43 linux-7vph kernel: pci 0000:15:00.5: PME# disabled
Mar 24 21:37:43 linux-7vph kernel: sd 0:0:0:0: [sda] Starting disk
Mar 24 21:37:43 linux-7vph kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 24 21:37:43 linux-7vph kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 24 21:37:43 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Mar 24 21:37:43 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Mar 24 21:37:43 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Mar 24 21:37:43 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Mar 24 21:37:43 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Mar 24 21:37:43 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Mar 24 21:37:43 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Mar 24 21:37:43 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Mar 24 21:37:43 linux-7vph kernel: ata1.00: configured for UDMA/133
Mar 24 21:37:43 linux-7vph kernel: ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
Mar 24 21:37:43 linux-7vph kernel: ata1: irq_stat 0x00400040, connection status changed
Mar 24 21:37:43 linux-7vph kernel: ata1.00: configured for UDMA/133
Mar 24 21:37:43 linux-7vph kernel: ata1: EH complete
Mar 24 21:37:43 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Mar 24 21:37:43 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Mar 24 21:37:43 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Mar 24 21:37:43 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 24 21:37:43 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Mar 24 21:37:43 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Mar 24 21:37:43 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Mar 24 21:37:43 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 24 21:37:43 linux-7vph kernel: pci 0000:00:02.0: power state changed by ACPI to D0
Mar 24 21:37:43 linux-7vph kernel: pci 0000:00:02.0: restoring config space at offset 0x1 (was 0x900407, writing 0x900403)
Mar 24 21:37:43 linux-7vph kernel: pci 0000:00:02.0: setting latency timer to 64
Mar 24 21:37:43 linux-7vph kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded
Mar 24 21:37:43 linux-7vph kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded
Mar 24 21:37:43 linux-7vph kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded
Mar 24 21:37:43 linux-7vph kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded
Mar 24 21:37:43 linux-7vph kernel: ata2.00: configured for UDMA/133
Mar 24 21:37:43 linux-7vph kernel: Restarting tasks ... done.
Mar 24 21:37:43 linux-7vph kernel: ata2: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
Mar 24 21:37:43 linux-7vph kernel: ata2: irq_stat 0x40000001
Mar 24 21:37:43 linux-7vph kernel: ata2.00: configured for UDMA/133
Mar 24 21:37:43 linux-7vph kernel: ata2: EH complete
Mar 24 21:37:43 linux-7vph kernel: ata1: limiting SATA link speed to 1.5 Gbps
Mar 24 21:37:43 linux-7vph kernel: ata1.00: exception Emask 0x10 SAct 0x10 SErr 0x10000 action 0xe frozen
Mar 24 21:37:43 linux-7vph kernel: ata1.00: irq_stat 0x00400008, PHY RDY changed
Mar 24 21:37:43 linux-7vph kernel: ata1: SError: { PHYRdyChg }
Mar 24 21:37:43 linux-7vph kernel: ata1.00: cmd 60/30:20:21:a3:b4/00:00:09:00:00/40 tag 4 ncq 24576 in
Mar 24 21:37:43 linux-7vph kernel:          res 50/00:30:21:a3:b4/00:00:09:00:00/40 Emask 0x10 (ATA bus error)
Mar 24 21:37:43 linux-7vph kernel: ata1.00: status: { DRDY }
Mar 24 21:37:43 linux-7vph kernel: ata1: hard resetting link
Mar 24 21:37:43 linux-7vph kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Mar 24 21:37:43 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Mar 24 21:37:43 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Mar 24 21:37:43 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Mar 24 21:37:43 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Mar 24 21:37:43 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Mar 24 21:37:43 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Mar 24 21:37:43 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Mar 24 21:37:43 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Mar 24 21:37:43 linux-7vph kernel: ata1.00: configured for UDMA/133
Mar 24 21:37:44 linux-7vph kernel: ata1.00: configured for UDMA/133
Mar 24 21:37:44 linux-7vph kernel: ata1: EH complete
Mar 24 21:37:44 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Mar 24 21:37:44 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Mar 24 21:37:44 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Mar 24 21:37:44 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 24 21:37:44 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Mar 24 21:37:44 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Mar 24 21:37:44 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Mar 24 21:37:44 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 24 21:47:13 linux-7vph pulseaudio[3884]: module-alsa-sink.c: Increasing wakeup watermark to 40.00 ms
Mar 24 21:47:24 linux-7vph shutdown[4439]: shutting down for system reboot


[-- Attachment #3: messages.2.6.28.txt --]
[-- Type: text/plain, Size: 12623 bytes --]

Mar 25 06:43:35 linux-7vph kernel: Syncing filesystems ... done.
Mar 25 06:43:35 linux-7vph kernel: Freezing user space processes ... (elapsed 0.00 seconds) done.
Mar 25 06:43:35 linux-7vph kernel: Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
Mar 25 06:43:35 linux-7vph kernel: PM: Shrinking memory... done (107944 pages freed)
Mar 25 06:43:35 linux-7vph kernel: PM: Freed 431776 kbytes in 4.82 seconds (89.58 MB/s)
Mar 25 06:43:35 linux-7vph kernel: Suspending console(s) (use no_console_suspend to debug)
Mar 25 06:43:35 linux-7vph kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Mar 25 06:43:35 linux-7vph kernel: ACPI handle has no context!
Mar 25 06:43:35 linux-7vph kernel: iwlagn 0000:03:00.0: PCI INT A disabled
Mar 25 06:43:35 linux-7vph kernel: HDA Intel 0000:00:1b.0: PCI INT B disabled
Mar 25 06:43:35 linux-7vph kernel: e1000e 0000:00:19.0: PME# enabled
Mar 25 06:43:36 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Mar 25 06:43:36 linux-7vph kernel: e1000e 0000:00:19.0: PME# enabled
Mar 25 06:43:36 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Mar 25 06:43:36 linux-7vph kernel: e1000e 0000:00:19.0: PCI INT A disabled
Mar 25 06:43:36 linux-7vph kernel: ACPI: Preparing to enter system sleep state S4
Mar 25 06:43:36 linux-7vph kernel: Disabling non-boot CPUs ...
Mar 25 06:43:36 linux-7vph kernel: CPU 1 is now offline
Mar 25 06:43:36 linux-7vph kernel: SMP alternatives: switching to UP code
Mar 25 06:43:36 linux-7vph kernel: CPU0 attaching NULL sched-domain.
Mar 25 06:43:36 linux-7vph kernel: CPU1 attaching NULL sched-domain.
Mar 25 06:43:44 linux-7vph kernel: CPU0 attaching NULL sched-domain.
Mar 25 06:43:44 linux-7vph kernel: CPU1 is down
Mar 25 06:43:44 linux-7vph kernel: Extended CMOS year: 2000
Mar 25 06:43:44 linux-7vph kernel: PM: Creating hibernation image: 
Mar 25 06:43:44 linux-7vph kernel: PM: Need to copy 124982 pages
Mar 25 06:43:44 linux-7vph kernel: x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
Mar 25 06:43:44 linux-7vph kernel: Intel machine check architecture supported.
Mar 25 06:43:44 linux-7vph kernel: Intel machine check reporting enabled on CPU#0.
Mar 25 06:43:44 linux-7vph kernel: Extended CMOS year: 2000
Mar 25 06:43:44 linux-7vph kernel: Enabling non-boot CPUs ...
Mar 25 06:43:44 linux-7vph kernel: SMP alternatives: switching to SMP code
Mar 25 06:43:44 linux-7vph kernel: Booting processor 1 APIC 0x1 ip 0x6000
Mar 25 06:43:44 linux-7vph kernel: Initializing CPU#1
Mar 25 06:43:44 linux-7vph kernel: Calibrating delay using timer specific routine.. 5054.03 BogoMIPS (lpj=10108070)
Mar 25 06:43:44 linux-7vph kernel: CPU: L1 I cache: 32K, L1 D cache: 32K
Mar 25 06:43:44 linux-7vph kernel: CPU: L2 cache: 6144K
Mar 25 06:43:44 linux-7vph kernel: CPU: Physical Processor ID: 0
Mar 25 06:43:44 linux-7vph kernel: CPU: Processor Core ID: 1
Mar 25 06:43:44 linux-7vph kernel: Intel machine check architecture supported.
Mar 25 06:43:44 linux-7vph kernel: Intel machine check reporting enabled on CPU#1.
Mar 25 06:43:44 linux-7vph kernel: x86 PAT enabled: cpu 1, old 0x7040600070406, new 0x7010600070106
Mar 25 06:43:44 linux-7vph kernel: CPU1: Intel(R) Core(TM)2 Duo CPU     T9400  @ 2.53GHz stepping 06
Mar 25 06:43:44 linux-7vph kernel: CPU0 attaching NULL sched-domain.
Mar 25 06:43:44 linux-7vph kernel: Switched to high resolution mode on CPU 1
Mar 25 06:43:44 linux-7vph kernel: CPU0 attaching sched-domain:
Mar 25 06:43:44 linux-7vph kernel:  domain 0: span 0-1 level MC
Mar 25 06:43:44 linux-7vph kernel:   groups: 0 1
Mar 25 06:43:44 linux-7vph kernel: CPU1 attaching sched-domain:
Mar 25 06:43:44 linux-7vph kernel:  domain 0: span 0-1 level MC
Mar 25 06:43:44 linux-7vph kernel:   groups: 1 0
Mar 25 06:43:44 linux-7vph kernel: CPU1 is up
Mar 25 06:43:44 linux-7vph kernel: ACPI: Waking up from system sleep state S4
Mar 25 06:43:44 linux-7vph kernel: ACPI: EC: non-query interrupt received, switching to interrupt mode
Mar 25 06:43:44 linux-7vph kernel: pci 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407)
Mar 25 06:43:44 linux-7vph kernel: pci 0000:00:02.0: power state changed by ACPI to D0
Mar 25 06:43:44 linux-7vph kernel: pci 0000:00:02.0: setting latency timer to 64
Mar 25 06:43:44 linux-7vph kernel: e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
Mar 25 06:43:44 linux-7vph kernel: e1000e 0000:00:19.0: setting latency timer to 64
Mar 25 06:43:44 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Mar 25 06:43:44 linux-7vph kernel: e1000e 0000:00:19.0: PME# disabled
Mar 25 06:43:44 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Mar 25 06:43:44 linux-7vph kernel: e1000e 0000:00:19.0: PME# disabled
Mar 25 06:43:44 linux-7vph kernel: e1000e 0000:00:19.0: irq 2298 for MSI/MSI-X
Mar 25 06:43:44 linux-7vph kernel: HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102)
Mar 25 06:43:44 linux-7vph kernel: HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Mar 25 06:43:44 linux-7vph kernel: HDA Intel 0000:00:1b.0: setting latency timer to 64
Mar 25 06:43:44 linux-7vph kernel: pcieport-driver 0000:00:1c.0: restoring config space at offset 0x7 (was 0x200000f0, writing 0xf0)
Mar 25 06:43:44 linux-7vph kernel: pcieport-driver 0000:00:1c.0: setting latency timer to 64
Mar 25 06:43:44 linux-7vph kernel: pcieport-driver 0000:00:1c.1: restoring config space at offset 0x7 (was 0x200000f0, writing 0xf0)
Mar 25 06:43:44 linux-7vph kernel: pcieport-driver 0000:00:1c.1: setting latency timer to 64
Mar 25 06:43:44 linux-7vph kernel: pcieport-driver 0000:00:1c.3: setting latency timer to 64
Mar 25 06:43:44 linux-7vph kernel: pcieport-driver 0000:00:1c.4: setting latency timer to 64
Mar 25 06:43:44 linux-7vph kernel: pci 0000:00:1e.0: setting latency timer to 64
Mar 25 06:43:44 linux-7vph kernel: ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00403, writing 0x2b00407)
Mar 25 06:43:44 linux-7vph kernel: ahci 0000:00:1f.2: setting latency timer to 64
Mar 25 06:43:44 linux-7vph kernel: iwlagn 0000:03:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
Mar 25 06:43:44 linux-7vph kernel: iwlagn 0000:03:00.0: irq 2297 for MSI/MSI-X
Mar 25 06:43:44 linux-7vph kernel: pci 0000:15:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Mar 25 06:43:44 linux-7vph kernel: Registered led device: iwl-phy0:radio
Mar 25 06:43:44 linux-7vph kernel: Registered led device: iwl-phy0:assoc
Mar 25 06:43:44 linux-7vph kernel: Registered led device: iwl-phy0:RX
Mar 25 06:43:44 linux-7vph kernel: Registered led device: iwl-phy0:TX
Mar 25 06:43:44 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 06:43:44 linux-7vph kernel: sd 0:0:0:0: [sda] Starting disk
Mar 25 06:43:44 linux-7vph kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 25 06:43:44 linux-7vph kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 25 06:43:44 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Mar 25 06:43:44 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Mar 25 06:43:44 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Mar 25 06:43:44 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Mar 25 06:43:44 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
Mar 25 06:43:44 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
Mar 25 06:43:44 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
Mar 25 06:43:44 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
Mar 25 06:43:44 linux-7vph kernel: ata1.00: configured for UDMA/133
Mar 25 06:43:44 linux-7vph kernel: ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
Mar 25 06:43:44 linux-7vph kernel: ata1: irq_stat 0x00400040, connection status changed
Mar 25 06:43:44 linux-7vph kernel: ata1.00: configured for UDMA/133
Mar 25 06:43:44 linux-7vph kernel: ata1: EH complete
Mar 25 06:43:44 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Mar 25 06:43:44 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Mar 25 06:43:44 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Mar 25 06:43:44 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 25 06:43:44 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
Mar 25 06:43:44 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
Mar 25 06:43:44 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Mar 25 06:43:44 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 25 06:43:44 linux-7vph kernel: pci 0000:00:02.0: power state changed by ACPI to D0
Mar 25 06:43:44 linux-7vph kernel: pci 0000:00:02.0: setting latency timer to 64
Mar 25 06:43:44 linux-7vph kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded
Mar 25 06:43:44 linux-7vph kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded
Mar 25 06:43:44 linux-7vph kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded
Mar 25 06:43:44 linux-7vph kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded
Mar 25 06:43:44 linux-7vph kernel: ata2.00: configured for UDMA/133
Mar 25 06:43:44 linux-7vph kernel: Restarting tasks ... done.
Mar 25 06:43:44 linux-7vph kernel: ata2: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
Mar 25 06:43:44 linux-7vph kernel: ata2: irq_stat 0x40000001
Mar 25 06:43:44 linux-7vph kernel: ata2.00: configured for UDMA/133
Mar 25 06:43:44 linux-7vph kernel: ata2: EH complete
Mar 25 06:43:44 linux-7vph kernel: wlan0: deauthenticated
Mar 25 06:43:44 linux-7vph kernel: iwlagn: index 0 not used in uCode key table.
Mar 25 06:43:44 linux-7vph kernel: wlan0: direct probe to AP 00:1d:92:1d:1e:8e try 1
Mar 25 06:43:44 linux-7vph kernel: wlan0 direct probe responded
Mar 25 06:43:44 linux-7vph kernel: wlan0: authenticate with AP 00:1d:92:1d:1e:8e
Mar 25 06:43:44 linux-7vph kernel: wlan0: authenticate with AP 00:1d:92:1d:1e:8e
Mar 25 06:43:44 linux-7vph kernel: wlan0: authenticated
Mar 25 06:43:44 linux-7vph kernel: wlan0: associate with AP 00:1d:92:1d:1e:8e
Mar 25 06:43:44 linux-7vph kernel: wlan0: associate with AP 00:1d:92:1d:1e:8e
Mar 25 06:43:44 linux-7vph kernel: wlan0: RX ReassocResp from 00:1d:92:1d:1e:8e (capab=0x411 status=0 aid=1)
Mar 25 06:43:44 linux-7vph kernel: wlan0: associated
Mar 25 06:43:44 linux-7vph kernel: phy0: failed to restore operational channel after scan
Mar 25 06:46:15 linux-7vph smartd[2680]: Device: /dev/sda [SAT], SMART Usage Attribute: 194 Temperature_Celsius changed from 152 to 229
Mar 25 06:47:42 linux-7vph dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 5
Mar 25 06:47:47 linux-7vph dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 12
Mar 25 06:47:48 linux-7vph dhclient: DHCPOFFER from 192.168.1.254
Mar 25 06:47:48 linux-7vph dhclient: DHCPREQUEST on wlan0 to 255.255.255.255 port 67
Mar 25 06:47:48 linux-7vph dhclient: DHCPACK from 192.168.1.254
Mar 25 06:47:48 linux-7vph dhclient: bound to 192.168.1.104 -- renewal in 1393 seconds.
Mar 25 06:47:48 linux-7vph avahi-daemon[2576]: Withdrawing address record for 192.168.1.103 on wlan0.
Mar 25 06:47:48 linux-7vph avahi-daemon[2576]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.103.
Mar 25 06:47:48 linux-7vph avahi-daemon[2576]: Interface wlan0.IPv4 no longer relevant for mDNS.
Mar 25 06:47:48 linux-7vph avahi-daemon[2576]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.104.
Mar 25 06:47:48 linux-7vph avahi-daemon[2576]: New relevant interface wlan0.IPv4 for mDNS.
Mar 25 06:47:48 linux-7vph avahi-daemon[2576]: Registering new address record for 192.168.1.104 on wlan0.IPv4.
Mar 25 06:47:48 linux-7vph dns-resolver: ATTENTION: You have modified /etc/resolv.conf. Leaving it untouched...
Mar 25 06:47:48 linux-7vph dns-resolver: You can find my version in /etc/resolv.conf.netconfig
Mar 25 06:47:49 linux-7vph dns-resolver: ATTENTION: You have modified /etc/resolv.conf. Leaving it untouched...
Mar 25 06:47:49 linux-7vph dns-resolver: You can find my version in /etc/resolv.conf.netconfig
Mar 25 06:47:54 linux-7vph dns-resolver: ATTENTION: You have modified /etc/resolv.conf. Leaving it untouched...
Mar 25 06:47:54 linux-7vph dns-resolver: You can find my version in /etc/resolv.conf.netconfig

[-- Attachment #4: dmidecode.txt --]
[-- Type: text/plain, Size: 14312 bytes --]

# dmidecode 2.9
SMBIOS 2.4 present.
74 structures occupying 2506 bytes.
Table at 0x000E0010.

Handle 0x0000, DMI type 0, 24 bytes
BIOS Information
	Vendor: LENOVO
	Version: 6FET50WW (1.20 )
	Release Date: 10/30/2008
	Address: 0xE0000
	Runtime Size: 128 kB
	ROM Size: 8192 kB
	Characteristics:
		PCI is supported
		PC Card (PCMCIA) is supported
		PNP is supported
		BIOS is upgradeable
		BIOS shadowing is allowed
		ESCD support is available
		Boot from CD is supported
		Selectable boot is supported
		BIOS ROM is socketed
		EDD is supported
		ACPI is supported
		USB legacy is supported
		BIOS boot specification is supported
		Targeted content distribution is supported
	BIOS Revision: 1.32
	Firmware Revision: 1.1

Handle 0x0001, DMI type 1, 27 bytes
System Information
	Manufacturer: LENOVO
	Product Name: 40622XG
	Version: ThinkPad W500
	Serial Number: L3A2165
	UUID: 05555D81-496B-11CB-AE65-9E9C05A36473
	Wake-up Type: Power Switch
	SKU Number: Not Specified
	Family: ThinkPad W500

Handle 0x0002, DMI type 2, 8 bytes
Base Board Information
	Manufacturer: LENOVO
	Product Name: 40622XG
	Version: Not Available
	Serial Number: VF22P88G03K

Handle 0x0003, DMI type 3, 13 bytes
Chassis Information
	Manufacturer: LENOVO
	Type: Notebook
	Lock: Not Present
	Version: Not Available
	Serial Number: Not Available
	Asset Tag: No Asset Information
	Boot-up State: Unknown
	Power Supply State: Unknown
	Thermal State: Unknown
	Security Status: Unknown

Handle 0x0004, DMI type 126, 13 bytes
Inactive

Handle 0x0005, DMI type 126, 13 bytes
Inactive

Handle 0x0006, DMI type 4, 35 bytes
Processor Information
	Socket Designation: None
	Type: Central Processor
	Family: Other
	Manufacturer: GenuineIntel
	ID: 76 06 01 00 FF FB EB BF
	Version: Intel(R) Core(TM)2 Duo CPU     T9400  @ 2.53GHz
	Voltage: 1.3 V
	External Clock: 266 MHz
	Max Speed: 2530 MHz
	Current Speed: 2530 MHz
	Status: Populated, Enabled
	Upgrade: None
	L1 Cache Handle: 0x000A
	L2 Cache Handle: 0x000C
	L3 Cache Handle: Not Provided
	Serial Number: Not Specified
	Asset Tag: Not Specified
	Part Number: Not Specified

Handle 0x0007, DMI type 5, 20 bytes
Memory Controller Information
	Error Detecting Method: None
	Error Correcting Capabilities:
		None
	Supported Interleave: One-way Interleave
	Current Interleave: One-way Interleave
	Maximum Memory Module Size: 4096 MB
	Maximum Total Memory Size: 8192 MB
	Supported Speeds:
		Other
	Supported Memory Types:
		DIMM
		SDRAM
	Memory Module Voltage: 2.9 V
	Associated Memory Slots: 2
		0x0008
		0x0009
	Enabled Error Correcting Capabilities:
		Unknown

Handle 0x0008, DMI type 6, 12 bytes
Memory Module Information
	Socket Designation: DIMM Slot 1
	Bank Connections: 0 1
	Current Speed: 155 ns
	Type: DIMM SDRAM
	Installed Size: 2048 MB (Double-bank Connection)
	Enabled Size: 2048 MB (Double-bank Connection)
	Error Status: OK

Handle 0x0009, DMI type 6, 12 bytes
Memory Module Information
	Socket Designation: DIMM Slot 2
	Bank Connections: 2 3
	Current Speed: 155 ns
	Type: DIMM SDRAM
	Installed Size: Not Installed
	Enabled Size: Not Installed
	Error Status: OK

Handle 0x000A, DMI type 7, 19 bytes
Cache Information
	Socket Designation: Internal L1 Cache
	Configuration: Enabled, Socketed, Level 1
	Operational Mode: Write Back
	Location: Internal
	Installed Size: 64 KB
	Maximum Size: 64 KB
	Supported SRAM Types:
		Synchronous
	Installed SRAM Type: Synchronous
	Speed: Unknown
	Error Correction Type: Single-bit ECC
	System Type: Instruction
	Associativity: 8-way Set-associative

Handle 0x000B, DMI type 7, 19 bytes
Cache Information
	Socket Designation: Internal L1 Cache
	Configuration: Enabled, Socketed, Level 1
	Operational Mode: Write Back
	Location: Internal
	Installed Size: 64 KB
	Maximum Size: 64 KB
	Supported SRAM Types:
		Synchronous
	Installed SRAM Type: Synchronous
	Speed: Unknown
	Error Correction Type: Single-bit ECC
	System Type: Data
	Associativity: 8-way Set-associative

Handle 0x000C, DMI type 7, 19 bytes
Cache Information
	Socket Designation: Internal L2 Cache
	Configuration: Enabled, Socketed, Level 2
	Operational Mode: Write Back
	Location: Internal
	Installed Size: 6144 KB
	Maximum Size: 6144 KB
	Supported SRAM Types:
		Burst
	Installed SRAM Type: Burst
	Speed: Unknown
	Error Correction Type: Single-bit ECC
	System Type: Unified
	Associativity: 8-way Set-associative

Handle 0x000D, DMI type 8, 9 bytes
Port Connector Information
	Internal Reference Designator: Not Available
	Internal Connector Type: None
	External Reference Designator: External Monitor
	External Connector Type: DB-15 female
	Port Type: Video Port

Handle 0x000E, DMI type 8, 9 bytes
Port Connector Information
	Internal Reference Designator: Not Available
	Internal Connector Type: None
	External Reference Designator: Microphone Jack
	External Connector Type: Mini Jack (headphones)
	Port Type: Audio Port

Handle 0x000F, DMI type 8, 9 bytes
Port Connector Information
	Internal Reference Designator: Not Available
	Internal Connector Type: None
	External Reference Designator: Headphone Jack
	External Connector Type: Mini Jack (headphones)
	Port Type: Audio Port

Handle 0x0010, DMI type 126, 9 bytes
Inactive

Handle 0x0011, DMI type 126, 9 bytes
Inactive

Handle 0x0012, DMI type 8, 9 bytes
Port Connector Information
	Internal Reference Designator: Not Available
	Internal Connector Type: None
	External Reference Designator: Modem
	External Connector Type: RJ-11
	Port Type: Modem Port

Handle 0x0013, DMI type 8, 9 bytes
Port Connector Information
	Internal Reference Designator: Not Available
	Internal Connector Type: None
	External Reference Designator: Ethernet
	External Connector Type: RJ-45
	Port Type: Network Port

Handle 0x0014, DMI type 8, 9 bytes
Port Connector Information
	Internal Reference Designator: Not Available
	Internal Connector Type: None
	External Reference Designator: USB 1
	External Connector Type: Access Bus (USB)
	Port Type: USB

Handle 0x0015, DMI type 8, 9 bytes
Port Connector Information
	Internal Reference Designator: Not Available
	Internal Connector Type: None
	External Reference Designator: USB 2
	External Connector Type: Access Bus (USB)
	Port Type: USB

Handle 0x0016, DMI type 8, 9 bytes
Port Connector Information
	Internal Reference Designator: Not Available
	Internal Connector Type: None
	External Reference Designator: USB 3
	External Connector Type: Access Bus (USB)
	Port Type: USB

Handle 0x0017, DMI type 126, 9 bytes
Inactive

Handle 0x0018, DMI type 126, 9 bytes
Inactive

Handle 0x0019, DMI type 126, 9 bytes
Inactive

Handle 0x001A, DMI type 126, 9 bytes
Inactive

Handle 0x001B, DMI type 126, 9 bytes
Inactive

Handle 0x001C, DMI type 126, 9 bytes
Inactive

Handle 0x001D, DMI type 126, 9 bytes
Inactive

Handle 0x001E, DMI type 126, 9 bytes
Inactive

Handle 0x001F, DMI type 8, 9 bytes
Port Connector Information
	Internal Reference Designator: Not Available
	Internal Connector Type: None
	External Reference Designator: IEEE1394
	External Connector Type: IEEE 1394
	Port Type: Firewire (IEEE P1394)

Handle 0x0020, DMI type 9, 13 bytes
System Slot Information
	Designation: ExpressCard Slot 1
	Type: x1 PCI Express
	Current Usage: Available
	Length: Other
	ID: 0
	Characteristics:
		Hot-plug devices are supported

Handle 0x0021, DMI type 9, 13 bytes
System Slot Information
	Designation: CardBus Slot 1
	Type: 32-bit PC Card (PCMCIA)
	Current Usage: Available
	Length: Other
	ID: Adapter 1, Socket 0
	Characteristics:
		5.0 V is provided
		3.3 V is provided
		PC Card-16 is supported
		Cardbus is supported
		Zoom Video is supported
		Modem ring resume is supported
		PME signal is supported
		Hot-plug devices are supported

Handle 0x0022, DMI type 126, 13 bytes
Inactive

Handle 0x0023, DMI type 126, 13 bytes
Inactive

Handle 0x0024, DMI type 9, 13 bytes
System Slot Information
	Designation: Media Card Slot 1
	Type: Other
	Current Usage: Available
	Length: Other
	Characteristics:
		Hot-plug devices are supported

Handle 0x0025, DMI type 126, 13 bytes
Inactive

Handle 0x0026, DMI type 126, 13 bytes
Inactive

Handle 0x0027, DMI type 10, 6 bytes
On Board Device Information
	Type: Other
	Status: Disabled
	Description: IBM Embedded Security hardware

Handle 0x0028, DMI type 11, 5 bytes
OEM Strings
	String 1: IBM ThinkPad Embedded Controller -[7VHT12WW-1.01    ]-

Handle 0x0029, DMI type 13, 22 bytes
BIOS Language Information
	Installable Languages: 1
		enUS
	Currently Installed Language: enUS

Handle 0x002A, DMI type 15, 25 bytes
System Event Log
	Area Length: 0 bytes
	Header Start Offset: 0x0000
	Header Length: 16 bytes
	Data Start Offset: 0x0010
	Access Method: General-purpose non-volatile data functions
	Access Address: 0x0000
	Status: Valid, Not Full
	Change Token: 0x000000BD
	Header Format: Type 1
	Supported Log Type Descriptors: 1
	Descriptor 1: POST error
	Data Format 1: POST results bitmap

Handle 0x002B, DMI type 16, 15 bytes
Physical Memory Array
	Location: System Board Or Motherboard
	Use: System Memory
	Error Correction Type: None
	Maximum Capacity: 4 GB
	Error Information Handle: Not Provided
	Number Of Devices: 2

Handle 0x002C, DMI type 17, 27 bytes
Memory Device
	Array Handle: 0x002B
	Error Information Handle: No Error
	Total Width: 64 bits
	Data Width: 64 bits
	Size: 2048 MB
	Form Factor: SODIMM
	Set: None
	Locator: DIMM 1
	Bank Locator: Bank 0/1
	Type: DDR2
	Type Detail: Synchronous
	Speed: 667 MHz (1.5 ns)
	Manufacturer: Not Specified
	Serial Number: Not Specified
	Asset Tag: Not Specified
	Part Number: Not Specified

Handle 0x002D, DMI type 17, 27 bytes
Memory Device
	Array Handle: 0x002B
	Error Information Handle: No Error
	Total Width: Unknown
	Data Width: Unknown
	Size: No Module Installed
	Form Factor: SODIMM
	Set: None
	Locator: DIMM 2
	Bank Locator: Bank 2/3
	Type: DDR2
	Type Detail: Synchronous
	Speed: 667 MHz (1.5 ns)
	Manufacturer: Not Specified
	Serial Number: Not Specified
	Asset Tag: Not Specified
	Part Number: Not Specified

Handle 0x002E, DMI type 18, 23 bytes
32-bit Memory Error Information
	Type: OK
	Granularity: Unknown
	Operation: Unknown
	Vendor Syndrome: Unknown
	Memory Array Address: Unknown
	Device Address: Unknown
	Resolution: Unknown

Handle 0x002F, DMI type 19, 15 bytes
Memory Array Mapped Address
	Starting Address: 0x00000000000
	Ending Address: 0x0007FFFFFFF
	Range Size: 2 GB
	Physical Array Handle: 0x002B
	Partition Width: 0

Handle 0x0030, DMI type 20, 19 bytes
Memory Device Mapped Address
	Starting Address: 0x00000000000
	Ending Address: 0x0007FFFFFFF
	Range Size: 2 GB
	Physical Device Handle: 0x002C
	Memory Array Mapped Address Handle: 0x002F
	Partition Row Position: 1

Handle 0x0031, DMI type 20, 19 bytes
Memory Device Mapped Address
	Starting Address: 0x0007FFFFC00
	Ending Address: 0x0007FFFFFFF
	Range Size: 1 kB
	Physical Device Handle: 0x002D
	Memory Array Mapped Address Handle: 0x002F
	Partition Row Position: 1

Handle 0x0032, DMI type 21, 7 bytes
Built-in Pointing Device
	Type: Track Point
	Interface: PS/2
	Buttons: 3

Handle 0x0033, DMI type 21, 7 bytes
Built-in Pointing Device
	Type: Touch Pad
	Interface: PS/2
	Buttons: 0

Handle 0x0034, DMI type 22, 26 bytes
Portable Battery
	Location: Rear
	Manufacturer: SONY
	Name: 42T4569
	Design Capacity: 56160 mWh
	Design Voltage: 10800 mV
	SBDS Version: 03.01
	Maximum Error: Unknown
	SBDS Serial Number: 06AB
	SBDS Manufacture Date: 2008-09-02
	SBDS Chemistry: LION
	OEM-specific Information: 0x00000000

Handle 0x0035, DMI type 126, 26 bytes
Inactive

Handle 0x0036, DMI type 24, 5 bytes
Hardware Security
	Power-On Password Status: Disabled
	Keyboard Password Status: Disabled
	Administrator Password Status: Disabled
	Front Panel Reset Status: Unknown

Handle 0x0037, DMI type 32, 11 bytes
System Boot Information
	Status: No errors detected

Handle 0x0038, DMI type 131, 17 bytes
OEM-specific Type
	Header and Data:
		83 11 38 00 01 02 03 FF FF 1F 00 00 00 00 00 02
		00
	Strings:
		BOOTINF 20h
		BOOTDEV 21h
		KEYPTRS 23h

Handle 0x0039, DMI type 131, 22 bytes
OEM-specific Type
	Header and Data:
		83 16 39 00 01 00 00 00 00 00 00 00 00 00 00 00
		00 00 00 00 00 01
	Strings:
		TVT-Enablement

Handle 0x003A, DMI type 132, 7 bytes
OEM-specific Type
	Header and Data:
		84 07 3A 00 02 D8 36

Handle 0x003B, DMI type 133, 5 bytes
OEM-specific Type
	Header and Data:
		85 05 3B 00 01
	Strings:
		KHOIHGIUCCHHII

Handle 0x003C, DMI type 134, 13 bytes
OEM-specific Type
	Header and Data:
		86 0D 3C 00 07 09 08 20 00 00 00 00 00

Handle 0x003D, DMI type 134, 16 bytes
OEM-specific Type
	Header and Data:
		86 10 3D 00 00 49 4E 54 43 01 01 00 00 00 01 02
	Strings:
		TPM INFO
		System Reserved

Handle 0x003E, DMI type 135, 13 bytes
OEM-specific Type
	Header and Data:
		87 0D 3E 00 54 50 07 00 01 00 00 00 00

Handle 0x003F, DMI type 135, 18 bytes
OEM-specific Type
	Header and Data:
		87 12 3F 00 54 50 07 01 01 B8 00 00 00 00 00 00
		00 00

Handle 0x0040, DMI type 135, 35 bytes
OEM-specific Type
	Header and Data:
		87 23 40 00 54 50 07 02 42 41 59 20 49 2F 4F 20
		01 00 02 00 00 0B 00 48 1C 3E 18 02 00 0B 00 40
		1C 3A 18

Handle 0x0041, DMI type 135, 34 bytes
OEM-specific Type
	Header and Data:
		87 22 41 00 54 50 07 04 01 06 01 01 02 00 02 01
		02 00 03 01 02 00 04 01 02 00 05 01 02 00 06 01
		02 00

Handle 0x0042, DMI type 136, 6 bytes
OEM-specific Type
	Header and Data:
		88 06 42 00 5A 5A

Handle 0x0043, DMI type 126, 28 bytes
Inactive

Handle 0x0044, DMI type 138, 40 bytes
OEM-specific Type
	Header and Data:
		8A 28 44 00 14 01 02 01 40 02 01 40 02 01 40 02
		01 40 01 40 42 49 4F 53 20 50 61 73 73 77 6F 72
		64 20 46 6F 72 6D 61 74

Handle 0x0045, DMI type 139, 37 bytes
OEM-specific Type
	Header and Data:
		8B 25 45 00 11 01 0A 00 00 00 00 00 00 00 00 00
		00 50 57 4D 53 20 4B 65 79 20 49 6E 66 6F 72 6D
		61 74 69 6F 6E

Handle 0x0046, DMI type 129, 8 bytes
OEM-specific Type
	Header and Data:
		81 08 46 00 01 01 02 00
	Strings:
		Intel_ASF
		Intel_ASF_001

Handle 0x0047, DMI type 130, 20 bytes
OEM-specific Type
	Header and Data:
		82 14 47 00 24 41 4D 54 01 00 00 00 00 00 00 00
		00 00 00 00

Handle 0x0048, DMI type 131, 64 bytes
OEM-specific Type
	Header and Data:
		83 40 48 00 14 00 00 00 00 00 40 2A 00 00 00 00
		F8 00 17 29 00 00 00 00 0F 00 00 00 00 00 04 00
		64 04 03 00 01 00 01 15 C8 00 F5 10 00 00 00 00
		00 00 00 00 07 00 00 00 76 50 72 6F 00 00 00 00

Handle 0x0049, DMI type 127, 4 bytes
End Of Table


[-- Attachment #5: lspci.txt --]
[-- Type: text/plain, Size: 2562 bytes --]

00:00.0 Host bridge: Intel Corporation Mobile 4 Series Chipset Memory Controller Hub (rev 07)
00:02.0 VGA compatible controller: Intel Corporation Mobile 4 Series Chipset Integrated Graphics Controller (rev 07)
00:02.1 Display controller: Intel Corporation Mobile 4 Series Chipset Integrated Graphics Controller (rev 07)
00:03.0 Communication controller: Intel Corporation Mobile 4 Series Chipset MEI Controller (rev 07)
00:19.0 Ethernet controller: Intel Corporation 82567LM Gigabit Network Connection (rev 03)
00:1a.0 USB Controller: Intel Corporation 82801I (ICH9 Family) USB UHCI Controller #4 (rev 03)
00:1a.1 USB Controller: Intel Corporation 82801I (ICH9 Family) USB UHCI Controller #5 (rev 03)
00:1a.2 USB Controller: Intel Corporation 82801I (ICH9 Family) USB UHCI Controller #6 (rev 03)
00:1a.7 USB Controller: Intel Corporation 82801I (ICH9 Family) USB2 EHCI Controller #2 (rev 03)
00:1b.0 Audio device: Intel Corporation 82801I (ICH9 Family) HD Audio Controller (rev 03)
00:1c.0 PCI bridge: Intel Corporation 82801I (ICH9 Family) PCI Express Port 1 (rev 03)
00:1c.1 PCI bridge: Intel Corporation 82801I (ICH9 Family) PCI Express Port 2 (rev 03)
00:1c.3 PCI bridge: Intel Corporation 82801I (ICH9 Family) PCI Express Port 4 (rev 03)
00:1c.4 PCI bridge: Intel Corporation 82801I (ICH9 Family) PCI Express Port 5 (rev 03)
00:1d.0 USB Controller: Intel Corporation 82801I (ICH9 Family) USB UHCI Controller #1 (rev 03)
00:1d.1 USB Controller: Intel Corporation 82801I (ICH9 Family) USB UHCI Controller #2 (rev 03)
00:1d.2 USB Controller: Intel Corporation 82801I (ICH9 Family) USB UHCI Controller #3 (rev 03)
00:1d.7 USB Controller: Intel Corporation 82801I (ICH9 Family) USB2 EHCI Controller #1 (rev 03)
00:1e.0 PCI bridge: Intel Corporation 82801 Mobile PCI Bridge (rev 93)
00:1f.0 ISA bridge: Intel Corporation ICH9M-E LPC Interface Controller (rev 03)
00:1f.2 SATA controller: Intel Corporation ICH9M/M-E SATA AHCI Controller (rev 03)
00:1f.3 SMBus: Intel Corporation 82801I (ICH9 Family) SMBus Controller (rev 03)
03:00.0 Network controller: Intel Corporation Wireless WiFi Link 5100
15:00.0 CardBus bridge: Ricoh Co Ltd RL5c476 II (rev ba)
15:00.1 FireWire (IEEE 1394): Ricoh Co Ltd R5C832 IEEE 1394 Controller (rev 04)
15:00.2 SD Host controller: Ricoh Co Ltd R5C822 SD/SDIO/MMC/MS/MSPro Host Adapter (rev 21)
15:00.3 System peripheral: Ricoh Co Ltd R5C843 MMC Host Controller (rev 11)
15:00.4 System peripheral: Ricoh Co Ltd R5C592 Memory Stick Bus Host Adapter (rev 11)
15:00.5 System peripheral: Ricoh Co Ltd xD-Picture Card Controller (rev 11)

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

* Re: 2.6.29 regression: ATA bus errors on resume
  2009-03-25  5:19   ` 2.6.29 regression: ATA bus errors on resume (was: EXT4: __ext4_get_inode_loc errors after s2disk) Niel Lambrechts
@ 2009-03-25  6:06     ` Jeff Garzik
  2009-03-25 21:40       ` Niel Lambrechts
  2009-03-25 22:16       ` James Bottomley
  0 siblings, 2 replies; 5+ messages in thread
From: Jeff Garzik @ 2009-03-25  6:06 UTC (permalink / raw)
  To: Niel Lambrechts
  Cc: linux.kernel, Linux IDE mailing list, Arjan van de Ven,
	Rafael J. Wysocki, linux-scsi

Niel Lambrechts wrote:
> On 03/25/2009 03:30 AM, Theodore Tso wrote:
>> On Tue, Mar 24, 2009 at 10:25:57PM +0200, Niel Lambrechts wrote:
>>> Hi,
>>>
>>> After upgrading to 2.6.29 I get the below errors after resuming from
>>> hibernating with s2disk. I ran fsck and tried doing the same thing again
>>> in 2.6.28.9-pae, but do not get any errors there.
>> The ext4 errors are interleaved with hardware errors, and the ext4
>> errors are about I/O errors.
>>
>> EXT4-fs error (device sda6): __ext4_get_inode_loc: unable to read inode block - inode=2346519
>> EXT4-fs error (device sda6) in ext4_reserve_inode_write: IO failure
>>
>> This looks more like a hibernation problem than an ext4 problem.
>> Looks like the hard drive is being left in some inconsistent state
>> after resuming from hibernation.
>>
>>      	   	       		   	   - Ted
> 
> Thanks for the info Theodore, this is definitely looks like some type of
> regression in 2.6.29, as the problem is not evident when I s2disk using
> 2.6.28.9, even after multiple suspend/resume cycles.
> 
> I found some 'ATA bus errors' and 'SError' messages in
> /var/log/messages, so I've attached the messages from both 2.6.29 and
> 2.6.28 for comparison.

Well, here is the interpretation of messages:

> ata1.00: irq_stat 0x00400008, PHY RDY changed
> ata1: SError: { PHYRdyChg CommWake }

Your SATA hardware flags a connect-or-disconnect event ("PHY RDY"), 
which requires us to abort a bunch of queued commands:

> ata1.00: cmd 60/18:00:77:88:6f/00:00:0e:00:00/40 tag 0 ncq 12288 in
>          res 50/00:30:07:b3:10/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
[...]
> ata1.00: cmd 60/30:68:07:b3:10/00:00:0c:00:00/40 tag 13 ncq 24576 in
>          res 50/00:30:07:b3:10/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)

  ...through the 14th command (tag 13).

> Mar 24 21:29:14 linux-7vph kernel: ata1: hard resetting link
> Mar 24 21:29:14 linux-7vph kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
> Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
> Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
> Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
> Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
> Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
> Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
> Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
> Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
> Mar 24 21:29:14 linux-7vph kernel: ata1.00: configured for UDMA/133
> Mar 24 21:29:14 linux-7vph kernel: ata1.00: configured for UDMA/133


SATA link is reset, and ACPI is re-run.

> Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE,SUGGEST_OK
> Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Sense Key : Aborted Command [current] [descriptor]
> Mar 24 21:29:14 linux-7vph kernel: Descriptor sense data with sense descriptors (in hex):
> Mar 24 21:29:14 linux-7vph kernel:         72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00 
> Mar 24 21:29:14 linux-7vph kernel:         0c 10 b3 07 
> Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Add. Sense: No additional sense information
> Mar 24 21:29:14 linux-7vph kernel: end_request: I/O error, dev sda, sector 242190455

The SCSI subsystem aborts each of the queued commands.

> Mar 24 21:29:14 linux-7vph kernel: ata1: EH complete

SATA error handling completes

> Mar 24 21:29:14 linux-7vph kernel: EXT4-fs error (device sda6): __ext4_get_inode_loc: unable to read inode block - inode=2330178, block=9338883

ext4 pukes, because of SATA/SCSI errors

> Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
> Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
> Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
> Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
> Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] 390721968 512-byte hardware sectors: (200 GB/186 GiB)
> Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Write Protect is off
> Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
> Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA

SCSI finished re-initialization.

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?

	Jeff




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

* Re: 2.6.29 regression: ATA bus errors on resume
  2009-03-25  6:06     ` 2.6.29 regression: ATA bus errors on resume Jeff Garzik
@ 2009-03-25 21:40       ` Niel Lambrechts
  2009-03-25 22:16       ` James Bottomley
  1 sibling, 0 replies; 5+ messages in thread
From: Niel Lambrechts @ 2009-03-25 21:40 UTC (permalink / raw)
  To: Jeff Garzik
  Cc: linux.kernel, Linux IDE mailing list, Arjan van de Ven,
	Rafael J. Wysocki, linux-scsi

[-- Attachment #1: Type: text/plain, Size: 1177 bytes --]

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

[-- Attachment #2: hibernate-x.txt --]
[-- Type: text/plain, Size: 17374 bytes --]

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


[-- Attachment #3: hibernate-console.txt --]
[-- Type: text/plain, Size: 12741 bytes --]

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


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

* Re: 2.6.29 regression: ATA bus errors on resume
  2009-03-25  6:06     ` 2.6.29 regression: ATA bus errors on resume Jeff Garzik
  2009-03-25 21:40       ` Niel Lambrechts
@ 2009-03-25 22:16       ` James Bottomley
  1 sibling, 0 replies; 5+ messages in thread
From: James Bottomley @ 2009-03-25 22:16 UTC (permalink / raw)
  To: Jeff Garzik
  Cc: Niel Lambrechts, linux.kernel, Linux IDE mailing list,
	Arjan van de Ven, Rafael J. Wysocki, linux-scsi

On Wed, 2009-03-25 at 02:06 -0400, Jeff Garzik wrote:
> Niel Lambrechts wrote:
> > On 03/25/2009 03:30 AM, Theodore Tso wrote:
> >> On Tue, Mar 24, 2009 at 10:25:57PM +0200, Niel Lambrechts wrote:
> >>> Hi,
> >>>
> >>> After upgrading to 2.6.29 I get the below errors after resuming from
> >>> hibernating with s2disk. I ran fsck and tried doing the same thing again
> >>> in 2.6.28.9-pae, but do not get any errors there.
> >> The ext4 errors are interleaved with hardware errors, and the ext4
> >> errors are about I/O errors.
> >>
> >> EXT4-fs error (device sda6): __ext4_get_inode_loc: unable to read inode block - inode=2346519
> >> EXT4-fs error (device sda6) in ext4_reserve_inode_write: IO failure
> >>
> >> This looks more like a hibernation problem than an ext4 problem.
> >> Looks like the hard drive is being left in some inconsistent state
> >> after resuming from hibernation.
> >>
> >>      	   	       		   	   - Ted
> > 
> > Thanks for the info Theodore, this is definitely looks like some type of
> > regression in 2.6.29, as the problem is not evident when I s2disk using
> > 2.6.28.9, even after multiple suspend/resume cycles.
> > 
> > I found some 'ATA bus errors' and 'SError' messages in
> > /var/log/messages, so I've attached the messages from both 2.6.29 and
> > 2.6.28 for comparison.
> 
> Well, here is the interpretation of messages:
> 
> > ata1.00: irq_stat 0x00400008, PHY RDY changed
> > ata1: SError: { PHYRdyChg CommWake }
> 
> Your SATA hardware flags a connect-or-disconnect event ("PHY RDY"), 
> which requires us to abort a bunch of queued commands:
> 
> > ata1.00: cmd 60/18:00:77:88:6f/00:00:0e:00:00/40 tag 0 ncq 12288 in
> >          res 50/00:30:07:b3:10/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
> [...]
> > ata1.00: cmd 60/30:68:07:b3:10/00:00:0c:00:00/40 tag 13 ncq 24576 in
> >          res 50/00:30:07:b3:10/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
> 
>   ...through the 14th command (tag 13).
> 
> > Mar 24 21:29:14 linux-7vph kernel: ata1: hard resetting link
> > Mar 24 21:29:14 linux-7vph kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
> > Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
> > Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
> > Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
> > Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
> > Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
> > Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
> > Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
> > Mar 24 21:29:14 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
> > Mar 24 21:29:14 linux-7vph kernel: ata1.00: configured for UDMA/133
> > Mar 24 21:29:14 linux-7vph kernel: ata1.00: configured for UDMA/133
> 
> 
> SATA link is reset, and ACPI is re-run.
> 
> > Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE,SUGGEST_OK
> > Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Sense Key : Aborted Command [current] [descriptor]
> > Mar 24 21:29:14 linux-7vph kernel: Descriptor sense data with sense descriptors (in hex):
> > Mar 24 21:29:14 linux-7vph kernel:         72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00 
> > Mar 24 21:29:14 linux-7vph kernel:         0c 10 b3 07 
> > Mar 24 21:29:14 linux-7vph kernel: sd 0:0:0:0: [sda] Add. Sense: No additional sense information
> > Mar 24 21:29:14 linux-7vph kernel: end_request: I/O error, dev sda, sector 242190455
> 
> The SCSI subsystem aborts each of the queued commands.

No .. this is the SCSI subsystem receives an ABORTED COMMAND return in
sense data for each of the outstanding I/Os

The only place these are generated is in ata_sense_to_error() which only
occurs if there's some type of ata error.

If I had to theorise, I'd say the system suspended with commands
outstanding to the device.  On resume, the device gets reset and returns
some type of ATA error which gets translated to ABORTED COMMAND which
causes a failure.

In the mid layer, we translate ABORTED_COMMAND into a retry until the
command runs out of them ... could it be there's a race readying the
device and we run through the retries before it can accept the command?

James



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

[parent not found: <cjJLV-4jA-15@gated-at.bofh.it>]

* Re: 2.6.29: EXT4: __ext4_get_inode_loc errors after s2disk
       [not found] ` <cjJLV-4jA-15@gated-at.bofh.it>
@ 2009-03-25 22:43   ` Niel Lambrechts
  0 siblings, 0 replies; 5+ messages in thread
From: Niel Lambrechts @ 2009-03-25 22:43 UTC (permalink / raw)
  To: Pavel Machek, linux.kernel

On 03/26/2009 12:30 AM, Pavel Machek wrote:
> On Tue 2009-03-24 22:25:57, Niel Lambrechts wrote:
>> After upgrading to 2.6.29 I get the below errors after resuming from
>> hibernating with s2disk. I ran fsck and tried doing the same thing again
>> in 2.6.28.9-pae, but do not get any errors there.
> 
> what kind of disk/what kind of controller is that?

Hi Pavel,

It is a Lenovo W500 Thinkpad.

lspci shows:

00:1f.2 SATA controller: Intel Corporation ICH9M/M-E SATA AHCI
Controller (rev 03)

and for the disk, hdparm shows:
/dev/sda:

 Model=HITACHI HTS722020K9SA00  FDE            , FwRev=DC4LC75A,
SerialNo=080726DP0470DTGMUNPC
 Config={ HardSect NotMFM HdSw>15uSec Fixed DTR>10Mbs }
 RawCHS=16383/16/63, TrkSize=0, SectSize=0, ECCbytes=4
 BuffType=DualPortCache, BuffSize=15203kB, MaxMultSect=16, MultSect=?16?
 CurCHS=16383/16/63, CurSects=16514064, LBA=yes, LBAsects=390721968
 IORDY=on/off, tPIO={min:120,w/IORDY:120}, tDMA={min:120,rec:120}
 PIO modes:  pio0 pio1 pio2 pio3 pio4
 DMA modes:  mdma0 mdma1 mdma2
 UDMA modes: udma0 udma1 udma2 udma3 udma4 udma5 *udma6
 AdvancedPM=yes: mode=0x80 (128) WriteCache=enabled
 Drive conforms to: unknown:  ATA/ATAPI-2,3,4,5,6,7

 * signifies the current active mode

Niel

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

end of thread, other threads:[~2009-03-25 22:43 UTC | newest]

Thread overview: 5+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
     [not found] <cjlqb-7sp-1@gated-at.bofh.it>
     [not found] ` <cjq6y-6sq-11@gated-at.bofh.it>
2009-03-25  5:19   ` 2.6.29 regression: ATA bus errors on resume (was: EXT4: __ext4_get_inode_loc errors after s2disk) Niel Lambrechts
2009-03-25  6:06     ` 2.6.29 regression: ATA bus errors on resume Jeff Garzik
2009-03-25 21:40       ` Niel Lambrechts
2009-03-25 22:16       ` James Bottomley
     [not found] ` <cjJLV-4jA-15@gated-at.bofh.it>
2009-03-25 22:43   ` 2.6.29: EXT4: __ext4_get_inode_loc errors after s2disk Niel Lambrechts

This is an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.