[ 26.929102] NVRM: on the primary VGA device. The NVIDIA Linux graphics driver [ 26.929104] NVRM: requires the use of a text-mode VGA console. Use of other console [ 26.929106] NVRM: drivers including, but not limited to, vesafb, may result in [ 26.929108] NVRM: corruption and stability problems, and is not supported. [ 27.056007] eth0: no IPv6 routers present [ 29.007163] init: plymouth-stop pre-start process (2086) terminated with status 1 [ 40.948769] Bluetooth: HIDP (Human Interface Emulation) ver 1.2 [ 40.950026] input: Logitech diNovo Edge as /devices/pci0000:00/0000:00:1d.2/usb8/8-1/8-1.1/8-1.1:1.0/bluetooth/hci0/hci0:11/input17 [ 40.950197] generic-bluetooth 0005:046D:B309.0004: input,hidraw3: BLUETOOTH HID v1.1b Mouse [Logitech diNovo Edge] on 00:07:61:93:03:23 [ 75.834389] show_signal_msg: 27 callbacks suppressed [ 75.834394] compiz[2598]: segfault at c1 ip 00007f86de7bbd5d sp 00007fffe2aa05b0 error 4 in libpixman-1.so.0.24.4[7f86de77a000+81000] [ 2593.004560] PM: Syncing filesystems ... done. [ 2593.168536] PM: Preparing system for mem sleep [ 2593.168547] Freezing user space processes ... (elapsed 0.01 seconds) done. [ 2593.184036] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [ 2593.200034] PM: Entering mem sleep [ 2593.200066] Suspending console(s) (use no_console_suspend to debug) [ 2593.200576] sd 5:0:0:0: [sdh] Synchronizing SCSI cache [ 2593.200724] sd 5:0:0:0: [sdh] Stopping disk [ 2593.200767] sd 3:0:0:0: [sdb] Synchronizing SCSI cache [ 2593.200897] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 2593.200899] sd 3:0:0:0: [sdb] Stopping disk [ 2593.201058] sd 0:0:0:0: [sda] Stopping disk [ 2593.201112] i8042 kbd 00:0d: wake-up capability enabled by ACPI [ 2593.201443] serial 00:0b: disabled [ 2593.201447] serial 00:0b: wake-up capability disabled by ACPI [ 2593.201607] pata_marvell 0000:02:00.0: PCI INT A disabled [ 2593.208048] ACPI handle has no context! [ 2593.216049] uhci_hcd 0000:00:1d.1: PCI INT B disabled [ 2593.236067] uhci_hcd 0000:00:1d.2: PCI INT C disabled [ 2593.240119] uhci_hcd 0000:00:1a.2: PCI INT C disabled [ 2593.240126] uhci_hcd 0000:00:1d.0: PCI INT A disabled [ 2593.240142] uhci_hcd 0000:00:1a.0: PCI INT A disabled [ 2593.240152] uhci_hcd 0000:00:1a.1: PCI INT B disabled [ 2593.244016] ehci_hcd 0000:00:1a.7: PCI INT C disabled [ 2593.280013] ehci_hcd 0000:00:1d.7: PCI INT A disabled [ 2593.312077] snd_hda_intel 0000:00:1b.0: PCI INT A disabled [ 2593.312109] ACPI handle has no context! [ 2593.328011] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 119.814 msecs [ 2593.652019] snd_hda_intel 0000:01:00.1: PCI INT B disabled [ 2593.652047] ACPI handle has no context! [ 2593.668009] PM: suspend of drv:snd_hda_intel dev:0000:01:00.1 complete after 462.485 msecs [ 2593.668021] PM: suspend of drv:pcieport dev:0000:00:01.0 complete after 458.132 msecs [ 2593.709682] PM: suspend of drv:sd dev:5:0:0:0 complete after 509.114 msecs [ 2593.709708] PM: suspend of drv:scsi dev:target5:0:0 complete after 509.106 msecs [ 2593.709721] PM: suspend of drv:scsi dev:host5 complete after 508.544 msecs [ 2593.724055] PM: suspend of drv:sd dev:0:0:0:0 complete after 523.163 msecs [ 2593.724067] PM: suspend of drv:scsi dev:target0:0:0 complete after 523.171 msecs [ 2593.724078] PM: suspend of drv:scsi dev:host0 complete after 522.820 msecs [ 2593.919126] PM: suspend of drv:sd dev:3:0:0:0 complete after 718.373 msecs [ 2593.919135] PM: suspend of drv:scsi dev:target3:0:0 complete after 718.353 msecs [ 2593.919147] PM: suspend of drv:scsi dev:host3 complete after 717.925 msecs [ 2593.932015] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 724.822 msecs [ 2593.932024] PM: suspend of drv: dev:pci0000:00 complete after 722.116 msecs [ 2593.932032] PM: suspend of devices complete after 731.655 msecs [ 2593.932034] PM: suspend devices took 0.732 seconds [ 2593.932326] sky2 0000:03:00.0: PME# enabled [ 2593.932336] pcieport 0000:00:1c.3: wake-up capability enabled by ACPI [ 2594.088029] PM: late suspend of drv:sky2 dev:0000:03:00.0 complete after 155.745 msecs [ 2594.088076] sky2 0000:04:00.0: PME# enabled [ 2594.088082] pcieport 0000:00:1c.2: wake-up capability enabled by ACPI [ 2594.244027] PM: late suspend of drv:sky2 dev:0000:04:00.0 complete after 155.993 msecs [ 2594.244214] ehci_hcd 0000:00:1d.7: PME# enabled [ 2594.244218] ehci_hcd 0000:00:1d.7: wake-up capability enabled by ACPI [ 2594.260060] uhci_hcd 0000:00:1d.2: wake-up capability enabled by ACPI [ 2594.260104] uhci_hcd 0000:00:1d.1: wake-up capability enabled by ACPI [ 2594.260135] uhci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI [ 2594.260166] ehci_hcd 0000:00:1a.7: PME# enabled [ 2594.260169] ehci_hcd 0000:00:1a.7: wake-up capability enabled by ACPI [ 2594.276047] uhci_hcd 0000:00:1a.2: wake-up capability enabled by ACPI [ 2594.276084] uhci_hcd 0000:00:1a.1: wake-up capability enabled by ACPI [ 2594.276122] uhci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI [ 2594.276164] PM: late suspend of devices complete after 344.127 msecs [ 2594.276490] ACPI: Preparing to enter system sleep state S3 [ 2594.276679] PM: Saving platform NVS memory [ 2594.277197] Disabling non-boot CPUs ... [ 2594.380027] CPU 1 is now offline [ 2594.484010] CPU 2 is now offline [ 2594.484571] Broke affinity for irq 23 [ 2594.588008] CPU 3 is now offline [ 2594.588260] ACPI: Low-level resume complete [ 2594.588260] PM: Restoring platform NVS memory [ 2594.588260] Enabling non-boot CPUs ... [ 2594.588260] Booting Node 0 Processor 1 APIC 0x1 [ 2594.588260] smpboot cpu 1: start_ip = 97000 [ 2594.278693] Calibrating delay loop (skipped) already calibrated this CPU [ 2594.619746] NMI watchdog enabled, takes one hw-pmu counter. [ 2594.620036] CPU1 is up [ 2594.620114] Booting Node 0 Processor 2 APIC 0x2 [ 2594.620116] smpboot cpu 2: start_ip = 97000 [ 2594.381828] Calibrating delay loop (skipped) already calibrated this CPU [ 2594.651519] NMI watchdog enabled, takes one hw-pmu counter. [ 2594.652048] CPU2 is up [ 2594.652160] Booting Node 0 Processor 3 APIC 0x3 [ 2594.652162] smpboot cpu 3: start_ip = 97000 [ 2594.485591] Calibrating delay loop (skipped) already calibrated this CPU [ 2594.683633] NMI watchdog enabled, takes one hw-pmu counter. [ 2594.684051] CPU3 is up [ 2594.687704] ACPI: Waking up from system sleep state S3 [ 2594.687928] x38_edac 0000:00:00.0: restoring config space at offset 0x1 (was 0x20900006, writing 0xa0900006) [ 2594.687942] pcieport 0000:00:01.0: restoring config space at offset 0xf (was 0xa0100, writing 0xa010b) [ 2594.687950] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 2594.687981] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 2594.688039] uhci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI [ 2594.688059] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 2594.688079] uhci_hcd 0000:00:1a.1: wake-up capability disabled by ACPI [ 2594.688098] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 2594.688117] uhci_hcd 0000:00:1a.2: wake-up capability disabled by ACPI [ 2594.688144] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [ 2594.688158] ehci_hcd 0000:00:1a.7: wake-up capability disabled by ACPI [ 2594.688162] ehci_hcd 0000:00:1a.7: PME# disabled [ 2594.688185] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100006, writing 0x100002) [ 2594.688203] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x20100, writing 0x2010a) [ 2594.688212] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0xf0, writing 0x1010) [ 2594.688219] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100507) [ 2594.688247] pcieport 0000:00:1c.2: restoring config space at offset 0xf (was 0x20300, writing 0x20305) [ 2594.688255] pcieport 0000:00:1c.2: restoring config space at offset 0x9 (was 0x1fff1, writing 0xc051c041) [ 2594.688263] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 2594.688291] pcieport 0000:00:1c.3: restoring config space at offset 0xf (was 0x20400, writing 0x2040f) [ 2594.688298] pcieport 0000:00:1c.3: restoring config space at offset 0x9 (was 0x1fff1, writing 0xc031c021) [ 2594.688307] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 2594.688335] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x20100, writing 0x2010a) [ 2594.688342] pcieport 0000:00:1c.4: restoring config space at offset 0x9 (was 0x1fff1, writing 0xc011c001) [ 2594.688351] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 2594.688388] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 2594.688407] uhci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI [ 2594.688427] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 2594.688446] uhci_hcd 0000:00:1d.1: wake-up capability disabled by ACPI [ 2594.688465] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 2594.688484] uhci_hcd 0000:00:1d.2: wake-up capability disabled by ACPI [ 2594.688510] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [ 2594.688524] ehci_hcd 0000:00:1d.7: wake-up capability disabled by ACPI [ 2594.688528] ehci_hcd 0000:00:1d.7: PME# disabled [ 2594.688535] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x20000, writing 0x200ff) [ 2594.688544] pci 0000:00:1e.0: restoring config space at offset 0x7 (was 0x2280e0e0, writing 0xa280e0e0) [ 2594.688602] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [ 2594.688647] nvidia 0000:01:00.0: restoring config space at offset 0xc (was 0xfe600000, writing 0x0) [ 2594.688656] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x800008, writing 0x800000) [ 2594.688695] snd_hda_intel 0000:01:00.1: restoring config space at offset 0x1 (was 0x100006, writing 0x100002) [ 2594.688722] pci 0000:05:00.0: restoring config space at offset 0xf (was 0x0, writing 0x30000) [ 2594.688738] pci 0000:05:00.0: restoring config space at offset 0x7 (was 0x42001f1, writing 0x242001f1) [ 2594.688743] pci 0000:05:00.0: restoring config space at offset 0x6 (was 0x0, writing 0x40070705) [ 2594.688752] pci 0000:05:00.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810008) [ 2594.688758] pci 0000:05:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100104) [ 2594.688784] pci 0000:05:00.1: restoring config space at offset 0xf (was 0x100, writing 0x3010b) [ 2594.688797] pci 0000:05:00.1: restoring config space at offset 0x9 (was 0x1fff1, writing 0xfbf1fbf1) [ 2594.688803] pci 0000:05:00.1: restoring config space at offset 0x7 (was 0x42001f1, writing 0x242001f1) [ 2594.688809] pci 0000:05:00.1: restoring config space at offset 0x6 (was 0x0, writing 0x40060605) [ 2594.688815] pci 0000:05:00.1: restoring config space at offset 0x4 (was 0x4, writing 0xfeaffc04) [ 2594.688821] pci 0000:05:00.1: restoring config space at offset 0x3 (was 0x810000, writing 0x810008) [ 2594.688827] pci 0000:05:00.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100106) [ 2594.688862] sky2 0000:04:00.0: restoring config space at offset 0xf (was 0x100, writing 0x105) [ 2594.688870] sky2 0000:04:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfe9c0000) [ 2594.688881] sky2 0000:04:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xd801) [ 2594.688887] sky2 0000:04:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfe9fc004) [ 2594.688892] sky2 0000:04:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [ 2594.688898] sky2 0000:04:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [ 2594.688949] sky2 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10f) [ 2594.688957] sky2 0000:03:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfe8c0000) [ 2594.688968] sky2 0000:03:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xc801) [ 2594.688974] sky2 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfe8fc004) [ 2594.688979] sky2 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [ 2594.688985] sky2 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [ 2594.689033] pata_marvell 0000:02:00.0: restoring config space at offset 0xf (was 0x80100, writing 0x8010b) [ 2594.689040] pata_marvell 0000:02:00.0: restoring config space at offset 0xc (was 0x80001, writing 0xfe780000) [ 2594.689048] pata_marvell 0000:02:00.0: restoring config space at offset 0x9 (was 0x800000, writing 0xfe7ffc00) [ 2594.689052] pata_marvell 0000:02:00.0: restoring config space at offset 0x8 (was 0x900001, writing 0xb401) [ 2594.689057] pata_marvell 0000:02:00.0: restoring config space at offset 0x7 (was 0x8801, writing 0xb481) [ 2594.689062] pata_marvell 0000:02:00.0: restoring config space at offset 0x6 (was 0x8201, writing 0xb801) [ 2594.689066] pata_marvell 0000:02:00.0: restoring config space at offset 0x5 (was 0x8041, writing 0xb881) [ 2594.689071] pata_marvell 0000:02:00.0: restoring config space at offset 0x4 (was 0x8001, writing 0xbc01) [ 2594.689076] pata_marvell 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [ 2594.689101] firewire_ohci 0000:08:03.0: restoring config space at offset 0xf (was 0x20000100, writing 0x2000010f) [ 2594.689114] firewire_ohci 0000:08:03.0: restoring config space at offset 0x5 (was 0x1, writing 0xec01) [ 2594.689119] firewire_ohci 0000:08:03.0: restoring config space at offset 0x4 (was 0x0, writing 0xfebff800) [ 2594.689123] firewire_ohci 0000:08:03.0: restoring config space at offset 0x3 (was 0x0, writing 0x4008) [ 2594.689128] firewire_ohci 0000:08:03.0: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100017) [ 2594.689346] PM: early resume of devices complete after 1.456 msecs [ 2594.689503] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 2594.689508] snd_hda_intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [ 2594.689510] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [ 2594.689513] snd_hda_intel 0000:00:1b.0: setting latency timer to 64 [ 2594.689541] usb usb3: root hub lost power or was reset [ 2594.689565] snd_hda_intel 0000:01:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 2594.689566] pci 0000:05:00.1: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 2594.689577] snd_hda_intel 0000:00:1b.0: irq 304 for MSI/MSI-X [ 2594.689581] snd_hda_intel 0000:01:00.1: setting latency timer to 64 [ 2594.689580] pci 0000:05:00.1: setting latency timer to 64 [ 2594.689633] pata_marvell 0000:02:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 2594.689640] uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 2594.689646] pata_marvell 0000:02:00.0: setting latency timer to 64 [ 2594.689649] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [ 2594.689651] uhci_hcd 0000:00:1a.2: setting latency timer to 64 [ 2594.689665] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [ 2594.689698] usb usb5: root hub lost power or was reset [ 2594.689702] usb usb4: root hub lost power or was reset [ 2594.689720] BAR5:00:04 01:7F 02:22 03:C8 04:00 05:00 06:00 07:00 08:00 09:00 0A:00 0B:00 0C:1F 0D:00 0E:00 0F:00 [ 2594.689940] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 2594.689946] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [ 2594.689951] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [ 2594.689954] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [ 2594.689957] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [ 2594.689966] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [ 2594.690005] usb usb6: root hub lost power or was reset [ 2594.690013] usb usb7: root hub lost power or was reset [ 2594.690028] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 2594.690037] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [ 2594.690069] usb usb8: root hub lost power or was reset [ 2594.690080] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [ 2594.690085] pci 0000:00:1e.0: setting latency timer to 64 [ 2594.690086] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [ 2594.690130] pci 0000:05:00.0: setting latency timer to 64 [ 2594.690134] pcieport 0000:00:1c.2: wake-up capability disabled by ACPI [ 2594.690139] sky2 0000:04:00.0: PME# disabled [ 2594.692052] ahci 0000:00:1f.2: setting latency timer to 64 [ 2594.692065] pcieport 0000:00:1c.3: wake-up capability disabled by ACPI [ 2594.692072] sky2 0000:03:00.0: PME# disabled [ 2594.693043] sd 3:0:0:0: [sdb] Starting disk [ 2594.693063] sd 5:0:0:0: [sdh] Starting disk [ 2594.696070] sd 0:0:0:0: [sda] Starting disk [ 2594.696570] serial 00:0b: activated [ 2594.696580] i8042 kbd 00:0d: wake-up capability disabled by ACPI [ 2594.752109] firewire_core: skipped bus generations, destroying all nodes [ 2594.808036] PM: resume of drv: dev:ep_00 complete after 117.845 msecs [ 2594.808043] PM: resume of drv:hub dev:6-0:1.0 complete after 117.861 msecs [ 2594.808060] PM: resume of drv:hub dev:4-0:1.0 complete after 117.918 msecs [ 2594.808070] PM: resume of drv: dev:ep_81 complete after 117.875 msecs [ 2594.808073] PM: resume of drv: dev:ep_00 complete after 117.924 msecs [ 2594.808077] PM: resume of drv:hub dev:5-0:1.0 complete after 117.916 msecs [ 2594.808082] PM: resume of drv: dev:ep_81 complete after 117.936 msecs [ 2594.808086] PM: resume of drv: dev:ep_00 complete after 117.911 msecs [ 2594.808089] PM: resume of drv: dev:ep_00 complete after 117.956 msecs [ 2594.808099] PM: resume of drv: dev:ep_81 complete after 117.927 msecs [ 2594.808102] PM: resume of drv:hub dev:3-0:1.0 complete after 117.978 msecs [ 2594.808109] PM: resume of drv: dev:ep_81 complete after 117.986 msecs [ 2594.868434] PM: resume of drv: dev:ep_00 complete after 177.842 msecs [ 2594.868446] PM: resume of drv:usb dev:2-2:1.0 complete after 177.959 msecs [ 2594.868450] PM: resume of drv:usb-storage dev:2-2:1.1 complete after 177.905 msecs [ 2594.868459] PM: resume of drv: dev:ep_83 complete after 177.968 msecs [ 2594.868471] PM: resume of drv: dev:ep_01 complete after 177.901 msecs [ 2594.868490] PM: resume of drv:scsi dev:host6 complete after 177.854 msecs [ 2594.868519] PM: resume of drv: dev:ep_05 complete after 177.964 msecs [ 2594.868529] PM: resume of drv: dev:ep_82 complete after 177.918 msecs [ 2594.868532] PM: resume of drv:scsi_host dev:host6 complete after 177.902 msecs [ 2594.868542] PM: resume of drv: dev:ep_86 complete after 178.008 msecs [ 2594.868554] PM: resume of drv:scsi dev:target6:0:0 complete after 177.670 msecs [ 2594.868584] PM: resume of drv:sd dev:6:0:0:3 complete after 177.600 msecs [ 2594.868596] PM: resume of drv:sd dev:6:0:0:2 complete after 177.632 msecs [ 2594.868604] PM: resume of drv:sd dev:6:0:0:0 complete after 177.717 msecs [ 2594.868609] PM: resume of drv:sd dev:6:0:0:1 complete after 177.690 msecs [ 2594.868612] PM: resume of drv:scsi_device dev:6:0:0:0 complete after 177.709 msecs [ 2594.868619] PM: resume of drv:scsi_device dev:6:0:0:2 complete after 177.651 msecs [ 2594.868625] PM: resume of drv:scsi_device dev:6:0:0:1 complete after 177.686 msecs [ 2594.868629] PM: resume of drv:scsi_device dev:6:0:0:3 complete after 177.629 msecs [ 2594.912032] PM: resume of drv:hub dev:8-0:1.0 complete after 221.738 msecs [ 2594.912039] PM: resume of drv: dev:ep_81 complete after 221.729 msecs [ 2594.912057] PM: resume of drv: dev:ep_00 complete after 221.731 msecs [ 2594.916022] PM: resume of drv: dev:ep_00 complete after 225.768 msecs [ 2594.916034] PM: resume of drv:hub dev:7-0:1.0 complete after 225.836 msecs [ 2594.916042] PM: resume of drv: dev:ep_81 complete after 225.809 msecs [ 2595.016020] ata2: SATA link down (SStatus 0 SControl 300) [ 2595.020024] ata5: SATA link down (SStatus 0 SControl 300) [ 2595.024023] usb 8-1: reset full-speed USB device number 2 using uhci_hcd [ 2595.024026] ata3: SATA link down (SStatus 0 SControl 300) [ 2595.093728] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 404.170 msecs [ 2595.140491] ata7.00: configured for UDMA/66 [ 2595.252029] firewire_core: rediscovered device fw0 [ 2595.388021] PM: resume of drv:hub dev:8-1:1.0 complete after 696.936 msecs [ 2595.388031] PM: resume of drv: dev:ep_00 complete after 696.914 msecs [ 2595.388042] PM: resume of drv: dev:ep_81 complete after 696.941 msecs [ 2595.391299] PM: resume of drv:btusb dev:8-1.1:1.0 complete after 699.955 msecs [ 2595.391304] PM: resume of drv:usb dev:8-1.1:1.2 complete after 699.836 msecs [ 2595.391308] PM: resume of drv:usb dev:8-1.1:1.3 complete after 699.793 msecs [ 2595.391313] PM: resume of drv: dev:ep_00 complete after 699.777 msecs [ 2595.391322] PM: resume of drv:btusb dev:8-1.1:1.1 complete after 699.899 msecs [ 2595.391327] PM: resume of drv: dev:ep_84 complete after 699.833 msecs [ 2595.391331] PM: resume of drv: dev:ep_82 complete after 699.951 msecs [ 2595.391342] PM: resume of drv: dev:ep_02 complete after 699.934 msecs [ 2595.391350] PM: resume of drv: dev:ep_04 complete after 699.836 msecs [ 2595.391354] PM: resume of drv: dev:ep_81 complete after 699.971 msecs [ 2595.391363] PM: resume of drv: dev:ep_03 complete after 699.907 msecs [ 2595.391369] PM: resume of drv: dev:ep_83 complete after 699.939 msecs [ 2595.420021] usb 7-2: reset low-speed USB device number 2 using uhci_hcd [ 2595.726215] PM: resume of drv:usbhid dev:7-2:1.0 complete after 1035.393 msecs [ 2595.726220] PM: resume of drv: dev:ep_00 complete after 1035.364 msecs [ 2595.726226] PM: resume of drv: dev:ep_81 complete after 1035.387 msecs [ 2595.781298] usb 8-1.2: reset full-speed USB device number 3 using uhci_hcd [ 2595.902299] PM: resume of drv:usbhid dev:8-1.2:1.0 complete after 1211.098 msecs [ 2595.902305] PM: resume of drv: dev:ep_00 complete after 1211.071 msecs [ 2595.902309] PM: resume of drv: dev:ep_81 complete after 1211.092 msecs [ 2595.953296] usb 8-1.3: reset full-speed USB device number 4 using uhci_hcd [ 2596.075299] PM: resume of drv:usbhid dev:8-1.3:1.0 complete after 1384.031 msecs [ 2596.075304] PM: resume of drv: dev:ep_00 complete after 1384.004 msecs [ 2596.075309] PM: resume of drv: dev:ep_81 complete after 1384.026 msecs [ 2597.653852] sky2 0000:04:00.0: eth0: Link is up at 1000 Mbps, full duplex, flow control both [ 2601.420015] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 2601.442959] ata1.00: configured for UDMA/133 [ 2601.458680] PM: resume of drv:sd dev:0:0:0:0 complete after 6768.253 msecs [ 2601.458691] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 6768.247 msecs [ 2601.458697] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 6759.105 msecs [ 2604.440014] ata6: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 2604.469854] ata6.00: configured for UDMA/133 [ 2604.496853] PM: resume of drv:sd dev:5:0:0:0 complete after 9805.702 msecs [ 2604.496863] PM: resume of drv:scsi_device dev:5:0:0:0 complete after 9805.695 msecs [ 2604.708009] ata4: softreset failed (device not ready) [ 2605.200013] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 2605.226852] ata4.00: configured for UDMA/133 [ 2605.252445] PM: resume of drv:sd dev:3:0:0:0 complete after 10561.671 msecs [ 2605.252456] PM: resume of drv:scsi_device dev:3:0:0:0 complete after 10561.666 msecs [ 2605.252467] PM: resume of drv:scsi_disk dev:3:0:0:0 complete after 3793.742 msecs [ 2605.252688] PM: resume of devices complete after 10563.309 msecs [ 2605.252911] PM: resume devices took 10.564 seconds [ 2605.252913] ------------[ cut here ]------------ [ 2605.252918] WARNING: at /build/buildd/linux-3.2.0/kernel/power/suspend_test.c:53 suspend_test_finish+0x86/0x90() [ 2605.252919] Hardware name: P5E WS Pro [ 2605.252921] Component: resume devices, time: 10564 [ 2605.252922] Modules linked in: hidp bnep parport_pc ppdev rfcomm dm_crypt snd_hda_codec_hdmi ir_lirc_codec lirc_dev snd_hda_codec_realtek ir_mce_kbd_decoder btusb nvidia(P) ir_sony_decoder bluetooth snd_hda_intel snd_hda_codec snd_hwdep snd_pcm ir_jvc_decoder ir_rc6_decoder snd_seq_midi snd_rawmidi ir_rc5_decoder snd_seq_midi_event ir_nec_decoder mceusb snd_seq rc_core joydev snd_timer snd_seq_device snd x38_edac mac_hid serio_raw soundcore edac_core snd_page_alloc shpchp asus_atk0110 coretemp lp parport vesafb hid_logitech ff_memless firewire_ohci firewire_core crc_itu_t usbhid hid pata_marvell sky2 usb_storage [ 2605.252952] Pid: 23150, comm: pm-suspend Tainted: P O 3.2.0-33-generic #52-Ubuntu [ 2605.252954] Call Trace: [ 2605.252959] [] warn_slowpath_common+0x7f/0xc0 [ 2605.252961] [] warn_slowpath_fmt+0x46/0x50 [ 2605.252964] [] suspend_test_finish+0x86/0x90 [ 2605.252966] [] suspend_devices_and_enter+0x10b/0x200 [ 2605.252969] [] enter_state+0xd1/0x110 [ 2605.252971] [] state_store+0xb7/0x130 [ 2605.252975] [] kobj_attr_store+0xf/0x30 [ 2605.252978] [] sysfs_write_file+0xef/0x170 [ 2605.252981] [] vfs_write+0xb3/0x180 [ 2605.252983] [] sys_write+0x4a/0x90 [ 2605.252986] [] system_call_fastpath+0x16/0x1b [ 2605.252988] ---[ end trace 6a7b6967043bb13d ]--- [ 2605.253041] PM: Finishing wakeup. [ 2605.253042] Restarting tasks ... [ 2605.254781] usb 8-1.1: USB disconnect, device number 5 [ 2605.264579] done. [ 3184.841758] EXT4-fs (sdg1): warning: maximal mount count reached, running e2fsck is recommended [ 3184.861881] EXT4-fs (sdg1): mounted filesystem with ordered data mode. Opts: (null) [ 7810.648111] sky2 0000:04:00.0: eth0: disabling interface [ 7810.923060] sky2 0000:04:00.0: eth0: enabling interface [ 7810.924455] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 7814.181537] sky2 0000:04:00.0: eth0: Link is up at 1000 Mbps, full duplex, flow control both [ 7814.182509] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 7824.528014] eth0: no IPv6 routers present [ 8095.965523] sky2 0000:04:00.0: eth0: disabling interface [ 8095.990992] sky2 0000:04:00.0: eth0: enabling interface [ 8095.992283] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 8099.610904] sky2 0000:04:00.0: eth0: Link is up at 1000 Mbps, full duplex, flow control both [ 8099.611876] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 8109.728011] eth0: no IPv6 routers present [18692.592716] sky2 0000:03:00.0: eth1: disabling interface [18694.577000] init: anacron main process (18603) killed by TERM signal [18696.772101] PM: Syncing filesystems ... done. [18696.927225] PM: Preparing system for mem sleep [18696.927237] Freezing user space processes ... (elapsed 0.01 seconds) done. [18696.940044] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [18696.956035] PM: Entering mem sleep [18696.956069] Suspending console(s) (use no_console_suspend to debug) [18696.956545] sd 5:0:0:0: [sdh] Synchronizing SCSI cache [18696.956728] sd 3:0:0:0: [sdb] Synchronizing SCSI cache [18696.956865] sd 5:0:0:0: [sdh] Stopping disk [18696.956866] sd 3:0:0:0: [sdb] Stopping disk [18696.956905] sd 0:0:0:0: [sda] Synchronizing SCSI cache [18696.957037] sd 0:0:0:0: [sda] Stopping disk [18696.957133] i8042 kbd 00:0d: wake-up capability enabled by ACPI [18696.957468] serial 00:0b: disabled [18696.957472] serial 00:0b: wake-up capability disabled by ACPI [18696.957674] snd_hda_intel 0000:01:00.1: PCI INT B disabled [18696.957702] ACPI handle has no context! [18696.960304] pata_marvell 0000:02:00.0: PCI INT A disabled [18696.964052] ACPI handle has no context! [18696.972105] uhci_hcd 0000:00:1d.1: PCI INT B disabled [18696.992049] uhci_hcd 0000:00:1d.2: PCI INT C disabled [18696.996055] uhci_hcd 0000:00:1a.2: PCI INT C disabled [18696.996062] uhci_hcd 0000:00:1d.0: PCI INT A disabled [18696.996073] uhci_hcd 0000:00:1a.0: PCI INT A disabled [18696.996087] uhci_hcd 0000:00:1a.1: PCI INT B disabled [18697.000023] ehci_hcd 0000:00:1a.7: PCI INT C disabled [18697.036012] ehci_hcd 0000:00:1d.7: PCI INT A disabled [18697.068084] snd_hda_intel 0000:00:1b.0: PCI INT A disabled [18697.068115] ACPI handle has no context! [18697.084012] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 118.963 msecs [18697.431745] PM: suspend of drv:sd dev:5:0:0:0 complete after 475.205 msecs [18697.431762] PM: suspend of drv:scsi dev:target5:0:0 complete after 475.166 msecs [18697.431773] PM: suspend of drv:scsi dev:host5 complete after 474.460 msecs [18697.472771] PM: suspend of drv:sd dev:0:0:0:0 complete after 515.867 msecs [18697.472783] PM: suspend of drv:scsi dev:target0:0:0 complete after 515.837 msecs [18697.472792] PM: suspend of drv:scsi dev:host0 complete after 515.412 msecs [18698.046650] PM: suspend of drv:sd dev:3:0:0:0 complete after 1089.924 msecs [18698.046661] PM: suspend of drv:scsi dev:target3:0:0 complete after 1089.886 msecs [18698.046676] PM: suspend of drv:scsi dev:host3 complete after 1089.331 msecs [18698.060019] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 1100.708 msecs [18698.060031] PM: suspend of drv: dev:pci0000:00 complete after 1094.068 msecs [18698.060039] PM: suspend of devices complete after 1103.673 msecs [18698.060042] PM: suspend devices took 1.104 seconds [18698.060330] sky2 0000:03:00.0: PME# enabled [18698.060341] pcieport 0000:00:1c.3: wake-up capability enabled by ACPI [18698.216035] PM: late suspend of drv:sky2 dev:0000:03:00.0 complete after 155.748 msecs [18698.216084] sky2 0000:04:00.0: PME# enabled [18698.216096] pcieport 0000:00:1c.2: wake-up capability enabled by ACPI [18698.372030] PM: late suspend of drv:sky2 dev:0000:04:00.0 complete after 155.990 msecs [18698.372218] ehci_hcd 0000:00:1d.7: PME# enabled [18698.372222] ehci_hcd 0000:00:1d.7: wake-up capability enabled by ACPI [18698.388060] uhci_hcd 0000:00:1d.2: wake-up capability enabled by ACPI [18698.388103] uhci_hcd 0000:00:1d.1: wake-up capability enabled by ACPI [18698.388135] uhci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI [18698.388257] ehci_hcd 0000:00:1a.7: PME# enabled [18698.388261] ehci_hcd 0000:00:1a.7: wake-up capability enabled by ACPI [18698.404054] uhci_hcd 0000:00:1a.2: wake-up capability enabled by ACPI [18698.404092] uhci_hcd 0000:00:1a.1: wake-up capability enabled by ACPI [18698.404130] uhci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI [18698.404184] PM: late suspend of devices complete after 344.140 msecs [18698.404510] ACPI: Preparing to enter system sleep state S3 [18698.404702] PM: Saving platform NVS memory [18698.405219] Disabling non-boot CPUs ... [18698.508031] CPU 1 is now offline [18698.612011] CPU 2 is now offline [18698.716008] CPU 3 is now offline [18698.716298] ACPI: Low-level resume complete [18698.716298] PM: Restoring platform NVS memory [18698.716298] Enabling non-boot CPUs ... [18698.716298] Booting Node 0 Processor 1 APIC 0x1 [18698.716298] smpboot cpu 1: start_ip = 97000 [18698.406718] Calibrating delay loop (skipped) already calibrated this CPU [18698.747799] NMI watchdog enabled, takes one hw-pmu counter. [18698.752035] CPU1 is up [18698.752114] Booting Node 0 Processor 2 APIC 0x2 [18698.752116] smpboot cpu 2: start_ip = 97000 [18698.509900] Calibrating delay loop (skipped) already calibrated this CPU [18698.783496] NMI watchdog enabled, takes one hw-pmu counter. [18698.784048] CPU2 is up [18698.784160] Booting Node 0 Processor 3 APIC 0x3 [18698.784162] smpboot cpu 3: start_ip = 97000 [18698.613601] Calibrating delay loop (skipped) already calibrated this CPU [18698.815632] NMI watchdog enabled, takes one hw-pmu counter. [18698.816050] CPU3 is up [18698.819715] ACPI: Waking up from system sleep state S3 [18698.819949] x38_edac 0000:00:00.0: restoring config space at offset 0x1 (was 0x20900006, writing 0x80900006) [18698.819963] pcieport 0000:00:01.0: restoring config space at offset 0xf (was 0xa0100, writing 0xa010b) [18698.819971] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [18698.820038] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [18698.820062] uhci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI [18698.820082] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [18698.820101] uhci_hcd 0000:00:1a.1: wake-up capability disabled by ACPI [18698.820121] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [18698.820140] uhci_hcd 0000:00:1a.2: wake-up capability disabled by ACPI [18698.820166] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [18698.820180] ehci_hcd 0000:00:1a.7: wake-up capability disabled by ACPI [18698.820185] ehci_hcd 0000:00:1a.7: PME# disabled [18698.820207] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100006, writing 0x100002) [18698.820225] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x20100, writing 0x2010a) [18698.820235] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0xf0, writing 0x1010) [18698.820242] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100507) [18698.820270] pcieport 0000:00:1c.2: restoring config space at offset 0xf (was 0x20300, writing 0x20305) [18698.820277] pcieport 0000:00:1c.2: restoring config space at offset 0x9 (was 0x1fff1, writing 0xc051c041) [18698.820286] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [18698.820314] pcieport 0000:00:1c.3: restoring config space at offset 0xf (was 0x20400, writing 0x2040f) [18698.820321] pcieport 0000:00:1c.3: restoring config space at offset 0x9 (was 0x1fff1, writing 0xc031c021) [18698.820330] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [18698.820357] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x20100, writing 0x2010a) [18698.820365] pcieport 0000:00:1c.4: restoring config space at offset 0x9 (was 0x1fff1, writing 0xc011c001) [18698.820374] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [18698.820411] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [18698.820430] uhci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI [18698.820450] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [18698.820468] uhci_hcd 0000:00:1d.1: wake-up capability disabled by ACPI [18698.820488] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [18698.820507] uhci_hcd 0000:00:1d.2: wake-up capability disabled by ACPI [18698.820532] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [18698.820547] ehci_hcd 0000:00:1d.7: wake-up capability disabled by ACPI [18698.820550] ehci_hcd 0000:00:1d.7: PME# disabled [18698.820557] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x20000, writing 0x200ff) [18698.820566] pci 0000:00:1e.0: restoring config space at offset 0x7 (was 0x2280e0e0, writing 0x8280e0e0) [18698.820625] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [18698.820669] nvidia 0000:01:00.0: restoring config space at offset 0xc (was 0xfe600000, writing 0x0) [18698.820679] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x800008, writing 0x800000) [18698.820718] snd_hda_intel 0000:01:00.1: restoring config space at offset 0x1 (was 0x100006, writing 0x100002) [18698.820745] pci 0000:05:00.0: restoring config space at offset 0xf (was 0x0, writing 0x30000) [18698.820762] pci 0000:05:00.0: restoring config space at offset 0x6 (was 0x0, writing 0x40070705) [18698.820770] pci 0000:05:00.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810008) [18698.820777] pci 0000:05:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100104) [18698.820803] pci 0000:05:00.1: restoring config space at offset 0xf (was 0x100, writing 0x3010b) [18698.820816] pci 0000:05:00.1: restoring config space at offset 0x9 (was 0x1fff1, writing 0xfbf1fbf1) [18698.820824] pci 0000:05:00.1: restoring config space at offset 0x6 (was 0x0, writing 0x40060605) [18698.820831] pci 0000:05:00.1: restoring config space at offset 0x4 (was 0x4, writing 0xfeaffc04) [18698.820836] pci 0000:05:00.1: restoring config space at offset 0x3 (was 0x810000, writing 0x810008) [18698.820842] pci 0000:05:00.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100106) [18698.820877] sky2 0000:04:00.0: restoring config space at offset 0xf (was 0x100, writing 0x105) [18698.820885] sky2 0000:04:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfe9c0000) [18698.820896] sky2 0000:04:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xd801) [18698.820902] sky2 0000:04:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfe9fc004) [18698.820907] sky2 0000:04:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [18698.820913] sky2 0000:04:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [18698.820964] sky2 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10f) [18698.820972] sky2 0000:03:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfe8c0000) [18698.820983] sky2 0000:03:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xc801) [18698.820989] sky2 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfe8fc004) [18698.820993] sky2 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [18698.820999] sky2 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [18698.821048] pata_marvell 0000:02:00.0: restoring config space at offset 0xf (was 0x80100, writing 0x8010b) [18698.821055] pata_marvell 0000:02:00.0: restoring config space at offset 0xc (was 0x80001, writing 0xfe780000) [18698.821062] pata_marvell 0000:02:00.0: restoring config space at offset 0x9 (was 0x800000, writing 0xfe7ffc00) [18698.821067] pata_marvell 0000:02:00.0: restoring config space at offset 0x8 (was 0x900001, writing 0xb401) [18698.821072] pata_marvell 0000:02:00.0: restoring config space at offset 0x7 (was 0x8801, writing 0xb481) [18698.821077] pata_marvell 0000:02:00.0: restoring config space at offset 0x6 (was 0x8201, writing 0xb801) [18698.821081] pata_marvell 0000:02:00.0: restoring config space at offset 0x5 (was 0x8041, writing 0xb881) [18698.821086] pata_marvell 0000:02:00.0: restoring config space at offset 0x4 (was 0x8001, writing 0xbc01) [18698.821091] pata_marvell 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [18698.821116] firewire_ohci 0000:08:03.0: restoring config space at offset 0xf (was 0x20000100, writing 0x2000010f) [18698.821129] firewire_ohci 0000:08:03.0: restoring config space at offset 0x5 (was 0x1, writing 0xec01) [18698.821134] firewire_ohci 0000:08:03.0: restoring config space at offset 0x4 (was 0x0, writing 0xfebff800) [18698.821138] firewire_ohci 0000:08:03.0: restoring config space at offset 0x3 (was 0x0, writing 0x4008) [18698.821143] firewire_ohci 0000:08:03.0: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100017) [18698.821354] PM: early resume of devices complete after 1.444 msecs [18698.821435] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [18698.821441] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [18698.821462] usb usb3: root hub lost power or was reset [18698.821476] snd_hda_intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [18698.821482] snd_hda_intel 0000:00:1b.0: setting latency timer to 64 [18698.821495] uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [18698.821506] uhci_hcd 0000:00:1a.2: setting latency timer to 64 [18698.821516] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [18698.821525] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [18698.821535] usb usb5: root hub lost power or was reset [18698.821549] snd_hda_intel 0000:00:1b.0: irq 304 for MSI/MSI-X [18698.821554] usb usb4: root hub lost power or was reset [18698.821573] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [18698.821578] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [18698.821594] snd_hda_intel 0000:01:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [18698.821600] snd_hda_intel 0000:01:00.1: setting latency timer to 64 [18698.821628] pci 0000:05:00.1: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [18698.821633] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [18698.821636] pci 0000:05:00.1: setting latency timer to 64 [18698.821645] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [18698.821664] pata_marvell 0000:02:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [18698.821670] pata_marvell 0000:02:00.0: setting latency timer to 64 [18698.821680] usb usb6: root hub lost power or was reset [18698.821709] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [18698.821721] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [18698.821759] usb usb7: root hub lost power or was reset [18698.821823] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [18698.821826] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [18698.821844] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [18698.821846] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [18698.821858] BAR5:00:04 01:7F 02:22 03:C8 04:00 05:00 06:00 07:00 08:00 [18698.821896] usb usb8: root hub lost power or was reset [18698.821899] 09:00 0A:00 0B:00 0C:1F 0D:00 0E:00 0F:00 [18698.821948] pci 0000:00:1e.0: setting latency timer to 64 [18698.822004] pci 0000:05:00.0: setting latency timer to 64 [18698.822032] pcieport 0000:00:1c.2: wake-up capability disabled by ACPI [18698.822047] sky2 0000:04:00.0: PME# disabled [18698.822049] ahci 0000:00:1f.2: setting latency timer to 64 [18698.822766] serial 00:0b: activated [18698.822775] i8042 kbd 00:0d: wake-up capability disabled by ACPI [18698.824032] sd 0:0:0:0: [sda] Starting disk [18698.824067] pcieport 0000:00:1c.3: wake-up capability disabled by ACPI [18698.824072] sky2 0000:03:00.0: PME# disabled [18698.826302] sd 3:0:0:0: [sdb] Starting disk [18698.826926] sd 5:0:0:0: [sdh] Starting disk [18698.944040] PM: resume of drv: dev:ep_00 complete after 122.035 msecs [18698.944056] PM: resume of drv: dev:ep_00 complete after 122.008 msecs [18698.944085] PM: resume of drv:hub dev:5-0:1.0 complete after 122.018 msecs [18698.944102] PM: resume of drv:hub dev:3-0:1.0 complete after 122.059 msecs [18698.944113] PM: resume of drv:hub dev:4-0:1.0 complete after 122.082 msecs [18698.944117] PM: resume of drv: dev:ep_00 complete after 122.097 msecs [18698.944120] PM: resume of drv: dev:ep_81 complete after 122.121 msecs [18698.944125] PM: resume of drv: dev:ep_81 complete after 122.108 msecs [18698.944134] PM: resume of drv: dev:ep_81 complete after 122.105 msecs [18698.944157] PM: resume of drv:hub dev:6-0:1.0 complete after 122.116 msecs [18698.944169] PM: resume of drv: dev:ep_00 complete after 122.035 msecs [18698.944173] PM: resume of drv: dev:ep_81 complete after 122.130 msecs [18699.000402] PM: resume of drv:usb dev:2-2:1.0 complete after 175.442 msecs [18699.000406] PM: resume of drv: dev:ep_00 complete after 174.399 msecs [18699.000412] PM: resume of drv: dev:ep_83 complete after 175.433 msecs [18699.000415] PM: resume of drv: dev:ep_05 complete after 174.596 msecs [18699.000419] PM: resume of drv: dev:ep_86 complete after 174.554 msecs [18699.000424] PM: resume of drv:usb-storage dev:2-2:1.1 complete after 174.511 msecs [18699.000438] PM: resume of drv: dev:ep_01 complete after 174.493 msecs [18699.000441] PM: resume of drv:scsi dev:host6 complete after 174.401 msecs [18699.000444] PM: resume of drv: dev:ep_82 complete after 174.469 msecs [18699.000454] PM: resume of drv:scsi_host dev:host6 complete after 174.388 msecs [18699.000457] PM: resume of drv:scsi dev:target6:0:0 complete after 173.989 msecs [18699.000473] PM: resume of drv:sd dev:6:0:0:0 complete after 173.978 msecs [18699.000476] PM: resume of drv:sd dev:6:0:0:2 complete after 173.863 msecs [18699.000480] PM: resume of drv:scsi_device dev:6:0:0:0 complete after 173.958 msecs [18699.000483] PM: resume of drv:scsi_device dev:6:0:0:2 complete after 173.845 msecs [18699.000487] PM: resume of drv:sd dev:6:0:0:1 complete after 173.937 msecs [18699.000494] PM: resume of drv:scsi_device dev:6:0:0:1 complete after 173.915 msecs [18699.000497] PM: resume of drv:sd dev:6:0:0:3 complete after 173.828 msecs [18699.000504] PM: resume of drv:scsi_device dev:6:0:0:3 complete after 173.809 msecs [18699.044023] PM: resume of drv:hub dev:7-0:1.0 complete after 221.888 msecs [18699.044032] PM: resume of drv: dev:ep_00 complete after 221.890 msecs [18699.044035] PM: resume of drv: dev:ep_81 complete after 221.897 msecs [18699.044039] PM: resume of drv:hub dev:8-0:1.0 complete after 221.886 msecs [18699.044045] PM: resume of drv: dev:ep_00 complete after 221.885 msecs [18699.044049] PM: resume of drv: dev:ep_81 complete after 221.892 msecs [18699.152029] ata3: SATA link down (SStatus 0 SControl 300) [18699.160025] ata2: SATA link down (SStatus 0 SControl 300) [18699.168022] ata5: SATA link down (SStatus 0 SControl 300) [18699.225917] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 404.337 msecs [18699.225932] firewire_core: skipped bus generations, destroying all nodes [18699.256487] ata7.00: configured for UDMA/66 [18699.304033] usb 7-2: reset low-speed USB device number 2 using uhci_hcd [18699.611148] PM: resume of drv:usbhid dev:7-2:1.0 complete after 784.768 msecs [18699.611155] PM: resume of drv: dev:ep_00 complete after 784.717 msecs [18699.611163] PM: resume of drv: dev:ep_81 complete after 784.749 msecs [18699.704021] usb 8-1: reset full-speed USB device number 2 using uhci_hcd [18699.724030] firewire_core: rediscovered device fw0 [18700.064022] PM: resume of drv:hub dev:8-1:1.0 complete after 1237.183 msecs [18700.064035] PM: resume of drv: dev:ep_00 complete after 1237.138 msecs [18700.064040] PM: resume of drv: dev:ep_81 complete after 1237.171 msecs [18700.137172] usb 8-1.2: reset full-speed USB device number 3 using uhci_hcd [18700.258175] PM: resume of drv:usbhid dev:8-1.2:1.0 complete after 1431.162 msecs [18700.258180] PM: resume of drv: dev:ep_00 complete after 1431.108 msecs [18700.258184] PM: resume of drv: dev:ep_81 complete after 1431.141 msecs [18700.309172] usb 8-1.3: reset full-speed USB device number 4 using uhci_hcd [18700.431174] PM: resume of drv:usbhid dev:8-1.3:1.0 complete after 1604.045 msecs [18700.431179] PM: resume of drv: dev:ep_00 complete after 1603.994 msecs [18700.431183] PM: resume of drv: dev:ep_81 complete after 1604.027 msecs [18701.800387] sky2 0000:04:00.0: eth0: Link is up at 1000 Mbps, full duplex, flow control both [18705.584014] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [18705.610937] ata1.00: configured for UDMA/133 [18705.626615] PM: resume of drv:sd dev:0:0:0:0 complete after 6802.583 msecs [18705.626626] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 6802.570 msecs [18705.626635] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 6800.716 msecs [18708.228014] ata6: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [18708.256912] ata6.00: configured for UDMA/133 [18708.283925] PM: resume of drv:sd dev:5:0:0:0 complete after 9456.999 msecs [18708.283935] PM: resume of drv:scsi_device dev:5:0:0:0 complete after 9456.978 msecs [18708.836012] ata4: softreset failed (device not ready) [18709.720014] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [18709.754678] ata4.00: configured for UDMA/133 [18709.780282] PM: resume of drv:sd dev:3:0:0:0 complete after 10953.980 msecs [18709.780293] PM: resume of drv:scsi_disk dev:3:0:0:0 complete after 4153.638 msecs [18709.780301] PM: resume of drv:scsi_device dev:3:0:0:0 complete after 10953.971 msecs [18709.780483] PM: resume of devices complete after 10959.096 msecs [18709.780705] PM: resume devices took 10.960 seconds [18709.780706] ------------[ cut here ]------------ [18709.780711] WARNING: at /build/buildd/linux-3.2.0/kernel/power/suspend_test.c:53 suspend_test_finish+0x86/0x90() [18709.780713] Hardware name: P5E WS Pro [18709.780715] Component: resume devices, time: 10960 [18709.780716] Modules linked in: hidp bnep parport_pc ppdev rfcomm dm_crypt snd_hda_codec_hdmi ir_lirc_codec lirc_dev snd_hda_codec_realtek ir_mce_kbd_decoder btusb nvidia(P) ir_sony_decoder bluetooth snd_hda_intel snd_hda_codec snd_hwdep snd_pcm ir_jvc_decoder ir_rc6_decoder snd_seq_midi snd_rawmidi ir_rc5_decoder snd_seq_midi_event ir_nec_decoder mceusb snd_seq rc_core joydev snd_timer snd_seq_device snd x38_edac mac_hid serio_raw soundcore edac_core snd_page_alloc shpchp asus_atk0110 coretemp lp parport vesafb hid_logitech ff_memless firewire_ohci firewire_core crc_itu_t usbhid hid pata_marvell sky2 usb_storage [18709.780746] Pid: 18523, comm: pm-suspend Tainted: P W O 3.2.0-33-generic #52-Ubuntu [18709.780748] Call Trace: [18709.780753] [] warn_slowpath_common+0x7f/0xc0 [18709.780755] [] warn_slowpath_fmt+0x46/0x50 [18709.780758] [] suspend_test_finish+0x86/0x90 [18709.780760] [] suspend_devices_and_enter+0x10b/0x200 [18709.780762] [] enter_state+0xd1/0x110 [18709.780765] [] state_store+0xb7/0x130 [18709.780769] [] kobj_attr_store+0xf/0x30 [18709.780772] [] sysfs_write_file+0xef/0x170 [18709.780775] [] vfs_write+0xb3/0x180 [18709.780777] [] sys_write+0x4a/0x90 [18709.780780] [] system_call_fastpath+0x16/0x1b [18709.780782] ---[ end trace 6a7b6967043bb13e ]--- [18709.780833] PM: Finishing wakeup. [18709.780834] Restarting tasks ... done. [18710.643277] sky2 0000:03:00.0: eth1: enabling interface [18710.644346] ADDRCONF(NETDEV_UP): eth1: link is not ready [19857.936019] usb 2-3: reset high-speed USB device number 4 using ehci_hcd [19930.466044] sky2 0000:03:00.0: eth1: disabling interface [19933.185882] PM: Syncing filesystems ... done. [19933.273064] PM: Preparing system for mem sleep [19933.273077] Freezing user space processes ... (elapsed 0.01 seconds) done. [19933.288037] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [19933.304030] PM: Entering mem sleep [19933.304058] Suspending console(s) (use no_console_suspend to debug) [19933.304535] sd 5:0:0:0: [sdh] Synchronizing SCSI cache [19933.304655] sd 5:0:0:0: [sdh] Stopping disk [19933.304724] sd 3:0:0:0: [sdb] Synchronizing SCSI cache [19933.304826] sd 3:0:0:0: [sdb] Stopping disk [19933.304888] sd 0:0:0:0: [sda] Synchronizing SCSI cache [19933.305031] sd 0:0:0:0: [sda] Stopping disk [19933.305102] i8042 kbd 00:0d: wake-up capability enabled by ACPI [19933.305437] serial 00:0b: disabled [19933.305441] serial 00:0b: wake-up capability disabled by ACPI [19933.305632] pata_marvell 0000:02:00.0: PCI INT A disabled [19933.308095] snd_hda_intel 0000:01:00.1: PCI INT B disabled [19933.308124] ACPI handle has no context! [19933.312052] ACPI handle has no context! [19933.320164] uhci_hcd 0000:00:1d.1: PCI INT B disabled [19933.340045] uhci_hcd 0000:00:1d.2: PCI INT C disabled [19933.344057] uhci_hcd 0000:00:1d.0: PCI INT A disabled [19933.344067] uhci_hcd 0000:00:1a.2: PCI INT C disabled [19933.344094] uhci_hcd 0000:00:1a.1: PCI INT B disabled [19933.344127] uhci_hcd 0000:00:1a.0: PCI INT A disabled [19933.348013] ehci_hcd 0000:00:1a.7: PCI INT C disabled [19933.384017] ehci_hcd 0000:00:1d.7: PCI INT A disabled [19933.412090] snd_hda_intel 0000:00:1b.0: PCI INT A disabled [19933.412131] ACPI handle has no context! [19933.428010] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 119.651 msecs [19933.841867] PM: suspend of drv:sd dev:0:0:0:0 complete after 536.980 msecs [19933.841881] PM: suspend of drv:scsi dev:target0:0:0 complete after 536.943 msecs [19933.841891] PM: suspend of drv:scsi dev:host0 complete after 536.549 msecs [19934.216764] PM: suspend of drv:sd dev:5:0:0:0 complete after 912.234 msecs [19934.216777] PM: suspend of drv:scsi dev:target5:0:0 complete after 912.193 msecs [19934.216787] PM: suspend of drv:scsi dev:host5 complete after 911.511 msecs [19934.380660] PM: suspend of drv:sd dev:3:0:0:0 complete after 1075.937 msecs [19934.380672] PM: suspend of drv:scsi dev:target3:0:0 complete after 1075.902 msecs [19934.380682] PM: suspend of drv:scsi dev:host3 complete after 1075.374 msecs [19934.396013] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 1087.829 msecs [19934.396022] PM: suspend of drv: dev:pci0000:00 complete after 1087.044 msecs [19934.396032] PM: suspend of devices complete after 1091.673 msecs [19934.396035] PM: suspend devices took 1.092 seconds [19934.396328] sky2 0000:03:00.0: PME# enabled [19934.396338] pcieport 0000:00:1c.3: wake-up capability enabled by ACPI [19934.552027] PM: late suspend of drv:sky2 dev:0000:03:00.0 complete after 155.742 msecs [19934.552075] sky2 0000:04:00.0: PME# enabled [19934.552081] pcieport 0000:00:1c.2: wake-up capability enabled by ACPI [19934.708030] PM: late suspend of drv:sky2 dev:0000:04:00.0 complete after 155.997 msecs [19934.708218] ehci_hcd 0000:00:1d.7: PME# enabled [19934.708223] ehci_hcd 0000:00:1d.7: wake-up capability enabled by ACPI [19934.724061] uhci_hcd 0000:00:1d.2: wake-up capability enabled by ACPI [19934.724103] uhci_hcd 0000:00:1d.1: wake-up capability enabled by ACPI [19934.724135] uhci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI [19934.724257] ehci_hcd 0000:00:1a.7: PME# enabled [19934.724261] ehci_hcd 0000:00:1a.7: wake-up capability enabled by ACPI [19934.740048] uhci_hcd 0000:00:1a.2: wake-up capability enabled by ACPI [19934.740084] uhci_hcd 0000:00:1a.1: wake-up capability enabled by ACPI [19934.740122] uhci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI [19934.740177] PM: late suspend of devices complete after 344.139 msecs [19934.740503] ACPI: Preparing to enter system sleep state S3 [19934.740692] PM: Saving platform NVS memory [19934.741216] Disabling non-boot CPUs ... [19934.844015] CPU 1 is now offline [19934.948009] CPU 2 is now offline [19935.052010] CPU 3 is now offline [19935.052273] ACPI: Low-level resume complete [19935.052273] PM: Restoring platform NVS memory [19935.052273] Enabling non-boot CPUs ... [19935.052273] Booting Node 0 Processor 1 APIC 0x1 [19935.052273] smpboot cpu 1: start_ip = 97000 [19934.742777] Calibrating delay loop (skipped) already calibrated this CPU [19935.083740] NMI watchdog enabled, takes one hw-pmu counter. [19935.084036] CPU1 is up [19935.084115] Booting Node 0 Processor 2 APIC 0x2 [19935.084118] smpboot cpu 2: start_ip = 97000 [19934.845936] Calibrating delay loop (skipped) already calibrated this CPU [19935.115483] NMI watchdog enabled, takes one hw-pmu counter. [19935.116051] CPU2 is up [19935.116167] Booting Node 0 Processor 3 APIC 0x3 [19935.116169] smpboot cpu 3: start_ip = 97000 [19934.949740] Calibrating delay loop (skipped) already calibrated this CPU [19935.147636] NMI watchdog enabled, takes one hw-pmu counter. [19935.148050] CPU3 is up [19935.151703] ACPI: Waking up from system sleep state S3 [19935.151927] x38_edac 0000:00:00.0: restoring config space at offset 0x1 (was 0x20900006, writing 0xa0900006) [19935.151942] pcieport 0000:00:01.0: restoring config space at offset 0xf (was 0xa0100, writing 0xa010b) [19935.151950] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [19935.151980] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [19935.152038] uhci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI [19935.152064] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [19935.152083] uhci_hcd 0000:00:1a.1: wake-up capability disabled by ACPI [19935.152103] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [19935.152122] uhci_hcd 0000:00:1a.2: wake-up capability disabled by ACPI [19935.152148] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [19935.152163] ehci_hcd 0000:00:1a.7: wake-up capability disabled by ACPI [19935.152167] ehci_hcd 0000:00:1a.7: PME# disabled [19935.152190] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100006, writing 0x100002) [19935.152208] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x20100, writing 0x2010a) [19935.152217] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0xf0, writing 0x1010) [19935.152224] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100507) [19935.152252] pcieport 0000:00:1c.2: restoring config space at offset 0xf (was 0x20300, writing 0x20305) [19935.152260] pcieport 0000:00:1c.2: restoring config space at offset 0x9 (was 0x1fff1, writing 0xc051c041) [19935.152269] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [19935.152296] pcieport 0000:00:1c.3: restoring config space at offset 0xf (was 0x20400, writing 0x2040f) [19935.152304] pcieport 0000:00:1c.3: restoring config space at offset 0x9 (was 0x1fff1, writing 0xc031c021) [19935.152313] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [19935.152340] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x20100, writing 0x2010a) [19935.152348] pcieport 0000:00:1c.4: restoring config space at offset 0x9 (was 0x1fff1, writing 0xc011c001) [19935.152357] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [19935.152394] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [19935.152413] uhci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI [19935.152433] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [19935.152451] uhci_hcd 0000:00:1d.1: wake-up capability disabled by ACPI [19935.152471] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [19935.152490] uhci_hcd 0000:00:1d.2: wake-up capability disabled by ACPI [19935.152516] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [19935.152530] ehci_hcd 0000:00:1d.7: wake-up capability disabled by ACPI [19935.152534] ehci_hcd 0000:00:1d.7: PME# disabled [19935.152541] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x20000, writing 0x200ff) [19935.152550] pci 0000:00:1e.0: restoring config space at offset 0x7 (was 0x2280e0e0, writing 0xa280e0e0) [19935.152609] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [19935.152654] nvidia 0000:01:00.0: restoring config space at offset 0xc (was 0xfe600000, writing 0x0) [19935.152663] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x800008, writing 0x800000) [19935.152702] snd_hda_intel 0000:01:00.1: restoring config space at offset 0x1 (was 0x100006, writing 0x100002) [19935.152729] pci 0000:05:00.0: restoring config space at offset 0xf (was 0x0, writing 0x30000) [19935.152747] pci 0000:05:00.0: restoring config space at offset 0x6 (was 0x0, writing 0x40070705) [19935.152755] pci 0000:05:00.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810008) [19935.152762] pci 0000:05:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100104) [19935.152788] pci 0000:05:00.1: restoring config space at offset 0xf (was 0x100, writing 0x3010b) [19935.152800] pci 0000:05:00.1: restoring config space at offset 0x9 (was 0x1fff1, writing 0xfbf1fbf1) [19935.152808] pci 0000:05:00.1: restoring config space at offset 0x6 (was 0x0, writing 0x40060605) [19935.152815] pci 0000:05:00.1: restoring config space at offset 0x4 (was 0x4, writing 0xfeaffc04) [19935.152820] pci 0000:05:00.1: restoring config space at offset 0x3 (was 0x810000, writing 0x810008) [19935.152827] pci 0000:05:00.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100106) [19935.152862] sky2 0000:04:00.0: restoring config space at offset 0xf (was 0x100, writing 0x105) [19935.152869] sky2 0000:04:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfe9c0000) [19935.152881] sky2 0000:04:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xd801) [19935.152887] sky2 0000:04:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfe9fc004) [19935.152891] sky2 0000:04:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [19935.152897] sky2 0000:04:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [19935.152949] sky2 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10f) [19935.152956] sky2 0000:03:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfe8c0000) [19935.152968] sky2 0000:03:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xc801) [19935.152974] sky2 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfe8fc004) [19935.152978] sky2 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [19935.152984] sky2 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [19935.153033] pata_marvell 0000:02:00.0: restoring config space at offset 0xf (was 0x80100, writing 0x8010b) [19935.153040] pata_marvell 0000:02:00.0: restoring config space at offset 0xc (was 0x80001, writing 0xfe780000) [19935.153048] pata_marvell 0000:02:00.0: restoring config space at offset 0x9 (was 0x800000, writing 0xfe7ffc00) [19935.153052] pata_marvell 0000:02:00.0: restoring config space at offset 0x8 (was 0x900001, writing 0xb401) [19935.153057] pata_marvell 0000:02:00.0: restoring config space at offset 0x7 (was 0x8801, writing 0xb481) [19935.153062] pata_marvell 0000:02:00.0: restoring config space at offset 0x6 (was 0x8201, writing 0xb801) [19935.153066] pata_marvell 0000:02:00.0: restoring config space at offset 0x5 (was 0x8041, writing 0xb881) [19935.153071] pata_marvell 0000:02:00.0: restoring config space at offset 0x4 (was 0x8001, writing 0xbc01) [19935.153076] pata_marvell 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [19935.153101] firewire_ohci 0000:08:03.0: restoring config space at offset 0xf (was 0x20000100, writing 0x2000010f) [19935.153115] firewire_ohci 0000:08:03.0: restoring config space at offset 0x5 (was 0x1, writing 0xec01) [19935.153119] firewire_ohci 0000:08:03.0: restoring config space at offset 0x4 (was 0x0, writing 0xfebff800) [19935.153123] firewire_ohci 0000:08:03.0: restoring config space at offset 0x3 (was 0x0, writing 0x4008) [19935.153129] firewire_ohci 0000:08:03.0: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100017) [19935.153338] PM: early resume of devices complete after 1.449 msecs [19935.153394] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [19935.153399] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [19935.153419] usb usb3: root hub lost power or was reset [19935.153429] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [19935.153433] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [19935.153451] usb usb4: root hub lost power or was reset [19935.153460] uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [19935.153464] uhci_hcd 0000:00:1a.2: setting latency timer to 64 [19935.153475] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [19935.153482] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [19935.153495] usb usb5: root hub lost power or was reset [19935.153504] snd_hda_intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [19935.153512] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [19935.153518] snd_hda_intel 0000:00:1b.0: setting latency timer to 64 [19935.153522] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [19935.153528] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [19935.153531] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [19935.153537] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [19935.153544] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [19935.153567] usb usb6: root hub lost power or was reset [19935.153570] usb usb7: root hub lost power or was reset [19935.153585] usb usb8: root hub lost power or was reset [19935.153591] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [19935.153598] pci 0000:00:1e.0: setting latency timer to 64 [19935.153604] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [19935.153612] ahci 0000:00:1f.2: setting latency timer to 64 [19935.153619] snd_hda_intel 0000:00:1b.0: irq 304 for MSI/MSI-X [19935.153648] snd_hda_intel 0000:01:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [19935.153658] snd_hda_intel 0000:01:00.1: setting latency timer to 64 [19935.153686] pci 0000:05:00.0: setting latency timer to 64 [19935.153694] pci 0000:05:00.1: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [19935.153697] pcieport 0000:00:1c.2: wake-up capability disabled by ACPI [19935.153702] pci 0000:05:00.1: setting latency timer to 64 [19935.153713] pcieport 0000:00:1c.3: wake-up capability disabled by ACPI [19935.153715] sky2 0000:04:00.0: PME# disabled [19935.153722] sky2 0000:03:00.0: PME# disabled [19935.153733] pata_marvell 0000:02:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [19935.153742] pata_marvell 0000:02:00.0: setting latency timer to 64 [19935.153862] BAR5:00:04 01:7F 02:22 03:C8 04:00 05:00 06:00 07:00 08:00 09:00 0A:00 0B:00 0C:1F 0D:00 0E:00 0F:00 [19935.154867] serial 00:0b: activated [19935.154877] i8042 kbd 00:0d: wake-up capability disabled by ACPI [19935.158123] sd 0:0:0:0: [sda] Starting disk [19935.158610] sd 3:0:0:0: [sdb] Starting disk [19935.159240] sd 5:0:0:0: [sdh] Starting disk [19935.276044] PM: resume of drv:hub dev:4-0:1.0 complete after 118.070 msecs [19935.276058] PM: resume of drv: dev:ep_00 complete after 118.065 msecs [19935.276070] PM: resume of drv: dev:ep_00 complete after 118.064 msecs [19935.276079] PM: resume of drv: dev:ep_81 complete after 118.086 msecs [19935.276082] PM: resume of drv: dev:ep_00 complete after 118.071 msecs [19935.276086] PM: resume of drv:hub dev:5-0:1.0 complete after 118.100 msecs [19935.276090] PM: resume of drv:hub dev:6-0:1.0 complete after 118.089 msecs [19935.276093] PM: resume of drv:hub dev:3-0:1.0 complete after 118.166 msecs [19935.276097] PM: resume of drv: dev:ep_00 complete after 118.148 msecs [19935.276100] PM: resume of drv: dev:ep_81 complete after 118.110 msecs [19935.276103] PM: resume of drv: dev:ep_81 complete after 118.093 msecs [19935.276113] PM: resume of drv: dev:ep_81 complete after 118.176 msecs [19935.332499] PM: resume of drv:usb dev:2-2:1.0 complete after 174.364 msecs [19935.332503] PM: resume of drv: dev:ep_00 complete after 174.186 msecs [19935.332509] PM: resume of drv: dev:ep_05 complete after 174.360 msecs [19935.332512] PM: resume of drv: dev:ep_83 complete after 174.373 msecs [19935.332517] PM: resume of drv: dev:ep_86 complete after 174.318 msecs [19935.332521] PM: resume of drv:usb-storage dev:2-2:1.1 complete after 174.292 msecs [19935.332530] PM: resume of drv: dev:ep_01 complete after 174.272 msecs [19935.332535] PM: resume of drv: dev:ep_82 complete after 174.247 msecs [19935.332538] PM: resume of drv:scsi dev:host6 complete after 174.190 msecs [19935.332546] PM: resume of drv:scsi_host dev:host6 complete after 174.172 msecs [19935.332551] PM: resume of drv:scsi dev:target6:0:0 complete after 173.779 msecs [19935.332566] PM: resume of drv:sd dev:6:0:0:0 complete after 173.765 msecs [19935.332570] PM: resume of drv:sd dev:6:0:0:2 complete after 173.647 msecs [19935.332574] PM: resume of drv:scsi_device dev:6:0:0:0 complete after 173.743 msecs [19935.332577] PM: resume of drv:scsi_device dev:6:0:0:2 complete after 173.628 msecs [19935.332582] PM: resume of drv:sd dev:6:0:0:1 complete after 173.721 msecs [19935.332588] PM: resume of drv:scsi_device dev:6:0:0:1 complete after 173.698 msecs [19935.332591] PM: resume of drv:sd dev:6:0:0:3 complete after 173.612 msecs [19935.332598] PM: resume of drv:scsi_device dev:6:0:0:3 complete after 173.593 msecs [19935.380030] PM: resume of drv: dev:ep_00 complete after 221.976 msecs [19935.380037] PM: resume of drv:hub dev:7-0:1.0 complete after 221.999 msecs [19935.380045] PM: resume of drv: dev:ep_00 complete after 221.948 msecs [19935.380056] PM: resume of drv: dev:ep_81 complete after 222.013 msecs [19935.380062] PM: resume of drv:hub dev:8-0:1.0 complete after 222.001 msecs [19935.380071] PM: resume of drv: dev:ep_81 complete after 221.980 msecs [19935.472030] ata2: SATA link down (SStatus 0 SControl 300) [19935.480031] ata3: SATA link down (SStatus 0 SControl 300) [19935.492026] usb 8-1: reset full-speed USB device number 2 using uhci_hcd [19935.496026] ata5: SATA link down (SStatus 0 SControl 300) [19935.557885] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 404.282 msecs [19935.557900] firewire_core: skipped bus generations, destroying all nodes [19935.588487] ata7.00: configured for UDMA/66 [19935.856030] PM: resume of drv:hub dev:8-1:1.0 complete after 696.879 msecs [19935.856041] PM: resume of drv: dev:ep_00 complete after 696.832 msecs [19935.856044] PM: resume of drv: dev:ep_81 complete after 696.865 msecs [19936.056030] firewire_core: rediscovered device fw0 [19936.092017] usb 7-2: reset low-speed USB device number 2 using uhci_hcd [19936.603044] PM: resume of drv:usbhid dev:7-2:1.0 complete after 1444.357 msecs [19936.603050] PM: resume of drv: dev:ep_00 complete after 1444.305 msecs [19936.603054] PM: resume of drv: dev:ep_81 complete after 1444.338 msecs [19936.657082] usb 8-1.2: reset full-speed USB device number 3 using uhci_hcd [19936.778087] PM: resume of drv: dev:ep_00 complete after 1618.702 msecs [19936.778097] PM: resume of drv:usbhid dev:8-1.2:1.0 complete after 1618.761 msecs [19936.778106] PM: resume of drv: dev:ep_81 complete after 1618.751 msecs [19936.829080] usb 8-1.3: reset full-speed USB device number 4 using uhci_hcd [19936.950086] PM: resume of drv: dev:ep_00 complete after 1790.585 msecs [19936.950097] PM: resume of drv:usbhid dev:8-1.3:1.0 complete after 1790.644 msecs [19936.950106] PM: resume of drv: dev:ep_81 complete after 1790.634 msecs [19938.115428] sky2 0000:04:00.0: eth0: Link is up at 1000 Mbps, full duplex, flow control both [19941.932013] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [19941.959909] ata1.00: configured for UDMA/133 [19941.975557] PM: resume of drv:sd dev:0:0:0:0 complete after 6817.435 msecs [19941.975570] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 6817.444 msecs [19941.975581] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 6817.675 msecs [19944.756013] ata6: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [19944.779496] ata6.00: configured for UDMA/133 [19944.806494] PM: resume of drv:sd dev:5:0:0:0 complete after 9647.255 msecs [19944.806504] PM: resume of drv:scsi_device dev:5:0:0:0 complete after 9647.235 msecs [19945.196009] ata4: softreset failed (device not ready) [19945.800038] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [19945.832408] ata4.00: configured for UDMA/133 [19945.857988] PM: resume of drv:sd dev:3:0:0:0 complete after 10699.379 msecs [19945.858010] PM: resume of drv:scsi_device dev:3:0:0:0 complete after 10699.382 msecs [19945.858020] PM: resume of drv:scsi_disk dev:3:0:0:0 complete after 3882.413 msecs [19945.858195] PM: resume of devices complete after 10704.825 msecs [19945.858423] PM: resume devices took 10.704 seconds [19945.858424] ------------[ cut here ]------------ [19945.858430] WARNING: at /build/buildd/linux-3.2.0/kernel/power/suspend_test.c:53 suspend_test_finish+0x86/0x90() [19945.858432] Hardware name: P5E WS Pro [19945.858433] Component: resume devices, time: 10704 [19945.858434] Modules linked in: hidp bnep parport_pc ppdev rfcomm dm_crypt snd_hda_codec_hdmi ir_lirc_codec lirc_dev snd_hda_codec_realtek ir_mce_kbd_decoder btusb nvidia(P) ir_sony_decoder bluetooth snd_hda_intel snd_hda_codec snd_hwdep snd_pcm ir_jvc_decoder ir_rc6_decoder snd_seq_midi snd_rawmidi ir_rc5_decoder snd_seq_midi_event ir_nec_decoder mceusb snd_seq rc_core joydev snd_timer snd_seq_device snd x38_edac mac_hid serio_raw soundcore edac_core snd_page_alloc shpchp asus_atk0110 coretemp lp parport vesafb hid_logitech ff_memless firewire_ohci firewire_core crc_itu_t usbhid hid pata_marvell sky2 usb_storage [19945.858465] Pid: 20321, comm: pm-suspend Tainted: P W O 3.2.0-33-generic #52-Ubuntu [19945.858467] Call Trace: [19945.858471] [] warn_slowpath_common+0x7f/0xc0 [19945.858474] [] warn_slowpath_fmt+0x46/0x50 [19945.858476] [] suspend_test_finish+0x86/0x90 [19945.858478] [] suspend_devices_and_enter+0x10b/0x200 [19945.858481] [] enter_state+0xd1/0x110 [19945.858483] [] state_store+0xb7/0x130 [19945.858487] [] kobj_attr_store+0xf/0x30 [19945.858490] [] sysfs_write_file+0xef/0x170 [19945.858493] [] vfs_write+0xb3/0x180 [19945.858495] [] sys_write+0x4a/0x90 [19945.858498] [] system_call_fastpath+0x16/0x1b [19945.858500] ---[ end trace 6a7b6967043bb13f ]--- [19945.858551] PM: Finishing wakeup. [19945.858552] Restarting tasks ... done. [19946.553858] sky2 0000:03:00.0: eth1: enabling interface [19946.554958] ADDRCONF(NETDEV_UP): eth1: link is not ready [20757.736603] EXT4-fs (sdg1): warning: maximal mount count reached, running e2fsck is recommended [20757.761434] EXT4-fs (sdg1): mounted filesystem with ordered data mode. Opts: (null) [27370.588035] type=1400 audit(1352411426.804:30): apparmor="STATUS" operation="profile_replace" name="/sbin/dhclient" pid=28208 comm="apparmor_parser" [27370.588149] type=1400 audit(1352411426.804:31): apparmor="STATUS" operation="profile_replace" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=28208 comm="apparmor_parser" [27370.588222] type=1400 audit(1352411426.804:32): apparmor="STATUS" operation="profile_replace" name="/usr/lib/connman/scripts/dhclient-script" pid=28208 comm="apparmor_parser" [34408.372463] EXT2-fs (dm-0): warning: mounting unchecked fs, running e2fsck is recommended [36320.307290] sky2 0000:03:00.0: eth1: disabling interface [36322.259475] init: anacron main process (2449) killed by TERM signal [36323.672098] PM: Syncing filesystems ... done. [36323.763431] PM: Preparing system for mem sleep [36323.763444] Freezing user space processes ... (elapsed 0.01 seconds) done. [36323.780039] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [36323.796030] PM: Entering mem sleep [36323.796065] Suspending console(s) (use no_console_suspend to debug) [36323.796531] sd 5:0:0:0: [sdh] Synchronizing SCSI cache [36323.796683] sd 3:0:0:0: [sdb] Synchronizing SCSI cache [36323.796796] sd 3:0:0:0: [sdb] Stopping disk [36323.796862] sd 0:0:0:0: [sda] Synchronizing SCSI cache [36323.797004] sd 0:0:0:0: [sda] Stopping disk [36323.797063] i8042 kbd 00:0d: wake-up capability enabled by ACPI [36323.797391] serial 00:0b: disabled [36323.797395] serial 00:0b: wake-up capability disabled by ACPI [36323.804046] ACPI handle has no context! [36323.812136] uhci_hcd 0000:00:1d.1: PCI INT B disabled [36323.812176] pata_marvell 0000:02:00.0: PCI INT A disabled [36323.832041] uhci_hcd 0000:00:1d.2: PCI INT C disabled [36323.836045] uhci_hcd 0000:00:1d.0: PCI INT A disabled [36323.836049] uhci_hcd 0000:00:1a.2: PCI INT C disabled [36323.836084] uhci_hcd 0000:00:1a.0: PCI INT A disabled [36323.836096] uhci_hcd 0000:00:1a.1: PCI INT B disabled [36323.840012] ehci_hcd 0000:00:1a.7: PCI INT C disabled [36323.876017] ehci_hcd 0000:00:1d.7: PCI INT A disabled [36323.908093] snd_hda_intel 0000:00:1b.0: PCI INT A disabled [36323.908124] ACPI handle has no context! [36323.924011] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 119.008 msecs [36323.972899] sd 5:0:0:0: [sdh] Stopping disk [36324.248022] snd_hda_intel 0000:01:00.1: PCI INT B disabled [36324.248051] ACPI handle has no context! [36324.264010] PM: suspend of drv:snd_hda_intel dev:0000:01:00.1 complete after 463.935 msecs [36324.264024] PM: suspend of drv:pcieport dev:0000:00:01.0 complete after 458.382 msecs [36324.336779] PM: suspend of drv:sd dev:0:0:0:0 complete after 539.935 msecs [36324.336792] PM: suspend of drv:scsi dev:target0:0:0 complete after 539.925 msecs [36324.336805] PM: suspend of drv:scsi dev:host0 complete after 539.527 msecs [36324.442361] PM: suspend of drv:sd dev:5:0:0:0 complete after 645.840 msecs [36324.442373] PM: suspend of drv:scsi dev:target5:0:0 complete after 645.821 msecs [36324.442383] PM: suspend of drv:scsi dev:host5 complete after 645.171 msecs [36324.882033] PM: suspend of drv:sd dev:3:0:0:0 complete after 1085.353 msecs [36324.882045] PM: suspend of drv:scsi dev:target3:0:0 complete after 1085.306 msecs [36324.882055] PM: suspend of drv:scsi dev:host3 complete after 1084.810 msecs [36324.896018] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 1095.830 msecs [36324.896026] PM: suspend of drv: dev:pci0000:00 complete after 1090.359 msecs [36324.896038] PM: suspend of devices complete after 1099.673 msecs [36324.896041] PM: suspend devices took 1.100 seconds [36324.896334] sky2 0000:03:00.0: PME# enabled [36324.896346] pcieport 0000:00:1c.3: wake-up capability enabled by ACPI [36325.052030] PM: late suspend of drv:sky2 dev:0000:03:00.0 complete after 155.738 msecs [36325.052078] sky2 0000:04:00.0: PME# enabled [36325.052084] pcieport 0000:00:1c.2: wake-up capability enabled by ACPI [36325.208029] PM: late suspend of drv:sky2 dev:0000:04:00.0 complete after 155.994 msecs [36325.208219] ehci_hcd 0000:00:1d.7: PME# enabled [36325.208223] ehci_hcd 0000:00:1d.7: wake-up capability enabled by ACPI [36325.224061] uhci_hcd 0000:00:1d.2: wake-up capability enabled by ACPI [36325.224104] uhci_hcd 0000:00:1d.1: wake-up capability enabled by ACPI [36325.224136] uhci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI [36325.224260] ehci_hcd 0000:00:1a.7: PME# enabled [36325.224264] ehci_hcd 0000:00:1a.7: wake-up capability enabled by ACPI [36325.240046] uhci_hcd 0000:00:1a.2: wake-up capability enabled by ACPI [36325.240083] uhci_hcd 0000:00:1a.1: wake-up capability enabled by ACPI [36325.240122] uhci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI [36325.240177] PM: late suspend of devices complete after 344.134 msecs [36325.240503] ACPI: Preparing to enter system sleep state S3 [36325.240692] PM: Saving platform NVS memory [36325.241219] Disabling non-boot CPUs ... [36325.344045] CPU 1 is now offline [36325.448009] CPU 2 is now offline [36325.552008] CPU 3 is now offline [36325.552312] ACPI: Low-level resume complete [36325.552312] PM: Restoring platform NVS memory [36325.552312] Enabling non-boot CPUs ... [36325.552312] Booting Node 0 Processor 1 APIC 0x1 [36325.552312] smpboot cpu 1: start_ip = 97000 [36325.242718] Calibrating delay loop (skipped) already calibrated this CPU [36325.583784] NMI watchdog enabled, takes one hw-pmu counter. [36325.584040] CPU1 is up [36325.584122] Booting Node 0 Processor 2 APIC 0x2 [36325.584124] smpboot cpu 2: start_ip = 97000 [36325.345897] Calibrating delay loop (skipped) already calibrated this CPU [36325.615494] NMI watchdog enabled, takes one hw-pmu counter. [36325.616048] CPU2 is up [36325.616166] Booting Node 0 Processor 3 APIC 0x3 [36325.616168] smpboot cpu 3: start_ip = 97000 [36325.449633] Calibrating delay loop (skipped) already calibrated this CPU [36325.647643] NMI watchdog enabled, takes one hw-pmu counter. [36325.648051] CPU3 is up [36325.651707] ACPI: Waking up from system sleep state S3 [36325.651930] x38_edac 0000:00:00.0: restoring config space at offset 0x1 (was 0x20900006, writing 0x80900006) [36325.651944] pcieport 0000:00:01.0: restoring config space at offset 0xf (was 0xa0100, writing 0xa010b) [36325.651952] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [36325.651983] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [36325.652044] uhci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI [36325.652064] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [36325.652084] uhci_hcd 0000:00:1a.1: wake-up capability disabled by ACPI [36325.652103] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [36325.652122] uhci_hcd 0000:00:1a.2: wake-up capability disabled by ACPI [36325.652149] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [36325.652163] ehci_hcd 0000:00:1a.7: wake-up capability disabled by ACPI [36325.652167] ehci_hcd 0000:00:1a.7: PME# disabled [36325.652190] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100006, writing 0x100002) [36325.652208] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x20100, writing 0x2010a) [36325.652217] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0xf0, writing 0x1010) [36325.652224] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100507) [36325.652252] pcieport 0000:00:1c.2: restoring config space at offset 0xf (was 0x20300, writing 0x20305) [36325.652260] pcieport 0000:00:1c.2: restoring config space at offset 0x9 (was 0x1fff1, writing 0xc051c041) [36325.652268] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [36325.652296] pcieport 0000:00:1c.3: restoring config space at offset 0xf (was 0x20400, writing 0x2040f) [36325.652303] pcieport 0000:00:1c.3: restoring config space at offset 0x9 (was 0x1fff1, writing 0xc031c021) [36325.652312] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [36325.652340] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x20100, writing 0x2010a) [36325.652348] pcieport 0000:00:1c.4: restoring config space at offset 0x9 (was 0x1fff1, writing 0xc011c001) [36325.652356] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [36325.652393] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [36325.652412] uhci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI [36325.652432] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [36325.652450] uhci_hcd 0000:00:1d.1: wake-up capability disabled by ACPI [36325.652470] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [36325.652489] uhci_hcd 0000:00:1d.2: wake-up capability disabled by ACPI [36325.652515] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [36325.652529] ehci_hcd 0000:00:1d.7: wake-up capability disabled by ACPI [36325.652533] ehci_hcd 0000:00:1d.7: PME# disabled [36325.652540] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x20000, writing 0x200ff) [36325.652549] pci 0000:00:1e.0: restoring config space at offset 0x7 (was 0x2280e0e0, writing 0x8280e0e0) [36325.652608] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [36325.652652] nvidia 0000:01:00.0: restoring config space at offset 0xc (was 0xfe600000, writing 0x0) [36325.652661] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x800008, writing 0x800000) [36325.652701] snd_hda_intel 0000:01:00.1: restoring config space at offset 0x1 (was 0x100006, writing 0x100002) [36325.652728] pci 0000:05:00.0: restoring config space at offset 0xf (was 0x0, writing 0x30000) [36325.652745] pci 0000:05:00.0: restoring config space at offset 0x6 (was 0x0, writing 0x40070705) [36325.652753] pci 0000:05:00.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810008) [36325.652760] pci 0000:05:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100104) [36325.652786] pci 0000:05:00.1: restoring config space at offset 0xf (was 0x100, writing 0x3010b) [36325.652798] pci 0000:05:00.1: restoring config space at offset 0x9 (was 0x1fff1, writing 0xfbf1fbf1) [36325.652807] pci 0000:05:00.1: restoring config space at offset 0x6 (was 0x0, writing 0x40060605) [36325.652813] pci 0000:05:00.1: restoring config space at offset 0x4 (was 0x4, writing 0xfeaffc04) [36325.652819] pci 0000:05:00.1: restoring config space at offset 0x3 (was 0x810000, writing 0x810008) [36325.652825] pci 0000:05:00.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100106) [36325.652860] sky2 0000:04:00.0: restoring config space at offset 0xf (was 0x100, writing 0x105) [36325.652868] sky2 0000:04:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfe9c0000) [36325.652879] sky2 0000:04:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xd801) [36325.652885] sky2 0000:04:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfe9fc004) [36325.652890] sky2 0000:04:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [36325.652896] sky2 0000:04:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [36325.652947] sky2 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10f) [36325.652955] sky2 0000:03:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfe8c0000) [36325.652966] sky2 0000:03:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xc801) [36325.652972] sky2 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfe8fc004) [36325.652977] sky2 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [36325.652983] sky2 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [36325.653031] pata_marvell 0000:02:00.0: restoring config space at offset 0xf (was 0x80100, writing 0x8010b) [36325.653038] pata_marvell 0000:02:00.0: restoring config space at offset 0xc (was 0x80001, writing 0xfe780000) [36325.653046] pata_marvell 0000:02:00.0: restoring config space at offset 0x9 (was 0x800000, writing 0xfe7ffc00) [36325.653050] pata_marvell 0000:02:00.0: restoring config space at offset 0x8 (was 0x900001, writing 0xb401) [36325.653055] pata_marvell 0000:02:00.0: restoring config space at offset 0x7 (was 0x8801, writing 0xb481) [36325.653060] pata_marvell 0000:02:00.0: restoring config space at offset 0x6 (was 0x8201, writing 0xb801) [36325.653064] pata_marvell 0000:02:00.0: restoring config space at offset 0x5 (was 0x8041, writing 0xb881) [36325.653069] pata_marvell 0000:02:00.0: restoring config space at offset 0x4 (was 0x8001, writing 0xbc01) [36325.653074] pata_marvell 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [36325.653099] firewire_ohci 0000:08:03.0: restoring config space at offset 0xf (was 0x20000100, writing 0x2000010f) [36325.653112] firewire_ohci 0000:08:03.0: restoring config space at offset 0x5 (was 0x1, writing 0xec01) [36325.653116] firewire_ohci 0000:08:03.0: restoring config space at offset 0x4 (was 0x0, writing 0xfebff800) [36325.653121] firewire_ohci 0000:08:03.0: restoring config space at offset 0x3 (was 0x0, writing 0x4008) [36325.653126] firewire_ohci 0000:08:03.0: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100017) [36325.653336] PM: early resume of devices complete after 1.444 msecs [36325.653438] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [36325.653451] snd_hda_intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [36325.653457] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [36325.653460] snd_hda_intel 0000:00:1b.0: setting latency timer to 64 [36325.653465] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [36325.653475] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [36325.653485] usb usb3: root hub lost power or was reset [36325.653503] usb usb4: root hub lost power or was reset [36325.653525] snd_hda_intel 0000:00:1b.0: irq 304 for MSI/MSI-X [36325.653536] uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [36325.653543] uhci_hcd 0000:00:1a.2: setting latency timer to 64 [36325.653565] usb usb5: root hub lost power or was reset [36325.653579] snd_hda_intel 0000:01:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [36325.653583] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [36325.653588] snd_hda_intel 0000:01:00.1: setting latency timer to 64 [36325.653592] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [36325.653624] pci 0000:05:00.1: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [36325.653632] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [36325.653638] pci 0000:05:00.1: setting latency timer to 64 [36325.653643] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [36325.653668] pata_marvell 0000:02:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [36325.653675] usb usb6: root hub lost power or was reset [36325.653677] pata_marvell 0000:02:00.0: setting latency timer to 64 [36325.653743] BAR5:00:04 01:7F 02:22 03:C8 04:00 05:00 06:00 07:00 08:00 09:00 0A:00 0B:00 0C:1F 0D:00 0E:00 0F:00 [36325.656075] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [36325.656079] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [36325.656097] usb usb7: root hub lost power or was reset [36325.656124] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [36325.656128] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [36325.656146] usb usb8: root hub lost power or was reset [36325.656166] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [36325.656171] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [36325.656207] pci 0000:00:1e.0: setting latency timer to 64 [36325.656232] pci 0000:05:00.0: setting latency timer to 64 [36325.656260] pcieport 0000:00:1c.2: wake-up capability disabled by ACPI [36325.656266] ahci 0000:00:1f.2: setting latency timer to 64 [36325.656268] sky2 0000:04:00.0: PME# disabled [36325.656418] sd 0:0:0:0: [sda] Starting disk [36325.656463] sd 5:0:0:0: [sdh] Starting disk [36325.656513] sd 3:0:0:0: [sdb] Starting disk [36325.656859] serial 00:0b: activated [36325.656869] i8042 kbd 00:0d: wake-up capability disabled by ACPI [36325.659220] pcieport 0000:00:1c.3: wake-up capability disabled by ACPI [36325.659226] sky2 0000:03:00.0: PME# disabled [36325.720110] firewire_core: skipped bus generations, destroying all nodes [36325.756630] PM: resume of drv:hub dev:2-1:1.0 complete after 102.407 msecs [36325.756633] PM: resume of drv: dev:ep_00 complete after 102.380 msecs [36325.756640] PM: resume of drv: dev:ep_81 complete after 102.402 msecs [36325.760128] PM: resume of drv:usb-storage dev:2-3:1.0 complete after 105.621 msecs [36325.760131] PM: resume of drv: dev:ep_00 complete after 105.547 msecs [36325.760139] PM: resume of drv: dev:ep_02 complete after 105.574 msecs [36325.760142] PM: resume of drv:scsi dev:host7 complete after 105.619 msecs [36325.760150] PM: resume of drv: dev:ep_81 complete after 105.599 msecs [36325.760153] PM: resume of drv:scsi_host dev:host7 complete after 105.618 msecs [36325.760159] PM: resume of drv:scsi dev:target7:0:0 complete after 105.316 msecs [36325.760173] PM: resume of drv:sd dev:7:0:0:0 complete after 105.313 msecs [36325.760180] PM: resume of drv:scsi_device dev:7:0:0:0 complete after 105.305 msecs [36325.772032] PM: resume of drv:hub dev:6-0:1.0 complete after 117.995 msecs [36325.772038] PM: resume of drv: dev:ep_00 complete after 117.973 msecs [36325.772045] PM: resume of drv: dev:ep_81 complete after 117.994 msecs [36325.772049] PM: resume of drv: dev:ep_00 complete after 118.161 msecs [36325.772054] PM: resume of drv:hub dev:4-0:1.0 complete after 118.170 msecs [36325.772059] PM: resume of drv:hub dev:3-0:1.0 complete after 118.196 msecs [36325.772083] PM: resume of drv: dev:ep_81 complete after 118.174 msecs [36325.772097] PM: resume of drv: dev:ep_00 complete after 118.190 msecs [36325.772107] PM: resume of drv: dev:ep_81 complete after 118.232 msecs [36325.776034] PM: resume of drv:hub dev:5-0:1.0 complete after 122.118 msecs [36325.776041] PM: resume of drv: dev:ep_81 complete after 122.122 msecs [36325.776052] PM: resume of drv: dev:ep_00 complete after 122.117 msecs [36325.836506] PM: resume of drv:usb dev:2-2:1.0 complete after 182.174 msecs [36325.836510] PM: resume of drv:usb-storage dev:2-2:1.1 complete after 182.112 msecs [36325.836517] PM: resume of drv: dev:ep_83 complete after 182.170 msecs [36325.836521] PM: resume of drv: dev:ep_05 complete after 182.157 msecs [36325.836524] PM: resume of drv: dev:ep_86 complete after 182.143 msecs [36325.836526] PM: resume of drv: dev:ep_01 complete after 182.117 msecs [36325.836533] PM: resume of drv: dev:ep_82 complete after 182.107 msecs [36325.836536] PM: resume of drv: dev:ep_00 complete after 182.092 msecs [36325.836539] PM: resume of drv:scsi dev:host6 complete after 182.083 msecs [36325.836547] PM: resume of drv:scsi_host dev:host6 complete after 182.075 msecs [36325.836552] PM: resume of drv:scsi dev:target6:0:0 complete after 181.849 msecs [36325.836568] PM: resume of drv:sd dev:6:0:0:0 complete after 181.850 msecs [36325.836571] PM: resume of drv:sd dev:6:0:0:2 complete after 181.787 msecs [36325.836575] PM: resume of drv:scsi_device dev:6:0:0:0 complete after 181.839 msecs [36325.836577] PM: resume of drv:scsi_device dev:6:0:0:2 complete after 181.782 msecs [36325.836582] PM: resume of drv:sd dev:6:0:0:1 complete after 181.831 msecs [36325.836588] PM: resume of drv:scsi_device dev:6:0:0:1 complete after 181.822 msecs [36325.836591] PM: resume of drv:sd dev:6:0:0:3 complete after 181.778 msecs [36325.836598] PM: resume of drv:scsi_device dev:6:0:0:3 complete after 181.771 msecs [36325.880021] PM: resume of drv:hub dev:8-0:1.0 complete after 225.863 msecs [36325.880026] PM: resume of drv:hub dev:7-0:1.0 complete after 225.933 msecs [36325.880031] PM: resume of drv: dev:ep_00 complete after 225.841 msecs [36325.880040] PM: resume of drv: dev:ep_00 complete after 225.906 msecs [36325.880043] PM: resume of drv: dev:ep_81 complete after 225.924 msecs [36325.880053] PM: resume of drv: dev:ep_81 complete after 225.880 msecs [36325.984024] ata3: SATA link down (SStatus 0 SControl 300) [36325.992024] ata5: SATA link down (SStatus 0 SControl 300) [36325.992045] usb 8-1: reset full-speed USB device number 2 using uhci_hcd [36326.004024] ata2: SATA link down (SStatus 0 SControl 300) [36326.057679] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 404.111 msecs [36326.104486] ata7.00: configured for UDMA/66 [36326.220029] firewire_core: rediscovered device fw0 [36326.356021] PM: resume of drv:hub dev:8-1:1.0 complete after 701.113 msecs [36326.356028] PM: resume of drv: dev:ep_00 complete after 701.091 msecs [36326.356033] PM: resume of drv: dev:ep_81 complete after 701.108 msecs [36326.388018] usb 7-2: reset low-speed USB device number 2 using uhci_hcd [36326.695139] PM: resume of drv:usbhid dev:7-2:1.0 complete after 1040.479 msecs [36326.695144] PM: resume of drv: dev:ep_00 complete after 1040.450 msecs [36326.695151] PM: resume of drv: dev:ep_81 complete after 1040.477 msecs [36326.749185] usb 8-1.2: reset full-speed USB device number 3 using uhci_hcd [36326.870187] PM: resume of drv:usbhid dev:8-1.2:1.0 complete after 1215.171 msecs [36326.870195] PM: resume of drv: dev:ep_00 complete after 1215.148 msecs [36326.870202] PM: resume of drv: dev:ep_81 complete after 1215.171 msecs [36326.921186] usb 8-1.3: reset full-speed USB device number 4 using uhci_hcd [36327.043192] PM: resume of drv:usbhid dev:8-1.3:1.0 complete after 1388.114 msecs [36327.043197] PM: resume of drv: dev:ep_00 complete after 1388.083 msecs [36327.043204] PM: resume of drv: dev:ep_81 complete after 1388.111 msecs [36328.655566] sky2 0000:04:00.0: eth0: Link is up at 1000 Mbps, full duplex, flow control both [36332.420016] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [36332.451093] ata1.00: configured for UDMA/133 [36332.466774] PM: resume of drv:sd dev:0:0:0:0 complete after 6812.488 msecs [36332.466786] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 6806.872 msecs [36332.466794] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 6812.493 msecs [36335.244014] ata6: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [36335.273305] ata6.00: configured for UDMA/133 [36335.300320] PM: resume of drv:sd dev:5:0:0:0 complete after 9645.350 msecs [36335.300330] PM: resume of drv:scsi_device dev:5:0:0:0 complete after 9645.344 msecs [36335.700009] ata4: softreset failed (device not ready) [36336.472014] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [36336.505825] ata4.00: configured for UDMA/133 [36336.531403] PM: resume of drv:sd dev:3:0:0:0 complete after 10876.790 msecs [36336.531414] PM: resume of drv:scsi_disk dev:3:0:0:0 complete after 4064.610 msecs [36336.531587] PM: resume of drv:scsi_device dev:3:0:0:0 complete after 10876.960 msecs [36336.531595] PM: resume of devices complete after 10878.226 msecs [36336.531817] PM: resume devices took 10.876 seconds [36336.531818] ------------[ cut here ]------------ [36336.531823] WARNING: at /build/buildd/linux-3.2.0/kernel/power/suspend_test.c:53 suspend_test_finish+0x86/0x90() [36336.531825] Hardware name: P5E WS Pro [36336.531826] Component: resume devices, time: 10876 [36336.531827] Modules linked in: ext2 hidp bnep parport_pc ppdev rfcomm dm_crypt snd_hda_codec_hdmi ir_lirc_codec lirc_dev snd_hda_codec_realtek ir_mce_kbd_decoder btusb nvidia(P) ir_sony_decoder bluetooth snd_hda_intel snd_hda_codec snd_hwdep snd_pcm ir_jvc_decoder ir_rc6_decoder snd_seq_midi snd_rawmidi ir_rc5_decoder snd_seq_midi_event ir_nec_decoder mceusb snd_seq rc_core joydev snd_timer snd_seq_device snd x38_edac mac_hid serio_raw soundcore edac_core snd_page_alloc shpchp asus_atk0110 coretemp lp parport vesafb hid_logitech ff_memless firewire_ohci firewire_core crc_itu_t usbhid hid pata_marvell sky2 usb_storage [36336.531859] Pid: 2367, comm: pm-suspend Tainted: P W O 3.2.0-33-generic #52-Ubuntu [36336.531860] Call Trace: [36336.531865] [] warn_slowpath_common+0x7f/0xc0 [36336.531868] [] warn_slowpath_fmt+0x46/0x50 [36336.531870] [] suspend_test_finish+0x86/0x90 [36336.531873] [] suspend_devices_and_enter+0x10b/0x200 [36336.531875] [] enter_state+0xd1/0x110 [36336.531877] [] state_store+0xb7/0x130 [36336.531881] [] kobj_attr_store+0xf/0x30 [36336.531884] [] sysfs_write_file+0xef/0x170 [36336.531887] [] vfs_write+0xb3/0x180 [36336.531889] [] sys_write+0x4a/0x90 [36336.531893] [] system_call_fastpath+0x16/0x1b [36336.531894] ---[ end trace 6a7b6967043bb140 ]--- [36336.531946] PM: Finishing wakeup. [36336.531948] Restarting tasks ... done. [36337.281699] sky2 0000:03:00.0: eth1: enabling interface [36337.282726] ADDRCONF(NETDEV_UP): eth1: link is not ready [36364.385130] type=1400 audit(1352552436.100:33): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/3544/cmdline" pid=3539 comm="dbus-daemon" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36364.386564] type=1400 audit(1352552436.100:34): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/3545/cmdline" pid=3539 comm="dbus-daemon" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36364.387642] type=1400 audit(1352552436.100:35): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/3546/cmdline" pid=3539 comm="dbus-daemon" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36364.392797] type=1400 audit(1352552436.108:36): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/3549/cmdline" pid=3539 comm="dbus-daemon" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36364.394274] type=1400 audit(1352552436.108:37): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/3548/cmdline" pid=3539 comm="dbus-daemon" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36364.495748] type=1400 audit(1352552436.208:38): apparmor="DENIED" operation="mount" parent=1 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/tmp/guest-02Dnk6/.gvfs/" pid=3568 comm="gvfs-fuse-daemo" fstype="fuse.gvfs-fuse-daemon" srcname="gvfs-fuse-daemon" flags="rw, nosuid, nodev" [36367.697052] type=1400 audit(1352552439.412:39): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/home/kristin/.face" pid=3664 comm="unity-panel-ser" requested_mask="r" denied_mask="r" fsuid=119 ouid=1001 [36369.437337] type=1400 audit(1352552441.152:40): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/3454/stat" pid=3645 comm="bamfdaemon" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36369.437385] type=1400 audit(1352552441.152:41): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/1670/stat" pid=3645 comm="bamfdaemon" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36382.609365] type=1400 audit(1352552454.324:42): apparmor="DENIED" operation="open" parent=3935 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/sys/kernel/pid_max" pid=3936 comm="ps" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36382.609929] type=1400 audit(1352552454.324:43): apparmor="DENIED" operation="open" parent=3935 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/1/stat" pid=3936 comm="ps" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36382.609944] type=1400 audit(1352552454.324:44): apparmor="DENIED" operation="open" parent=3935 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/2/stat" pid=3936 comm="ps" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36382.609959] type=1400 audit(1352552454.324:45): apparmor="DENIED" operation="open" parent=3935 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/3/stat" pid=3936 comm="ps" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36382.609973] type=1400 audit(1352552454.324:46): apparmor="DENIED" operation="open" parent=3935 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/6/stat" pid=3936 comm="ps" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36382.609987] type=1400 audit(1352552454.324:47): apparmor="DENIED" operation="open" parent=3935 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/7/stat" pid=3936 comm="ps" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36382.610001] type=1400 audit(1352552454.324:48): apparmor="DENIED" operation="open" parent=3935 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/21/stat" pid=3936 comm="ps" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36382.610016] type=1400 audit(1352552454.324:49): apparmor="DENIED" operation="open" parent=3935 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/22/stat" pid=3936 comm="ps" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36382.610030] type=1400 audit(1352552454.324:50): apparmor="DENIED" operation="open" parent=3935 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/23/stat" pid=3936 comm="ps" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36382.610045] type=1400 audit(1352552454.324:51): apparmor="DENIED" operation="open" parent=3935 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/24/stat" pid=3936 comm="ps" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36444.852886] audit_printk_skb: 891 callbacks suppressed [36444.852889] type=1400 audit(1352552516.568:349): apparmor="DENIED" operation="file_mmap" parent=1 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/anon_hugepage//deleted" pid=3981 comm="firefox" requested_mask="r" denied_mask="r" fsuid=119 ouid=119 [36444.852920] type=1400 audit(1352552516.568:350): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/3787/coredump_filter" pid=3981 comm="firefox" requested_mask="w" denied_mask="w" fsuid=119 ouid=119 [36445.572457] type=1400 audit(1352552517.288:351): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/3787/net/if_inet6" pid=3981 comm="firefox" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36445.572490] type=1400 audit(1352552517.288:352): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/3787/net/ipv6_route" pid=3981 comm="firefox" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36445.572510] type=1400 audit(1352552517.288:353): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/3787/net/if_inet6" pid=3981 comm="firefox" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36445.671592] type=1400 audit(1352552517.384:354): apparmor="DENIED" operation="file_mmap" parent=3981 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/anon_hugepage//deleted" pid=4004 comm="java" requested_mask="r" denied_mask="r" fsuid=119 ouid=119 [36445.671619] type=1400 audit(1352552517.384:355): apparmor="DENIED" operation="open" parent=3981 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/3998/coredump_filter" pid=4004 comm="java" requested_mask="w" denied_mask="w" fsuid=119 ouid=119 [36445.741773] type=1400 audit(1352552517.456:356): apparmor="DENIED" operation="open" parent=3981 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/3998/net/if_inet6" pid=4004 comm="java" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36445.741804] type=1400 audit(1352552517.456:357): apparmor="DENIED" operation="open" parent=3981 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/3998/net/ipv6_route" pid=4004 comm="java" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36445.741824] type=1400 audit(1352552517.456:358): apparmor="DENIED" operation="open" parent=3981 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/3998/net/if_inet6" pid=4004 comm="java" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36456.668801] audit_printk_skb: 27 callbacks suppressed [36456.668804] type=1400 audit(1352552528.384:368): apparmor="DENIED" operation="open" parent=4066 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/scsi/scsi" pid=4144 comm="java" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [36475.510071] type=1400 audit(1352552547.224:369): apparmor="DENIED" operation="open" parent=4066 profile="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" name="/proc/scsi/scsi" pid=4153 comm="java" requested_mask="r" denied_mask="r" fsuid=119 ouid=0 [37857.904018] usb 2-3: reset high-speed USB device number 4 using ehci_hcd [54509.271888] SGI XFS with ACLs, security attributes, realtime, large block/inode numbers, no debug enabled [54509.273400] SGI XFS Quota Management subsystem [54509.296802] JFS: nTxBlock = 8192, nTxLock = 65536 [54509.342834] NTFS driver 2.1.30 [Flags: R/O MODULE]. [54509.450371] QNX4 filesystem 0.2.3 registered. [54509.509540] Btrfs loaded [61529.676350] EXT4-fs (sdg1): warning: maximal mount count reached, running e2fsck is recommended [61529.700286] EXT4-fs (sdg1): mounted filesystem with ordered data mode. Opts: (null) [82494.495401] sky2 0000:03:00.0: eth1: disabling interface [82497.155101] init: anacron main process (11081) killed by TERM signal [82499.175369] PM: Syncing filesystems ... done. [82499.244042] PM: Preparing system for mem sleep [82499.244054] Freezing user space processes ... (elapsed 0.01 seconds) done. [82499.260043] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [82499.276030] PM: Entering mem sleep [82499.276069] Suspending console(s) (use no_console_suspend to debug) [82499.276552] sd 5:0:0:0: [sdh] Synchronizing SCSI cache [82499.276721] sd 5:0:0:0: [sdh] Stopping disk [82499.276757] sd 3:0:0:0: [sdb] Synchronizing SCSI cache [82499.276850] sd 3:0:0:0: [sdb] Stopping disk [82499.276936] sd 0:0:0:0: [sda] Synchronizing SCSI cache [82499.277082] sd 0:0:0:0: [sda] Stopping disk [82499.277113] i8042 kbd 00:0d: wake-up capability enabled by ACPI [82499.277454] serial 00:0b: disabled [82499.277458] serial 00:0b: wake-up capability disabled by ACPI [82499.277599] pata_marvell 0000:02:00.0: PCI INT A disabled [82499.284039] ACPI handle has no context! [82499.292051] uhci_hcd 0000:00:1d.1: PCI INT B disabled [82499.312083] uhci_hcd 0000:00:1d.2: PCI INT C disabled [82499.316127] uhci_hcd 0000:00:1a.0: PCI INT A disabled [82499.316140] uhci_hcd 0000:00:1a.1: PCI INT B disabled [82499.316154] uhci_hcd 0000:00:1d.0: PCI INT A disabled [82499.316195] uhci_hcd 0000:00:1a.2: PCI INT C disabled [82499.320015] ehci_hcd 0000:00:1a.7: PCI INT C disabled [82499.356013] ehci_hcd 0000:00:1d.7: PCI INT A disabled [82499.380081] snd_hda_intel 0000:00:1b.0: PCI INT A disabled [82499.380123] ACPI handle has no context! [82499.396016] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 118.019 msecs [82499.724028] snd_hda_intel 0000:01:00.1: PCI INT B disabled [82499.724058] ACPI handle has no context! [82499.740010] PM: suspend of drv:snd_hda_intel dev:0000:01:00.1 complete after 462.269 msecs [82499.740024] PM: suspend of drv:pcieport dev:0000:00:01.0 complete after 461.423 msecs [82499.749436] PM: suspend of drv:sd dev:5:0:0:0 complete after 472.888 msecs [82499.749454] PM: suspend of drv:scsi dev:target5:0:0 complete after 472.846 msecs [82499.749469] PM: suspend of drv:scsi dev:host5 complete after 472.129 msecs [82499.794179] PM: suspend of drv:sd dev:0:0:0:0 complete after 517.244 msecs [82499.794191] PM: suspend of drv:scsi dev:target0:0:0 complete after 517.213 msecs [82499.794200] PM: suspend of drv:scsi dev:host0 complete after 516.795 msecs [82500.377156] PM: suspend of drv:sd dev:3:0:0:0 complete after 1100.400 msecs [82500.377173] PM: suspend of drv:scsi dev:target3:0:0 complete after 1100.370 msecs [82500.377189] PM: suspend of drv:scsi dev:host3 complete after 1099.817 msecs [82500.392013] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 1114.210 msecs [82500.392023] PM: suspend of drv: dev:pci0000:00 complete after 1113.399 msecs [82500.392028] PM: suspend of devices complete after 1115.657 msecs [82500.392031] PM: suspend devices took 1.116 seconds [82500.392312] sky2 0000:03:00.0: PME# enabled [82500.392322] pcieport 0000:00:1c.3: wake-up capability enabled by ACPI [82500.548032] PM: late suspend of drv:sky2 dev:0000:03:00.0 complete after 155.762 msecs [82500.548079] sky2 0000:04:00.0: PME# enabled [82500.548085] pcieport 0000:00:1c.2: wake-up capability enabled by ACPI [82500.704030] PM: late suspend of drv:sky2 dev:0000:04:00.0 complete after 155.993 msecs [82500.704217] ehci_hcd 0000:00:1d.7: PME# enabled [82500.704221] ehci_hcd 0000:00:1d.7: wake-up capability enabled by ACPI [82500.720061] uhci_hcd 0000:00:1d.2: wake-up capability enabled by ACPI [82500.720105] uhci_hcd 0000:00:1d.1: wake-up capability enabled by ACPI [82500.720136] uhci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI [82500.720258] ehci_hcd 0000:00:1a.7: PME# enabled [82500.720262] ehci_hcd 0000:00:1a.7: wake-up capability enabled by ACPI [82500.736047] uhci_hcd 0000:00:1a.2: wake-up capability enabled by ACPI [82500.736084] uhci_hcd 0000:00:1a.1: wake-up capability enabled by ACPI [82500.736122] uhci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI [82500.736176] PM: late suspend of devices complete after 344.143 msecs [82500.736502] ACPI: Preparing to enter system sleep state S3 [82500.736692] PM: Saving platform NVS memory [82500.737245] Disabling non-boot CPUs ... [82500.840012] CPU 1 is now offline [82500.944010] CPU 2 is now offline [82501.048007] CPU 3 is now offline [82501.048290] ACPI: Low-level resume complete [82501.048290] PM: Restoring platform NVS memory [82501.048290] Enabling non-boot CPUs ... [82501.048290] Booting Node 0 Processor 1 APIC 0x1 [82501.048290] smpboot cpu 1: start_ip = 97000 [82500.738751] Calibrating delay loop (skipped) already calibrated this CPU [82501.079810] NMI watchdog enabled, takes one hw-pmu counter. [82501.084036] CPU1 is up [82501.084116] Booting Node 0 Processor 2 APIC 0x2 [82501.084117] smpboot cpu 2: start_ip = 97000 [82500.841907] Calibrating delay loop (skipped) already calibrated this CPU [82501.115488] NMI watchdog enabled, takes one hw-pmu counter. [82501.116052] CPU2 is up [82501.116164] Booting Node 0 Processor 3 APIC 0x3 [82501.116165] smpboot cpu 3: start_ip = 97000 [82500.945594] Calibrating delay loop (skipped) already calibrated this CPU [82501.147640] NMI watchdog enabled, takes one hw-pmu counter. [82501.148049] CPU3 is up [82501.151708] ACPI: Waking up from system sleep state S3 [82501.151940] x38_edac 0000:00:00.0: restoring config space at offset 0x1 (was 0x20900006, writing 0xa0900006) [82501.151954] pcieport 0000:00:01.0: restoring config space at offset 0xf (was 0xa0100, writing 0xa010b) [82501.151962] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [82501.152021] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [82501.152050] uhci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI [82501.152070] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [82501.152089] uhci_hcd 0000:00:1a.1: wake-up capability disabled by ACPI [82501.152109] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [82501.152128] uhci_hcd 0000:00:1a.2: wake-up capability disabled by ACPI [82501.152154] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [82501.152168] ehci_hcd 0000:00:1a.7: wake-up capability disabled by ACPI [82501.152173] ehci_hcd 0000:00:1a.7: PME# disabled [82501.152195] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100006, writing 0x100002) [82501.152213] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x20100, writing 0x2010a) [82501.152222] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0xf0, writing 0x1010) [82501.152230] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100507) [82501.152258] pcieport 0000:00:1c.2: restoring config space at offset 0xf (was 0x20300, writing 0x20305) [82501.152265] pcieport 0000:00:1c.2: restoring config space at offset 0x9 (was 0x1fff1, writing 0xc051c041) [82501.152274] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [82501.152301] pcieport 0000:00:1c.3: restoring config space at offset 0xf (was 0x20400, writing 0x2040f) [82501.152309] pcieport 0000:00:1c.3: restoring config space at offset 0x9 (was 0x1fff1, writing 0xc031c021) [82501.152318] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [82501.152345] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x20100, writing 0x2010a) [82501.152353] pcieport 0000:00:1c.4: restoring config space at offset 0x9 (was 0x1fff1, writing 0xc011c001) [82501.152362] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [82501.152398] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [82501.152417] uhci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI [82501.152437] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [82501.152456] uhci_hcd 0000:00:1d.1: wake-up capability disabled by ACPI [82501.152475] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [82501.152494] uhci_hcd 0000:00:1d.2: wake-up capability disabled by ACPI [82501.152520] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [82501.152534] ehci_hcd 0000:00:1d.7: wake-up capability disabled by ACPI [82501.152538] ehci_hcd 0000:00:1d.7: PME# disabled [82501.152545] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x20000, writing 0x200ff) [82501.152553] pci 0000:00:1e.0: restoring config space at offset 0x7 (was 0x2280e0e0, writing 0xa280e0e0) [82501.152612] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [82501.152657] nvidia 0000:01:00.0: restoring config space at offset 0xc (was 0xfe600000, writing 0x0) [82501.152666] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x800008, writing 0x800000) [82501.152705] snd_hda_intel 0000:01:00.1: restoring config space at offset 0x1 (was 0x100006, writing 0x100002) [82501.152732] pci 0000:05:00.0: restoring config space at offset 0xf (was 0x0, writing 0x30000) [82501.152750] pci 0000:05:00.0: restoring config space at offset 0x6 (was 0x0, writing 0x40070705) [82501.152758] pci 0000:05:00.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810008) [82501.152764] pci 0000:05:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100104) [82501.152790] pci 0000:05:00.1: restoring config space at offset 0xf (was 0x100, writing 0x3010b) [82501.152803] pci 0000:05:00.1: restoring config space at offset 0x9 (was 0x1fff1, writing 0xfbf1fbf1) [82501.152811] pci 0000:05:00.1: restoring config space at offset 0x6 (was 0x0, writing 0x40060605) [82501.152818] pci 0000:05:00.1: restoring config space at offset 0x4 (was 0x4, writing 0xfeaffc04) [82501.152823] pci 0000:05:00.1: restoring config space at offset 0x3 (was 0x810000, writing 0x810008) [82501.152830] pci 0000:05:00.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100106) [82501.152865] sky2 0000:04:00.0: restoring config space at offset 0xf (was 0x100, writing 0x105) [82501.152872] sky2 0000:04:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfe9c0000) [82501.152883] sky2 0000:04:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xd801) [82501.152889] sky2 0000:04:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfe9fc004) [82501.152894] sky2 0000:04:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [82501.152900] sky2 0000:04:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [82501.152951] sky2 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10f) [82501.152959] sky2 0000:03:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfe8c0000) [82501.152970] sky2 0000:03:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xc801) [82501.152976] sky2 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfe8fc004) [82501.152981] sky2 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [82501.152987] sky2 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [82501.153035] pata_marvell 0000:02:00.0: restoring config space at offset 0xf (was 0x80100, writing 0x8010b) [82501.153042] pata_marvell 0000:02:00.0: restoring config space at offset 0xc (was 0x80001, writing 0xfe780000) [82501.153049] pata_marvell 0000:02:00.0: restoring config space at offset 0x9 (was 0x800000, writing 0xfe7ffc00) [82501.153054] pata_marvell 0000:02:00.0: restoring config space at offset 0x8 (was 0x900001, writing 0xb401) [82501.153059] pata_marvell 0000:02:00.0: restoring config space at offset 0x7 (was 0x8801, writing 0xb481) [82501.153063] pata_marvell 0000:02:00.0: restoring config space at offset 0x6 (was 0x8201, writing 0xb801) [82501.153068] pata_marvell 0000:02:00.0: restoring config space at offset 0x5 (was 0x8041, writing 0xb881) [82501.153073] pata_marvell 0000:02:00.0: restoring config space at offset 0x4 (was 0x8001, writing 0xbc01) [82501.153077] pata_marvell 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [82501.153102] firewire_ohci 0000:08:03.0: restoring config space at offset 0xf (was 0x20000100, writing 0x2000010f) [82501.153116] firewire_ohci 0000:08:03.0: restoring config space at offset 0x5 (was 0x1, writing 0xec01) [82501.153120] firewire_ohci 0000:08:03.0: restoring config space at offset 0x4 (was 0x0, writing 0xfebff800) [82501.153124] firewire_ohci 0000:08:03.0: restoring config space at offset 0x3 (was 0x0, writing 0x4008) [82501.153130] firewire_ohci 0000:08:03.0: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100017) [82501.153340] PM: early resume of devices complete after 1.438 msecs [82501.153416] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [82501.153424] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [82501.153432] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [82501.153440] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [82501.153450] usb usb3: root hub lost power or was reset [82501.153464] uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [82501.153470] usb usb4: root hub lost power or was reset [82501.153473] uhci_hcd 0000:00:1a.2: setting latency timer to 64 [82501.153486] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [82501.153495] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [82501.153498] usb usb5: root hub lost power or was reset [82501.153509] snd_hda_intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [82501.153513] snd_hda_intel 0000:00:1b.0: setting latency timer to 64 [82501.153539] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [82501.153544] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [82501.153554] snd_hda_intel 0000:00:1b.0: irq 304 for MSI/MSI-X [82501.153568] usb usb6: root hub lost power or was reset [82501.153579] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [82501.153584] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [82501.153594] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [82501.153601] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [82501.153607] usb usb7: root hub lost power or was reset [82501.153620] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [82501.153625] usb usb8: root hub lost power or was reset [82501.153627] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [82501.153636] pci 0000:00:1e.0: setting latency timer to 64 [82501.153646] ahci 0000:00:1f.2: setting latency timer to 64 [82501.153701] snd_hda_intel 0000:01:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [82501.153706] snd_hda_intel 0000:01:00.1: setting latency timer to 64 [82501.153747] pci 0000:05:00.0: setting latency timer to 64 [82501.153757] pci 0000:05:00.1: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [82501.153765] pci 0000:05:00.1: setting latency timer to 64 [82501.153776] pcieport 0000:00:1c.2: wake-up capability disabled by ACPI [82501.153783] sky2 0000:04:00.0: PME# disabled [82501.153839] pcieport 0000:00:1c.3: wake-up capability disabled by ACPI [82501.153855] sky2 0000:03:00.0: PME# disabled [82501.154416] serial 00:0b: activated [82501.154425] i8042 kbd 00:0d: wake-up capability disabled by ACPI [82501.156762] pata_marvell 0000:02:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [82501.156767] pata_marvell 0000:02:00.0: setting latency timer to 64 [82501.157437] BAR5:00:04 01:7F 02:22 03:C8 04:00 05:00 06:00 07:00 08:00 09:00 0A:00 0B:00 0C:1F 0D:00 0E:00 0F:00 [82501.157619] sd 0:0:0:0: [sda] Starting disk [82501.157895] sd 3:0:0:0: [sdb] Starting disk [82501.158295] sd 5:0:0:0: [sdh] Starting disk [82501.216106] firewire_core: skipped bus generations, destroying all nodes [82501.260161] PM: resume of drv:usb-storage dev:2-3:1.0 complete after 102.364 msecs [82501.260165] PM: resume of drv: dev:ep_00 complete after 102.288 msecs [82501.260177] PM: resume of drv: dev:ep_81 complete after 102.334 msecs [82501.260180] PM: resume of drv:scsi dev:host7 complete after 102.366 msecs [82501.260182] PM: resume of drv: dev:ep_02 complete after 102.323 msecs [82501.260192] PM: resume of drv:scsi_host dev:host7 complete after 102.365 msecs [82501.260196] PM: resume of drv:scsi dev:target7:0:0 complete after 102.019 msecs [82501.260209] PM: resume of drv:sd dev:7:0:0:0 complete after 102.020 msecs [82501.260216] PM: resume of drv:scsi_device dev:7:0:0:0 complete after 102.012 msecs [82501.260657] PM: resume of drv:hub dev:2-1:1.0 complete after 103.051 msecs [82501.260664] PM: resume of drv: dev:ep_00 complete after 103.052 msecs [82501.260667] PM: resume of drv: dev:ep_81 complete after 103.056 msecs [82501.276035] PM: resume of drv:hub dev:4-0:1.0 complete after 118.508 msecs [82501.276041] PM: resume of drv: dev:ep_00 complete after 118.503 msecs [82501.276063] PM: resume of drv:hub dev:3-0:1.0 complete after 118.565 msecs [82501.276071] PM: resume of drv: dev:ep_00 complete after 118.550 msecs [82501.276082] PM: resume of drv: dev:ep_81 complete after 118.534 msecs [82501.276086] PM: resume of drv: dev:ep_00 complete after 118.500 msecs [82501.276089] PM: resume of drv: dev:ep_81 complete after 118.586 msecs [82501.276099] PM: resume of drv:hub dev:6-0:1.0 complete after 118.520 msecs [82501.276107] PM: resume of drv: dev:ep_81 complete after 118.532 msecs [82501.280043] PM: resume of drv:hub dev:5-0:1.0 complete after 122.484 msecs [82501.280052] PM: resume of drv: dev:ep_81 complete after 122.489 msecs [82501.280063] PM: resume of drv: dev:ep_00 complete after 122.487 msecs [82501.340410] PM: resume of drv:usb dev:2-2:1.0 complete after 182.766 msecs [82501.340414] PM: resume of drv:usb-storage dev:2-2:1.1 complete after 182.741 msecs [82501.340421] PM: resume of drv: dev:ep_83 complete after 182.775 msecs [82501.340424] PM: resume of drv: dev:ep_86 complete after 182.764 msecs [82501.340434] PM: resume of drv: dev:ep_01 complete after 182.724 msecs [82501.340438] PM: resume of drv: dev:ep_05 complete after 182.777 msecs [82501.340447] PM: resume of drv: dev:ep_82 complete after 182.730 msecs [82501.340457] PM: resume of drv: dev:ep_00 complete after 182.714 msecs [82501.340460] PM: resume of drv:scsi dev:host6 complete after 182.710 msecs [82501.340468] PM: resume of drv:scsi_host dev:host6 complete after 182.704 msecs [82501.340473] PM: resume of drv:scsi dev:target6:0:0 complete after 182.441 msecs [82501.340487] PM: resume of drv:sd dev:6:0:0:0 complete after 182.439 msecs [82501.340490] PM: resume of drv:sd dev:6:0:0:3 complete after 182.345 msecs [82501.340495] PM: resume of drv:scsi_device dev:6:0:0:0 complete after 182.430 msecs [82501.340497] PM: resume of drv:scsi_device dev:6:0:0:3 complete after 182.339 msecs [82501.340502] PM: resume of drv:sd dev:6:0:0:1 complete after 182.422 msecs [82501.340508] PM: resume of drv:scsi_device dev:6:0:0:1 complete after 182.413 msecs [82501.340512] PM: resume of drv:sd dev:6:0:0:2 complete after 182.399 msecs [82501.340519] PM: resume of drv:scsi_device dev:6:0:0:2 complete after 182.393 msecs [82501.380032] PM: resume of drv:hub dev:7-0:1.0 complete after 222.449 msecs [82501.380038] PM: resume of drv: dev:ep_00 complete after 222.449 msecs [82501.380042] PM: resume of drv:hub dev:8-0:1.0 complete after 222.448 msecs [82501.380047] PM: resume of drv: dev:ep_00 complete after 222.447 msecs [82501.380066] PM: resume of drv: dev:ep_81 complete after 222.469 msecs [82501.380079] PM: resume of drv: dev:ep_81 complete after 222.493 msecs [82501.480027] ata2: SATA link down (SStatus 0 SControl 300) [82501.488022] ata5: SATA link down (SStatus 0 SControl 300) [82501.508031] ata3: SATA link down (SStatus 0 SControl 300) [82501.557596] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 403.941 msecs [82501.592483] ata7.00: configured for UDMA/66 [82501.640024] usb 7-2: reset low-speed USB device number 2 using uhci_hcd [82501.716030] firewire_core: rediscovered device fw0 [82501.947601] PM: resume of drv:usbhid dev:7-2:1.0 complete after 789.616 msecs [82501.947610] PM: resume of drv: dev:ep_00 complete after 789.593 msecs [82501.947615] PM: resume of drv: dev:ep_81 complete after 789.615 msecs [82502.040017] usb 8-1: reset full-speed USB device number 2 using uhci_hcd [82502.404026] PM: resume of drv:hub dev:8-1:1.0 complete after 1245.789 msecs [82502.404039] PM: resume of drv: dev:ep_81 complete after 1245.787 msecs [82502.404043] PM: resume of drv: dev:ep_00 complete after 1245.773 msecs [82502.477615] usb 8-1.3: reset full-speed USB device number 4 using uhci_hcd [82502.598615] PM: resume of drv:usbhid dev:8-1.3:1.0 complete after 1440.189 msecs [82502.598624] PM: resume of drv: dev:ep_00 complete after 1440.168 msecs [82502.598629] PM: resume of drv: dev:ep_81 complete after 1440.189 msecs [82502.649614] usb 8-1.2: reset full-speed USB device number 3 using uhci_hcd [82502.771614] PM: resume of drv:usbhid dev:8-1.2:1.0 complete after 1613.251 msecs [82502.771622] PM: resume of drv: dev:ep_00 complete after 1613.229 msecs [82502.771628] PM: resume of drv: dev:ep_81 complete after 1613.250 msecs [82504.086029] sky2 0000:04:00.0: eth0: Link is up at 1000 Mbps, full duplex, flow control both [82507.920015] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [82507.950401] ata1.00: configured for UDMA/133 [82507.966057] PM: resume of drv:sd dev:0:0:0:0 complete after 6808.439 msecs [82507.966068] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 6808.527 msecs [82507.966073] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 6808.450 msecs [82510.512014] ata6: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [82510.537315] ata6.00: configured for UDMA/133 [82510.564337] PM: resume of drv:sd dev:5:0:0:0 complete after 9406.053 msecs [82510.564346] PM: resume of drv:scsi_device dev:5:0:0:0 complete after 9406.042 msecs [82511.192009] ata4: softreset failed (device not ready) [82511.852021] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [82511.886312] ata4.00: configured for UDMA/133 [82511.911877] PM: resume of drv:sd dev:3:0:0:0 complete after 10753.983 msecs [82511.911894] PM: resume of drv:scsi_device dev:3:0:0:0 complete after 10753.976 msecs [82511.911899] PM: resume of drv:scsi_disk dev:3:0:0:0 complete after 3945.807 msecs [82511.912073] PM: resume of devices complete after 10758.700 msecs [82511.912294] PM: resume devices took 10.760 seconds [82511.912296] ------------[ cut here ]------------ [82511.912301] WARNING: at /build/buildd/linux-3.2.0/kernel/power/suspend_test.c:53 suspend_test_finish+0x86/0x90() [82511.912303] Hardware name: P5E WS Pro [82511.912304] Component: resume devices, time: 10760 [82511.912305] Modules linked in: btrfs zlib_deflate libcrc32c ufs qnx4 hfsplus hfs minix ntfs vfat msdos fat jfs xfs reiserfs ext2 hidp bnep parport_pc ppdev rfcomm dm_crypt snd_hda_codec_hdmi ir_lirc_codec lirc_dev snd_hda_codec_realtek ir_mce_kbd_decoder btusb nvidia(P) ir_sony_decoder bluetooth snd_hda_intel snd_hda_codec snd_hwdep snd_pcm ir_jvc_decoder ir_rc6_decoder snd_seq_midi snd_rawmidi ir_rc5_decoder snd_seq_midi_event ir_nec_decoder mceusb snd_seq rc_core joydev snd_timer snd_seq_device snd x38_edac mac_hid serio_raw soundcore edac_core snd_page_alloc shpchp asus_atk0110 coretemp lp parport vesafb hid_logitech ff_memless firewire_ohci firewire_core crc_itu_t usbhid hid pata_marvell sky2 usb_storage [82511.912343] Pid: 10998, comm: pm-suspend Tainted: P W O 3.2.0-33-generic #52-Ubuntu [82511.912345] Call Trace: [82511.912350] [] warn_slowpath_common+0x7f/0xc0 [82511.912352] [] warn_slowpath_fmt+0x46/0x50 [82511.912355] [] suspend_test_finish+0x86/0x90 [82511.912357] [] suspend_devices_and_enter+0x10b/0x200 [82511.912360] [] enter_state+0xd1/0x110 [82511.912362] [] state_store+0xb7/0x130 [82511.912366] [] kobj_attr_store+0xf/0x30 [82511.912369] [] sysfs_write_file+0xef/0x170 [82511.912372] [] vfs_write+0xb3/0x180 [82511.912374] [] sys_write+0x4a/0x90 [82511.912378] [] system_call_fastpath+0x16/0x1b [82511.912379] ---[ end trace 6a7b6967043bb141 ]--- [82511.912432] PM: Finishing wakeup. [82511.912433] Restarting tasks ... done. [82512.790975] sky2 0000:03:00.0: eth1: enabling interface [82512.792124] ADDRCONF(NETDEV_UP): eth1: link is not ready [82857.920022] usb 2-3: reset high-speed USB device number 4 using ehci_hcd [88180.196181] sd 6:0:0:2: [sde] 15547392 512-byte logical blocks: (7.96 GB/7.41 GiB) [88180.197676] sd 6:0:0:2: [sde] No Caching mode page present [88180.197680] sd 6:0:0:2: [sde] Assuming drive cache: write through [88180.200427] sd 6:0:0:2: [sde] No Caching mode page present [88180.200430] sd 6:0:0:2: [sde] Assuming drive cache: write through [88180.201563] sde: sde1