[   47.511857] EXT4-fs (sdb1): re-mounted. Opts: commit=0
[   47.920087] eth0: no IPv6 routers present
[   53.046189] EXT4-fs (sdc1): re-mounted. Opts: commit=0
[   53.048545] EXT4-fs (sdb1): re-mounted. Opts: commit=0
[  497.963149] Intel AES-NI instructions are not detected.
[  498.054875] padlock_aes: VIA PadLock not detected.
[ 1635.004517] process `skype' is using obsolete setsockopt SO_BSDCOMPAT
[19447.109716] EXT4-fs (sdc1): re-mounted. Opts: commit=0
[19447.113082] EXT4-fs (sdb1): re-mounted. Opts: commit=0
[19449.534956] PM: Syncing filesystems ... done.
[19449.584477] PM: Preparing system for mem sleep
[19449.584488] Freezing user space processes ... (elapsed 0.02 seconds) done.
[19449.610062] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
[19449.630037] PM: Entering mem sleep
[19449.630089] Suspending console(s) (use no_console_suspend to debug)
[19449.630607] sd 3:0:0:0: [sdc] Synchronizing SCSI cache
[19449.630680] sd 3:0:0:0: [sdc] Stopping disk
[19449.630749] sd 2:0:0:0: [sdb] Synchronizing SCSI cache
[19449.630884] sd 2:0:0:0: [sdb] Stopping disk
[19449.631234] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[19449.631472] serial 00:0b: disabled
[19449.631478] serial 00:0b: wake-up capability disabled by ACPI
[19449.631612] ACPI handle has no context!
[19449.631701] ACPI handle has no context!
[19449.631707] ATL1E 0000:02:00.0: PME# enabled
[19449.631825] uhci_hcd 0000:00:1d.2: PCI INT C disabled
[19449.631848] uhci_hcd 0000:00:1d.1: PCI INT B disabled
[19449.632481] ehci_hcd 0000:00:1a.7: PCI INT C disabled
[19449.632510] uhci_hcd 0000:00:1a.2: PCI INT C disabled
[19449.632516] uhci_hcd 0000:00:1a.1: PCI INT B disabled
[19449.632525] uhci_hcd 0000:00:1a.0: PCI INT A disabled
[19449.640057] pcieport 0000:00:1c.5: wake-up capability enabled by ACPI
[19449.640067] ATL1E 0000:02:00.0: PCI INT A disabled
[19449.640074] ACPI handle has no context!
[19449.650098] uhci_hcd 0000:00:1d.0: PCI INT A disabled
[19449.730037] ehci_hcd 0000:00:1d.7: PCI INT A disabled
[19449.740100] HDA Intel 0000:00:1b.0: PCI INT A disabled
[19449.740131] ACPI handle has no context!
[19449.760022] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 128.125 msecs
[19450.066727] PM: suspend of drv:sd dev:2:0:0:0 complete after 435.980 msecs
[19450.066744] PM: suspend of drv:scsi dev:target2:0:0 complete after 435.939 msecs
[19450.066757] PM: suspend of drv:scsi dev:host2 complete after 435.408 msecs
[19450.092967] sd 0:0:0:0: [sda] Stopping disk
[19450.093064] PM: suspend of drv:sd dev:0:0:0:0 complete after 461.851 msecs
[19450.093077] PM: suspend of drv:scsi dev:target0:0:0 complete after 461.836 msecs
[19450.093089] PM: suspend of drv:scsi dev:host0 complete after 461.702 msecs
[19450.758630] PM: suspend of drv:sd dev:3:0:0:0 complete after 1128.035 msecs
[19450.758640] PM: suspend of drv:scsi dev:target3:0:0 complete after 1127.988 msecs
[19450.758649] PM: suspend of drv:scsi dev:host3 complete after 1127.308 msecs
[19450.770025] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 1138.267 msecs
[19450.770034] PM: suspend of drv: dev:pci0000:00 complete after 1137.499 msecs
[19450.770041] PM: suspend of devices complete after 1139.496 msecs
[19450.770045] PM: suspend devices took 1.140 seconds
[19450.810149] PM: late suspend of devices complete after 40.099 msecs
[19450.810656] ACPI: Preparing to enter system sleep state S3
[19450.810907] PM: Saving platform NVS memory
[19450.811548] Disabling non-boot CPUs ...
[19450.940036] CPU 1 is now offline
[19451.060017] CPU 2 is now offline
[19451.180016] CPU 3 is now offline
[19451.180250] Back to C!
[19451.180250] PM: Restoring platform NVS memory
[19451.180250] Enabling non-boot CPUs ...
[19451.180250] Booting Node 0 Processor 1 APIC 0x1
[19451.370012] Switched to NOHz mode on CPU #1
[19451.370063] CPU1 is up
[19451.370164] Booting Node 0 Processor 2 APIC 0x2
[19451.560021] Switched to NOHz mode on CPU #2
[19451.620103] CPU2 is up
[19451.620212] Booting Node 0 Processor 3 APIC 0x3
[19451.810019] Switched to NOHz mode on CPU #3
[19451.880102] CPU3 is up
[19451.882584] ACPI: Waking up from system sleep state S3
[19451.882929] pcieport 0000:00:01.0: restoring config space at offset 0xf (was 0x1a0100, writing 0x1a010a)
[19451.882939] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507)
[19451.882973] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001)
[19451.883000] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001)
[19451.883026] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001)
[19451.883060] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002)
[19451.883095] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100006, writing 0x100002)
[19451.883115] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x20100, writing 0x2010b)
[19451.883124] pcieport 0000:00:1c.0: restoring config space at offset 0x8 (was 0xfff0, writing 0xf030f000)
[19451.883128] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0x200000f0, writing 0x1010)
[19451.883136] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100507)
[19451.883166] pcieport 0000:00:1c.5: restoring config space at offset 0xf (was 0x20200, writing 0x2020a)
[19451.883174] pcieport 0000:00:1c.5: restoring config space at offset 0x9 (was 0x1fff1, writing 0xf051f041)
[19451.883183] pcieport 0000:00:1c.5: restoring config space at offset 0x1 (was 0x100107, writing 0x100507)
[19451.883224] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001)
[19451.883250] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001)
[19451.883277] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001)
[19451.883310] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002)
[19451.883329] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x20000, writing 0x200ff)
[19451.883399] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407)
[19451.883446] nvidia 0000:01:00.0: restoring config space at offset 0xc (was 0xfe980000, writing 0x0)
[19451.883455] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x8, writing 0x0)
[19451.883495] ATL1E 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
[19451.883515] ATL1E 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8)
[19451.883521] ATL1E 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[19451.883546] firewire_ohci 0000:04:03.0: restoring config space at offset 0xf (was 0x180c0100, writing 0x180c0105)
[19451.883560] firewire_ohci 0000:04:03.0: restoring config space at offset 0x4 (was 0x0, writing 0xfebff000)
[19451.883565] firewire_ohci 0000:04:03.0: restoring config space at offset 0x3 (was 0x0, writing 0x4008)
[19451.883570] firewire_ohci 0000:04:03.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900016)
[19451.883781] PM: early resume of devices complete after 0.914 msecs
[19451.883865] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21
[19451.883869] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[19451.883872] uhci_hcd 0000:00:1a.1: setting latency timer to 64
[19451.883875] uhci_hcd 0000:00:1a.0: setting latency timer to 64
[19451.883899] usb usb4: root hub lost power or was reset
[19451.883901] usb usb3: root hub lost power or was reset
[19451.883914] uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
[19451.883918] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18
[19451.883920] uhci_hcd 0000:00:1a.2: setting latency timer to 64
[19451.883925] ehci_hcd 0000:00:1a.7: setting latency timer to 64
[19451.883942] usb usb5: root hub lost power or was reset
[19451.883948] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
[19451.883952] HDA Intel 0000:00:1b.0: setting latency timer to 64
[19451.883960] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23
[19451.883967] uhci_hcd 0000:00:1d.0: setting latency timer to 64
[19451.883992] usb usb6: root hub lost power or was reset
[19451.884011] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19
[19451.884014] HDA Intel 0000:00:1b.0: irq 44 for MSI/MSI-X
[19451.884015] uhci_hcd 0000:00:1d.1: setting latency timer to 64
[19451.884037] usb usb7: root hub lost power or was reset
[19451.884048] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
[19451.884052] uhci_hcd 0000:00:1d.2: setting latency timer to 64
[19451.884078] usb usb8: root hub lost power or was reset
[19451.884153] ATL1E 0000:02:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
[19451.884160] ATL1E 0000:02:00.0: setting latency timer to 64
[19451.900043] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23
[19451.900049] ehci_hcd 0000:00:1d.7: setting latency timer to 64
[19451.900095] pci 0000:00:1e.0: setting latency timer to 64
[19451.900124] pcieport 0000:00:1c.5: wake-up capability disabled by ACPI
[19451.900130] ATL1E 0000:02:00.0: PME# disabled
[19451.900140] ahci 0000:00:1f.2: setting latency timer to 64
[19451.900287] sd 3:0:0:0: [sdc] Starting disk
[19451.900309] sd 2:0:0:0: [sdb] Starting disk
[19451.900314] sd 0:0:0:0: [sda] Starting disk
[19451.901059] serial 00:0b: activated
[19451.960141] firewire_core: skipped bus generations, destroying all nodes
[19451.995182] PM: resume of drv:HDA Intel dev:0000:00:1b.0 complete after 111.243 msecs
[19452.010048] PM: resume of drv:usb dev:usb5 complete after 125.768 msecs
[19452.010061] PM: resume of drv:hub dev:5-0:1.0 complete after 125.780 msecs
[19452.010071] PM: resume of drv: dev:ep_00 complete after 125.781 msecs
[19452.010074] PM: resume of drv:usb dev:usb3 complete after 125.857 msecs
[19452.010078] PM: resume of drv: dev:ep_81 complete after 125.790 msecs
[19452.010086] PM: resume of drv:hub dev:3-0:1.0 complete after 125.858 msecs
[19452.010094] PM: resume of drv: dev:ep_00 complete after 125.858 msecs
[19452.010099] PM: resume of drv: dev:ep_81 complete after 125.869 msecs
[19452.010127] PM: resume of drv:usb dev:usb7 complete after 125.710 msecs
[19452.010141] PM: resume of drv:hub dev:7-0:1.0 complete after 125.704 msecs
[19452.010143] PM: resume of drv:usb dev:usb8 complete after 125.659 msecs
[19452.010152] PM: resume of drv: dev:ep_00 complete after 125.684 msecs
[19452.010158] PM: resume of drv: dev:ep_81 complete after 125.705 msecs
[19452.010162] PM: resume of drv: dev:ep_00 complete after 125.628 msecs
[19452.010167] PM: resume of drv:hub dev:8-0:1.0 complete after 125.664 msecs
[19452.010182] PM: resume of drv: dev:ep_81 complete after 125.662 msecs
[19452.020052] PM: resume of drv:usb dev:usb4 complete after 135.805 msecs
[19452.020061] PM: resume of drv:hub dev:4-0:1.0 complete after 135.803 msecs
[19452.020064] PM: resume of drv: dev:ep_00 complete after 135.795 msecs
[19452.020070] PM: resume of drv: dev:ep_81 complete after 135.807 msecs
[19452.040886] PM: resume of drv:usb dev:2-4 complete after 156.040 msecs
[19452.040900] PM: resume of drv:hub dev:2-4:1.0 complete after 156.038 msecs
[19452.040908] PM: resume of drv: dev:ep_81 complete after 156.030 msecs
[19452.040912] PM: resume of drv: dev:ep_00 complete after 156.020 msecs
[19452.041263] PM: resume of drv:usb dev:2-3 complete after 156.526 msecs
[19452.041271] PM: resume of drv:hub dev:2-3:1.0 complete after 156.518 msecs
[19452.041273] PM: resume of drv: dev:ep_00 complete after 156.485 msecs
[19452.041278] PM: resume of drv: dev:ep_81 complete after 156.507 msecs
[19452.100459] PM: resume of drv:usb dev:2-4.4 complete after 215.309 msecs
[19452.100469] PM: resume of drv:usbhid dev:2-4.4:1.0 complete after 215.304 msecs
[19452.100472] PM: resume of drv: dev:ep_00 complete after 215.245 msecs
[19452.100477] PM: resume of drv: dev:ep_81 complete after 215.297 msecs
[19452.100479] PM: resume of drv:usbhid dev:2-4.4:1.1 complete after 215.282 msecs
[19452.100485] PM: resume of drv: dev:ep_82 complete after 215.276 msecs
[19452.100783] PM: resume of drv:usb dev:2-4.1 complete after 215.725 msecs
[19452.100791] PM: resume of drv:usbhid dev:2-4.1:1.0 complete after 215.719 msecs
[19452.100794] PM: resume of drv:usbhid dev:2-4.1:1.1 complete after 215.691 msecs
[19452.100798] PM: resume of drv: dev:ep_81 complete after 215.710 msecs
[19452.100801] PM: resume of drv: dev:ep_82 complete after 215.683 msecs
[19452.100809] PM: resume of drv: dev:ep_00 complete after 215.675 msecs
[19452.110013] PM: resume of drv:usb dev:2-4.6 complete after 224.727 msecs
[19452.110027] PM: resume of drv:uvcvideo dev:2-4.6:1.0 complete after 224.725 msecs
[19452.110029] PM: resume of drv:snd-usb-audio dev:2-4.6:1.2 complete after 224.680 msecs
[19452.110034] PM: resume of drv: dev:ep_87 complete after 224.717 msecs
[19452.110036] PM: resume of drv:snd-usb-audio dev:2-4.6:1.3 complete after 224.672 msecs
[19452.110038] PM: resume of drv:uvcvideo dev:2-4.6:1.1 complete after 224.706 msecs
[19452.110041] PM: resume of drv: dev:ep_00 complete after 224.664 msecs
[19452.140018] PM: resume of drv:usb dev:usb6 complete after 255.725 msecs
[19452.140035] PM: resume of drv: dev:ep_00 complete after 255.648 msecs
[19452.140038] PM: resume of drv:hub dev:6-0:1.0 complete after 255.738 msecs
[19452.140053] PM: resume of drv: dev:ep_81 complete after 255.695 msecs
[19452.260024] usb 6-2: reset full speed USB device using uhci_hcd and address 2
[19452.290033] ata5: SATA link down (SStatus 0 SControl 300)
[19452.330027] ata6: SATA link down (SStatus 0 SControl 300)
[19452.497364] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 613.242 msecs
[19452.497415] firewire_core: rediscovered device fw0
[19452.570025] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[19452.572460] ata2.00: configured for UDMA/100
[19453.095309] snd-usb-audio 6-2:1.0: no reset_resume for driver snd-usb-audio?
[19453.095313] snd-usb-audio 6-2:1.1: no reset_resume for driver snd-usb-audio?
[19453.095316] snd-usb-audio 6-2:1.2: no reset_resume for driver snd-usb-audio?
[19453.096533] PM: resume of drv:usb dev:6-2 complete after 1211.582 msecs
[19453.096546] PM: resume of drv:usb dev:6-2:1.0 complete after 1211.580 msecs
[19453.096548] PM: resume of drv:usb dev:6-2:1.2 complete after 1211.551 msecs
[19453.096551] PM: resume of drv:usb dev:6-2:1.1 complete after 1211.570 msecs
[19453.096553] PM: resume of drv:usbhid dev:6-2:1.3 complete after 1211.541 msecs
[19453.096564] PM: resume of drv: dev:ep_00 complete after 1211.522 msecs
[19453.096565] PM: resume of drv: dev:ep_83 complete after 1211.538 msecs
[19458.780027] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[19458.792775] ata1.00: configured for UDMA/133
[19458.815905] PM: resume of drv:sd dev:0:0:0:0 complete after 6931.086 msecs
[19458.815918] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 6931.085 msecs
[19458.815933] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 6855.847 msecs
[19461.960020] ata4: softreset failed (device not ready)
[19461.990060] ata3: softreset failed (device not ready)
[19464.160169] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[19464.199512] ata3.00: configured for UDMA/133
[19464.225609] PM: resume of drv:sd dev:2:0:0:0 complete after 12340.351 msecs
[19464.225619] PM: resume of drv:scsi_device dev:2:0:0:0 complete after 12340.347 msecs
[19464.225633] PM: resume of drv:scsi_disk dev:2:0:0:0 complete after 5409.610 msecs
[19467.800024] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[19467.812145] ata4.00: configured for UDMA/133
[19467.839423] PM: resume of drv:sd dev:3:0:0:0 complete after 15954.012 msecs
[19467.839434] PM: resume of drv:scsi_device dev:3:0:0:0 complete after 15954.000 msecs
[19467.839448] PM: resume of drv:scsi_disk dev:3:0:0:0 complete after 3613.804 msecs
[19467.839510] PM: resume of devices complete after 15955.696 msecs
[19467.880794] PM: resume devices took 16.000 seconds
[19467.880796] ------------[ cut here ]------------
[19467.880801] WARNING: at /build/buildd/linux-2.6.38/kernel/power/suspend_test.c:53 suspend_test_finish+0x86/0x90()
[19467.880803] Hardware name: P5Q-PRO
[19467.880805] Component: resume devices, time: 16000
[19467.880806] Modules linked in: cryptd aes_x86_64 aes_generic binfmt_misc parport_pc ppdev nvidia(P) snd_hda_codec_realtek snd_usb_audio snd_hda_intel snd_usbmidi_lib snd_hda_codec snd_hwdep snd_seq_midi coretemp snd_pcm snd_seq_midi_event snd_rawmidi firewire_sbp2 snd_seq snd_timer snd_seq_device snd uvcvideo videodev lp parport psmouse soundcore serio_raw v4l2_compat_ioctl32 snd_page_alloc asus_atk0110 joydev atl1 reiserfs uvesafb vesafb hid_sunplus usbhid hid firewire_ohci firewire_core crc_itu_t atl1e ahci libahci
[19467.880835] Pid: 6463, comm: pm-suspend Tainted: P            2.6.38-9-generic #43-Ubuntu
[19467.880837] Call Trace:
[19467.880843]  [<ffffffff81065c9f>] ? warn_slowpath_common+0x7f/0xc0
[19467.880846]  [<ffffffff81065d96>] ? warn_slowpath_fmt+0x46/0x50
[19467.880848]  [<ffffffff810a7526>] ? suspend_test_finish+0x86/0x90
[19467.880851]  [<ffffffff810a723c>] ? suspend_devices_and_enter+0xac/0x170
[19467.880853]  [<ffffffff810a7420>] ? enter_state+0x120/0x150
[19467.880855]  [<ffffffff810a6876>] ? state_store+0xc6/0x100
[19467.880859]  [<ffffffff812dcac7>] ? kobj_attr_store+0x17/0x20
[19467.880862]  [<ffffffff811d3c9e>] ? sysfs_write_file+0xde/0x160
[19467.880866]  [<ffffffff81164d86>] ? vfs_write+0xc6/0x180
[19467.880868]  [<ffffffff811650a1>] ? sys_write+0x51/0x90
[19467.880871]  [<ffffffff8100c002>] ? system_call_fastpath+0x16/0x1b
[19467.880873] ---[ end trace ea0822a32019ee08 ]---
[19467.880918] PM: Finishing wakeup.
[19467.880919] Restarting tasks ... done.
[19468.452659] ATL1E 0000:02:00.0: irq 45 for MSI/MSI-X
[19468.510681] ADDRCONF(NETDEV_UP): eth0: link is not ready
[19469.043684] EXT4-fs (sdc1): re-mounted. Opts: commit=0
[19469.046811] EXT4-fs (sdb1): re-mounted. Opts: commit=0
[19470.730519] ATL1E 0000:02:00.0: eth0: NIC Link is Up <1000 Mbps Full Duplex>
[19470.731136] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready