[ 17.508401] r8169: eth0: link up [ 17.508406] r8169: eth0: link up [ 22.652799] svc: failed to register lockdv1 RPC service (errno 97). [ 22.693110] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory [ 22.712123] NFSD: starting 90-second grace period [ 25.766802] vboxdrv: Trying to deactivate the NMI watchdog permanently... [ 25.766805] vboxdrv: Warning: 2.6.31+ kernel detected. Most likely the hardware performance [ 25.766805] vboxdrv: counter framework which can generate NMIs is active. You have to prevent [ 25.766806] vboxdrv: the usage of hardware performance counters by [ 25.766807] vboxdrv: echo 2 > /proc/sys/kernel/perf_counter_paranoid [ 25.766810] vboxdrv: Found 2 processor cores. [ 25.767130] vboxdrv: fAsync=0 offMin=0x1cc offMax=0x1eae [ 25.767334] vboxdrv: TSC mode is 'synchronous', kernel timer mode is 'normal'. [ 25.767336] vboxdrv: Successfully loaded version 3.1.4 (interface 0x00100001). [ 27.120235] ppdev: user-space parallel port driver [ 27.652007] eth0: no IPv6 routers present [ 29.759598] usb 3-2: usbfs: interface 0 claimed by usblp while 'usb' sets config #1 [23911.449291] r8169: eth0: link down [31758.112178] PM: Syncing filesystems ... done. [31758.284010] PM: Preparing system for mem sleep [31758.284013] Freezing user space processes ... (elapsed 0.00 seconds) done. [31758.284420] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [31758.284457] PM: Entering mem sleep [31758.284465] Suspending console(s) (use no_console_suspend to debug) [31758.332018] sd 0:0:0:0: [sda] Synchronizing SCSI cache [31758.337459] sd 0:0:0:0: [sda] Stopping disk [31758.339170] ACPI handle has no context! [31758.546899] PM: suspend of drv:nvidia dev:0000:01:00.0 complete after 207.711 msecs [31758.546978] ata_piix 0000:00:1f.5: PCI INT B disabled [31758.560108] ata_piix 0000:00:1f.2: PCI INT B disabled [31758.576018] ehci_hcd 0000:00:1d.7: PCI INT A disabled [31758.576026] uhci_hcd 0000:00:1d.2: PCI INT C disabled [31758.576033] uhci_hcd 0000:00:1d.1: PCI INT B disabled [31758.576039] uhci_hcd 0000:00:1d.0: PCI INT A disabled [31758.680065] HDA Intel 0000:00:1b.0: PCI INT A disabled [31758.696010] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.960 msecs [31758.696018] ehci_hcd 0000:00:1a.7: PCI INT C disabled [31758.696025] uhci_hcd 0000:00:1a.2: PCI INT C disabled [31758.696032] uhci_hcd 0000:00:1a.1: PCI INT B disabled [31758.696038] uhci_hcd 0000:00:1a.0: PCI INT A disabled [31758.696098] PM: suspend of devices complete after 411.412 msecs [31758.696101] PM: suspend devices took 0.412 seconds [31758.696225] r8169 0000:04:00.0: PME# enabled [31758.744111] PM: late suspend of devices complete after 48.006 msecs [31758.744628] ACPI: Preparing to enter system sleep state S3 [31758.760008] Disabling non-boot CPUs ... [31758.760025] CPU0 attaching NULL sched-domain. [31758.760029] CPU1 attaching NULL sched-domain. [31758.824010] CPU0 attaching NULL sched-domain. [31758.825141] Breaking affinity for irq 16 [31758.825150] Breaking affinity for irq 22 [31758.825174] Breaking affinity for irq 28 [31758.928011] CPU 1 is now offline [31758.928014] SMP alternatives: switching to UP code [31758.932743] Back to C! [31758.932743] CPU0: Thermal monitoring enabled (TM2) [31758.932743] Enabling non-boot CPUs ... [31758.932743] SMP alternatives: switching to SMP code [31758.937073] Booting processor 1 APIC 0x1 ip 0x6000 [31758.932421] Initializing CPU#1 [31758.932421] CPU: L1 I cache: 32K, L1 D cache: 32K [31758.932421] CPU: L2 cache: 3072K [31758.932421] CPU: Physical Processor ID: 0 [31758.932421] CPU: Processor Core ID: 1 [31758.932421] CPU1: Thermal monitoring enabled (TM2) [31759.028073] CPU1: Intel(R) Core(TM)2 Duo CPU E7600 @ 3.06GHz stepping 0a [31759.028081] checking TSC synchronization [CPU#0 -> CPU#1]: passed. [31759.048140] CPU0 attaching NULL sched-domain. [31759.076011] CPU0 attaching sched-domain: [31759.076014] domain 0: span 0-1 level MC [31759.076017] groups: 0 1 [31759.076022] CPU1 attaching sched-domain: [31759.076024] domain 0: span 0-1 level MC [31759.076027] groups: 1 0 [31759.076392] CPU1 is up [31759.076812] ACPI: Waking up from system sleep state S3 [31759.077754] HDA Intel 0000:00:1b.0: restoring config space at offset 0xf (was 0x100, writing 0x10a) [31759.077765] HDA Intel 0000:00:1b.0: restoring config space at offset 0x4 (was 0x4, writing 0xf9100004) [31759.077768] HDA Intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [31759.077772] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100002) [31759.077790] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [31759.077797] pcieport 0000:00:1c.0: restoring config space at offset 0x9 (was 0x10001, writing 0x80318021) [31759.077800] pcieport 0000:00:1c.0: restoring config space at offset 0x8 (was 0x0, writing 0x80108000) [31759.077803] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0x20000000, writing 0x9090) [31759.077808] pcieport 0000:00:1c.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810008) [31759.077812] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [31759.077837] pcieport 0000:00:1c.3: restoring config space at offset 0xf (was 0x400, writing 0x40f) [31759.077844] pcieport 0000:00:1c.3: restoring config space at offset 0x9 (was 0x10001, writing 0x80518041) [31759.077847] pcieport 0000:00:1c.3: restoring config space at offset 0x8 (was 0x0, writing 0xf900f900) [31759.077850] pcieport 0000:00:1c.3: restoring config space at offset 0x7 (was 0x20000000, writing 0xb0b0) [31759.077855] pcieport 0000:00:1c.3: restoring config space at offset 0x3 (was 0x810000, writing 0x810008) [31759.077859] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [31759.077884] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x100, writing 0x10b) [31759.077891] pcieport 0000:00:1c.4: restoring config space at offset 0x9 (was 0x10001, writing 0x80718061) [31759.077894] pcieport 0000:00:1c.4: restoring config space at offset 0x8 (was 0x0, writing 0xf8f0f700) [31759.077897] pcieport 0000:00:1c.4: restoring config space at offset 0x7 (was 0xf0f0, writing 0xc0c0) [31759.077903] pcieport 0000:00:1c.4: restoring config space at offset 0x3 (was 0x810000, writing 0x810008) [31759.077906] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [31759.078076] ata_piix 0000:00:1f.2: restoring config space at offset 0x8 (was 0xf001, writing 0xda01) [31759.078119] ata_piix 0000:00:1f.5: restoring config space at offset 0x1 (was 0x2b00003, writing 0x2b00007) [31759.078136] nvidia 0000:01:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [31759.078143] nvidia 0000:01:00.0: restoring config space at offset 0x7 (was 0x4, writing 0xf5000004) [31759.078147] nvidia 0000:01:00.0: restoring config space at offset 0x5 (was 0xc, writing 0xe000000c) [31759.078149] nvidia 0000:01:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xf4000000) [31759.078154] nvidia 0000:01:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) [31759.078190] r8169 0000:04:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [31759.078206] r8169 0000:04:00.0: restoring config space at offset 0x6 (was 0x4, writing 0xf8000004) [31759.078212] r8169 0000:04:00.0: restoring config space at offset 0x4 (was 0xfc01, writing 0xc001) [31759.078217] r8169 0000:04:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [31759.078223] r8169 0000:04:00.0: restoring config space at offset 0x1 (was 0x100003, writing 0x100407) [31759.078289] PM: early resume of devices complete after 0.687 msecs [31759.078342] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [31759.078346] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [31759.078363] usb usb3: root hub lost power or was reset [31759.078394] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [31759.078397] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [31759.078414] usb usb4: root hub lost power or was reset [31759.078427] uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [31759.078431] uhci_hcd 0000:00:1a.2: setting latency timer to 64 [31759.078447] usb usb5: root hub lost power or was reset [31759.078460] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [31759.078464] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [31759.078470] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [31759.078474] HDA Intel 0000:00:1b.0: setting latency timer to 64 [31759.172162] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [31759.172168] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [31759.172187] usb usb6: root hub lost power or was reset [31759.172211] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [31759.172214] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [31759.172231] usb usb7: root hub lost power or was reset [31759.172246] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [31759.172250] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [31759.172267] usb usb8: root hub lost power or was reset [31759.172280] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [31759.172287] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [31759.172293] pci 0000:00:1e.0: setting latency timer to 64 [31759.172299] ata_piix 0000:00:1f.2: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [31759.172301] ata_piix 0000:00:1f.2: setting latency timer to 64 [31759.173017] ata_piix 0000:00:1f.5: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [31759.173020] ata_piix 0000:00:1f.5: setting latency timer to 64 [31759.463181] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 289.585 msecs [31759.463242] r8169 0000:04:00.0: PME# disabled [31759.578550] ata2: SATA link down (SStatus 4 SControl 300) [31759.589065] ata3: SATA link down (SStatus 4 SControl 300) [31759.599598] ata4: SATA link down (SStatus 4 SControl 300) [31759.820011] PM: resume of drv:usb dev:usb3 complete after 255.984 msecs [31760.068014] PM: resume of drv:usb dev:usb6 complete after 247.988 msecs [31760.070091] sd 0:0:0:0: [sda] Starting disk [31764.772010] ata1: link is slow to respond, please be patient (ready=0) [31769.196011] ata1: SRST failed (errno=-16) [31770.176044] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [31770.184198] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [31770.184201] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [31770.184203] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [31770.192312] ata1.00: configured for UDMA/133 [31770.198404] PM: resume of drv:sd dev:0:0:0:0 complete after 10128.308 msecs [31770.456023] usb 3-1: reset low speed USB device using uhci_hcd and address 2 [31770.763588] PM: resume of drv:usb dev:3-1 complete after 565.171 msecs [31770.763596] input input3: event field not found [31770.763598] input input3: event field not found [31770.763600] input input3: event field not found [31770.763601] input input3: event field not found [31770.763603] input input3: event field not found [31770.763605] input input3: event field not found [31770.763606] input input3: event field not found [31770.872011] usb 3-2: reset full speed USB device using uhci_hcd and address 3 [31771.021588] usblp 3-2:1.0: no reset_resume for driver usblp? [31771.021681] usblp0: removed [31771.021687] PM: resume of drv:usb dev:3-2 complete after 258.075 msecs [31771.132012] usb 6-2: reset full speed USB device using uhci_hcd and address 2 [31771.286982] cdc_acm 6-2:1.1: no reset_resume for driver cdc_acm? [31771.286984] cdc_acm 6-2:1.2: no reset_resume for driver cdc_acm? [31771.286987] cdc_acm 6-2:1.3: no reset_resume for driver cdc_acm? [31771.286989] cdc_acm 6-2:1.4: no reset_resume for driver cdc_acm? [31771.287186] PM: resume of drv:usb dev:6-2 complete after 265.493 msecs [31771.287229] PM: resume of devices complete after 12208.917 msecs [31771.287267] cdc_acm 6-2:1.1: ttyACM0: USB ACM device [31771.289053] cdc_acm 6-2:1.3: ttyACM1: USB ACM device [31771.294625] usblp0: USB Bidirectional printer dev 3 if 0 alt 0 proto 2 vid 0x04B8 pid 0x0005 [31771.294810] PM: resume devices took 12.216 seconds [31771.294811] ------------[ cut here ]------------ [31771.294816] WARNING: at /build/buildd/linux-2.6.32/kernel/power/suspend_test.c:53 suspend_test_finish+0x89/0x90() [31771.294818] Hardware name: EP35-DS3 [31771.294819] Component: resume devices, time: 12216 [31771.294820] Modules linked in: binfmt_misc ppdev vboxnetadp vboxnetflt vboxdrv nfsd exportfs nfs lockd nfs_acl auth_rpcgss sunrpc snd_hda_codec_realtek cdc_acm cdc_wdm snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss fbcon tileblit snd_seq_midi font snd_rawmidi bitblit softcursor snd_seq_midi_event psmouse nvidia(P) snd_seq snd_timer snd_seq_device vga16fb snd vgastate intel_agp agpgart soundcore snd_page_alloc usblp serio_raw lp parport usb_storage usbhid hid r8169 mii [31771.294844] Pid: 4109, comm: pm-suspend Tainted: P 2.6.32-16-generic #25-Ubuntu [31771.294845] Call Trace: [31771.294849] [] warn_slowpath_common+0x72/0xa0 [31771.294851] [] ? suspend_test_finish+0x89/0x90 [31771.294853] [] ? suspend_test_finish+0x89/0x90 [31771.294855] [] warn_slowpath_fmt+0x2b/0x30 [31771.294857] [] suspend_test_finish+0x89/0x90 [31771.294859] [] suspend_devices_and_enter+0xa1/0xd0 [31771.294862] [] ? printk+0x1d/0x22 [31771.294864] [] enter_state+0xbd/0xf0 [31771.294866] [] state_store+0x75/0xc0 [31771.294868] [] ? state_store+0x0/0xc0 [31771.294870] [] kobj_attr_store+0x20/0x30 [31771.294873] [] sysfs_write_file+0x95/0x100 [31771.294875] [] vfs_write+0xa2/0x1a0 [31771.294877] [] ? sysfs_write_file+0x0/0x100 [31771.294879] [] ? do_page_fault+0x152/0x380 [31771.294881] [] sys_write+0x42/0x70 [31771.294883] [] syscall_call+0x7/0xb [31771.294885] ---[ end trace 43b8f44b41c70d54 ]--- [31771.294910] PM: Finishing wakeup. [31771.294911] Restarting tasks ... done. [31771.381314] input: AT Raw Set 2 keyboard as /devices/platform/i8042/serio1/input/input5 [31771.596917] input: AT Raw Set 2 keyboard as /devices/platform/i8042/serio1/input/input6 [31772.014083] r8169: eth0: link down [31772.014317] ADDRCONF(NETDEV_UP): eth0: link is not ready [31773.598985] sd 4:0:0:0: [sdb] 960512 512-byte logical blocks: (491 MB/469 MiB) [31773.603982] sd 4:0:0:0: [sdb] Assuming drive cache: write through [31773.615983] sd 4:0:0:0: [sdb] Assuming drive cache: write through [31773.615988] sdb: sdb1 [31817.722319] padlock: VIA PadLock not detected. [31909.587224] r8169: eth0: link up [31909.587460] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [31920.392009] eth0: no IPv6 routers present [31962.307102] SysRq : SAK [31962.307118] SAK: killed process 1344 (Xorg): task_session(p)==tty->session [31962.307179] SAK: killed process 1026 (console-kit-dae): fd#11 opened to the tty [31962.307272] SAK: killed process 1036 (console-kit-dae): fd#11 opened to the tty [31962.307275] SAK: killed process 1039 (console-kit-dae): fd#11 opened to the tty [31962.307278] SAK: killed process 1040 (console-kit-dae): fd#11 opened to the tty [31962.307281] SAK: killed process 1041 (console-kit-dae): fd#11 opened to the tty [31962.307284] SAK: killed process 1042 (console-kit-dae): fd#11 opened to the tty [31962.307286] SAK: killed process 1043 (console-kit-dae): fd#11 opened to the tty [31962.307289] SAK: killed process 1044 (console-kit-dae): fd#11 opened to the tty [31962.307292] SAK: killed process 1045 (console-kit-dae): fd#11 opened to the tty [31962.307295] SAK: killed process 1046 (console-kit-dae): fd#11 opened to the tty [31962.307297] SAK: killed process 1047 (console-kit-dae): fd#11 opened to the tty [31962.307300] SAK: killed process 1048 (console-kit-dae): fd#11 opened to the tty [31962.307303] SAK: killed process 1049 (console-kit-dae): fd#11 opened to the tty [31962.307305] SAK: killed process 1050 (console-kit-dae): fd#11 opened to the tty [31962.307308] SAK: killed process 1051 (console-kit-dae): fd#11 opened to the tty [31962.307311] SAK: killed process 1052 (console-kit-dae): fd#11 opened to the tty [31962.307314] SAK: killed process 1053 (console-kit-dae): fd#11 opened to the tty [31962.307316] SAK: killed process 1054 (console-kit-dae): fd#11 opened to the tty [31962.307319] SAK: killed process 1055 (console-kit-dae): fd#11 opened to the tty [31962.307322] SAK: killed process 1056 (console-kit-dae): fd#11 opened to the tty [31962.307325] SAK: killed process 1057 (console-kit-dae): fd#11 opened to the tty [31962.307327] SAK: killed process 1058 (console-kit-dae): fd#11 opened to the tty [31962.307330] SAK: killed process 1059 (console-kit-dae): fd#11 opened to the tty [31962.307332] SAK: killed process 1060 (console-kit-dae): fd#11 opened to the tty [31962.307335] SAK: killed process 1061 (console-kit-dae): fd#11 opened to the tty [31962.307338] SAK: killed process 1062 (console-kit-dae): fd#11 opened to the tty [31962.307341] SAK: killed process 1063 (console-kit-dae): fd#11 opened to the tty [31962.307343] SAK: killed process 1064 (console-kit-dae): fd#11 opened to the tty [31962.307346] SAK: killed process 1065 (console-kit-dae): fd#11 opened to the tty [31962.307349] SAK: killed process 1066 (console-kit-dae): fd#11 opened to the tty [31962.307352] SAK: killed process 1067 (console-kit-dae): fd#11 opened to the tty [31962.307354] SAK: killed process 1068 (console-kit-dae): fd#11 opened to the tty [31962.307357] SAK: killed process 1069 (console-kit-dae): fd#11 opened to the tty [31962.307360] SAK: killed process 1070 (console-kit-dae): fd#11 opened to the tty [31962.307362] SAK: killed process 1071 (console-kit-dae): fd#11 opened to the tty [31962.307365] SAK: killed process 1072 (console-kit-dae): fd#11 opened to the tty [31962.307368] SAK: killed process 1073 (console-kit-dae): fd#11 opened to the tty [31962.307370] SAK: killed process 1074 (console-kit-dae): fd#11 opened to the tty [31962.307373] SAK: killed process 1075 (console-kit-dae): fd#11 opened to the tty [31962.307376] SAK: killed process 1076 (console-kit-dae): fd#11 opened to the tty [31962.307378] SAK: killed process 1077 (console-kit-dae): fd#11 opened to the tty [31962.307381] SAK: killed process 1078 (console-kit-dae): fd#11 opened to the tty [31962.307384] SAK: killed process 1079 (console-kit-dae): fd#11 opened to the tty [31962.307386] SAK: killed process 1080 (console-kit-dae): fd#11 opened to the tty [31962.307389] SAK: killed process 1081 (console-kit-dae): fd#11 opened to the tty [31962.307392] SAK: killed process 1082 (console-kit-dae): fd#11 opened to the tty [31962.307395] SAK: killed process 1083 (console-kit-dae): fd#11 opened to the tty [31962.307397] SAK: killed process 1084 (console-kit-dae): fd#11 opened to the tty [31962.307400] SAK: killed process 1085 (console-kit-dae): fd#11 opened to the tty [31962.307403] SAK: killed process 1086 (console-kit-dae): fd#11 opened to the tty [31962.307405] SAK: killed process 1087 (console-kit-dae): fd#11 opened to the tty [31962.307408] SAK: killed process 1088 (console-kit-dae): fd#11 opened to the tty [31962.307411] SAK: killed process 1089 (console-kit-dae): fd#11 opened to the tty [31962.307414] SAK: killed process 1090 (console-kit-dae): fd#11 opened to the tty [31962.307416] SAK: killed process 1091 (console-kit-dae): fd#11 opened to the tty [31962.307419] SAK: killed process 1092 (console-kit-dae): fd#11 opened to the tty [31962.307422] SAK: killed process 1093 (console-kit-dae): fd#11 opened to the tty [31962.307424] SAK: killed process 1094 (console-kit-dae): fd#11 opened to the tty [31962.307427] SAK: killed process 1095 (console-kit-dae): fd#11 opened to the tty [31962.307429] SAK: killed process 1096 (console-kit-dae): fd#11 opened to the tty [31962.307432] SAK: killed process 1097 (console-kit-dae): fd#11 opened to the tty [31962.307435] SAK: killed process 1098 (console-kit-dae): fd#11 opened to the tty [31962.307438] SAK: killed process 1107 (console-kit-dae): fd#11 opened to the tty [31962.307440] SAK: killed process 5103 (console-kit-dae): fd#11 opened to the tty [31962.307456] SAK: killed process 1344 (Xorg): task_session(p)==tty->session