[ 5675.402580] PM: Preparing system for mem sleep [ 5675.402585] Freezing user space processes ... (elapsed 1.39 seconds) done. [ 5676.800216] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [ 5676.800256] PM: Entering mem sleep [ 5676.800262] Suspending console(s) (use no_console_suspend to debug) [ 5676.801405] btusb_intr_complete: hci0 urb ffff88011ddc4600 failed to resubmit (1) [ 5676.802396] btusb_bulk_complete: hci0 urb ffff88011ddc4b40 failed to resubmit (1) [ 5676.803397] btusb_bulk_complete: hci0 urb ffff88011ddc4480 failed to resubmit (1) [ 5676.880146] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 5676.908461] sd 0:0:0:0: [sda] Stopping disk [ 5677.909075] PM: suspend of drv:sd dev:0:0:0:0 complete after 1028.929 msecs [ 5677.920829] wl 0000:03:00.0: PCI INT A disabled [ 5677.940367] wl 0000:03:00.0: power state changed by ACPI to D3 [ 5678.160121] HDA Intel 0000:00:08.0: PCI INT A disabled [ 5678.180107] PM: suspend of drv:HDA Intel dev:0000:00:08.0 complete after 149.996 msecs [ 5678.180116] ehci_hcd 0000:00:06.1: PCI INT B disabled [ 5678.180125] ohci_hcd 0000:00:06.0: PCI INT A disabled [ 5678.180132] ehci_hcd 0000:00:04.1: PCI INT B disabled [ 5678.180140] ohci_hcd 0000:00:04.0: PCI INT A disabled [ 5678.180147] pci 0000:00:03.5: PCI INT B disabled [ 5678.180444] PM: suspend of devices complete after 1379.767 msecs [ 5678.180446] PM: suspend devices took 1.380 seconds [ 5678.260387] PM: late suspend of devices complete after 79.937 msecs [ 5678.350082] ACPI: Preparing to enter system sleep state S3 [ 5678.440016] Disabling non-boot CPUs ... [ 5678.440037] CPU0 attaching NULL sched-domain. [ 5678.440040] CPU1 attaching NULL sched-domain. [ 5678.600022] CPU0 attaching NULL sched-domain. [ 5678.710036] CPU 1 is now offline [ 5678.710039] SMP alternatives: switching to UP code [ 5678.717549] Extended CMOS year: 2000 [ 5678.717549] Back to C! [ 5678.717549] CPU0: Thermal monitoring enabled (TM2) [ 5678.717549] Extended CMOS year: 2000 [ 5678.722072] Enabling non-boot CPUs ... [ 5678.722308] SMP alternatives: switching to SMP code [ 5678.729090] Booting processor 1 APIC 0x1 ip 0x6000 [ 5678.717114] Initializing CPU#1 [ 5678.717114] CPU: L1 I cache: 32K, L1 D cache: 32K [ 5678.717114] CPU: L2 cache: 3072K [ 5678.717114] CPU 1/0x1 -> Node 0 [ 5678.717114] CPU: Physical Processor ID: 0 [ 5678.717114] CPU: Processor Core ID: 1 [ 5678.717114] CPU1: Thermal monitoring enabled (TM2) [ 5678.890105] CPU1: Intel(R) Core(TM)2 Duo CPU P7350 @ 2.00GHz stepping 06 [ 5678.890183] CPU0 attaching NULL sched-domain. [ 5678.960027] CPU0 attaching sched-domain: [ 5678.960030] domain 0: span 0-1 level MC [ 5678.960033] groups: 0 1 [ 5678.960037] CPU1 attaching sched-domain: [ 5678.960039] domain 0: span 0-1 level MC [ 5678.960041] groups: 1 0 [ 5678.960480] CPU1 is up [ 5678.961083] ACPI: Waking up from system sleep state S3 [ 5679.430563] pci 0000:00:03.0: restoring config space at offset 0xf (was 0x0, writing 0xff) [ 5679.430616] nForce2_smbus 0000:00:03.2: restoring config space at offset 0xf (was 0x100, writing 0x10f) [ 5679.430624] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x9 (was 0xa01, writing 0x2101) [ 5679.430627] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x8 (was 0xb01, writing 0x2141) [ 5679.430633] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x4 (was 0x1, writing 0x2181) [ 5679.430747] pci 0000:00:03.5: restoring config space at offset 0x4 (was 0x0, writing 0xd3300000) [ 5679.430757] pci 0000:00:03.5: restoring config space at offset 0x1 (was 0xa00000, writing 0xa00002) [ 5679.430775] ohci_hcd 0000:00:04.0: restoring config space at offset 0xf (was 0x1030100, writing 0x103010b) [ 5679.430805] ehci_hcd 0000:00:04.1: restoring config space at offset 0xf (was 0x1030200, writing 0x103020a) [ 5679.430816] ehci_hcd 0000:00:04.1: restoring config space at offset 0x4 (was 0x0, writing 0xd3389200) [ 5679.430821] ehci_hcd 0000:00:04.1: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002) [ 5679.430842] ohci_hcd 0000:00:06.0: restoring config space at offset 0xf (was 0x1030100, writing 0x1030107) [ 5679.430871] ehci_hcd 0000:00:06.1: restoring config space at offset 0xf (was 0x1030200, writing 0x1030205) [ 5679.430882] ehci_hcd 0000:00:06.1: restoring config space at offset 0x4 (was 0x0, writing 0xd3389100) [ 5679.430887] ehci_hcd 0000:00:06.1: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002) [ 5679.430908] HDA Intel 0000:00:08.0: restoring config space at offset 0xf (was 0x5020100, writing 0x502010f) [ 5679.430919] HDA Intel 0000:00:08.0: restoring config space at offset 0x4 (was 0x0, writing 0xd3380000) [ 5679.430924] HDA Intel 0000:00:08.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002) [ 5679.430940] pci 0000:00:09.0: restoring config space at offset 0xf (was 0x2000000, writing 0x20000ff) [ 5679.430946] pci 0000:00:09.0: restoring config space at offset 0x9 (was 0x0, writing 0xfff0) [ 5679.430950] pci 0000:00:09.0: restoring config space at offset 0x8 (was 0x0, writing 0xd320d320) [ 5679.430953] pci 0000:00:09.0: restoring config space at offset 0x7 (was 0x22800000, writing 0x228000f0) [ 5679.430957] pci 0000:00:09.0: restoring config space at offset 0x6 (was 0x0, writing 0x10100) [ 5679.430963] pci 0000:00:09.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00006) [ 5679.430981] forcedeth 0000:00:0a.0: restoring config space at offset 0xf (was 0x14010100, writing 0x1401010e) [ 5679.430989] forcedeth 0000:00:0a.0: restoring config space at offset 0x7 (was 0x0, writing 0xd3389300) [ 5679.430992] forcedeth 0000:00:0a.0: restoring config space at offset 0x6 (was 0x0, writing 0xd3389000) [ 5679.430996] forcedeth 0000:00:0a.0: restoring config space at offset 0x5 (was 0x1, writing 0x21e1) [ 5679.430999] forcedeth 0000:00:0a.0: restoring config space at offset 0x4 (was 0x0, writing 0xd3386000) [ 5679.431004] forcedeth 0000:00:0a.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00007) [ 5679.431026] ahci 0000:00:0b.0: restoring config space at offset 0xf (was 0x1030100, writing 0x103010b) [ 5679.431032] ahci 0000:00:0b.0: restoring config space at offset 0x9 (was 0x0, writing 0xd3384000) [ 5679.431035] ahci 0000:00:0b.0: restoring config space at offset 0x8 (was 0xff01, writing 0x21c1) [ 5679.431039] ahci 0000:00:0b.0: restoring config space at offset 0x7 (was 0xff2d, writing 0x21e9) [ 5679.431042] ahci 0000:00:0b.0: restoring config space at offset 0x6 (was 0xff19, writing 0x21d1) [ 5679.431045] ahci 0000:00:0b.0: restoring config space at offset 0x5 (was 0xff25, writing 0x21ed) [ 5679.431049] ahci 0000:00:0b.0: restoring config space at offset 0x4 (was 0xff11, writing 0x21d9) [ 5679.431053] ahci 0000:00:0b.0: restoring config space at offset 0x1 (was 0xb00001, writing 0xb00407) [ 5679.431081] pci 0000:00:10.0: restoring config space at offset 0xf (was 0x0, writing 0x800ff) [ 5679.431087] pci 0000:00:10.0: restoring config space at offset 0x9 (was 0x10011, writing 0xd1f1c001) [ 5679.431090] pci 0000:00:10.0: restoring config space at offset 0x8 (was 0x10, writing 0xd300d200) [ 5679.431093] pci 0000:00:10.0: restoring config space at offset 0x7 (was 0x101, writing 0x1111) [ 5679.431097] pci 0000:00:10.0: restoring config space at offset 0x6 (was 0x0, writing 0x20200) [ 5679.431101] pci 0000:00:10.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10040) [ 5679.431105] pci 0000:00:10.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) [ 5679.431174] pcieport 0000:00:15.0: restoring config space at offset 0xf (was 0x100, writing 0x1ff) [ 5679.431197] pcieport 0000:00:15.0: restoring config space at offset 0x9 (was 0x10011, writing 0x1fff1) [ 5679.431205] pcieport 0000:00:15.0: restoring config space at offset 0x8 (was 0x10, writing 0xd310d310) [ 5679.431213] pcieport 0000:00:15.0: restoring config space at offset 0x7 (was 0x101, writing 0x1f1) [ 5679.431221] pcieport 0000:00:15.0: restoring config space at offset 0x6 (was 0x0, writing 0x30300) [ 5679.431235] pcieport 0000:00:15.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10040) [ 5679.431246] pcieport 0000:00:15.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [ 5679.431351] nvidia 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [ 5679.431357] nvidia 0000:02:00.0: restoring config space at offset 0x9 (was 0x1, writing 0x1001) [ 5679.431361] nvidia 0000:02:00.0: restoring config space at offset 0x7 (was 0xc, writing 0xd000000c) [ 5679.431365] nvidia 0000:02:00.0: restoring config space at offset 0x5 (was 0xc, writing 0xc000000c) [ 5679.431369] nvidia 0000:02:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xd2000000) [ 5679.431373] nvidia 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) [ 5679.431561] wl 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a) [ 5679.431577] wl 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xd3100004) [ 5679.431582] wl 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x40) [ 5679.431588] wl 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100006) [ 5679.431917] PM: early resume of devices complete after 1.572 msecs [ 5688.170304] PM: resume of drv:battery dev:PNP0C0A:00 complete after 8737.905 msecs [ 5688.170339] pci 0000:00:03.5: PCI INT B -> Link[LPMU] -> GSI 16 (level, low) -> IRQ 16 [ 5688.170347] ohci_hcd 0000:00:04.0: PCI INT A -> Link[LUS0] -> GSI 19 (level, low) -> IRQ 19 [ 5688.170352] ohci_hcd 0000:00:04.0: setting latency timer to 64 [ 5688.200117] ehci_hcd 0000:00:04.1: PCI INT B -> Link[LUS2] -> GSI 21 (level, low) -> IRQ 21 [ 5688.200122] ehci_hcd 0000:00:04.1: setting latency timer to 64 [ 5688.200133] ohci_hcd 0000:00:06.0: PCI INT A -> Link[Z000] -> GSI 18 (level, low) -> IRQ 18 [ 5688.200138] ohci_hcd 0000:00:06.0: setting latency timer to 64 [ 5688.230116] ehci_hcd 0000:00:06.1: PCI INT B -> Link[Z001] -> GSI 20 (level, low) -> IRQ 20 [ 5688.230121] ehci_hcd 0000:00:06.1: setting latency timer to 64 [ 5688.230133] HDA Intel 0000:00:08.0: PCI INT A -> Link[LAZA] -> GSI 22 (level, low) -> IRQ 22 [ 5688.230138] HDA Intel 0000:00:08.0: setting latency timer to 64 [ 5688.230168] pci 0000:00:09.0: setting latency timer to 64 [ 5688.310852] ahci 0000:00:0b.0: setting latency timer to 64 [ 5688.310925] pci 0000:00:10.0: setting latency timer to 64 [ 5688.842542] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 5688.842562] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 5688.846895] ata2.00: configured for UDMA/66 [ 5688.880117] ata1.00: configured for UDMA/133 [ 5697.293679] PM: resume of drv:nvidia dev:0000:02:00.0 complete after 8982.741 msecs [ 5697.293806] wl 0000:03:00.0: power state changed by ACPI to D0 [ 5697.294057] wl 0000:03:00.0: power state changed by ACPI to D0 [ 5697.294151] wl 0000:03:00.0: power state changed by ACPI to D0 [ 5697.294244] wl 0000:03:00.0: power state changed by ACPI to D0 [ 5697.294252] wl 0000:03:00.0: PCI INT A -> Link[Z00F] -> GSI 23 (level, low) -> IRQ 23 [ 5697.294257] wl 0000:03:00.0: setting latency timer to 64 [ 5697.560123] PM: resume of drv:usb dev:usb3 complete after 109.992 msecs [ 5697.670118] PM: resume of drv:usb dev:usb4 complete after 109.978 msecs [ 5697.750440] sd 0:0:0:0: [sda] Starting disk [ 5698.046082] PM: resume of devices complete after 18614.128 msecs [ 5698.046285] PM: resume devices took 18.610 seconds [ 5698.046286] ------------[ cut here ]------------ [ 5698.046293] WARNING: at /build/buildd/linux-2.6.32/kernel/power/suspend_test.c:53 suspend_test_finish+0x88/0x90() [ 5698.046295] Hardware name: MacBook5,1 [ 5698.046297] Component: resume devices, time: 18610 [ 5698.046298] Modules linked in: coretemp snd_hda_codec_realtek binfmt_misc snd_hda_intel rfcomm snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi sco snd_seq_midi_event snd_seq bridge snd_timer snd_seq_device ppdev stp bnep snd applesmc l2cap soundcore uvcvideo led_class joydev videodev lp lib80211_crypt_tkip v4l1_compat vga16fb parport input_polldev i2c_nforce2 bcm5974 nvidia(P) v4l2_compat_ioctl32 vgastate btusb snd_page_alloc wl(P) nvidia_bl lib80211 bluetooth shpchp fbcon tileblit font bitblit softcursor hid_apple usbhid hid uvesafb ahci forcedeth [ 5698.046339] Pid: 11296, comm: pm-suspend Tainted: P W 2.6.32-24-generic #38-Ubuntu [ 5698.046345] Call Trace: [ 5698.046351] [] warn_slowpath_common+0x7b/0xc0 [ 5698.046354] [] warn_slowpath_fmt+0x41/0x50 [ 5698.046357] [] suspend_test_finish+0x88/0x90 [ 5698.046360] [] suspend_devices_and_enter+0xb1/0xe0 [ 5698.046364] [] enter_state+0xd8/0x110 [ 5698.046367] [] state_store+0x9a/0x100 [ 5698.046371] [] kobj_attr_store+0x17/0x20 [ 5698.046375] [] sysfs_write_file+0xe5/0x170 [ 5698.046379] [] vfs_write+0xb8/0x1a0 [ 5698.046383] [] ? do_page_fault+0x158/0x3b0 [ 5698.046386] [] sys_write+0x51/0x80 [ 5698.046390] [] system_call_fastpath+0x16/0x1b [ 5698.046392] ---[ end trace afe9e044aed71441 ]--- [ 5698.046433] PM: Finishing wakeup. [ 5698.046434] Restarting tasks ... done. [ 5698.443614] forcedeth 0000:00:0a.0: irq 26 for MSI/MSI-X [ 5698.443806] eth0: no link during initialization. [ 5698.444594] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 5708.732572] eth1: no IPv6 routers present