[ 5.336100] input: HDA NVidia HDMI/DP,pcm=9 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input16 [ 5.336189] input: HDA NVidia HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input17 [ 5.336254] input: HDA NVidia HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input18 [ 5.336318] input: HDA NVidia HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input19 [ 5.336706] vgaarb: device changed decodes: PCI:0000:01:00.0,olddecodes=io+mem,decodes=none:owns=io+mem [ 5.336848] NVRM: loading NVIDIA UNIX x86_64 Kernel Module 304.51 Tue Sep 18 17:16:56 PDT 2012 [ 5.406353] init: cryptdisks-enable main process (1690) terminated with status 1 [ 5.408682] init: udev-fallback-graphics main process (1689) terminated with status 1 [ 5.411233] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready [ 5.411551] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready [ 5.421048] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 5.421327] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 5.434148] init: lightdm main process (1708) killed by TERM signal [ 5.442448] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX [ 5.443361] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 6.662564] NVRM: GPU at 0000:01:00: GPU-65cb4a24-cffc-8f57-1cc5-86ca88300717 [ 15.422310] vboxdrv: Found 4 processor cores. [ 15.422475] vboxdrv: fAsync=0 offMin=0x258 offMax=0x17f0 [ 15.422547] vboxdrv: TSC mode is 'synchronous', kernel timer mode is 'normal'. [ 15.422548] vboxdrv: Successfully loaded version 4.2.4 (interface 0x001a0004). [ 15.633827] vboxpci: IOMMU not found (not registered) [ 15.982088] init: plymouth-stop pre-start process (2436) terminated with status 1 [ 50.478757] do_trap: 33 callbacks suppressed [ 50.478761] mpegaudioparse0[3380] trap divide error ip:7fbbc189965f sp:7fbba3ff95c8 error:0 in libgstflump3dec.so[7fbbc1889000+30000] [ 62.577672] EXT4-fs (dm-0): mounted filesystem with ordered data mode. Opts: (null) [ 6572.523796] type=1701 audit(1354421336.356:22): auid=4294967295 uid=1000 gid=1000 ses=4294967295 pid=13621 comm="chromium-browse" reason="seccomp" sig=0 syscall=39 compat=0 ip=0x7f1f67a7bed9 code=0x50000 [ 6573.211496] type=1701 audit(1354421337.044:23): auid=4294967295 uid=1000 gid=1000 ses=4294967295 pid=13666 comm="chromium-browse" reason="seccomp" sig=0 syscall=39 compat=0 ip=0x7f9d7cf9fed9 code=0x50000 [12008.109219] PM: Syncing filesystems ... done. [12008.121710] PM: Preparing system for mem sleep [12008.613549] Freezing user space processes ... (elapsed 0.01 seconds) done. [12008.628166] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [12008.644146] PM: Entering mem sleep [12008.644171] Suspending console(s) (use no_console_suspend to debug) [12008.645001] sd 2:0:0:0: [sdc] Synchronizing SCSI cache [12008.645041] sd 1:0:0:0: [sdb] Synchronizing SCSI cache [12008.645084] sd 0:0:0:0: [sda] Synchronizing SCSI cache [12008.645151] sd 1:0:0:0: [sdb] Stopping disk [12008.645153] sd 2:0:0:0: [sdc] Stopping disk [12008.645154] sd 0:0:0:0: [sda] Stopping disk [12008.686108] i8042 aux 00:09: wake-up capability disabled by ACPI [12008.686139] i8042 kbd 00:08: wake-up capability enabled by ACPI [12008.686279] serial 00:06: disabled [12008.686451] pci 0000:00:1e.0: wake-up capability enabled by ACPI [12008.788106] ACPI handle has no context! [12008.804009] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 117.349 msecs [12009.132037] ACPI handle has no context! [12009.148009] PM: suspend of drv:snd_hda_intel dev:0000:01:00.1 complete after 461.589 msecs [12009.148018] PM: suspend of drv:pcieport dev:0000:00:01.0 complete after 461.279 msecs [12009.237603] PM: suspend of drv:sd dev:2:0:0:0 complete after 592.604 msecs [12009.237612] PM: suspend of drv:scsi dev:target2:0:0 complete after 592.609 msecs [12009.237622] PM: suspend of drv:scsi dev:host2 complete after 551.747 msecs [12009.237653] PM: suspend of drv: dev:ata3 complete after 551.773 msecs [12009.252008] PM: suspend of drv:ata_piix dev:0000:00:1f.5 complete after 565.577 msecs [12009.252013] PM: suspend of drv: dev:pci0000:00 complete after 565.253 msecs [12009.252017] PM: suspend of devices complete after 607.560 msecs [12009.252019] PM: suspend devices took 0.608 seconds [12009.252200] PM: late suspend of devices complete after 0.180 msecs [12009.252407] ehci_hcd 0000:00:1d.7: wake-up capability enabled by ACPI [12009.268043] uhci_hcd 0000:00:1d.3: wake-up capability enabled by ACPI [12009.268073] uhci_hcd 0000:00:1d.2: wake-up capability enabled by ACPI [12009.268102] uhci_hcd 0000:00:1d.1: wake-up capability enabled by ACPI [12009.268132] uhci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI [12009.268160] ehci_hcd 0000:00:1a.7: wake-up capability enabled by ACPI [12009.284037] uhci_hcd 0000:00:1a.1: wake-up capability enabled by ACPI [12009.284066] uhci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI [12009.284101] PM: noirq suspend of devices complete after 31.899 msecs [12009.284307] ACPI: Preparing to enter system sleep state S3 [12009.284469] PM: Saving platform NVS memory [12009.284779] Disabling non-boot CPUs ... [12009.388006] CPU 1 is now offline [12009.389726] CPU 2 is now offline [12009.390398] Broke affinity for irq 23 [12009.492004] CPU 3 is now offline [12009.492313] ACPI: Low-level resume complete [12009.492313] PM: Restoring platform NVS memory [12009.492313] Enabling non-boot CPUs ... [12009.492313] Booting Node 0 Processor 1 APIC 0x1 [12009.508052] CPU1 is up [12009.508108] Booting Node 0 Processor 2 APIC 0x2 [12009.524084] CPU2 is up [12009.524153] Booting Node 0 Processor 3 APIC 0x3 [12009.540057] CPU3 is up [12009.542449] ACPI: Waking up from system sleep state S3 [12009.542775] uhci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI [12009.542809] uhci_hcd 0000:00:1a.1: wake-up capability disabled by ACPI [12009.542845] ehci_hcd 0000:00:1a.7: wake-up capability disabled by ACPI [12009.542909] uhci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI [12009.542943] uhci_hcd 0000:00:1d.1: wake-up capability disabled by ACPI [12009.542977] uhci_hcd 0000:00:1d.2: wake-up capability disabled by ACPI [12009.543011] uhci_hcd 0000:00:1d.3: wake-up capability disabled by ACPI [12009.543047] ehci_hcd 0000:00:1d.7: wake-up capability disabled by ACPI [12009.543451] PM: noirq resume of devices complete after 0.802 msecs [12009.543549] PM: early resume of devices complete after 0.072 msecs [12009.543607] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [12009.543611] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [12009.543613] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [12009.543632] usb usb4: root hub lost power or was reset [12009.543637] usb usb3: root hub lost power or was reset [12009.543673] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [12009.543674] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [12009.543687] snd_hda_intel 0000:00:1b.0: irq 41 for MSI/MSI-X [12009.543701] usb usb5: root hub lost power or was reset [12009.543702] usb usb6: root hub lost power or was reset [12009.543721] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [12009.543742] usb usb7: root hub lost power or was reset [12009.543845] uhci_hcd 0000:00:1d.3: setting latency timer to 64 [12009.543866] usb usb8: root hub lost power or was reset [12009.543888] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [12009.543896] pci 0000:00:1e.0: setting latency timer to 64 [12009.543942] ata_piix 0000:00:1f.2: setting latency timer to 64 [12009.543987] ata_piix 0000:00:1f.5: setting latency timer to 64 [12009.544045] pci 0000:00:1e.0: wake-up capability disabled by ACPI [12009.548393] serial 00:06: activated [12009.548434] i8042 kbd 00:08: wake-up capability disabled by ACPI [12009.664036] PM: resume of drv:hub dev:4-0:1.0 complete after 119.909 msecs [12009.664040] PM: resume of drv:hub dev:8-0:1.0 complete after 119.612 msecs [12009.664043] PM: resume of drv: dev:ep_00 complete after 119.876 msecs [12009.664045] PM: resume of drv: dev:ep_00 complete after 119.588 msecs [12009.664047] PM: resume of drv:hub dev:5-0:1.0 complete after 119.828 msecs [12009.664049] PM: resume of drv: dev:ep_81 complete after 119.921 msecs [12009.664051] PM: resume of drv:hub dev:7-0:1.0 complete after 119.687 msecs [12009.664053] PM: resume of drv: dev:ep_00 complete after 119.793 msecs [12009.664055] PM: resume of drv: dev:ep_00 complete after 119.664 msecs [12009.664057] PM: resume of drv: dev:ep_81 complete after 119.814 msecs [12009.664058] PM: resume of drv: dev:ep_81 complete after 119.618 msecs [12009.664061] PM: resume of drv: dev:ep_81 complete after 119.684 msecs [12009.664073] PM: resume of drv: dev:ep_00 complete after 119.966 msecs [12009.664078] PM: resume of drv:hub dev:3-0:1.0 complete after 119.997 msecs [12009.664087] PM: resume of drv: dev:ep_00 complete after 119.758 msecs [12009.664091] PM: resume of drv:hub dev:6-0:1.0 complete after 119.795 msecs [12009.664092] PM: resume of drv: dev:ep_81 complete after 119.995 msecs [12009.664098] PM: resume of drv: dev:ep_81 complete after 119.787 msecs [12009.668276] PM: resume of drv:rt2800usb dev:2-7.2:1.0 complete after 121.576 msecs [12009.668283] PM: resume of drv: dev:ep_00 complete after 121.454 msecs [12009.668286] PM: resume of drv: dev:ep_81 complete after 121.571 msecs [12009.668289] PM: resume of drv: dev:ep_01 complete after 121.556 msecs [12009.668291] PM: resume of drv: dev:ep_02 complete after 121.542 msecs [12009.668294] PM: resume of drv: dev:ep_03 complete after 121.528 msecs [12009.668297] PM: resume of drv: dev:ep_04 complete after 121.515 msecs [12009.668299] PM: resume of drv: dev:ep_05 complete after 121.502 msecs [12009.668302] PM: resume of drv: dev:ep_06 complete after 121.489 msecs [12009.668309] PM: resume of drv:ch341 dev:2-6.1.4:1.0 complete after 121.269 msecs [12009.668312] PM: resume of drv:usbhid dev:2-6.1.1.3:1.0 complete after 119.645 msecs [12009.668316] PM: resume of drv: dev:ep_81 complete after 120.418 msecs [12009.668317] PM: resume of drv: dev:ep_00 complete after 120.389 msecs [12009.668319] PM: resume of drv: dev:ep_81 complete after 119.633 msecs [12009.668320] PM: resume of drv: dev:ep_82 complete after 121.262 msecs [12009.668322] PM: resume of drv: dev:ep_00 complete after 119.620 msecs [12009.668323] PM: resume of drv: dev:ep_02 complete after 121.249 msecs [12009.668326] PM: resume of drv:ch341-uart dev:ttyUSB0 complete after 119.603 msecs [12009.668520] PM: resume of drv:usbhid dev:2-6.3:1.0 complete after 121.905 msecs [12009.668524] PM: resume of drv: dev:ep_81 complete after 121.893 msecs [12009.668526] PM: resume of drv: dev:ep_00 complete after 121.861 msecs [12009.668528] PM: resume of drv: dev:ep_01 complete after 121.881 msecs [12009.732012] usb 1-2: reset high-speed USB device number 2 using ehci_hcd [12009.733969] PM: resume of drv:btusb dev:2-6.1.2:1.0 complete after 187.041 msecs [12009.733979] PM: resume of drv: dev:ep_81 complete after 187.036 msecs [12009.733981] PM: resume of drv:btusb dev:2-6.1.2:1.1 complete after 186.988 msecs [12009.733987] PM: resume of drv: dev:ep_82 complete after 187.010 msecs [12009.733989] PM: resume of drv: dev:ep_00 complete after 186.980 msecs [12009.733990] PM: resume of drv: dev:ep_02 complete after 187.031 msecs [12009.733992] PM: resume of drv: dev:ep_83 complete after 185.235 msecs [12009.733993] PM: resume of drv: dev:ep_03 complete after 185.254 msecs [12009.762928] Bluetooth: hci0 SCO packet for unknown connection handle 60416 [12009.772891] Bluetooth: hci0 SCO packet for unknown connection handle 65129 [12009.782854] Bluetooth: hci0 SCO packet for unknown connection handle 26114 [12009.802782] Bluetooth: hci0 SCO packet for unknown connection handle 64792 [12009.812746] Bluetooth: hci0 SCO packet for unknown connection handle 1533 [12009.822711] Bluetooth: hci0 SCO packet for unknown connection handle 65422 [12009.822712] Bluetooth: hci0 SCO packet for unknown connection handle 5936 [12009.832673] Bluetooth: hci0 SCO packet for unknown connection handle 65302 [12009.842637] Bluetooth: hci0 SCO packet for unknown connection handle 29692 [12009.862564] Bluetooth: hci0 SCO packet for unknown connection handle 65022 [12009.872531] Bluetooth: hci0 SCO packet for unknown connection handle 2077 [12009.872532] Bluetooth: hci0 SCO packet for unknown connection handle 43264 [12009.872533] Bluetooth: hci0 SCO packet for unknown connection handle 65183 [12009.892454] Bluetooth: hci0 SCO packet for unknown connection handle 64935 [12009.892455] Bluetooth: hci0 SCO packet for unknown connection handle 352 [12009.902420] Bluetooth: hci0 SCO packet for unknown connection handle 396 [12009.912385] Bluetooth: hci0 SCO packet for unknown connection handle 65083 [12009.912387] Bluetooth: hci0 SCO packet for unknown connection handle 65485 [12009.912387] Bluetooth: hci0 SCO packet for unknown connection handle 282 [12009.936177] usb 2-6.1.1.2: reset full-speed USB device number 10 using ehci_hcd [12010.020040] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [12010.024048] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [12010.024050] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [12010.024304] ata2.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [12010.024306] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [12010.025877] ata2.00: ACPI cmd c6/00:01:00:00:00:a0 (SET MULTIPLE MODE) succeeded [12010.025879] ata2.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [12010.028109] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [12010.028111] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [12010.029867] ata1.00: ACPI cmd c6/00:01:00:00:00:a0 (SET MULTIPLE MODE) succeeded [12010.029869] ata1.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [12010.032212] PM: resume of drv:usbhid dev:2-6.1.1.2:1.0 complete after 484.250 msecs [12010.032220] PM: resume of drv: dev:ep_81 complete after 484.234 msecs [12010.032226] PM: resume of drv: dev:ep_00 complete after 483.597 msecs [12010.048193] ata4.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [12010.048194] ata4.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [12010.048196] ata4.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [12010.048260] ata2.00: configured for UDMA/133 [12010.048282] PM: resume of drv:scsi dev:host1 complete after 504.344 msecs [12010.048290] PM: resume of drv:scsi_host dev:host1 complete after 504.348 msecs [12010.048293] PM: resume of drv:scsi dev:target1:0:0 complete after 502.374 msecs [12010.048300] PM: resume of drv:sd dev:1:0:0:0 complete after 502.337 msecs [12010.048303] sd 1:0:0:0: [sdb] Starting disk [12010.048421] PM: resume of drv:scsi_device dev:1:0:0:0 complete after 502.443 msecs [12010.052177] ata1.00: configured for UDMA/133 [12010.052195] PM: resume of drv:scsi dev:host0 complete after 508.268 msecs [12010.052198] PM: resume of drv:ata_port dev:ata1 complete after 503.598 msecs [12010.052204] PM: resume of drv:scsi_host dev:host0 complete after 508.270 msecs [12010.052206] PM: resume of drv:scsi dev:target0:0:0 complete after 507.734 msecs [12010.052213] PM: resume of drv:sd dev:0:0:0:0 complete after 507.725 msecs [12010.052215] sd 0:0:0:0: [sda] Starting disk [12010.052364] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 507.397 msecs [12010.085727] snd-usb-audio 1-2:1.2: no reset_resume for driver snd-usb-audio? [12010.085729] snd-usb-audio 1-2:1.3: no reset_resume for driver snd-usb-audio? [12010.085875] PM: resume of drv:uvcvideo dev:1-2:1.0 complete after 539.568 msecs [12010.085877] PM: resume of drv:usb dev:1-2:1.2 complete after 539.519 msecs [12010.085880] PM: resume of drv:uvcvideo dev:1-2:1.1 complete after 539.540 msecs [12010.085881] PM: resume of drv: dev:ep_00 complete after 539.491 msecs [12010.085883] PM: resume of drv:usb dev:1-2:1.3 complete after 539.509 msecs [12010.085892] PM: resume of drv: dev:ep_87 complete after 539.569 msecs [12010.096114] ata4.00: configured for UDMA/33 [12010.172292] PM: resume of drv:scsi dev:host3 complete after 628.297 msecs [12010.172299] PM: resume of drv:scsi dev:target3:0:0 complete after 626.256 msecs [12010.172301] PM: resume of drv:scsi_host dev:host3 complete after 628.303 msecs [12010.172305] PM: resume of drv:sr dev:3:0:0:0 complete after 626.072 msecs [12010.172311] PM: resume of drv:scsi_device dev:3:0:0:0 complete after 626.038 msecs [12015.060006] ata3: link is slow to respond, please be patient (ready=0) [12016.628035] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [12016.636109] ata3.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [12016.636110] ata3.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [12016.636280] ata3.00: ACPI cmd c6/00:10:00:00:00:a0 (SET MULTIPLE MODE) succeeded [12016.636283] ata3.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [12016.660266] ata3.00: configured for UDMA/133 [12016.660451] PM: resume of drv:ata_port dev:ata3 complete after 6608.239 msecs [12016.660454] PM: resume of drv:scsi dev:host2 complete after 7116.494 msecs [12016.660463] PM: resume of drv:scsi_host dev:host2 complete after 7116.495 msecs [12016.660465] PM: resume of drv:scsi dev:target2:0:0 complete after 7114.468 msecs [12016.660471] PM: resume of drv:sd dev:2:0:0:0 complete after 7114.461 msecs [12016.660474] sd 2:0:0:0: [sdc] Starting disk [12016.676883] PM: resume of drv:scsi_device dev:2:0:0:0 complete after 7130.856 msecs [12019.688008] [12019.688008] floppy driver state [12019.688009] ------------------- [12019.688026] now=4297897218 last interrupt=4294892575 diff=3004643 last called handler=reset_interrupt [floppy] [12019.688027] timeout_message=lock fdc [12019.688027] last output bytes: [12019.688028] 0 0 0 [12019.688028] 0 0 0 [12019.688029] 0 0 0 [12019.688030] 8 80 4294892574 [12019.688030] 8 90 4294892574 [12019.688031] 8 80 4294892574 [12019.688031] 8 90 4294892574 [12019.688032] e 80 4294892574 [12019.688033] 13 80 4294892574 [12019.688033] 0 90 4294892574 [12019.688034] 1a 90 4294892574 [12019.688034] 0 90 4294892574 [12019.688035] 12 90 4294892574 [12019.688035] 0 90 4294892574 [12019.688036] 14 90 4294892574 [12019.688037] 18 80 4294892574 [12019.688037] 8 80 4294892575 [12019.688038] 8 80 4294892575 [12019.688038] 8 80 4294892575 [12019.688039] 8 80 4294892575 [12019.688039] last result at 4294892575 [12019.688040] last redo_fd_request at 4294892575 [12019.688045] status=8 [12019.688045] fdc_busy=1 [12019.688048] do_floppy=reset_interrupt [floppy] [12019.688049] cont=ffffffffa0009ec0 [12019.688049] current_req= (null) [12019.688050] command_status=-1 [12019.688050] [12019.688054] floppy0: floppy timeout called [12019.688223] PM: resume of devices complete after 10144.667 msecs [12020.187185] PM: resume devices took 10.644 seconds [12020.187186] ------------[ cut here ]------------ [12020.187191] WARNING: at /build/buildd/linux-3.5.0/kernel/power/suspend_test.c:53 suspend_test_finish+0x86/0x90() [12020.187192] Hardware name: MS-7360 [12020.187193] Component: resume devices, time: 10644 [12020.187193] Modules linked in: dm_crypt pci_stub vboxpci(O) vboxnetadp(O) vboxnetflt(O) vboxdrv(O) snd_hda_codec_hdmi arc4 rt2800usb rt2800lib crc_ccitt rt2x00usb rt2x00lib mac80211 cfg80211 gpio_ich joydev nvidia(PO) ch341 usbserial snd_usb_audio uvcvideo snd_hda_codec_realtek videobuf2_core snd_usbmidi_lib hid_dr videodev btusb videobuf2_vmalloc videobuf2_memops hid_logitech ff_memless kvm_intel snd_hda_intel snd_hda_codec kvm snd_hwdep snd_pcm snd_seq_midi psmouse microcode snd_rawmidi serio_raw snd_seq_midi_event snd_seq snd_timer snd_seq_device rfcomm bnep mac_hid snd lpc_ich bluetooth soundcore parport_pc snd_page_alloc ppdev f71882fg coretemp adt7475 hwmon_vid nfsd nfs lp parport lockd fscache auth_rpcgss nfs_acl sunrpc binfmt_misc hid_generic usbhid hid e1000 floppy [last unloaded: ac97_bus] [12020.187231] Pid: 21350, comm: s2ram Tainted: P O 3.5.0-19-generic #30-Ubuntu [12020.187232] Call Trace: [12020.187233] [] warn_slowpath_common+0x7f/0xc0 [12020.187237] [] warn_slowpath_fmt+0x46/0x50 [12020.187239] [] suspend_test_finish+0x86/0x90 [12020.187240] [] suspend_devices_and_enter+0xd8/0x310 [12020.187242] [] pm_suspend+0x1f6/0x210 [12020.187244] [] state_store+0x87/0xf0 [12020.187245] [] kobj_attr_store+0xf/0x20 [12020.187248] [] sysfs_write_file+0xdc/0x150 [12020.187251] [] vfs_write+0xac/0x180 [12020.187253] [] sys_write+0x4a/0x90 [12020.187255] [] system_call_fastpath+0x16/0x1b [12020.187258] ---[ end trace aae2b01f7d432c20 ]--- [12020.187959] PM: Finishing wakeup. [12020.187960] Restarting tasks ... done. [12020.223144] set resolution quirk: cval->res = 384 [12031.088838] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready [12031.091171] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [12031.114196] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX [12031.115251] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [20427.063004] Netfilter messages via NETLINK v0.30. [24702.857025] PM: Syncing filesystems ... done. [24702.877102] PM: Preparing system for mem sleep [24703.233863] Freezing user space processes ... (elapsed 0.01 seconds) done. [24703.248167] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [24703.264154] PM: Entering mem sleep [24703.264174] Suspending console(s) (use no_console_suspend to debug) [24703.265056] sd 2:0:0:0: [sdc] Synchronizing SCSI cache [24703.265086] sd 1:0:0:0: [sdb] Synchronizing SCSI cache [24703.265125] sd 0:0:0:0: [sda] Synchronizing SCSI cache [24703.265201] sd 0:0:0:0: [sda] Stopping disk [24703.265203] sd 1:0:0:0: [sdb] Stopping disk [24703.265253] sd 2:0:0:0: [sdc] Stopping disk [24703.305617] i8042 aux 00:09: wake-up capability disabled by ACPI [24703.305649] i8042 kbd 00:08: wake-up capability enabled by ACPI [24703.305789] serial 00:06: disabled [24703.305956] pci 0000:00:1e.0: wake-up capability enabled by ACPI [24703.412104] ACPI handle has no context! [24703.428006] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 119.856 msecs [24703.752039] ACPI handle has no context! [24703.768007] PM: suspend of drv:snd_hda_intel dev:0000:01:00.1 complete after 462.187 msecs [24703.768023] PM: suspend of drv:pcieport dev:0000:00:01.0 complete after 459.715 msecs [24703.861618] PM: suspend of drv:sd dev:2:0:0:0 complete after 596.571 msecs [24703.861628] PM: suspend of drv:scsi dev:target2:0:0 complete after 596.569 msecs [24703.861634] PM: suspend of drv:scsi dev:host2 complete after 556.336 msecs [24703.861661] PM: suspend of drv: dev:ata3 complete after 556.344 msecs [24703.876009] PM: suspend of drv:ata_piix dev:0000:00:1f.5 complete after 570.178 msecs [24703.876016] PM: suspend of drv: dev:pci0000:00 complete after 567.691 msecs [24703.876024] PM: suspend of devices complete after 611.561 msecs [24703.876025] PM: suspend devices took 0.612 seconds [24703.876234] PM: late suspend of devices complete after 0.208 msecs [24703.876446] ehci_hcd 0000:00:1d.7: wake-up capability enabled by ACPI [24703.892057] uhci_hcd 0000:00:1d.3: wake-up capability enabled by ACPI [24703.892088] uhci_hcd 0000:00:1d.2: wake-up capability enabled by ACPI [24703.892118] uhci_hcd 0000:00:1d.1: wake-up capability enabled by ACPI [24703.892147] uhci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI [24703.892176] ehci_hcd 0000:00:1a.7: wake-up capability enabled by ACPI [24703.908039] uhci_hcd 0000:00:1a.1: wake-up capability enabled by ACPI [24703.908068] uhci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI [24703.908115] PM: noirq suspend of devices complete after 31.879 msecs [24703.908322] ACPI: Preparing to enter system sleep state S3 [24703.908471] PM: Saving platform NVS memory [24703.908797] Disabling non-boot CPUs ... [24704.012006] CPU 1 is now offline [24704.116007] CPU 2 is now offline [24704.117595] CPU 3 is now offline [24704.117895] ACPI: Low-level resume complete [24704.117895] PM: Restoring platform NVS memory [24704.117895] Enabling non-boot CPUs ... [24704.117895] Booting Node 0 Processor 1 APIC 0x1 [24704.132074] CPU1 is up [24704.132135] Booting Node 0 Processor 2 APIC 0x2 [24704.148052] CPU2 is up [24704.148120] Booting Node 0 Processor 3 APIC 0x3 [24704.164057] CPU3 is up [24704.166432] ACPI: Waking up from system sleep state S3 [24704.166771] uhci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI [24704.166805] uhci_hcd 0000:00:1a.1: wake-up capability disabled by ACPI [24704.166842] ehci_hcd 0000:00:1a.7: wake-up capability disabled by ACPI [24704.166906] uhci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI [24704.166939] uhci_hcd 0000:00:1d.1: wake-up capability disabled by ACPI [24704.166973] uhci_hcd 0000:00:1d.2: wake-up capability disabled by ACPI [24704.167007] uhci_hcd 0000:00:1d.3: wake-up capability disabled by ACPI [24704.167043] ehci_hcd 0000:00:1d.7: wake-up capability disabled by ACPI [24704.167445] PM: noirq resume of devices complete after 0.798 msecs [24704.167543] PM: early resume of devices complete after 0.072 msecs [24704.167601] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [24704.167604] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [24704.167606] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [24704.167629] usb usb4: root hub lost power or was reset [24704.167631] usb usb3: root hub lost power or was reset [24704.167667] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [24704.167671] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [24704.167697] usb usb5: root hub lost power or was reset [24704.167703] usb usb6: root hub lost power or was reset [24704.167708] snd_hda_intel 0000:00:1b.0: irq 41 for MSI/MSI-X [24704.167713] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [24704.167723] uhci_hcd 0000:00:1d.3: setting latency timer to 64 [24704.167739] usb usb7: root hub lost power or was reset [24704.167754] usb usb8: root hub lost power or was reset [24704.167761] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [24704.167768] pci 0000:00:1e.0: setting latency timer to 64 [24704.167788] ata_piix 0000:00:1f.2: setting latency timer to 64 [24704.167791] ata_piix 0000:00:1f.5: setting latency timer to 64 [24704.167896] pci 0000:00:1e.0: wake-up capability disabled by ACPI [24704.170174] serial 00:06: activated [24704.170295] i8042 kbd 00:08: wake-up capability disabled by ACPI [24704.288030] PM: resume of drv:hub dev:7-0:1.0 complete after 119.023 msecs [24704.288039] PM: resume of drv:hub dev:6-0:1.0 complete after 119.098 msecs [24704.288041] PM: resume of drv: dev:ep_81 complete after 119.015 msecs [24704.288043] PM: resume of drv:hub dev:8-0:1.0 complete after 118.967 msecs [24704.288048] PM: resume of drv: dev:ep_00 complete after 119.075 msecs [24704.288053] PM: resume of drv: dev:ep_81 complete after 118.964 msecs [24704.288054] PM: resume of drv: dev:ep_00 complete after 119.015 msecs [24704.288059] PM: resume of drv: dev:ep_00 complete after 118.956 msecs [24704.288063] PM: resume of drv: dev:ep_81 complete after 119.108 msecs [24704.288068] PM: resume of drv:hub dev:5-0:1.0 complete after 119.199 msecs [24704.288070] PM: resume of drv: dev:ep_00 complete after 119.167 msecs [24704.288074] PM: resume of drv: dev:ep_81 complete after 119.189 msecs [24704.288078] PM: resume of drv:hub dev:4-0:1.0 complete after 119.935 msecs [24704.288083] PM: resume of drv: dev:ep_81 complete after 119.273 msecs [24704.288084] PM: resume of drv: dev:ep_00 complete after 119.253 msecs [24704.288098] PM: resume of drv:hub dev:3-0:1.0 complete after 119.976 msecs [24704.288101] PM: resume of drv: dev:ep_00 complete after 119.968 msecs [24704.288108] PM: resume of drv: dev:ep_81 complete after 119.981 msecs [24704.292194] PM: resume of drv:rt2800usb dev:2-7.2:1.0 complete after 122.467 msecs [24704.292196] PM: resume of drv: dev:ep_00 complete after 122.338 msecs [24704.292208] PM: resume of drv: dev:ep_03 complete after 122.416 msecs [24704.292210] PM: resume of drv: dev:ep_04 complete after 122.404 msecs [24704.292211] PM: resume of drv: dev:ep_01 complete after 122.452 msecs [24704.292214] PM: resume of drv: dev:ep_05 complete after 122.392 msecs [24704.292216] PM: resume of drv: dev:ep_81 complete after 122.474 msecs [24704.292224] PM: resume of drv: dev:ep_06 complete after 122.386 msecs [24704.292234] PM: resume of drv: dev:ep_02 complete after 122.458 msecs [24704.292435] PM: resume of drv:usbhid dev:2-6.3:1.0 complete after 122.795 msecs [24704.292441] PM: resume of drv: dev:ep_00 complete after 122.754 msecs [24704.292445] PM: resume of drv: dev:ep_81 complete after 122.790 msecs [24704.292448] PM: resume of drv: dev:ep_01 complete after 122.777 msecs [24704.292689] PM: resume of drv:usb dev:2-6.1.4 complete after 122.637 msecs [24704.292691] PM: resume of drv:hub dev:2-6.1:1.0 complete after 123.123 msecs [24704.292698] PM: resume of drv:usb dev:2-6.1.2 complete after 122.759 msecs [24704.292701] PM: resume of drv:usb dev:2-6.1.1 complete after 122.828 msecs [24704.292702] PM: resume of drv: dev:ep_00 complete after 123.103 msecs [24704.292705] PM: resume of drv: dev:ep_81 complete after 123.122 msecs [24704.340261] PM: resume of drv:ch341 dev:2-6.1.4:1.0 complete after 170.193 msecs [24704.340264] PM: resume of drv: dev:ep_00 complete after 170.132 msecs [24704.340273] PM: resume of drv: dev:ep_02 complete after 170.172 msecs [24704.340274] PM: resume of drv: dev:ep_81 complete after 170.158 msecs [24704.340278] PM: resume of drv:ch341-uart dev:ttyUSB0 complete after 169.795 msecs [24704.340280] PM: resume of drv: dev:ep_82 complete after 170.196 msecs [24704.340636] PM: resume of drv:hub dev:2-6.1.1:1.0 complete after 170.747 msecs [24704.340638] PM: resume of drv:usb dev:2-6.1.1.3 complete after 170.409 msecs [24704.340644] PM: resume of drv: dev:ep_81 complete after 170.739 msecs [24704.340646] PM: resume of drv:usb dev:2-6.1.1.2 complete after 170.497 msecs [24704.340648] PM: resume of drv: dev:ep_00 complete after 170.726 msecs [24704.356011] usb 1-2: reset high-speed USB device number 2 using ehci_hcd [24704.388246] PM: resume of drv:usbhid dev:2-6.1.1.3:1.0 complete after 217.967 msecs [24704.388248] PM: resume of drv: dev:ep_00 complete after 217.781 msecs [24704.388252] PM: resume of drv: dev:ep_81 complete after 217.954 msecs [24704.388468] PM: resume of drv:usbhid dev:2-6.1.1.2:1.0 complete after 218.304 msecs [24704.388470] PM: resume of drv: dev:ep_00 complete after 218.267 msecs [24704.388475] PM: resume of drv: dev:ep_81 complete after 218.292 msecs [24704.394026] PM: resume of drv:btusb dev:2-6.1.2:1.1 complete after 224.006 msecs [24704.394028] PM: resume of drv:btusb dev:2-6.1.2:1.0 complete after 224.072 msecs [24704.394036] PM: resume of drv: dev:ep_00 complete after 224.000 msecs [24704.394040] PM: resume of drv: dev:ep_03 complete after 223.540 msecs [24704.394042] PM: resume of drv: dev:ep_81 complete after 224.071 msecs [24704.394049] PM: resume of drv: dev:ep_82 complete after 224.046 msecs [24704.394053] PM: resume of drv: dev:ep_02 complete after 224.067 msecs [24704.394061] PM: resume of drv: dev:ep_83 complete after 223.544 msecs [24704.644039] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [24704.648044] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [24704.648046] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [24704.652112] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [24704.652113] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [24704.653814] ata1.00: ACPI cmd c6/00:01:00:00:00:a0 (SET MULTIPLE MODE) succeeded [24704.653816] ata1.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [24704.656110] ata2.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [24704.656112] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [24704.657658] ata2.00: ACPI cmd c6/00:01:00:00:00:a0 (SET MULTIPLE MODE) succeeded [24704.657659] ata2.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [24704.672162] ata4.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [24704.672164] ata4.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [24704.672165] ata4.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [24704.676176] ata1.00: configured for UDMA/133 [24704.680183] ata2.00: configured for UDMA/133 [24704.680202] PM: resume of drv:scsi dev:host1 complete after 512.191 msecs [24704.680209] PM: resume of drv:scsi_host dev:host1 complete after 512.195 msecs [24704.680211] PM: resume of drv:scsi dev:target1:0:0 complete after 511.045 msecs [24704.680217] PM: resume of drv:sd dev:1:0:0:0 complete after 511.035 msecs [24704.680222] sd 1:0:0:0: [sdb] Starting disk [24704.680356] PM: resume of drv:scsi_device dev:1:0:0:0 complete after 511.156 msecs [24704.692011] PM: resume of drv:scsi dev:host0 complete after 524.097 msecs [24704.692013] PM: resume of drv:ata_port dev:ata1 complete after 521.572 msecs [24704.692021] PM: resume of drv:scsi_host dev:host0 complete after 524.021 msecs [24704.692022] PM: resume of drv:scsi dev:target0:0:0 complete after 522.901 msecs [24704.692028] PM: resume of drv:sd dev:0:0:0:0 complete after 522.892 msecs [24704.692030] sd 0:0:0:0: [sda] Starting disk [24704.692183] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 523.032 msecs [24704.710012] snd-usb-audio 1-2:1.2: no reset_resume for driver snd-usb-audio? [24704.710014] snd-usb-audio 1-2:1.3: no reset_resume for driver snd-usb-audio? [24704.710169] PM: resume of drv:uvcvideo dev:1-2:1.1 complete after 540.807 msecs [24704.710171] PM: resume of drv:uvcvideo dev:1-2:1.0 complete after 540.842 msecs [24704.710175] PM: resume of drv:usb dev:1-2:1.2 complete after 540.799 msecs [24704.710183] PM: resume of drv:usb dev:1-2:1.3 complete after 540.790 msecs [24704.710188] PM: resume of drv: dev:ep_87 complete after 540.843 msecs [24704.710190] PM: resume of drv: dev:ep_00 complete after 540.779 msecs [24704.720116] ata4.00: configured for UDMA/33 [24704.787200] PM: resume of drv:scsi dev:host3 complete after 619.142 msecs [24704.787206] PM: resume of drv:scsi_host dev:host3 complete after 619.142 msecs [24704.787211] PM: resume of drv:scsi dev:target3:0:0 complete after 617.949 msecs [24704.787216] PM: resume of drv:sr dev:3:0:0:0 complete after 617.936 msecs [24704.787222] PM: resume of drv:scsi_device dev:3:0:0:0 complete after 617.928 msecs [24709.684006] ata3: link is slow to respond, please be patient (ready=0) [24711.252035] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [24711.260109] ata3.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [24711.260110] ata3.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [24711.260276] ata3.00: ACPI cmd c6/00:10:00:00:00:a0 (SET MULTIPLE MODE) succeeded [24711.260278] ata3.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [24711.284266] ata3.00: configured for UDMA/133 [24711.284449] PM: resume of drv:scsi dev:host2 complete after 7116.408 msecs [24711.284456] PM: resume of drv:ata_port dev:ata3 complete after 6592.432 msecs [24711.284460] PM: resume of drv:scsi_host dev:host2 complete after 7116.405 msecs [24711.284465] PM: resume of drv:scsi dev:target2:0:0 complete after 7115.250 msecs [24711.284471] PM: resume of drv:sd dev:2:0:0:0 complete after 7115.240 msecs [24711.284475] sd 2:0:0:0: [sdc] Starting disk [24711.299058] PM: resume of drv:scsi_device dev:2:0:0:0 complete after 7129.812 msecs [24714.312041] [24714.312041] floppy driver state [24714.312042] ------------------- [24714.312059] now=4301070874 last interrupt=4294892575 diff=6178299 last called handler=reset_interrupt [floppy] [24714.312059] timeout_message=lock fdc [24714.312060] last output bytes: [24714.312061] 0 0 0 [24714.312061] 0 0 0 [24714.312062] 0 0 0 [24714.312062] 8 80 4294892574 [24714.312063] 8 90 4294892574 [24714.312064] 8 80 4294892574 [24714.312064] 8 90 4294892574 [24714.312065] e 80 4294892574 [24714.312065] 13 80 4294892574 [24714.312066] 0 90 4294892574 [24714.312067] 1a 90 4294892574 [24714.312067] 0 90 4294892574 [24714.312068] 12 90 4294892574 [24714.312068] 0 90 4294892574 [24714.312069] 14 90 4294892574 [24714.312069] 18 80 4294892574 [24714.312070] 8 80 4294892575 [24714.312071] 8 80 4294892575 [24714.312071] 8 80 4294892575 [24714.312072] 8 80 4294892575 [24714.312072] last result at 4294892575 [24714.312073] last redo_fd_request at 4297897218 [24714.312078] status=0 [24714.312078] fdc_busy=1 [24714.312081] do_floppy=reset_interrupt [floppy] [24714.312081] cont=ffffffffa0009ec0 [24714.312082] current_req= (null) [24714.312083] command_status=-1 [24714.312083] [24714.312086] floppy0: floppy timeout called [24714.312240] PM: resume of devices complete after 10144.689 msecs [24714.812288] PM: resume devices took 10.648 seconds [24714.812288] ------------[ cut here ]------------ [24714.812293] WARNING: at /build/buildd/linux-3.5.0/kernel/power/suspend_test.c:53 suspend_test_finish+0x86/0x90() [24714.812293] Hardware name: MS-7360 [24714.812294] Component: resume devices, time: 10648 [24714.812294] Modules linked in: nfnetlink_log nfnetlink dm_crypt pci_stub vboxpci(O) vboxnetadp(O) vboxnetflt(O) vboxdrv(O) snd_hda_codec_hdmi arc4 rt2800usb rt2800lib crc_ccitt rt2x00usb rt2x00lib mac80211 cfg80211 gpio_ich joydev nvidia(PO) ch341 usbserial snd_usb_audio uvcvideo snd_hda_codec_realtek videobuf2_core snd_usbmidi_lib hid_dr videodev btusb videobuf2_vmalloc videobuf2_memops hid_logitech ff_memless kvm_intel snd_hda_intel snd_hda_codec kvm snd_hwdep snd_pcm snd_seq_midi psmouse microcode snd_rawmidi serio_raw snd_seq_midi_event snd_seq snd_timer snd_seq_device rfcomm bnep mac_hid snd lpc_ich bluetooth soundcore parport_pc snd_page_alloc ppdev f71882fg coretemp adt7475 hwmon_vid nfsd nfs lp parport lockd fscache auth_rpcgss nfs_acl sunrpc binfmt_misc hid_generic usbhid hid e1000 floppy [last unloaded: ac97_bus] [24714.812326] Pid: 7377, comm: s2ram Tainted: P W O 3.5.0-19-generic #30-Ubuntu [24714.812327] Call Trace: [24714.812328] [] warn_slowpath_common+0x7f/0xc0 [24714.812332] [] warn_slowpath_fmt+0x46/0x50 [24714.812334] [] suspend_test_finish+0x86/0x90 [24714.812335] [] suspend_devices_and_enter+0xd8/0x310 [24714.812337] [] pm_suspend+0x1f6/0x210 [24714.812339] [] state_store+0x87/0xf0 [24714.812341] [] kobj_attr_store+0xf/0x20 [24714.812343] [] sysfs_write_file+0xdc/0x150 [24714.812346] [] vfs_write+0xac/0x180 [24714.812348] [] sys_write+0x4a/0x90 [24714.812350] [] system_call_fastpath+0x16/0x1b [24714.812353] ---[ end trace aae2b01f7d432c21 ]--- [24714.812463] PM: Finishing wakeup. [24714.812464] Restarting tasks ... done. [24714.862725] set resolution quirk: cval->res = 384 [24726.006288] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready [24726.009082] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [24726.031763] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX [24726.032745] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [37132.930104] PM: Syncing filesystems ... done. [37132.946169] PM: Preparing system for mem sleep [37133.454936] Freezing user space processes ... (elapsed 0.01 seconds) done. [37133.472168] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [37133.488152] PM: Entering mem sleep [37133.488175] Suspending console(s) (use no_console_suspend to debug) [37133.489063] sd 2:0:0:0: [sdc] Synchronizing SCSI cache [37133.489089] sd 1:0:0:0: [sdb] Synchronizing SCSI cache [37133.489127] sd 0:0:0:0: [sda] Synchronizing SCSI cache [37133.489208] sd 0:0:0:0: [sda] Stopping disk [37133.489209] sd 2:0:0:0: [sdc] Stopping disk [37133.489210] sd 1:0:0:0: [sdb] Stopping disk [37133.530411] i8042 aux 00:09: wake-up capability disabled by ACPI [37133.530443] i8042 kbd 00:08: wake-up capability enabled by ACPI [37133.530582] serial 00:06: disabled [37133.530762] pci 0000:00:1e.0: wake-up capability enabled by ACPI [37133.636095] ACPI handle has no context! [37133.652006] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 119.816 msecs [37133.976042] ACPI handle has no context! [37133.992010] PM: suspend of drv:snd_hda_intel dev:0000:01:00.1 complete after 461.394 msecs [37133.992021] PM: suspend of drv:pcieport dev:0000:00:01.0 complete after 459.689 msecs [37134.071321] PM: suspend of drv:sd dev:2:0:0:0 complete after 582.266 msecs [37134.071330] PM: suspend of drv:scsi dev:target2:0:0 complete after 582.270 msecs [37134.071335] PM: suspend of drv:scsi dev:host2 complete after 541.261 msecs [37134.071364] PM: suspend of drv: dev:ata3 complete after 541.260 msecs [37134.084008] PM: suspend of drv:ata_piix dev:0000:00:1f.5 complete after 553.385 msecs [37134.084016] PM: suspend of drv: dev:pci0000:00 complete after 551.663 msecs [37134.084021] PM: suspend of devices complete after 595.562 msecs [37134.084023] PM: suspend devices took 0.596 seconds [37134.084278] PM: late suspend of devices complete after 0.253 msecs [37134.084490] ehci_hcd 0000:00:1d.7: wake-up capability enabled by ACPI [37134.100053] uhci_hcd 0000:00:1d.3: wake-up capability enabled by ACPI [37134.100084] uhci_hcd 0000:00:1d.2: wake-up capability enabled by ACPI [37134.100114] uhci_hcd 0000:00:1d.1: wake-up capability enabled by ACPI [37134.100143] uhci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI [37134.100172] ehci_hcd 0000:00:1a.7: wake-up capability enabled by ACPI [37134.116039] uhci_hcd 0000:00:1a.1: wake-up capability enabled by ACPI [37134.116068] uhci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI [37134.116114] PM: noirq suspend of devices complete after 31.835 msecs [37134.116320] ACPI: Preparing to enter system sleep state S3 [37134.116471] PM: Saving platform NVS memory [37134.116802] Disabling non-boot CPUs ... [37134.220027] CPU 1 is now offline [37134.324007] CPU 2 is now offline [37134.428005] CPU 3 is now offline [37134.428273] ACPI: Low-level resume complete [37134.428273] PM: Restoring platform NVS memory [37134.428273] Enabling non-boot CPUs ... [37134.428273] Booting Node 0 Processor 1 APIC 0x1 [37134.444048] CPU1 is up [37134.444105] Booting Node 0 Processor 2 APIC 0x2 [37134.460045] CPU2 is up [37134.460145] Booting Node 0 Processor 3 APIC 0x3 [37134.476055] CPU3 is up [37134.478436] ACPI: Waking up from system sleep state S3 [37134.478776] uhci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI [37134.478810] uhci_hcd 0000:00:1a.1: wake-up capability disabled by ACPI [37134.478847] ehci_hcd 0000:00:1a.7: wake-up capability disabled by ACPI [37134.478911] uhci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI [37134.478945] uhci_hcd 0000:00:1d.1: wake-up capability disabled by ACPI [37134.478978] uhci_hcd 0000:00:1d.2: wake-up capability disabled by ACPI [37134.479012] uhci_hcd 0000:00:1d.3: wake-up capability disabled by ACPI [37134.479048] ehci_hcd 0000:00:1d.7: wake-up capability disabled by ACPI [37134.479451] PM: noirq resume of devices complete after 0.798 msecs [37134.479549] PM: early resume of devices complete after 0.072 msecs [37134.479648] snd_hda_intel 0000:00:1b.0: irq 41 for MSI/MSI-X [37134.484039] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [37134.484067] usb usb4: root hub lost power or was reset [37134.484102] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [37134.484119] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [37134.484133] usb usb3: root hub lost power or was reset [37134.484157] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [37134.484171] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [37134.484185] usb usb5: root hub lost power or was reset [37134.484201] usb usb6: root hub lost power or was reset [37134.484231] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [37134.484236] uhci_hcd 0000:00:1d.3: setting latency timer to 64 [37134.484262] usb usb7: root hub lost power or was reset [37134.484266] usb usb8: root hub lost power or was reset [37134.484283] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [37134.484305] pci 0000:00:1e.0: setting latency timer to 64 [37134.484397] ata_piix 0000:00:1f.2: setting latency timer to 64 [37134.484404] ata_piix 0000:00:1f.5: setting latency timer to 64 [37134.484435] pci 0000:00:1e.0: wake-up capability disabled by ACPI [37134.487032] serial 00:06: activated [37134.488695] i8042 kbd 00:08: wake-up capability disabled by ACPI [37134.604035] PM: resume of drv:hub dev:7-0:1.0 complete after 123.816 msecs [37134.604038] PM: resume of drv: dev:ep_00 complete after 124.043 msecs [37134.604042] PM: resume of drv: dev:ep_81 complete after 123.814 msecs [37134.604046] PM: resume of drv: dev:ep_00 complete after 123.806 msecs [37134.604058] PM: resume of drv:hub dev:4-0:1.0 complete after 124.009 msecs [37134.604061] PM: resume of drv:hub dev:3-0:1.0 complete after 124.094 msecs [37134.604064] PM: resume of drv: dev:ep_00 complete after 123.984 msecs [37134.604082] PM: resume of drv: dev:ep_81 complete after 124.102 msecs [37134.604085] PM: resume of drv:hub dev:6-0:1.0 complete after 123.923 msecs [37134.604086] PM: resume of drv: dev:ep_81 complete after 124.021 msecs [37134.604089] PM: resume of drv: dev:ep_81 complete after 123.915 msecs [37134.604090] PM: resume of drv:hub dev:5-0:1.0 complete after 123.984 msecs [37134.604092] PM: resume of drv: dev:ep_00 complete after 123.902 msecs [37134.604094] PM: resume of drv:hub dev:8-0:1.0 complete after 123.827 msecs [37134.604100] PM: resume of drv: dev:ep_81 complete after 123.820 msecs [37134.604102] PM: resume of drv: dev:ep_81 complete after 123.981 msecs [37134.604103] PM: resume of drv: dev:ep_00 complete after 123.968 msecs [37134.604115] PM: resume of drv: dev:ep_00 complete after 123.822 msecs [37134.608274] PM: resume of drv:rt2800usb dev:2-7.2:1.0 complete after 127.478 msecs [37134.608276] PM: resume of drv: dev:ep_00 complete after 127.374 msecs [37134.608284] PM: resume of drv: dev:ep_81 complete after 127.475 msecs [37134.608285] PM: resume of drv: dev:ep_01 complete after 127.464 msecs [37134.608287] PM: resume of drv: dev:ep_02 complete after 127.453 msecs [37134.608288] PM: resume of drv: dev:ep_04 complete after 127.428 msecs [37134.608290] PM: resume of drv: dev:ep_03 complete after 127.443 msecs [37134.608292] PM: resume of drv: dev:ep_06 complete after 127.406 msecs [37134.608293] PM: resume of drv: dev:ep_05 complete after 127.420 msecs [37134.608516] PM: resume of drv:usbhid dev:2-6.3:1.0 complete after 127.788 msecs [37134.608518] PM: resume of drv: dev:ep_00 complete after 127.751 msecs [37134.608525] PM: resume of drv: dev:ep_01 complete after 127.772 msecs [37134.608527] PM: resume of drv: dev:ep_81 complete after 127.785 msecs [37134.608768] PM: resume of drv:hub dev:2-6.1:1.0 complete after 128.091 msecs [37134.608769] PM: resume of drv: dev:ep_00 complete after 128.067 msecs [37134.608772] PM: resume of drv: dev:ep_81 complete after 128.083 msecs [37134.608773] PM: resume of drv:usb dev:2-6.1.1 complete after 127.858 msecs [37134.608779] PM: resume of drv:usb dev:2-6.1.4 complete after 127.718 msecs [37134.608780] PM: resume of drv:usb dev:2-6.1.2 complete after 127.813 msecs [37134.656220] PM: resume of drv:ch341 dev:2-6.1.4:1.0 complete after 175.146 msecs [37134.656222] PM: resume of drv: dev:ep_00 complete after 175.095 msecs [37134.656227] PM: resume of drv: dev:ep_82 complete after 175.141 msecs [37134.656229] PM: resume of drv: dev:ep_02 complete after 175.129 msecs [37134.656231] PM: resume of drv: dev:ep_81 complete after 175.117 msecs [37134.656237] PM: resume of drv:ch341-uart dev:ttyUSB0 complete after 174.984 msecs [37134.656593] PM: resume of drv:hub dev:2-6.1.1:1.0 complete after 175.666 msecs [37134.656595] PM: resume of drv: dev:ep_00 complete after 175.641 msecs [37134.656598] PM: resume of drv: dev:ep_81 complete after 175.658 msecs [37134.656599] PM: resume of drv:usb dev:2-6.1.1.2 complete after 175.456 msecs [37134.656605] PM: resume of drv:usb dev:2-6.1.1.3 complete after 175.409 msecs [37134.672011] usb 1-2: reset high-speed USB device number 2 using ehci_hcd [37134.704326] PM: resume of drv:usbhid dev:2-6.1.1.3:1.0 complete after 223.117 msecs [37134.704332] PM: resume of drv: dev:ep_00 complete after 223.093 msecs [37134.704334] PM: resume of drv: dev:ep_81 complete after 223.108 msecs [37134.704428] PM: resume of drv:usbhid dev:2-6.1.1.2:1.0 complete after 223.273 msecs [37134.704429] PM: resume of drv: dev:ep_00 complete after 223.247 msecs [37134.704432] PM: resume of drv: dev:ep_81 complete after 223.265 msecs [37134.709985] PM: resume of drv:btusb dev:2-6.1.2:1.0 complete after 229.005 msecs [37134.709989] PM: resume of drv:btusb dev:2-6.1.2:1.1 complete after 228.956 msecs [37134.709992] PM: resume of drv: dev:ep_81 complete after 228.999 msecs [37134.709996] PM: resume of drv: dev:ep_82 complete after 228.976 msecs [37134.709997] PM: resume of drv: dev:ep_02 complete after 228.990 msecs [37134.709999] PM: resume of drv: dev:ep_00 complete after 228.952 msecs [37134.710005] PM: resume of drv: dev:ep_03 complete after 228.739 msecs [37134.710011] PM: resume of drv: dev:ep_83 complete after 228.733 msecs [37134.960038] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [37134.964049] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [37134.964051] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [37134.968112] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [37134.968114] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [37134.969704] ata1.00: ACPI cmd c6/00:01:00:00:00:a0 (SET MULTIPLE MODE) succeeded [37134.969706] ata1.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [37134.972111] ata2.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [37134.972113] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [37134.973823] ata2.00: ACPI cmd c6/00:01:00:00:00:a0 (SET MULTIPLE MODE) succeeded [37134.973824] ata2.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [37134.988126] ata4.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [37134.988127] ata4.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [37134.988129] ata4.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [37134.992178] ata1.00: configured for UDMA/133 [37134.996180] ata2.00: configured for UDMA/133 [37134.996194] PM: resume of drv:scsi dev:host1 complete after 516.392 msecs [37134.996201] PM: resume of drv:scsi dev:target1:0:0 complete after 515.856 msecs [37134.996203] PM: resume of drv:scsi_host dev:host1 complete after 516.397 msecs [37134.996206] PM: resume of drv:sd dev:1:0:0:0 complete after 515.849 msecs [37134.996209] sd 1:0:0:0: [sdb] Starting disk [37134.996331] PM: resume of drv:scsi_device dev:1:0:0:0 complete after 515.955 msecs [37135.008013] PM: resume of drv:ata_port dev:ata1 complete after 519.156 msecs [37135.008015] PM: resume of drv:scsi dev:host0 complete after 528.222 msecs [37135.008025] PM: resume of drv:scsi_host dev:host0 complete after 528.227 msecs [37135.008032] PM: resume of drv:scsi dev:target0:0:0 complete after 527.726 msecs [37135.008040] PM: resume of drv:sd dev:0:0:0:0 complete after 527.720 msecs [37135.008042] sd 0:0:0:0: [sda] Starting disk [37135.008182] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 527.849 msecs [37135.026142] snd-usb-audio 1-2:1.2: no reset_resume for driver snd-usb-audio? [37135.026143] snd-usb-audio 1-2:1.3: no reset_resume for driver snd-usb-audio? [37135.026294] PM: resume of drv:uvcvideo dev:1-2:1.0 complete after 545.812 msecs [37135.026296] PM: resume of drv:uvcvideo dev:1-2:1.1 complete after 545.790 msecs [37135.026299] PM: resume of drv: dev:ep_87 complete after 545.805 msecs [37135.026300] PM: resume of drv:usb dev:1-2:1.2 complete after 545.780 msecs [37135.026308] PM: resume of drv:usb dev:1-2:1.3 complete after 545.776 msecs [37135.026310] PM: resume of drv: dev:ep_00 complete after 545.764 msecs [37135.026313] PM: resume of drv: dev:ep_86 complete after 545.022 msecs [37135.036112] ata4.00: configured for UDMA/33 [37135.112244] PM: resume of drv:scsi dev:host3 complete after 632.416 msecs [37135.112252] PM: resume of drv:scsi_host dev:host3 complete after 632.419 msecs [37135.112254] PM: resume of drv:scsi dev:target3:0:0 complete after 631.826 msecs [37135.112259] PM: resume of drv:sr dev:3:0:0:0 complete after 631.819 msecs [37135.112265] PM: resume of drv:scsi_device dev:3:0:0:0 complete after 631.810 msecs [37140.000006] ata3: link is slow to respond, please be patient (ready=0) [37141.680035] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [37141.688110] ata3.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [37141.688112] ata3.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [37141.688277] ata3.00: ACPI cmd c6/00:10:00:00:00:a0 (SET MULTIPLE MODE) succeeded [37141.688278] ata3.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [37141.712268] ata3.00: configured for UDMA/133 [37141.712458] PM: resume of drv:scsi dev:host2 complete after 7232.639 msecs [37141.712461] PM: resume of drv:ata_port dev:ata3 complete after 6704.434 msecs [37141.712468] PM: resume of drv:scsi_host dev:host2 complete after 7232.643 msecs [37141.712479] PM: resume of drv:scsi dev:target2:0:0 complete after 7232.090 msecs [37141.712491] PM: resume of drv:sd dev:2:0:0:0 complete after 7232.089 msecs [37141.712493] sd 2:0:0:0: [sdc] Starting disk [37141.728368] PM: resume of drv:scsi_device dev:2:0:0:0 complete after 7247.953 msecs [37144.744007] [37144.744008] floppy driver state [37144.744008] ------------------- [37144.744026] now=4304178482 last interrupt=4294892575 diff=9285907 last called handler=reset_interrupt [floppy] [37144.744027] timeout_message=lock fdc [37144.744027] last output bytes: [37144.744028] 0 0 0 [37144.744029] 0 0 0 [37144.744029] 0 0 0 [37144.744030] 8 80 4294892574 [37144.744030] 8 90 4294892574 [37144.744031] 8 80 4294892574 [37144.744032] 8 90 4294892574 [37144.744032] e 80 4294892574 [37144.744033] 13 80 4294892574 [37144.744033] 0 90 4294892574 [37144.744034] 1a 90 4294892574 [37144.744034] 0 90 4294892574 [37144.744035] 12 90 4294892574 [37144.744036] 0 90 4294892574 [37144.744036] 14 90 4294892574 [37144.744037] 18 80 4294892574 [37144.744037] 8 80 4294892575 [37144.744038] 8 80 4294892575 [37144.744038] 8 80 4294892575 [37144.744039] 8 80 4294892575 [37144.744039] last result at 4294892575 [37144.744040] last redo_fd_request at 4301070874 [37144.744045] status=0 [37144.744045] fdc_busy=1 [37144.744048] do_floppy=reset_interrupt [floppy] [37144.744049] cont=ffffffffa0009ec0 [37144.744049] current_req= (null) [37144.744050] command_status=-1 [37144.744050] [37144.744054] floppy0: floppy timeout called [37144.744223] PM: resume of devices complete after 10264.666 msecs [37145.245929] PM: resume devices took 10.768 seconds [37145.245930] ------------[ cut here ]------------ [37145.245934] WARNING: at /build/buildd/linux-3.5.0/kernel/power/suspend_test.c:53 suspend_test_finish+0x86/0x90() [37145.245935] Hardware name: MS-7360 [37145.245936] Component: resume devices, time: 10768 [37145.245936] Modules linked in: nfnetlink_log nfnetlink dm_crypt pci_stub vboxpci(O) vboxnetadp(O) vboxnetflt(O) vboxdrv(O) snd_hda_codec_hdmi arc4 rt2800usb rt2800lib crc_ccitt rt2x00usb rt2x00lib mac80211 cfg80211 gpio_ich joydev nvidia(PO) ch341 usbserial snd_usb_audio uvcvideo snd_hda_codec_realtek videobuf2_core snd_usbmidi_lib hid_dr videodev btusb videobuf2_vmalloc videobuf2_memops hid_logitech ff_memless kvm_intel snd_hda_intel snd_hda_codec kvm snd_hwdep snd_pcm snd_seq_midi psmouse microcode snd_rawmidi serio_raw snd_seq_midi_event snd_seq snd_timer snd_seq_device rfcomm bnep mac_hid snd lpc_ich bluetooth soundcore parport_pc snd_page_alloc ppdev f71882fg coretemp adt7475 hwmon_vid nfsd nfs lp parport lockd fscache auth_rpcgss nfs_acl sunrpc binfmt_misc hid_generic usbhid hid e1000 floppy [last unloaded: ac97_bus] [37145.245975] Pid: 27135, comm: s2ram Tainted: P W O 3.5.0-19-generic #30-Ubuntu [37145.245976] Call Trace: [37145.245978] [] warn_slowpath_common+0x7f/0xc0 [37145.245981] [] warn_slowpath_fmt+0x46/0x50 [37145.245983] [] suspend_test_finish+0x86/0x90 [37145.245985] [] suspend_devices_and_enter+0xd8/0x310 [37145.245986] [] pm_suspend+0x1f6/0x210 [37145.245988] [] state_store+0x87/0xf0 [37145.245990] [] kobj_attr_store+0xf/0x20 [37145.245993] [] sysfs_write_file+0xdc/0x150 [37145.245995] [] vfs_write+0xac/0x180 [37145.245998] [] sys_write+0x4a/0x90 [37145.245999] [] system_call_fastpath+0x16/0x1b [37145.246003] ---[ end trace aae2b01f7d432c22 ]--- [37145.246112] PM: Finishing wakeup. [37145.246113] Restarting tasks ... done. [37145.292209] set resolution quirk: cval->res = 384 [37156.117524] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready [37156.126928] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [37156.141716] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX [37156.142742] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [42089.676125] PM: Syncing filesystems ... done. [42089.699575] PM: Preparing system for mem sleep [42090.122951] Freezing user space processes ... (elapsed 0.01 seconds) done. [42090.140169] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [42090.156155] PM: Entering mem sleep [42090.156182] Suspending console(s) (use no_console_suspend to debug) [42090.156998] sd 2:0:0:0: [sdc] Synchronizing SCSI cache [42090.157020] sd 1:0:0:0: [sdb] Synchronizing SCSI cache [42090.157055] sd 0:0:0:0: [sda] Synchronizing SCSI cache [42090.157132] sd 1:0:0:0: [sdb] Stopping disk [42090.157143] sd 0:0:0:0: [sda] Stopping disk [42090.157146] sd 2:0:0:0: [sdc] Stopping disk [42090.198263] i8042 aux 00:09: wake-up capability disabled by ACPI [42090.198296] i8042 kbd 00:08: wake-up capability enabled by ACPI [42090.198435] serial 00:06: disabled [42090.198596] pci 0000:00:1e.0: wake-up capability enabled by ACPI [42090.300099] ACPI handle has no context! [42090.316009] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 117.186 msecs [42090.644042] ACPI handle has no context! [42090.660009] PM: suspend of drv:snd_hda_intel dev:0000:01:00.1 complete after 461.541 msecs [42090.660021] PM: suspend of drv:pcieport dev:0000:00:01.0 complete after 461.088 msecs [42090.733817] PM: suspend of drv:sd dev:2:0:0:0 complete after 576.828 msecs [42090.733828] PM: suspend of drv:scsi dev:target2:0:0 complete after 576.838 msecs [42090.733837] PM: suspend of drv:scsi dev:host2 complete after 535.911 msecs [42090.733857] PM: suspend of drv: dev:ata3 complete after 535.915 msecs [42090.748009] PM: suspend of drv:ata_piix dev:0000:00:1f.5 complete after 549.370 msecs [42090.748014] PM: suspend of drv: dev:pci0000:00 complete after 549.061 msecs [42090.748019] PM: suspend of devices complete after 591.550 msecs [42090.748020] PM: suspend devices took 0.592 seconds [42090.748287] PM: late suspend of devices complete after 0.265 msecs [42090.748501] ehci_hcd 0000:00:1d.7: wake-up capability enabled by ACPI [42090.764055] uhci_hcd 0000:00:1d.3: wake-up capability enabled by ACPI [42090.764086] uhci_hcd 0000:00:1d.2: wake-up capability enabled by ACPI [42090.764115] uhci_hcd 0000:00:1d.1: wake-up capability enabled by ACPI [42090.764144] uhci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI [42090.764174] ehci_hcd 0000:00:1a.7: wake-up capability enabled by ACPI [42090.780037] uhci_hcd 0000:00:1a.1: wake-up capability enabled by ACPI [42090.780067] uhci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI [42090.780113] PM: noirq suspend of devices complete after 31.824 msecs [42090.780319] ACPI: Preparing to enter system sleep state S3 [42090.780468] PM: Saving platform NVS memory [42090.780793] Disabling non-boot CPUs ... [42090.884006] CPU 1 is now offline [42090.988010] CPU 2 is now offline [42091.092010] CPU 3 is now offline [42091.092266] ACPI: Low-level resume complete [42091.092266] PM: Restoring platform NVS memory [42091.092266] Enabling non-boot CPUs ... [42091.092266] Booting Node 0 Processor 1 APIC 0x1 [42091.108065] CPU1 is up [42091.108122] Booting Node 0 Processor 2 APIC 0x2 [42091.124051] CPU2 is up [42091.124148] Booting Node 0 Processor 3 APIC 0x3 [42091.140053] CPU3 is up [42091.142432] ACPI: Waking up from system sleep state S3 [42091.142777] uhci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI [42091.142811] uhci_hcd 0000:00:1a.1: wake-up capability disabled by ACPI [42091.142847] ehci_hcd 0000:00:1a.7: wake-up capability disabled by ACPI [42091.142912] uhci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI [42091.142946] uhci_hcd 0000:00:1d.1: wake-up capability disabled by ACPI [42091.142980] uhci_hcd 0000:00:1d.2: wake-up capability disabled by ACPI [42091.143014] uhci_hcd 0000:00:1d.3: wake-up capability disabled by ACPI [42091.143050] ehci_hcd 0000:00:1d.7: wake-up capability disabled by ACPI [42091.143454] PM: noirq resume of devices complete after 0.802 msecs [42091.143553] PM: early resume of devices complete after 0.072 msecs [42091.143606] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [42091.143617] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [42091.143619] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [42091.143634] usb usb3: root hub lost power or was reset [42091.143645] usb usb4: root hub lost power or was reset [42091.143679] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [42091.143682] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [42091.143712] usb usb6: root hub lost power or was reset [42091.143715] usb usb5: root hub lost power or was reset [42091.143718] snd_hda_intel 0000:00:1b.0: irq 41 for MSI/MSI-X [42091.143728] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [42091.143737] uhci_hcd 0000:00:1d.3: setting latency timer to 64 [42091.143754] usb usb7: root hub lost power or was reset [42091.143765] usb usb8: root hub lost power or was reset [42091.143779] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [42091.143784] pci 0000:00:1e.0: setting latency timer to 64 [42091.143902] ata_piix 0000:00:1f.2: setting latency timer to 64 [42091.143981] ata_piix 0000:00:1f.5: setting latency timer to 64 [42091.144000] pci 0000:00:1e.0: wake-up capability disabled by ACPI [42091.146563] serial 00:06: activated [42091.147396] i8042 kbd 00:08: wake-up capability disabled by ACPI [42091.264033] PM: resume of drv:hub dev:8-0:1.0 complete after 118.758 msecs [42091.264035] PM: resume of drv:hub dev:7-0:1.0 complete after 118.825 msecs [42091.264042] PM: resume of drv: dev:ep_00 complete after 118.737 msecs [42091.264043] PM: resume of drv: dev:ep_00 complete after 118.802 msecs [42091.264048] PM: resume of drv:hub dev:6-0:1.0 complete after 118.896 msecs [42091.264050] PM: resume of drv: dev:ep_81 complete after 118.756 msecs [42091.264051] PM: resume of drv: dev:ep_81 complete after 118.824 msecs [42091.264054] PM: resume of drv: dev:ep_00 complete after 118.879 msecs [42091.264063] PM: resume of drv: dev:ep_81 complete after 118.903 msecs [42091.264091] PM: resume of drv: dev:ep_00 complete after 119.055 msecs [42091.264092] PM: resume of drv:hub dev:4-0:1.0 complete after 120.010 msecs [42091.264101] PM: resume of drv: dev:ep_81 complete after 119.984 msecs [42091.264105] PM: resume of drv:hub dev:5-0:1.0 complete after 119.025 msecs [42091.264111] PM: resume of drv: dev:ep_00 complete after 118.999 msecs [42091.264113] PM: resume of drv:hub dev:3-0:1.0 complete after 120.052 msecs [42091.264121] PM: resume of drv: dev:ep_81 complete after 119.025 msecs [42091.264123] PM: resume of drv: dev:ep_00 complete after 120.049 msecs [42091.264130] PM: resume of drv: dev:ep_81 complete after 120.061 msecs [42091.268248] PM: resume of drv:rt2800usb dev:2-7.2:1.0 complete after 120.839 msecs [42091.268250] PM: resume of drv: dev:ep_00 complete after 120.734 msecs [42091.268257] PM: resume of drv: dev:ep_81 complete after 120.835 msecs [42091.268259] PM: resume of drv: dev:ep_01 complete after 120.824 msecs [42091.268260] PM: resume of drv: dev:ep_02 complete after 120.813 msecs [42091.268262] PM: resume of drv: dev:ep_04 complete after 120.786 msecs [42091.268263] PM: resume of drv: dev:ep_03 complete after 120.800 msecs [42091.268265] PM: resume of drv: dev:ep_06 complete after 120.763 msecs [42091.268266] PM: resume of drv: dev:ep_05 complete after 120.777 msecs [42091.268500] PM: resume of drv:usbhid dev:2-6.3:1.0 complete after 122.666 msecs [42091.268507] PM: resume of drv: dev:ep_00 complete after 122.409 msecs [42091.268511] PM: resume of drv: dev:ep_81 complete after 122.450 msecs [42091.268514] PM: resume of drv: dev:ep_01 complete after 122.436 msecs [42091.268748] PM: resume of drv:usb dev:2-6.1.1 complete after 121.217 msecs [42091.268750] PM: resume of drv:hub dev:2-6.1:1.0 complete after 122.979 msecs [42091.268752] PM: resume of drv:usb dev:2-6.1.4 complete after 121.073 msecs [42091.268753] PM: resume of drv:usb dev:2-6.1.2 complete after 121.171 msecs [42091.268756] PM: resume of drv: dev:ep_00 complete after 122.956 msecs [42091.268763] PM: resume of drv: dev:ep_81 complete after 122.978 msecs [42091.316328] PM: resume of drv:ch341 dev:2-6.1.4:1.0 complete after 168.638 msecs [42091.316339] PM: resume of drv: dev:ep_00 complete after 168.595 msecs [42091.316341] PM: resume of drv: dev:ep_82 complete after 168.637 msecs [42091.316343] PM: resume of drv: dev:ep_02 complete after 168.627 msecs [42091.316351] PM: resume of drv: dev:ep_81 complete after 168.622 msecs [42091.316360] PM: resume of drv:ch341-uart dev:ttyUSB0 complete after 168.500 msecs [42091.316699] PM: resume of drv:hub dev:2-6.1.1:1.0 complete after 169.156 msecs [42091.316701] PM: resume of drv:usb dev:2-6.1.1.2 complete after 168.945 msecs [42091.316708] PM: resume of drv: dev:ep_00 complete after 169.137 msecs [42091.316709] PM: resume of drv:usb dev:2-6.1.1.3 complete after 168.900 msecs [42091.316711] PM: resume of drv: dev:ep_81 complete after 169.153 msecs [42091.328013] usb 1-2: reset high-speed USB device number 2 using ehci_hcd [42091.364305] PM: resume of drv:usbhid dev:2-6.1.1.3:1.0 complete after 216.484 msecs [42091.364307] PM: resume of drv: dev:ep_00 complete after 216.460 msecs [42091.364310] PM: resume of drv: dev:ep_81 complete after 216.477 msecs [42091.364408] PM: resume of drv:usbhid dev:2-6.1.1.2:1.0 complete after 216.639 msecs [42091.364409] PM: resume of drv: dev:ep_00 complete after 216.614 msecs [42091.364413] PM: resume of drv: dev:ep_81 complete after 216.633 msecs [42091.371569] PM: resume of drv:btusb dev:2-6.1.2:1.0 complete after 223.972 msecs [42091.371570] PM: resume of drv: dev:ep_00 complete after 223.905 msecs [42091.371578] PM: resume of drv:btusb dev:2-6.1.2:1.1 complete after 223.928 msecs [42091.371585] PM: resume of drv: dev:ep_81 complete after 223.976 msecs [42091.371588] PM: resume of drv: dev:ep_03 complete after 223.713 msecs [42091.371589] PM: resume of drv: dev:ep_83 complete after 223.703 msecs [42091.371591] PM: resume of drv: dev:ep_02 complete after 223.966 msecs [42091.371592] PM: resume of drv: dev:ep_82 complete after 223.957 msecs [42091.620039] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [42091.624044] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [42091.624049] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [42091.628114] ata2.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [42091.628116] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [42091.629801] ata2.00: ACPI cmd c6/00:01:00:00:00:a0 (SET MULTIPLE MODE) succeeded [42091.629804] ata2.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [42091.632114] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [42091.632116] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [42091.633849] ata1.00: ACPI cmd c6/00:01:00:00:00:a0 (SET MULTIPLE MODE) succeeded [42091.633851] ata1.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [42091.648123] ata4.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [42091.648125] ata4.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [42091.648126] ata4.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [42091.652178] ata2.00: configured for UDMA/133 [42091.652201] PM: resume of drv:scsi dev:host1 complete after 508.264 msecs [42091.652212] PM: resume of drv:scsi_host dev:host1 complete after 508.267 msecs [42091.652218] PM: resume of drv:scsi dev:target1:0:0 complete after 506.852 msecs [42091.652225] PM: resume of drv:sd dev:1:0:0:0 complete after 506.842 msecs [42091.652228] sd 1:0:0:0: [sdb] Starting disk [42091.652350] PM: resume of drv:scsi_device dev:1:0:0:0 complete after 506.952 msecs [42091.656192] ata1.00: configured for UDMA/133 [42091.656211] PM: resume of drv:scsi dev:host0 complete after 512.301 msecs [42091.656213] PM: resume of drv:ata_port dev:ata1 complete after 508.689 msecs [42091.656218] PM: resume of drv:scsi_host dev:host0 complete after 512.307 msecs [42091.656227] PM: resume of drv:scsi dev:target0:0:0 complete after 510.907 msecs [42091.656236] PM: resume of drv:sd dev:0:0:0:0 complete after 510.901 msecs [42091.656238] sd 0:0:0:0: [sda] Starting disk [42091.656385] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 511.034 msecs [42091.680111] ata4.00: configured for UDMA/33 [42091.682203] snd-usb-audio 1-2:1.2: no reset_resume for driver snd-usb-audio? [42091.682204] snd-usb-audio 1-2:1.3: no reset_resume for driver snd-usb-audio? [42091.682367] PM: resume of drv:uvcvideo dev:1-2:1.0 complete after 536.841 msecs [42091.682370] PM: resume of drv:usb dev:1-2:1.2 complete after 536.796 msecs [42091.682372] PM: resume of drv:uvcvideo dev:1-2:1.1 complete after 536.814 msecs [42091.682374] PM: resume of drv: dev:ep_00 complete after 536.770 msecs [42091.682376] PM: resume of drv:usb dev:1-2:1.3 complete after 536.787 msecs [42091.682388] PM: resume of drv: dev:ep_87 complete after 536.845 msecs [42091.758444] PM: resume of drv:scsi dev:host3 complete after 614.461 msecs [42091.758453] PM: resume of drv:scsi_host dev:host3 complete after 614.464 msecs [42091.758455] PM: resume of drv:scsi dev:target3:0:0 complete after 612.991 msecs [42091.758460] PM: resume of drv:sr dev:3:0:0:0 complete after 612.981 msecs [42091.758466] PM: resume of drv:scsi_device dev:3:0:0:0 complete after 612.972 msecs [42096.660006] ata3: link is slow to respond, please be patient (ready=0) [42098.340035] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [42098.348109] ata3.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [42098.348111] ata3.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [42098.348275] ata3.00: ACPI cmd c6/00:10:00:00:00:a0 (SET MULTIPLE MODE) succeeded [42098.348278] ata3.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [42098.372269] ata3.00: configured for UDMA/133 [42098.372459] PM: resume of drv:scsi dev:host2 complete after 7228.497 msecs [42098.372468] PM: resume of drv:scsi_host dev:host2 complete after 7228.501 msecs [42098.372470] PM: resume of drv:scsi dev:target2:0:0 complete after 7227.052 msecs [42098.372476] PM: resume of drv:sd dev:2:0:0:0 complete after 7227.045 msecs [42098.372479] sd 2:0:0:0: [sdc] Starting disk [42098.372486] PM: resume of drv:ata_port dev:ata3 complete after 6716.258 msecs [42098.383743] PM: resume of drv:scsi_device dev:2:0:0:0 complete after 7238.295 msecs [42101.400005] [42101.400006] floppy driver state [42101.400006] ------------------- [42101.400025] now=4305417646 last interrupt=4294892575 diff=10525071 last called handler=reset_interrupt [floppy] [42101.400025] timeout_message=lock fdc [42101.400026] last output bytes: [42101.400027] 0 0 0 [42101.400027] 0 0 0 [42101.400028] 0 0 0 [42101.400029] 8 80 4294892574 [42101.400029] 8 90 4294892574 [42101.400030] 8 80 4294892574 [42101.400030] 8 90 4294892574 [42101.400031] e 80 4294892574 [42101.400031] 13 80 4294892574 [42101.400032] 0 90 4294892574 [42101.400033] 1a 90 4294892574 [42101.400033] 0 90 4294892574 [42101.400034] 12 90 4294892574 [42101.400034] 0 90 4294892574 [42101.400035] 14 90 4294892574 [42101.400035] 18 80 4294892574 [42101.400036] 8 80 4294892575 [42101.400037] 8 80 4294892575 [42101.400037] 8 80 4294892575 [42101.400038] 8 80 4294892575 [42101.400038] last result at 4294892575 [42101.400039] last redo_fd_request at 4304178482 [42101.400044] status=0 [42101.400044] fdc_busy=1 [42101.400047] do_floppy=reset_interrupt [floppy] [42101.400048] cont=ffffffffa0009ec0 [42101.400048] current_req= (null) [42101.400049] command_status=-1 [42101.400049] [42101.400052] floppy0: floppy timeout called [42101.400217] PM: resume of devices complete after 10256.657 msecs [42101.899106] PM: resume devices took 10.756 seconds [42101.899107] ------------[ cut here ]------------ [42101.899111] WARNING: at /build/buildd/linux-3.5.0/kernel/power/suspend_test.c:53 suspend_test_finish+0x86/0x90() [42101.899112] Hardware name: MS-7360 [42101.899112] Component: resume devices, time: 10756 [42101.899113] Modules linked in: nfnetlink_log nfnetlink dm_crypt pci_stub vboxpci(O) vboxnetadp(O) vboxnetflt(O) vboxdrv(O) snd_hda_codec_hdmi arc4 rt2800usb rt2800lib crc_ccitt rt2x00usb rt2x00lib mac80211 cfg80211 gpio_ich joydev nvidia(PO) ch341 usbserial snd_usb_audio uvcvideo snd_hda_codec_realtek videobuf2_core snd_usbmidi_lib hid_dr videodev btusb videobuf2_vmalloc videobuf2_memops hid_logitech ff_memless kvm_intel snd_hda_intel snd_hda_codec kvm snd_hwdep snd_pcm snd_seq_midi psmouse microcode snd_rawmidi serio_raw snd_seq_midi_event snd_seq snd_timer snd_seq_device rfcomm bnep mac_hid snd lpc_ich bluetooth soundcore parport_pc snd_page_alloc ppdev f71882fg coretemp adt7475 hwmon_vid nfsd nfs lp parport lockd fscache auth_rpcgss nfs_acl sunrpc binfmt_misc hid_generic usbhid hid e1000 floppy [last unloaded: ac97_bus] [42101.899147] Pid: 2769, comm: s2ram Tainted: P W O 3.5.0-19-generic #30-Ubuntu [42101.899148] Call Trace: [42101.899150] [] warn_slowpath_common+0x7f/0xc0 [42101.899153] [] warn_slowpath_fmt+0x46/0x50 [42101.899155] [] suspend_test_finish+0x86/0x90 [42101.899157] [] suspend_devices_and_enter+0xd8/0x310 [42101.899159] [] pm_suspend+0x1f6/0x210 [42101.899160] [] state_store+0x87/0xf0 [42101.899162] [] kobj_attr_store+0xf/0x20 [42101.899165] [] sysfs_write_file+0xdc/0x150 [42101.899168] [] vfs_write+0xac/0x180 [42101.899170] [] sys_write+0x4a/0x90 [42101.899171] [] system_call_fastpath+0x16/0x1b [42101.899175] ---[ end trace aae2b01f7d432c23 ]--- [42101.899295] PM: Finishing wakeup. [42101.899296] Restarting tasks ... done. [42101.948267] set resolution quirk: cval->res = 384 [42112.769421] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready [42112.773584] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [42112.793789] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX [42112.794807] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [42162.292012] Bluetooth: hci0 command tx timeout [42209.997073] audit_printk_skb: 5 callbacks suppressed [42209.997077] type=1701 audit(1354496017.358:24): auid=4294967295 uid=1000 gid=1000 ses=4294967295 pid=4191 comm="chromium-browse" reason="seccomp" sig=0 syscall=39 compat=0 ip=0x7f1b90e6eed9 code=0x50000 [42210.764982] type=1701 audit(1354496018.126:25): auid=4294967295 uid=1000 gid=1000 ses=4294967295 pid=4252 comm="chromium-browse" reason="seccomp" sig=0 syscall=39 compat=0 ip=0x7fdfe9844ed9 code=0x50000