[88456.354373]  [<c056e41c>] ? printk+0x18/0x1c
[88456.354376]  [<c0174d49>] enter_state+0xb9/0xf0
[88456.354379]  [<c017443d>] state_store+0x6d/0xb0
[88456.354382]  [<c01743d0>] ? state_store+0x0/0xb0
[88456.354387]  [<c0311a70>] kobj_attr_store+0x20/0x30
[88456.354391]  [<c0239290>] sysfs_write_file+0x90/0x100
[88456.354395]  [<c01e747a>] vfs_write+0x9a/0x190
[88456.354398]  [<c0239200>] ? sysfs_write_file+0x0/0x100
[88456.354401]  [<c0572d4b>] ? do_page_fault+0x19b/0x380
[88456.354404]  [<c01e7f3d>] sys_write+0x3d/0x70
[88456.354408]  [<c010336c>] syscall_call+0x7/0xb
[88456.354410] ---[ end trace 4869b1817e9d6970 ]---
[88456.354781] PM: Finishing wakeup.
[88456.354783] Restarting tasks ... done.
[88459.969495] forcedeth 0000:00:0a.0: irq 26 for MSI/MSI-X
[88459.969687] eth0: no link during initialization.
[88459.970151] ADDRCONF(NETDEV_UP): eth0: link is not ready
[88470.468033] eth2: no IPv6 routers present
[90397.105249] PM: Syncing filesystems ... done.
[90397.230544] PM: Preparing system for mem sleep
[90397.230549] Freezing user space processes ... (elapsed 1.29 seconds) done.
[90398.528204] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
[90398.528249] PM: Entering mem sleep
[90398.528255] Suspending console(s) (use no_console_suspend to debug)
[90398.529441] btusb_bulk_complete: hci0 urb ed1ce300 failed to resubmit (1)
[90398.530445] btusb_bulk_complete: hci0 urb f115af80 failed to resubmit (1)
[90398.531441] btusb_intr_complete: hci0 urb f115a580 failed to resubmit (1)
[90398.596053] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[90398.596177] sd 0:0:0:0: [sda] Stopping disk
[90399.584425] wl 0000:03:00.0: PCI INT A disabled
[90399.600147] wl 0000:03:00.0: power state changed by ACPI to D3
[90399.652415] forcedeth 0000:00:0a.0: wake-up capability disabled by ACPI
[90399.652420] forcedeth 0000:00:0a.0: PME# disabled
[90399.780045] HDA Intel 0000:00:08.0: PCI INT A disabled
[90399.796100] ehci_hcd 0000:00:06.1: PCI INT B disabled
[90399.796106] ohci_hcd 0000:00:06.0: PCI INT A disabled
[90399.796112] ehci_hcd 0000:00:04.1: PCI INT B disabled
[90399.796118] ohci_hcd 0000:00:04.0: PCI INT A disabled
[90399.796179] PM: suspend devices took 1.272 seconds
[90399.796416] ehci_hcd 0000:00:06.1: PME# disabled
[90399.812035] ohci_hcd 0000:00:06.0: PME# disabled
[90399.828039] ehci_hcd 0000:00:04.1: PME# disabled
[90399.844034] ohci_hcd 0000:00:04.0: PME# disabled
[90399.948069] ACPI: Preparing to enter system sleep state S3
[90400.004204] Disabling non-boot CPUs ...
[90400.006363] kvm: disabling virtualization on CPU1
[90400.108017] CPU 1 is now offline
[90400.108020] SMP alternatives: switching to UP code
[90400.115015] CPU0 attaching NULL sched-domain.
[90400.115018] CPU1 attaching NULL sched-domain.
[90400.115023] CPU0 attaching NULL sched-domain.
[90400.115232] CPU1 is down
[90400.115306] Extended CMOS year: 2000
[90400.115306] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[90400.115306] Back to C!
[90400.115306] CPU0: Thermal LVT vector (0xfa) already installed
[90400.115306] Extended CMOS year: 2000
[90400.119205] Enabling non-boot CPUs ...
[90400.119459] SMP alternatives: switching to SMP code
[90400.126173] Booting processor 1 APIC 0x1 ip 0x6000
[90400.114886] Initializing CPU#1
[90400.114886] Calibrating delay using timer specific routine.. 3980.05 BogoMIPS (lpj=7960107)
[90400.114886] CPU: L1 I cache: 32K, L1 D cache: 32K
[90400.114886] CPU: L2 cache: 3072K
[90400.114886] CPU: Physical Processor ID: 0
[90400.114886] CPU: Processor Core ID: 1
[90400.114886] mce: CPU supports 6 MCE banks
[90400.114886] CPU1: Thermal monitoring enabled (TM2)
[90400.114886] x86 PAT enabled: cpu 1, old 0x7040600070406, new 0x7010600070106
[90400.217817] CPU1: Intel(R) Core(TM)2 Duo CPU     P7350  @ 2.00GHz stepping 06
[90400.217847] kvm: enabling virtualization on CPU1
[90400.217909] CPU0 attaching NULL sched-domain.
[90400.221014] Switched to high resolution mode on CPU 1
[90400.232026] CPU0 attaching sched-domain:
[90400.232029]  domain 0: span 0-1 level MC
[90400.232031]   groups: 0 1
[90400.232035] CPU1 attaching sched-domain:
[90400.232037]  domain 0: span 0-1 level MC
[90400.232039]   groups: 1 0
[90400.233015] CPU1 is up
[90400.233018] ACPI: Waking up from system sleep state S3
[90400.656217] pci 0000:00:03.0: restoring config space at offset 0xf (was 0x0, writing 0xff)
[90400.656268] nForce2_smbus 0000:00:03.2: restoring config space at offset 0xf (was 0x100, writing 0x10f)
[90400.656276] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x9 (was 0xa01, writing 0x2101)
[90400.656280] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x8 (was 0xb01, writing 0x2141)
[90400.656287] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x4 (was 0x1, writing 0x2181)
[90400.656399] pci 0000:00:03.5: restoring config space at offset 0x4 (was 0x0, writing 0x93300000)
[90400.656409] pci 0000:00:03.5: restoring config space at offset 0x1 (was 0xa00000, writing 0xa00006)
[90400.656427] ohci_hcd 0000:00:04.0: restoring config space at offset 0xf (was 0x1030100, writing 0x103010b)
[90400.656451] ohci_hcd 0000:00:04.0: PME# disabled
[90400.656461] ehci_hcd 0000:00:04.1: restoring config space at offset 0xf (was 0x1030200, writing 0x103020a)
[90400.656473] ehci_hcd 0000:00:04.1: restoring config space at offset 0x4 (was 0x0, writing 0x93389200)
[90400.656478] ehci_hcd 0000:00:04.1: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[90400.656495] ehci_hcd 0000:00:04.1: PME# disabled
[90400.656502] ohci_hcd 0000:00:06.0: restoring config space at offset 0xf (was 0x1030100, writing 0x1030107)
[90400.656525] ohci_hcd 0000:00:06.0: PME# disabled
[90400.656535] ehci_hcd 0000:00:06.1: restoring config space at offset 0xf (was 0x1030200, writing 0x1030205)
[90400.656546] ehci_hcd 0000:00:06.1: restoring config space at offset 0x4 (was 0x0, writing 0x93389100)
[90400.656552] ehci_hcd 0000:00:06.1: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[90400.656568] ehci_hcd 0000:00:06.1: PME# disabled
[90400.656576] HDA Intel 0000:00:08.0: restoring config space at offset 0xf (was 0x5020100, writing 0x502010f)
[90400.656588] HDA Intel 0000:00:08.0: restoring config space at offset 0x4 (was 0x0, writing 0x93380000)
[90400.656594] HDA Intel 0000:00:08.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[90400.656609] pci 0000:00:09.0: restoring config space at offset 0xf (was 0x2000000, writing 0x20000ff)
[90400.656617] pci 0000:00:09.0: restoring config space at offset 0x9 (was 0x0, writing 0xfff0)
[90400.656620] pci 0000:00:09.0: restoring config space at offset 0x8 (was 0x0, writing 0x93209320)
[90400.656624] pci 0000:00:09.0: restoring config space at offset 0x7 (was 0x22800000, writing 0x28000f0)
[90400.656628] pci 0000:00:09.0: restoring config space at offset 0x6 (was 0x0, writing 0x10100)
[90400.656635] pci 0000:00:09.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00006)
[90400.656653] forcedeth 0000:00:0a.0: restoring config space at offset 0xf (was 0x14010100, writing 0x1401010e)
[90400.656661] forcedeth 0000:00:0a.0: restoring config space at offset 0x7 (was 0x0, writing 0x93389300)
[90400.656665] forcedeth 0000:00:0a.0: restoring config space at offset 0x6 (was 0x0, writing 0x93389000)
[90400.656669] forcedeth 0000:00:0a.0: restoring config space at offset 0x5 (was 0x1, writing 0x21e1)
[90400.656673] forcedeth 0000:00:0a.0: restoring config space at offset 0x4 (was 0x0, writing 0x93386000)
[90400.656678] forcedeth 0000:00:0a.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00007)
[90400.656700] ahci 0000:00:0b.0: restoring config space at offset 0xf (was 0x1030100, writing 0x103010b)
[90400.656706] ahci 0000:00:0b.0: restoring config space at offset 0x9 (was 0x0, writing 0x93384000)
[90400.656710] ahci 0000:00:0b.0: restoring config space at offset 0x8 (was 0xff01, writing 0x21c1)
[90400.656714] ahci 0000:00:0b.0: restoring config space at offset 0x7 (was 0xff2d, writing 0x21e9)
[90400.656717] ahci 0000:00:0b.0: restoring config space at offset 0x6 (was 0xff19, writing 0x21d1)
[90400.656721] ahci 0000:00:0b.0: restoring config space at offset 0x5 (was 0xff25, writing 0x21ed)
[90400.656725] ahci 0000:00:0b.0: restoring config space at offset 0x4 (was 0xff11, writing 0x21d9)
[90400.656730] ahci 0000:00:0b.0: restoring config space at offset 0x1 (was 0xb00001, writing 0xb00407)
[90400.656757] pci 0000:00:10.0: restoring config space at offset 0xf (was 0x0, writing 0x800ff)
[90400.656764] pci 0000:00:10.0: restoring config space at offset 0x9 (was 0x10011, writing 0x91f18001)
[90400.656768] pci 0000:00:10.0: restoring config space at offset 0x8 (was 0x10, writing 0x93009200)
[90400.656771] pci 0000:00:10.0: restoring config space at offset 0x7 (was 0x101, writing 0x1111)
[90400.656775] pci 0000:00:10.0: restoring config space at offset 0x6 (was 0x0, writing 0x20200)
[90400.656780] pci 0000:00:10.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10040)
[90400.656784] pci 0000:00:10.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[90400.656854] pcieport-driver 0000:00:15.0: restoring config space at offset 0xf (was 0x100, writing 0x1ff)
[90400.656875] pcieport-driver 0000:00:15.0: restoring config space at offset 0xa (was 0x0, writing 0xffffffff)
[90400.656884] pcieport-driver 0000:00:15.0: restoring config space at offset 0x9 (was 0x10011, writing 0x1fff1)
[90400.656892] pcieport-driver 0000:00:15.0: restoring config space at offset 0x8 (was 0x10, writing 0x93109310)
[90400.656900] pcieport-driver 0000:00:15.0: restoring config space at offset 0x7 (was 0x101, writing 0x1f1)
[90400.656909] pcieport-driver 0000:00:15.0: restoring config space at offset 0x6 (was 0x0, writing 0x30300)
[90400.656923] pcieport-driver 0000:00:15.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10040)
[90400.656935] pcieport-driver 0000:00:15.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407)
[90400.657040] nvidia 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
[90400.657047] nvidia 0000:02:00.0: restoring config space at offset 0x9 (was 0x1, writing 0x1001)
[90400.657051] nvidia 0000:02:00.0: restoring config space at offset 0x7 (was 0xc, writing 0x9000000c)
[90400.657055] nvidia 0000:02:00.0: restoring config space at offset 0x5 (was 0xc, writing 0x8000000c)
[90400.657059] nvidia 0000:02:00.0: restoring config space at offset 0x4 (was 0x0, writing 0x92000000)
[90400.657064] nvidia 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[90400.657269] wl 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
[90400.657287] wl 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0x93100004)
[90400.657292] wl 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x40)
[90400.657298] wl 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100006)
[90407.904292] nForce2_smbus 0000:00:03.2: PME# disabled
[90407.904299] ohci_hcd 0000:00:04.0: PME# disabled
[90407.904307] ohci_hcd 0000:00:04.0: PCI INT A -> Link[LUS0] -> GSI 19 (level, low) -> IRQ 19
[90407.904312] ohci_hcd 0000:00:04.0: setting latency timer to 64
[90407.928104] ehci_hcd 0000:00:04.1: PME# disabled
[90407.928110] ehci_hcd 0000:00:04.1: PCI INT B -> Link[LUS2] -> GSI 21 (level, low) -> IRQ 21
[90407.928115] ehci_hcd 0000:00:04.1: setting latency timer to 64
[90407.928123] ohci_hcd 0000:00:06.0: PME# disabled
[90407.928129] ohci_hcd 0000:00:06.0: PCI INT A -> Link[Z000] -> GSI 18 (level, low) -> IRQ 18
[90407.928134] ohci_hcd 0000:00:06.0: setting latency timer to 64
[90407.952096] ehci_hcd 0000:00:06.1: PME# disabled
[90407.952102] ehci_hcd 0000:00:06.1: PCI INT B -> Link[Z001] -> GSI 20 (level, low) -> IRQ 20
[90407.952108] ehci_hcd 0000:00:06.1: setting latency timer to 64
[90407.952116] HDA Intel 0000:00:08.0: PCI INT A -> Link[LAZA] -> GSI 23 (level, low) -> IRQ 23
[90407.952122] HDA Intel 0000:00:08.0: setting latency timer to 64
[90407.952144] pci 0000:00:09.0: setting latency timer to 64
[90407.952149] forcedeth 0000:00:0a.0: wake-up capability disabled by ACPI
[90407.952155] forcedeth 0000:00:0a.0: PME# disabled
[90408.016821] ahci 0000:00:0b.0: setting latency timer to 64
[90408.016849] pci 0000:00:10.0: setting latency timer to 64
[90408.509039] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[90408.509056] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[90408.509950] ata1.00: configured for UDMA/100
[90408.519962] ata2.00: configured for UDMA/133
[90408.524740] ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
[90408.524742] ata1: irq_stat 0x00400040, connection status changed
[90408.525625] ata1.00: configured for UDMA/100
[90408.525627] ata1: EH complete
[90408.541038] ata2: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
[90408.541041] ata2: irq_stat 0x40000001
[90408.551018] ata2.00: configured for UDMA/133
[90408.551021] ata2: EH complete
[90412.872375] wl 0000:03:00.0: PCI INT A -> Link[Z00F] -> GSI 23 (level, low) -> IRQ 23
[90412.872381] wl 0000:03:00.0: setting latency timer to 64
[90413.220336] sd 0:0:0:0: [sda] Starting disk
[90413.461996] applesmc: device has already been initialized (0xe0, 0x00).
[90413.462166] PM: resume devices took 12.804 seconds
[90413.462168] ------------[ cut here ]------------
[90413.462174] WARNING: at /build/buildd/linux-2.6.31/kernel/power/suspend_test.c:52 suspend_test_finish+0x80/0x90()
[90413.462176] Hardware name: MacBook5,1
[90413.462178] Component: resume devices
[90413.462179] Modules linked in: nls_utf8 nls_iso8859_1 nls_cp437 ufs qnx4 hfsplus hfs minix ntfs vfat msdos fat jfs xfs exportfs reiserfs binfmt_misc ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT xt_tcpudp ppdev bridge stp bnep kvm_intel kvm snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device nvidia(P) agpgart lib80211_crypt_tkip snd applesmc soundcore uvcvideo joydev nvidia_bl led_class snd_page_alloc shpchp iptable_filter i2c_nforce2 wl(P) bcm5974 input_polldev ip_tables videodev btusb v4l1_compat lib80211 x_tables mbp_nvidia_bl lp parport hid_apple usbhid forcedeth
[90413.462232] Pid: 18769, comm: pm-suspend Tainted: P        W  2.6.31-14-generic #48-Ubuntu
[90413.462234] Call Trace:
[90413.462240]  [<c014518d>] warn_slowpath_common+0x6d/0xa0
[90413.462244]  [<c0174e70>] ? suspend_test_finish+0x80/0x90
[90413.462247]  [<c0174e70>] ? suspend_test_finish+0x80/0x90
[90413.462250]  [<c0145206>] warn_slowpath_fmt+0x26/0x30
[90413.462253]  [<c0174e70>] suspend_test_finish+0x80/0x90
[90413.462257]  [<c0174c5f>] suspend_devices_and_enter+0x9f/0xd0
[90413.462261]  [<c056e41c>] ? printk+0x18/0x1c
[90413.462264]  [<c0174d49>] enter_state+0xb9/0xf0
[90413.462267]  [<c017443d>] state_store+0x6d/0xb0
[90413.462270]  [<c01743d0>] ? state_store+0x0/0xb0
[90413.462274]  [<c0311a70>] kobj_attr_store+0x20/0x30
[90413.462279]  [<c0239290>] sysfs_write_file+0x90/0x100
[90413.462283]  [<c01e747a>] vfs_write+0x9a/0x190
[90413.462286]  [<c0239200>] ? sysfs_write_file+0x0/0x100
[90413.462289]  [<c0572d4b>] ? do_page_fault+0x19b/0x380
[90413.462292]  [<c01e7f3d>] sys_write+0x3d/0x70
[90413.462296]  [<c010336c>] syscall_call+0x7/0xb
[90413.462298] ---[ end trace 4869b1817e9d6971 ]---
[90413.462667] PM: Finishing wakeup.
[90413.462669] Restarting tasks ... done.
[90417.336189] forcedeth 0000:00:0a.0: irq 26 for MSI/MSI-X
[90417.336382] eth0: no link during initialization.
[90417.336847] ADDRCONF(NETDEV_UP): eth0: link is not ready
[90427.780041] eth2: no IPv6 routers present
[93887.401307] PM: Syncing filesystems ... done.
[93887.522558] PM: Preparing system for mem sleep
[93887.522563] Freezing user space processes ... (elapsed 0.00 seconds) done.
[93887.523643] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
[93887.523685] PM: Entering mem sleep
[93887.523697] Suspending console(s) (use no_console_suspend to debug)
[93887.525073] btusb_bulk_complete: hci0 urb ed078b00 failed to resubmit (1)
[93887.526068] btusb_bulk_complete: hci0 urb ed078a00 failed to resubmit (1)
[93887.527067] btusb_intr_complete: hci0 urb ed078700 failed to resubmit (1)
[93887.592053] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[93887.592250] sd 0:0:0:0: [sda] Stopping disk
[93888.528427] wl 0000:03:00.0: PCI INT A disabled
[93888.544165] wl 0000:03:00.0: power state changed by ACPI to D3
[93888.592417] forcedeth 0000:00:0a.0: wake-up capability disabled by ACPI
[93888.592422] forcedeth 0000:00:0a.0: PME# disabled
[93888.720044] HDA Intel 0000:00:08.0: PCI INT A disabled
[93888.736101] ehci_hcd 0000:00:06.1: PCI INT B disabled
[93888.736107] ohci_hcd 0000:00:06.0: PCI INT A disabled
[93888.736113] ehci_hcd 0000:00:04.1: PCI INT B disabled
[93888.736119] ohci_hcd 0000:00:04.0: PCI INT A disabled
[93888.736181] PM: suspend devices took 1.216 seconds
[93888.736418] ehci_hcd 0000:00:06.1: PME# disabled
[93888.752035] ohci_hcd 0000:00:06.0: PME# disabled
[93888.768038] ehci_hcd 0000:00:04.1: PME# disabled
[93888.784034] ohci_hcd 0000:00:04.0: PME# disabled
[93888.896069] ACPI: Preparing to enter system sleep state S3
[93888.944209] Disabling non-boot CPUs ...
[93888.946370] kvm: disabling virtualization on CPU1
[93889.048014] CPU 1 is now offline
[93889.048016] SMP alternatives: switching to UP code
[93889.055019] CPU0 attaching NULL sched-domain.
[93889.055022] CPU1 attaching NULL sched-domain.
[93889.055027] CPU0 attaching NULL sched-domain.
[93889.055230] CPU1 is down
[93889.055304] Extended CMOS year: 2000
[93889.055304] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[93889.055304] Back to C!
[93889.055304] CPU0: Thermal LVT vector (0xfa) already installed
[93889.055304] Extended CMOS year: 2000
[93889.059205] Enabling non-boot CPUs ...
[93889.059461] SMP alternatives: switching to SMP code
[93889.066174] Booting processor 1 APIC 0x1 ip 0x6000
[93889.054897] Initializing CPU#1
[93889.054897] Calibrating delay using timer specific routine.. 3980.04 BogoMIPS (lpj=7960085)
[93889.054897] CPU: L1 I cache: 32K, L1 D cache: 32K
[93889.054897] CPU: L2 cache: 3072K
[93889.054897] CPU: Physical Processor ID: 0
[93889.054897] CPU: Processor Core ID: 1
[93889.054897] mce: CPU supports 6 MCE banks
[93889.054897] CPU1: Thermal monitoring enabled (TM2)
[93889.054897] x86 PAT enabled: cpu 1, old 0x7040600070406, new 0x7010600070106
[93889.157817] CPU1: Intel(R) Core(TM)2 Duo CPU     P7350  @ 2.00GHz stepping 06
[93889.157847] kvm: enabling virtualization on CPU1
[93889.157914] CPU0 attaching NULL sched-domain.
[93889.161014] Switched to high resolution mode on CPU 1
[93889.172024] CPU0 attaching sched-domain:
[93889.172027]  domain 0: span 0-1 level MC
[93889.172030]   groups: 0 1
[93889.172034] CPU1 attaching sched-domain:
[93889.172035]  domain 0: span 0-1 level MC
[93889.172037]   groups: 1 0
[93889.173015] CPU1 is up
[93889.173018] ACPI: Waking up from system sleep state S3
[93889.596216] pci 0000:00:03.0: restoring config space at offset 0xf (was 0x0, writing 0xff)
[93889.596267] nForce2_smbus 0000:00:03.2: restoring config space at offset 0xf (was 0x100, writing 0x10f)
[93889.596275] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x9 (was 0xa01, writing 0x2101)
[93889.596279] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x8 (was 0xb01, writing 0x2141)
[93889.596285] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x4 (was 0x1, writing 0x2181)
[93889.596398] pci 0000:00:03.5: restoring config space at offset 0x4 (was 0x0, writing 0x93300000)
[93889.596408] pci 0000:00:03.5: restoring config space at offset 0x1 (was 0xa00000, writing 0xa00006)
[93889.596426] ohci_hcd 0000:00:04.0: restoring config space at offset 0xf (was 0x1030100, writing 0x103010b)
[93889.596450] ohci_hcd 0000:00:04.0: PME# disabled
[93889.596460] ehci_hcd 0000:00:04.1: restoring config space at offset 0xf (was 0x1030200, writing 0x103020a)
[93889.596471] ehci_hcd 0000:00:04.1: restoring config space at offset 0x4 (was 0x0, writing 0x93389200)
[93889.596477] ehci_hcd 0000:00:04.1: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[93889.596494] ehci_hcd 0000:00:04.1: PME# disabled
[93889.596501] ohci_hcd 0000:00:06.0: restoring config space at offset 0xf (was 0x1030100, writing 0x1030107)
[93889.596524] ohci_hcd 0000:00:06.0: PME# disabled
[93889.596533] ehci_hcd 0000:00:06.1: restoring config space at offset 0xf (was 0x1030200, writing 0x1030205)
[93889.596545] ehci_hcd 0000:00:06.1: restoring config space at offset 0x4 (was 0x0, writing 0x93389100)
[93889.596551] ehci_hcd 0000:00:06.1: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[93889.596567] ehci_hcd 0000:00:06.1: PME# disabled
[93889.596575] HDA Intel 0000:00:08.0: restoring config space at offset 0xf (was 0x5020100, writing 0x502010f)
[93889.596587] HDA Intel 0000:00:08.0: restoring config space at offset 0x4 (was 0x0, writing 0x93380000)
[93889.596592] HDA Intel 0000:00:08.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[93889.596607] pci 0000:00:09.0: restoring config space at offset 0xf (was 0x2000000, writing 0x20000ff)
[93889.596615] pci 0000:00:09.0: restoring config space at offset 0x9 (was 0x0, writing 0xfff0)
[93889.596619] pci 0000:00:09.0: restoring config space at offset 0x8 (was 0x0, writing 0x93209320)
[93889.596623] pci 0000:00:09.0: restoring config space at offset 0x7 (was 0x22800000, writing 0x228000f0)
[93889.596627] pci 0000:00:09.0: restoring config space at offset 0x6 (was 0x0, writing 0x10100)
[93889.596633] pci 0000:00:09.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00006)
[93889.596651] forcedeth 0000:00:0a.0: restoring config space at offset 0xf (was 0x14010100, writing 0x1401010e)
[93889.596660] forcedeth 0000:00:0a.0: restoring config space at offset 0x7 (was 0x0, writing 0x93389300)
[93889.596663] forcedeth 0000:00:0a.0: restoring config space at offset 0x6 (was 0x0, writing 0x93389000)
[93889.596667] forcedeth 0000:00:0a.0: restoring config space at offset 0x5 (was 0x1, writing 0x21e1)
[93889.596671] forcedeth 0000:00:0a.0: restoring config space at offset 0x4 (was 0x0, writing 0x93386000)
[93889.596676] forcedeth 0000:00:0a.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00007)
[93889.596698] ahci 0000:00:0b.0: restoring config space at offset 0xf (was 0x1030100, writing 0x103010b)
[93889.596704] ahci 0000:00:0b.0: restoring config space at offset 0x9 (was 0x0, writing 0x93384000)
[93889.596708] ahci 0000:00:0b.0: restoring config space at offset 0x8 (was 0xff01, writing 0x21c1)
[93889.596712] ahci 0000:00:0b.0: restoring config space at offset 0x7 (was 0xff2d, writing 0x21e9)
[93889.596716] ahci 0000:00:0b.0: restoring config space at offset 0x6 (was 0xff19, writing 0x21d1)
[93889.596719] ahci 0000:00:0b.0: restoring config space at offset 0x5 (was 0xff25, writing 0x21ed)
[93889.596723] ahci 0000:00:0b.0: restoring config space at offset 0x4 (was 0xff11, writing 0x21d9)
[93889.596728] ahci 0000:00:0b.0: restoring config space at offset 0x1 (was 0xb00001, writing 0xb00407)
[93889.596756] pci 0000:00:10.0: restoring config space at offset 0xf (was 0x0, writing 0x800ff)
[93889.596762] pci 0000:00:10.0: restoring config space at offset 0x9 (was 0x10011, writing 0x91f18001)
[93889.596766] pci 0000:00:10.0: restoring config space at offset 0x8 (was 0x10, writing 0x93009200)
[93889.596770] pci 0000:00:10.0: restoring config space at offset 0x7 (was 0x101, writing 0x1111)
[93889.596773] pci 0000:00:10.0: restoring config space at offset 0x6 (was 0x0, writing 0x20200)
[93889.596778] pci 0000:00:10.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10040)
[93889.596782] pci 0000:00:10.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[93889.596852] pcieport-driver 0000:00:15.0: restoring config space at offset 0xf (was 0x100, writing 0x1ff)
[93889.596873] pcieport-driver 0000:00:15.0: restoring config space at offset 0xa (was 0x0, writing 0xffffffff)
[93889.596882] pcieport-driver 0000:00:15.0: restoring config space at offset 0x9 (was 0x10011, writing 0x1fff1)
[93889.596890] pcieport-driver 0000:00:15.0: restoring config space at offset 0x8 (was 0x10, writing 0x93109310)
[93889.596899] pcieport-driver 0000:00:15.0: restoring config space at offset 0x7 (was 0x101, writing 0x1f1)
[93889.596907] pcieport-driver 0000:00:15.0: restoring config space at offset 0x6 (was 0x0, writing 0x30300)
[93889.596922] pcieport-driver 0000:00:15.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10040)
[93889.596933] pcieport-driver 0000:00:15.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407)
[93889.597038] nvidia 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
[93889.597045] nvidia 0000:02:00.0: restoring config space at offset 0x9 (was 0x1, writing 0x1001)
[93889.597049] nvidia 0000:02:00.0: restoring config space at offset 0x7 (was 0xc, writing 0x9000000c)
[93889.597053] nvidia 0000:02:00.0: restoring config space at offset 0x5 (was 0xc, writing 0x8000000c)
[93889.597057] nvidia 0000:02:00.0: restoring config space at offset 0x4 (was 0x0, writing 0x92000000)
[93889.597062] nvidia 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[93889.597267] wl 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
[93889.597285] wl 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0x93100004)
[93889.597290] wl 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x40)
[93889.597296] wl 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100006)
[93897.196275] nForce2_smbus 0000:00:03.2: PME# disabled
[93897.196282] ohci_hcd 0000:00:04.0: PME# disabled
[93897.196289] ohci_hcd 0000:00:04.0: PCI INT A -> Link[LUS0] -> GSI 19 (level, low) -> IRQ 19
[93897.196295] ohci_hcd 0000:00:04.0: setting latency timer to 64
[93897.220104] ehci_hcd 0000:00:04.1: PME# disabled
[93897.220110] ehci_hcd 0000:00:04.1: PCI INT B -> Link[LUS2] -> GSI 21 (level, low) -> IRQ 21
[93897.220116] ehci_hcd 0000:00:04.1: setting latency timer to 64
[93897.220123] ohci_hcd 0000:00:06.0: PME# disabled
[93897.220129] ohci_hcd 0000:00:06.0: PCI INT A -> Link[Z000] -> GSI 18 (level, low) -> IRQ 18
[93897.220134] ohci_hcd 0000:00:06.0: setting latency timer to 64
[93897.244097] ehci_hcd 0000:00:06.1: PME# disabled
[93897.244103] ehci_hcd 0000:00:06.1: PCI INT B -> Link[Z001] -> GSI 20 (level, low) -> IRQ 20
[93897.244108] ehci_hcd 0000:00:06.1: setting latency timer to 64
[93897.244125] HDA Intel 0000:00:08.0: PCI INT A -> Link[LAZA] -> GSI 23 (level, low) -> IRQ 23
[93897.244130] HDA Intel 0000:00:08.0: setting latency timer to 64
[93897.244153] pci 0000:00:09.0: setting latency timer to 64
[93897.244163] forcedeth 0000:00:0a.0: wake-up capability disabled by ACPI
[93897.244168] forcedeth 0000:00:0a.0: PME# disabled
[93897.308827] ahci 0000:00:0b.0: setting latency timer to 64
[93897.308855] pci 0000:00:10.0: setting latency timer to 64
[93897.801037] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[93897.801054] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[93897.801930] ata1.00: configured for UDMA/100
[93897.811975] ata2.00: configured for UDMA/133
[93897.816753] ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
[93897.816755] ata1: irq_stat 0x00400040, connection status changed
[93897.817641] ata1.00: configured for UDMA/100
[93897.817643] ata1: EH complete
[93897.833037] ata2: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
[93897.833039] ata2: irq_stat 0x40000001
[93897.843017] ata2.00: configured for UDMA/133
[93897.843020] ata2: EH complete
[93901.388026] ACPI Exception: AE_TIME, Returned by Handler for [EmbeddedControl] 20090521 evregion-424
[93901.388035] ACPI Error (psparse-0537): Method parse/execution failed [\_SB_.PCI0.LPCB.EC__._Q10] (Node f7012c30), AE_TIME
[93902.171912] wl 0000:03:00.0: PCI INT A -> Link[Z00F] -> GSI 23 (level, low) -> IRQ 23
[93902.171917] wl 0000:03:00.0: setting latency timer to 64
[93902.624215] sd 0:0:0:0: [sda] Starting disk
[93902.922111] applesmc: device has already been initialized (0xe0, 0x00).
[93902.922280] PM: resume devices took 13.324 seconds
[93902.922281] ------------[ cut here ]------------
[93902.922288] WARNING: at /build/buildd/linux-2.6.31/kernel/power/suspend_test.c:52 suspend_test_finish+0x80/0x90()
[93902.922291] Hardware name: MacBook5,1
[93902.922292] Component: resume devices
[93902.922293] Modules linked in: nls_utf8 nls_iso8859_1 nls_cp437 ufs qnx4 hfsplus hfs minix ntfs vfat msdos fat jfs xfs exportfs reiserfs binfmt_misc ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT xt_tcpudp ppdev bridge stp bnep kvm_intel kvm snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device nvidia(P) agpgart lib80211_crypt_tkip snd applesmc soundcore uvcvideo joydev nvidia_bl led_class snd_page_alloc shpchp iptable_filter i2c_nforce2 wl(P) bcm5974 input_polldev ip_tables videodev btusb v4l1_compat lib80211 x_tables mbp_nvidia_bl lp parport hid_apple usbhid forcedeth
[93902.922346] Pid: 26329, comm: pm-suspend Tainted: P        W  2.6.31-14-generic #48-Ubuntu
[93902.922348] Call Trace:
[93902.922354]  [<c014518d>] warn_slowpath_common+0x6d/0xa0
[93902.922358]  [<c0174e70>] ? suspend_test_finish+0x80/0x90
[93902.922361]  [<c0174e70>] ? suspend_test_finish+0x80/0x90
[93902.922364]  [<c0145206>] warn_slowpath_fmt+0x26/0x30
[93902.922367]  [<c0174e70>] suspend_test_finish+0x80/0x90
[93902.922371]  [<c0174c5f>] suspend_devices_and_enter+0x9f/0xd0
[93902.922375]  [<c056e41c>] ? printk+0x18/0x1c
[93902.922379]  [<c0174d49>] enter_state+0xb9/0xf0
[93902.922382]  [<c017443d>] state_store+0x6d/0xb0
[93902.922385]  [<c01743d0>] ? state_store+0x0/0xb0
[93902.922389]  [<c0311a70>] kobj_attr_store+0x20/0x30
[93902.922393]  [<c0239290>] sysfs_write_file+0x90/0x100
[93902.922398]  [<c01e747a>] vfs_write+0x9a/0x190
[93902.922401]  [<c0239200>] ? sysfs_write_file+0x0/0x100
[93902.922404]  [<c0572d4b>] ? do_page_fault+0x19b/0x380
[93902.922407]  [<c01e7f3d>] sys_write+0x3d/0x70
[93902.922411]  [<c010336c>] syscall_call+0x7/0xb
[93902.922413] ---[ end trace 4869b1817e9d6972 ]---
[93902.922782] PM: Finishing wakeup.
[93902.922784] Restarting tasks ... done.
[93903.032026] usb 2-2: new high speed USB device using ehci_hcd and address 6
[93903.086269] forcedeth 0000:00:0a.0: irq 26 for MSI/MSI-X
[93903.197546] usb 2-2: configuration #1 chosen from 1 choice
[93903.197835] hub 2-2:1.0: USB hub found
[93903.198309] hub 2-2:1.0: 3 ports detected
[93903.517086] usb 2-2.2: new low speed USB device using ehci_hcd and address 7
[93903.615677] usb 2-2.2: configuration #1 chosen from 1 choice
[93903.619649] input: Apple, Inc Apple Keyboard as /devices/pci0000:00/0000:00:06.1/usb2/2-2/2-2.2/2-2.2:1.0/input/input15
[93903.619719] apple 0003:05AC:0221.0009: input,hidraw3: USB HID v1.11 Keyboard [Apple, Inc Apple Keyboard] on usb-0000:00:06.1-2.2/input0
[93903.622757] input: Apple, Inc Apple Keyboard as /devices/pci0000:00/0000:00:06.1/usb2/2-2/2-2.2/2-2.2:1.1/input/input16
[93903.622817] apple 0003:05AC:0221.000A: input,hidraw4: USB HID v1.11 Device [Apple, Inc Apple Keyboard] on usb-0000:00:06.1-2.2/input1
[93903.693203] usb 2-2.3: new low speed USB device using ehci_hcd and address 8
[93908.797087] usb 2-2.3: configuration #1 chosen from 1 choice
[93908.811157] input: Microsoft Microsoft Basic Optical Mouse as /devices/pci0000:00/0000:00:06.1/usb2/2-2/2-2.3/2-2.3:1.0/input/input17
[93908.811275] generic-usb 0003:045E:0084.000B: input,hidraw5: USB HID v1.11 Mouse [Microsoft Microsoft Basic Optical Mouse] on usb-0000:00:06.1-2.3/input0
[93913.984017] eth2: no IPv6 routers present
[112570.336935] CE: hpet increasing min_delta_ns to 33750 nsec
[123643.198048] usb 2-2: USB disconnect, address 6
[123643.198053] usb 2-2.2: USB disconnect, address 7
[123643.252541] usb 2-2.3: USB disconnect, address 8
[123677.932240] eth0: link down.
[123683.027406] PM: Syncing filesystems ... done.
[123683.043558] PM: Preparing system for mem sleep
[123683.043563] Freezing user space processes ... (elapsed 0.01 seconds) done.
[123683.057853] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
[123683.057887] PM: Entering mem sleep
[123683.057899] Suspending console(s) (use no_console_suspend to debug)
[123683.124049] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[123683.124188] sd 0:0:0:0: [sda] Stopping disk
[123684.044422] wl 0000:03:00.0: PCI INT A disabled
[123684.060166] wl 0000:03:00.0: power state changed by ACPI to D3
[123684.120354] forcedeth 0000:00:0a.0: wake-up capability disabled by ACPI
[123684.120359] forcedeth 0000:00:0a.0: PME# disabled
[123684.248044] HDA Intel 0000:00:08.0: PCI INT A disabled
[123684.264037] ehci_hcd 0000:00:06.1: PCI INT B disabled
[123684.264043] ohci_hcd 0000:00:06.0: PCI INT A disabled
[123684.264050] ehci_hcd 0000:00:04.1: PCI INT B disabled
[123684.264055] ohci_hcd 0000:00:04.0: PCI INT A disabled
[123684.264118] PM: suspend devices took 1.208 seconds
[123684.264355] ehci_hcd 0000:00:06.1: PME# disabled
[123684.280035] ohci_hcd 0000:00:06.0: PME# disabled
[123684.296038] ehci_hcd 0000:00:04.1: PME# disabled
[123684.312034] ohci_hcd 0000:00:04.0: PME# disabled
[123684.416069] ACPI: Preparing to enter system sleep state S3
[123684.464196] Disabling non-boot CPUs ...
[123684.466374] kvm: disabling virtualization on CPU1
[123684.568017] CPU 1 is now offline
[123684.568020] SMP alternatives: switching to UP code
[123684.575037] CPU0 attaching NULL sched-domain.
[123684.575039] CPU1 attaching NULL sched-domain.
[123684.575044] CPU0 attaching NULL sched-domain.
[123684.575300] CPU1 is down
[123684.575375] Extended CMOS year: 2000
[123684.575375] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[123684.575375] Back to C!
[123684.575375] CPU0: Thermal LVT vector (0xfa) already installed
[123684.575375] Extended CMOS year: 2000
[123684.579282] Enabling non-boot CPUs ...
[123684.579540] SMP alternatives: switching to SMP code
[123684.586269] Booting processor 1 APIC 0x1 ip 0x6000
[123684.574880] Initializing CPU#1
[123684.574880] Calibrating delay using timer specific routine.. 3980.02 BogoMIPS (lpj=7960049)
[123684.574880] CPU: L1 I cache: 32K, L1 D cache: 32K
[123684.574880] CPU: L2 cache: 3072K
[123684.574880] CPU: Physical Processor ID: 0
[123684.574880] CPU: Processor Core ID: 1
[123684.574880] mce: CPU supports 6 MCE banks
[123684.574880] CPU1: Thermal monitoring enabled (TM2)
[123684.574880] x86 PAT enabled: cpu 1, old 0x7040600070406, new 0x7010600070106
[123684.677818] CPU1: Intel(R) Core(TM)2 Duo CPU     P7350  @ 2.00GHz stepping 06
[123684.677848] kvm: enabling virtualization on CPU1
[123684.677911] CPU0 attaching NULL sched-domain.
[123684.681013] Switched to high resolution mode on CPU 1
[123684.692024] CPU0 attaching sched-domain:
[123684.692027]  domain 0: span 0-1 level MC
[123684.692029]   groups: 0 1
[123684.692033] CPU1 attaching sched-domain:
[123684.692035]  domain 0: span 0-1 level MC
[123684.692037]   groups: 1 0
[123684.693015] CPU1 is up
[123684.693018] ACPI: Waking up from system sleep state S3
[123685.124217] pci 0000:00:03.0: restoring config space at offset 0xf (was 0x0, writing 0xff)
[123685.124269] nForce2_smbus 0000:00:03.2: restoring config space at offset 0xf (was 0x100, writing 0x10f)
[123685.124277] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x9 (was 0xa01, writing 0x2101)
[123685.124281] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x8 (was 0xb01, writing 0x2141)
[123685.124287] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x4 (was 0x1, writing 0x2181)
[123685.124400] pci 0000:00:03.5: restoring config space at offset 0x4 (was 0x0, writing 0x93300000)
[123685.124410] pci 0000:00:03.5: restoring config space at offset 0x1 (was 0xa00000, writing 0xa00006)
[123685.124427] ohci_hcd 0000:00:04.0: restoring config space at offset 0xf (was 0x1030100, writing 0x103010b)
[123685.124452] ohci_hcd 0000:00:04.0: PME# disabled
[123685.124461] ehci_hcd 0000:00:04.1: restoring config space at offset 0xf (was 0x1030200, writing 0x103020a)
[123685.124473] ehci_hcd 0000:00:04.1: restoring config space at offset 0x4 (was 0x0, writing 0x93389200)
[123685.124479] ehci_hcd 0000:00:04.1: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[123685.124495] ehci_hcd 0000:00:04.1: PME# disabled
[123685.124503] ohci_hcd 0000:00:06.0: restoring config space at offset 0xf (was 0x1030100, writing 0x1030107)
[123685.124526] ohci_hcd 0000:00:06.0: PME# disabled
[123685.124535] ehci_hcd 0000:00:06.1: restoring config space at offset 0xf (was 0x1030200, writing 0x1030205)
[123685.124547] ehci_hcd 0000:00:06.1: restoring config space at offset 0x4 (was 0x0, writing 0x93389100)
[123685.124552] ehci_hcd 0000:00:06.1: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[123685.124569] ehci_hcd 0000:00:06.1: PME# disabled
[123685.124576] HDA Intel 0000:00:08.0: restoring config space at offset 0xf (was 0x5020100, writing 0x502010f)
[123685.124588] HDA Intel 0000:00:08.0: restoring config space at offset 0x4 (was 0x0, writing 0x93380000)
[123685.124594] HDA Intel 0000:00:08.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[123685.124609] pci 0000:00:09.0: restoring config space at offset 0xf (was 0x2000000, writing 0x20000ff)
[123685.124616] pci 0000:00:09.0: restoring config space at offset 0x9 (was 0x0, writing 0xfff0)
[123685.124620] pci 0000:00:09.0: restoring config space at offset 0x8 (was 0x0, writing 0x93209320)
[123685.124624] pci 0000:00:09.0: restoring config space at offset 0x7 (was 0x22800000, writing 0x28000f0)
[123685.124628] pci 0000:00:09.0: restoring config space at offset 0x6 (was 0x0, writing 0x10100)
[123685.124635] pci 0000:00:09.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00006)
[123685.124653] forcedeth 0000:00:0a.0: restoring config space at offset 0xf (was 0x14010100, writing 0x1401010e)
[123685.124661] forcedeth 0000:00:0a.0: restoring config space at offset 0x7 (was 0x0, writing 0x93389300)
[123685.124665] forcedeth 0000:00:0a.0: restoring config space at offset 0x6 (was 0x0, writing 0x93389000)
[123685.124668] forcedeth 0000:00:0a.0: restoring config space at offset 0x5 (was 0x1, writing 0x21e1)
[123685.124672] forcedeth 0000:00:0a.0: restoring config space at offset 0x4 (was 0x0, writing 0x93386000)
[123685.124677] forcedeth 0000:00:0a.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00007)
[123685.124699] ahci 0000:00:0b.0: restoring config space at offset 0xf (was 0x1030100, writing 0x103010b)
[123685.124706] ahci 0000:00:0b.0: restoring config space at offset 0x9 (was 0x0, writing 0x93384000)
[123685.124709] ahci 0000:00:0b.0: restoring config space at offset 0x8 (was 0xff01, writing 0x21c1)
[123685.124713] ahci 0000:00:0b.0: restoring config space at offset 0x7 (was 0xff2d, writing 0x21e9)
[123685.124716] ahci 0000:00:0b.0: restoring config space at offset 0x6 (was 0xff19, writing 0x21d1)
[123685.124720] ahci 0000:00:0b.0: restoring config space at offset 0x5 (was 0xff25, writing 0x21ed)
[123685.124724] ahci 0000:00:0b.0: restoring config space at offset 0x4 (was 0xff11, writing 0x21d9)
[123685.124728] ahci 0000:00:0b.0: restoring config space at offset 0x1 (was 0xb00001, writing 0xb00407)
[123685.124756] pci 0000:00:10.0: restoring config space at offset 0xf (was 0x0, writing 0x800ff)
[123685.124763] pci 0000:00:10.0: restoring config space at offset 0x9 (was 0x10011, writing 0x91f18001)
[123685.124766] pci 0000:00:10.0: restoring config space at offset 0x8 (was 0x10, writing 0x93009200)
[123685.124770] pci 0000:00:10.0: restoring config space at offset 0x7 (was 0x101, writing 0x1111)
[123685.124773] pci 0000:00:10.0: restoring config space at offset 0x6 (was 0x0, writing 0x20200)
[123685.124778] pci 0000:00:10.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10040)
[123685.124782] pci 0000:00:10.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[123685.124852] pcieport-driver 0000:00:15.0: restoring config space at offset 0xf (was 0x100, writing 0x1ff)
[123685.124873] pcieport-driver 0000:00:15.0: restoring config space at offset 0xa (was 0x0, writing 0xffffffff)
[123685.124881] pcieport-driver 0000:00:15.0: restoring config space at offset 0x9 (was 0x10011, writing 0x1fff1)
[123685.124889] pcieport-driver 0000:00:15.0: restoring config space at offset 0x8 (was 0x10, writing 0x93109310)
[123685.124898] pcieport-driver 0000:00:15.0: restoring config space at offset 0x7 (was 0x101, writing 0x1f1)
[123685.124906] pcieport-driver 0000:00:15.0: restoring config space at offset 0x6 (was 0x0, writing 0x30300)
[123685.124921] pcieport-driver 0000:00:15.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10040)
[123685.124932] pcieport-driver 0000:00:15.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407)
[123685.125037] nvidia 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
[123685.125044] nvidia 0000:02:00.0: restoring config space at offset 0x9 (was 0x1, writing 0x1001)
[123685.125048] nvidia 0000:02:00.0: restoring config space at offset 0x7 (was 0xc, writing 0x9000000c)
[123685.125052] nvidia 0000:02:00.0: restoring config space at offset 0x5 (was 0xc, writing 0x8000000c)
[123685.125056] nvidia 0000:02:00.0: restoring config space at offset 0x4 (was 0x0, writing 0x92000000)
[123685.125061] nvidia 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[123685.125266] wl 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
[123685.125284] wl 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0x93100004)
[123685.125289] wl 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x40)
[123685.125295] wl 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100006)
[123692.396291] nForce2_smbus 0000:00:03.2: PME# disabled
[123692.396298] ohci_hcd 0000:00:04.0: PME# disabled
[123692.396306] ohci_hcd 0000:00:04.0: PCI INT A -> Link[LUS0] -> GSI 19 (level, low) -> IRQ 19
[123692.396311] ohci_hcd 0000:00:04.0: setting latency timer to 64
[123692.420104] ehci_hcd 0000:00:04.1: PME# disabled
[123692.420110] ehci_hcd 0000:00:04.1: PCI INT B -> Link[LUS2] -> GSI 21 (level, low) -> IRQ 21
[123692.420116] ehci_hcd 0000:00:04.1: setting latency timer to 64
[123692.420123] ohci_hcd 0000:00:06.0: PME# disabled
[123692.420129] ohci_hcd 0000:00:06.0: PCI INT A -> Link[Z000] -> GSI 18 (level, low) -> IRQ 18
[123692.420134] ohci_hcd 0000:00:06.0: setting latency timer to 64
[123692.444096] ehci_hcd 0000:00:06.1: PME# disabled
[123692.444102] ehci_hcd 0000:00:06.1: PCI INT B -> Link[Z001] -> GSI 20 (level, low) -> IRQ 20
[123692.444108] ehci_hcd 0000:00:06.1: setting latency timer to 64
[123692.444116] HDA Intel 0000:00:08.0: PCI INT A -> Link[LAZA] -> GSI 23 (level, low) -> IRQ 23
[123692.444122] HDA Intel 0000:00:08.0: setting latency timer to 64
[123692.444145] pci 0000:00:09.0: setting latency timer to 64
[123692.444149] forcedeth 0000:00:0a.0: wake-up capability disabled by ACPI
[123692.444155] forcedeth 0000:00:0a.0: PME# disabled
[123692.508827] ahci 0000:00:0b.0: setting latency timer to 64
[123692.508854] pci 0000:00:10.0: setting latency timer to 64
[123693.000049] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[123693.000066] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[123693.000931] ata1.00: configured for UDMA/100
[123693.010986] ata2.00: configured for UDMA/133
[123693.017035] ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
[123693.017037] ata1: irq_stat 0x00400040, connection status changed
[123693.017907] ata1.00: configured for UDMA/100
[123693.017909] ata1: EH complete
[123693.029039] ata2: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
[123693.029041] ata2: irq_stat 0x40000001
[123693.039017] ata2.00: configured for UDMA/133
[123693.039019] ata2: EH complete
[123697.365043] wl 0000:03:00.0: PCI INT A -> Link[Z00F] -> GSI 23 (level, low) -> IRQ 23
[123697.365049] wl 0000:03:00.0: setting latency timer to 64
[123697.712199] sd 0:0:0:0: [sda] Starting disk
[123697.959358] applesmc: device has already been initialized (0xe0, 0x00).
[123697.959523] PM: resume devices took 12.832 seconds
[123697.959525] ------------[ cut here ]------------
[123697.959532] WARNING: at /build/buildd/linux-2.6.31/kernel/power/suspend_test.c:52 suspend_test_finish+0x80/0x90()
[123697.959534] Hardware name: MacBook5,1
[123697.959536] Component: resume devices
[123697.959537] Modules linked in: nls_utf8 nls_iso8859_1 nls_cp437 ufs qnx4 hfsplus hfs minix ntfs vfat msdos fat jfs xfs exportfs reiserfs binfmt_misc ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT xt_tcpudp ppdev bridge stp bnep kvm_intel kvm snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device nvidia(P) agpgart lib80211_crypt_tkip snd applesmc soundcore uvcvideo joydev nvidia_bl led_class snd_page_alloc shpchp iptable_filter i2c_nforce2 wl(P) bcm5974 input_polldev ip_tables videodev btusb v4l1_compat lib80211 x_tables mbp_nvidia_bl lp parport hid_apple usbhid forcedeth
[123697.959590] Pid: 9803, comm: pm-suspend Tainted: P        W  2.6.31-14-generic #48-Ubuntu
[123697.959593] Call Trace:
[123697.959599]  [<c014518d>] warn_slowpath_common+0x6d/0xa0
[123697.959602]  [<c0174e70>] ? suspend_test_finish+0x80/0x90
[123697.959605]  [<c0174e70>] ? suspend_test_finish+0x80/0x90
[123697.959609]  [<c0145206>] warn_slowpath_fmt+0x26/0x30
[123697.959612]  [<c0174e70>] suspend_test_finish+0x80/0x90
[123697.959615]  [<c0174c5f>] suspend_devices_and_enter+0x9f/0xd0
[123697.959620]  [<c056e41c>] ? printk+0x18/0x1c
[123697.959623]  [<c0174d49>] enter_state+0xb9/0xf0
[123697.959626]  [<c017443d>] state_store+0x6d/0xb0
[123697.959629]  [<c01743d0>] ? state_store+0x0/0xb0
[123697.959633]  [<c0311a70>] kobj_attr_store+0x20/0x30
[123697.959637]  [<c0239290>] sysfs_write_file+0x90/0x100
[123697.959642]  [<c01e747a>] vfs_write+0x9a/0x190
[123697.959645]  [<c0239200>] ? sysfs_write_file+0x0/0x100
[123697.959648]  [<c0572d4b>] ? do_page_fault+0x19b/0x380
[123697.959651]  [<c01e7f3d>] sys_write+0x3d/0x70
[123697.959655]  [<c010336c>] syscall_call+0x7/0xb
[123697.959657] ---[ end trace 4869b1817e9d6973 ]---
[123697.959885] PM: Finishing wakeup.
[123697.959886] Restarting tasks ... done.
[123701.700618] forcedeth 0000:00:0a.0: irq 26 for MSI/MSI-X
[123701.700810] eth0: no link during initialization.
[123701.701295] ADDRCONF(NETDEV_UP): eth0: link is not ready
[123712.109036] eth2: no IPv6 routers present
[130857.753961] PM: Syncing filesystems ... done.
[130857.768514] PM: Preparing system for mem sleep
[130857.768518] Freezing user space processes ... (elapsed 0.00 seconds) done.
[130857.769368] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
[130857.769410] PM: Entering mem sleep
[130857.769422] Suspending console(s) (use no_console_suspend to debug)
[130857.836055] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[130857.836250] sd 0:0:0:0: [sda] Stopping disk
[130858.744428] wl 0000:03:00.0: PCI INT A disabled
[130858.760164] wl 0000:03:00.0: power state changed by ACPI to D3
[130858.816414] forcedeth 0000:00:0a.0: wake-up capability disabled by ACPI
[130858.816420] forcedeth 0000:00:0a.0: PME# disabled
[130858.944045] HDA Intel 0000:00:08.0: PCI INT A disabled
[130858.960100] ehci_hcd 0000:00:06.1: PCI INT B disabled
[130858.960106] ohci_hcd 0000:00:06.0: PCI INT A disabled
[130858.960112] ehci_hcd 0000:00:04.1: PCI INT B disabled
[130858.960118] ohci_hcd 0000:00:04.0: PCI INT A disabled
[130858.960180] PM: suspend devices took 1.192 seconds
[130858.960416] ehci_hcd 0000:00:06.1: PME# disabled
[130858.976035] ohci_hcd 0000:00:06.0: PME# disabled
[130858.992039] ehci_hcd 0000:00:04.1: PME# disabled
[130859.008035] ohci_hcd 0000:00:04.0: PME# disabled
[130859.112069] ACPI: Preparing to enter system sleep state S3
[130859.160207] Disabling non-boot CPUs ...
[130859.162371] kvm: disabling virtualization on CPU1
[130859.264017] CPU 1 is now offline
[130859.264020] SMP alternatives: switching to UP code
[130859.271000] CPU0 attaching NULL sched-domain.
[130859.271003] CPU1 attaching NULL sched-domain.
[130859.271008] CPU0 attaching NULL sched-domain.
[130859.271230] CPU1 is down
[130859.271304] Extended CMOS year: 2000
[130859.271304] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[130859.271304] Back to C!
[130859.271304] CPU0: Thermal LVT vector (0xfa) already installed
[130859.271304] Extended CMOS year: 2000
[130859.275205] Enabling non-boot CPUs ...
[130859.275465] SMP alternatives: switching to SMP code
[130859.282180] Booting processor 1 APIC 0x1 ip 0x6000
[130859.270858] Initializing CPU#1
[130859.270858] Calibrating delay using timer specific routine.. 3980.04 BogoMIPS (lpj=7960087)
[130859.270858] CPU: L1 I cache: 32K, L1 D cache: 32K
[130859.270858] CPU: L2 cache: 3072K
[130859.270858] CPU: Physical Processor ID: 0
[130859.270858] CPU: Processor Core ID: 1
[130859.270858] mce: CPU supports 6 MCE banks
[130859.270858] CPU1: Thermal monitoring enabled (TM2)
[130859.270858] x86 PAT enabled: cpu 1, old 0x7040600070406, new 0x7010600070106
[130859.373820] CPU1: Intel(R) Core(TM)2 Duo CPU     P7350  @ 2.00GHz stepping 06
[130859.373849] kvm: enabling virtualization on CPU1
[130859.373916] CPU0 attaching NULL sched-domain.
[130859.377014] Switched to high resolution mode on CPU 1
[130859.388024] CPU0 attaching sched-domain:
[130859.388027]  domain 0: span 0-1 level MC
[130859.388029]   groups: 0 1
[130859.388033] CPU1 attaching sched-domain:
[130859.388035]  domain 0: span 0-1 level MC
[130859.388037]   groups: 1 0
[130859.389015] CPU1 is up
[130859.389018] ACPI: Waking up from system sleep state S3
[130859.804219] pci 0000:00:03.0: restoring config space at offset 0xf (was 0x0, writing 0xff)
[130859.804271] nForce2_smbus 0000:00:03.2: restoring config space at offset 0xf (was 0x100, writing 0x10f)
[130859.804279] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x9 (was 0xa01, writing 0x2101)
[130859.804283] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x8 (was 0xb01, writing 0x2141)
[130859.804289] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x4 (was 0x1, writing 0x2181)
[130859.804401] pci 0000:00:03.5: restoring config space at offset 0x4 (was 0x0, writing 0x93300000)
[130859.804411] pci 0000:00:03.5: restoring config space at offset 0x1 (was 0xa00000, writing 0xa00006)
[130859.804429] ohci_hcd 0000:00:04.0: restoring config space at offset 0xf (was 0x1030100, writing 0x103010b)
[130859.804453] ohci_hcd 0000:00:04.0: PME# disabled
[130859.804463] ehci_hcd 0000:00:04.1: restoring config space at offset 0xf (was 0x1030200, writing 0x103020a)
[130859.804475] ehci_hcd 0000:00:04.1: restoring config space at offset 0x4 (was 0x0, writing 0x93389200)
[130859.804480] ehci_hcd 0000:00:04.1: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[130859.804497] ehci_hcd 0000:00:04.1: PME# disabled
[130859.804504] ohci_hcd 0000:00:06.0: restoring config space at offset 0xf (was 0x1030100, writing 0x1030107)
[130859.804527] ohci_hcd 0000:00:06.0: PME# disabled
[130859.804537] ehci_hcd 0000:00:06.1: restoring config space at offset 0xf (was 0x1030200, writing 0x1030205)
[130859.804548] ehci_hcd 0000:00:06.1: restoring config space at offset 0x4 (was 0x0, writing 0x93389100)
[130859.804554] ehci_hcd 0000:00:06.1: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[130859.804570] ehci_hcd 0000:00:06.1: PME# disabled
[130859.804578] HDA Intel 0000:00:08.0: restoring config space at offset 0xf (was 0x5020100, writing 0x502010f)
[130859.804590] HDA Intel 0000:00:08.0: restoring config space at offset 0x4 (was 0x0, writing 0x93380000)
[130859.804595] HDA Intel 0000:00:08.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[130859.804610] pci 0000:00:09.0: restoring config space at offset 0xf (was 0x2000000, writing 0x20000ff)
[130859.804618] pci 0000:00:09.0: restoring config space at offset 0x9 (was 0x0, writing 0xfff0)
[130859.804622] pci 0000:00:09.0: restoring config space at offset 0x8 (was 0x0, writing 0x93209320)
[130859.804626] pci 0000:00:09.0: restoring config space at offset 0x7 (was 0x22800000, writing 0x228000f0)
[130859.804629] pci 0000:00:09.0: restoring config space at offset 0x6 (was 0x0, writing 0x10100)
[130859.804636] pci 0000:00:09.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00006)
[130859.804654] forcedeth 0000:00:0a.0: restoring config space at offset 0xf (was 0x14010100, writing 0x1401010e)
[130859.804662] forcedeth 0000:00:0a.0: restoring config space at offset 0x7 (was 0x0, writing 0x93389300)
[130859.804666] forcedeth 0000:00:0a.0: restoring config space at offset 0x6 (was 0x0, writing 0x93389000)
[130859.804670] forcedeth 0000:00:0a.0: restoring config space at offset 0x5 (was 0x1, writing 0x21e1)
[130859.804674] forcedeth 0000:00:0a.0: restoring config space at offset 0x4 (was 0x0, writing 0x93386000)
[130859.804678] forcedeth 0000:00:0a.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00007)
[130859.804700] ahci 0000:00:0b.0: restoring config space at offset 0xf (was 0x1030100, writing 0x103010b)
[130859.804707] ahci 0000:00:0b.0: restoring config space at offset 0x9 (was 0x0, writing 0x93384000)
[130859.804711] ahci 0000:00:0b.0: restoring config space at offset 0x8 (was 0xff01, writing 0x21c1)
[130859.804714] ahci 0000:00:0b.0: restoring config space at offset 0x7 (was 0xff2d, writing 0x21e9)
[130859.804718] ahci 0000:00:0b.0: restoring config space at offset 0x6 (was 0xff19, writing 0x21d1)
[130859.804721] ahci 0000:00:0b.0: restoring config space at offset 0x5 (was 0xff25, writing 0x21ed)
[130859.804725] ahci 0000:00:0b.0: restoring config space at offset 0x4 (was 0xff11, writing 0x21d9)
[130859.804730] ahci 0000:00:0b.0: restoring config space at offset 0x1 (was 0xb00001, writing 0xb00407)
[130859.804758] pci 0000:00:10.0: restoring config space at offset 0xf (was 0x0, writing 0x800ff)
[130859.804764] pci 0000:00:10.0: restoring config space at offset 0x9 (was 0x10011, writing 0x91f18001)
[130859.804768] pci 0000:00:10.0: restoring config space at offset 0x8 (was 0x10, writing 0x93009200)
[130859.804771] pci 0000:00:10.0: restoring config space at offset 0x7 (was 0x101, writing 0x1111)
[130859.804775] pci 0000:00:10.0: restoring config space at offset 0x6 (was 0x0, writing 0x20200)
[130859.804780] pci 0000:00:10.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10040)
[130859.804784] pci 0000:00:10.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[130859.804854] pcieport-driver 0000:00:15.0: restoring config space at offset 0xf (was 0x100, writing 0x1ff)
[130859.804874] pcieport-driver 0000:00:15.0: restoring config space at offset 0xa (was 0x0, writing 0xffffffff)
[130859.804883] pcieport-driver 0000:00:15.0: restoring config space at offset 0x9 (was 0x10011, writing 0x1fff1)
[130859.804891] pcieport-driver 0000:00:15.0: restoring config space at offset 0x8 (was 0x10, writing 0x93109310)
[130859.804899] pcieport-driver 0000:00:15.0: restoring config space at offset 0x7 (was 0x101, writing 0x1f1)
[130859.804907] pcieport-driver 0000:00:15.0: restoring config space at offset 0x6 (was 0x0, writing 0x30300)
[130859.804922] pcieport-driver 0000:00:15.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10040)
[130859.804933] pcieport-driver 0000:00:15.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407)
[130859.805038] nvidia 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
[130859.805045] nvidia 0000:02:00.0: restoring config space at offset 0x9 (was 0x1, writing 0x1001)
[130859.805049] nvidia 0000:02:00.0: restoring config space at offset 0x7 (was 0xc, writing 0x9000000c)
[130859.805053] nvidia 0000:02:00.0: restoring config space at offset 0x5 (was 0xc, writing 0x8000000c)
[130859.805057] nvidia 0000:02:00.0: restoring config space at offset 0x4 (was 0x0, writing 0x92000000)
[130859.805062] nvidia 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[130859.805267] wl 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
[130859.805285] wl 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0x93100004)
[130859.805290] wl 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x40)
[130859.805296] wl 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100006)
[130867.076296] nForce2_smbus 0000:00:03.2: PME# disabled
[130867.076303] ohci_hcd 0000:00:04.0: PME# disabled
[130867.076311] ohci_hcd 0000:00:04.0: PCI INT A -> Link[LUS0] -> GSI 19 (level, low) -> IRQ 19
[130867.076316] ohci_hcd 0000:00:04.0: setting latency timer to 64
[130867.100103] ehci_hcd 0000:00:04.1: PME# disabled
[130867.100110] ehci_hcd 0000:00:04.1: PCI INT B -> Link[LUS2] -> GSI 21 (level, low) -> IRQ 21
[130867.100115] ehci_hcd 0000:00:04.1: setting latency timer to 64
[130867.100123] ohci_hcd 0000:00:06.0: PME# disabled
[130867.100128] ohci_hcd 0000:00:06.0: PCI INT A -> Link[Z000] -> GSI 18 (level, low) -> IRQ 18
[130867.100134] ohci_hcd 0000:00:06.0: setting latency timer to 64
[130867.124099] ehci_hcd 0000:00:06.1: PME# disabled
[130867.124105] ehci_hcd 0000:00:06.1: PCI INT B -> Link[Z001] -> GSI 20 (level, low) -> IRQ 20
[130867.124110] ehci_hcd 0000:00:06.1: setting latency timer to 64
[130867.124119] HDA Intel 0000:00:08.0: PCI INT A -> Link[LAZA] -> GSI 23 (level, low) -> IRQ 23
[130867.124124] HDA Intel 0000:00:08.0: setting latency timer to 64
[130867.124147] pci 0000:00:09.0: setting latency timer to 64
[130867.124152] forcedeth 0000:00:0a.0: wake-up capability disabled by ACPI
[130867.124163] forcedeth 0000:00:0a.0: PME# disabled
[130867.188820] ahci 0000:00:0b.0: setting latency timer to 64
[130867.188848] pci 0000:00:10.0: setting latency timer to 64
[130867.681038] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[130867.681055] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[130867.681942] ata1.00: configured for UDMA/100
[130867.691961] ata2.00: configured for UDMA/133
[130867.696739] ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
[130867.696741] ata1: irq_stat 0x00400040, connection status changed
[130867.697632] ata1.00: configured for UDMA/100
[130867.697634] ata1: EH complete
[130867.713037] ata2: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
[130867.713039] ata2: irq_stat 0x40000001
[130867.723017] ata2.00: configured for UDMA/133
[130867.723019] ata2: EH complete
[130872.044819] wl 0000:03:00.0: PCI INT A -> Link[Z00F] -> GSI 23 (level, low) -> IRQ 23
[130872.044824] wl 0000:03:00.0: setting latency timer to 64
[130872.392269] sd 0:0:0:0: [sda] Starting disk
[130872.647869] applesmc: device has already been initialized (0xe0, 0x00).
[130872.648048] PM: resume devices took 12.844 seconds
[130872.648049] ------------[ cut here ]------------
[130872.648056] WARNING: at /build/buildd/linux-2.6.31/kernel/power/suspend_test.c:52 suspend_test_finish+0x80/0x90()
[130872.648058] Hardware name: MacBook5,1
[130872.648059] Component: resume devices
[130872.648061] Modules linked in: nls_utf8 nls_iso8859_1 nls_cp437 ufs qnx4 hfsplus hfs minix ntfs vfat msdos fat jfs xfs exportfs reiserfs binfmt_misc ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT xt_tcpudp ppdev bridge stp bnep kvm_intel kvm snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device nvidia(P) agpgart lib80211_crypt_tkip snd applesmc soundcore uvcvideo joydev nvidia_bl led_class snd_page_alloc shpchp iptable_filter i2c_nforce2 wl(P) bcm5974 input_polldev ip_tables videodev btusb v4l1_compat lib80211 x_tables mbp_nvidia_bl lp parport hid_apple usbhid forcedeth
[130872.648113] Pid: 15374, comm: pm-suspend Tainted: P        W  2.6.31-14-generic #48-Ubuntu
[130872.648116] Call Trace:
[130872.648122]  [<c014518d>] warn_slowpath_common+0x6d/0xa0
[130872.648125]  [<c0174e70>] ? suspend_test_finish+0x80/0x90
[130872.648129]  [<c0174e70>] ? suspend_test_finish+0x80/0x90
[130872.648132]  [<c0145206>] warn_slowpath_fmt+0x26/0x30
[130872.648135]  [<c0174e70>] suspend_test_finish+0x80/0x90
[130872.648139]  [<c0174c5f>] suspend_devices_and_enter+0x9f/0xd0
[130872.648143]  [<c056e41c>] ? printk+0x18/0x1c
[130872.648146]  [<c0174d49>] enter_state+0xb9/0xf0
[130872.648149]  [<c017443d>] state_store+0x6d/0xb0
[130872.648152]  [<c01743d0>] ? state_store+0x0/0xb0
[130872.648157]  [<c0311a70>] kobj_attr_store+0x20/0x30
[130872.648161]  [<c0239290>] sysfs_write_file+0x90/0x100
[130872.648165]  [<c01e747a>] vfs_write+0x9a/0x190
[130872.648169]  [<c0239200>] ? sysfs_write_file+0x0/0x100
[130872.648172]  [<c0572d4b>] ? do_page_fault+0x19b/0x380
[130872.648175]  [<c01e7f3d>] sys_write+0x3d/0x70
[130872.648179]  [<c010336c>] syscall_call+0x7/0xb
[130872.648181] ---[ end trace 4869b1817e9d6974 ]---
[130872.648408] PM: Finishing wakeup.
[130872.648409] Restarting tasks ... done.
[130876.679013] forcedeth 0000:00:0a.0: irq 26 for MSI/MSI-X
[130876.679205] eth0: no link during initialization.
[130876.679667] ADDRCONF(NETDEV_UP): eth0: link is not ready
[130887.156093] eth2: no IPv6 routers present
[133704.339586] PM: Syncing filesystems ... done.
[133704.354048] PM: Preparing system for mem sleep
[133704.354052] Freezing user space processes ... (elapsed 0.00 seconds) done.
[133704.355023] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
[133704.355064] PM: Entering mem sleep
[133704.355077] Suspending console(s) (use no_console_suspend to debug)
[133704.420053] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[133704.420247] sd 0:0:0:0: [sda] Stopping disk
[133705.336427] wl 0000:03:00.0: PCI INT A disabled
[133705.352164] wl 0000:03:00.0: power state changed by ACPI to D3
[133705.412417] forcedeth 0000:00:0a.0: wake-up capability disabled by ACPI
[133705.412422] forcedeth 0000:00:0a.0: PME# disabled
[133705.540045] HDA Intel 0000:00:08.0: PCI INT A disabled
[133705.556099] ehci_hcd 0000:00:06.1: PCI INT B disabled
[133705.556106] ohci_hcd 0000:00:06.0: PCI INT A disabled
[133705.556112] ehci_hcd 0000:00:04.1: PCI INT B disabled
[133705.556118] ohci_hcd 0000:00:04.0: PCI INT A disabled
[133705.556180] PM: suspend devices took 1.204 seconds
[133705.556417] ehci_hcd 0000:00:06.1: PME# disabled
[133705.572035] ohci_hcd 0000:00:06.0: PME# disabled
[133705.588038] ehci_hcd 0000:00:04.1: PME# disabled
[133705.604034] ohci_hcd 0000:00:04.0: PME# disabled
[133705.708069] ACPI: Preparing to enter system sleep state S3
[133705.756208] Disabling non-boot CPUs ...
[133705.758373] kvm: disabling virtualization on CPU1
[133705.860017] CPU 1 is now offline
[133705.860020] SMP alternatives: switching to UP code
[133705.867011] CPU0 attaching NULL sched-domain.
[133705.867014] CPU1 attaching NULL sched-domain.
[133705.867021] CPU0 attaching NULL sched-domain.
[133705.867242] CPU1 is down
[133705.867316] Extended CMOS year: 2000
[133705.867316] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[133705.867316] Back to C!
[133705.867316] CPU0: Thermal LVT vector (0xfa) already installed
[133705.867316] Extended CMOS year: 2000
[133705.871217] Enabling non-boot CPUs ...
[133705.871473] SMP alternatives: switching to SMP code
[133705.878191] Booting processor 1 APIC 0x1 ip 0x6000
[133705.866886] Initializing CPU#1
[133705.866886] Calibrating delay using timer specific routine.. 3980.04 BogoMIPS (lpj=7960084)
[133705.866886] CPU: L1 I cache: 32K, L1 D cache: 32K
[133705.866886] CPU: L2 cache: 3072K
[133705.866886] CPU: Physical Processor ID: 0
[133705.866886] CPU: Processor Core ID: 1
[133705.866886] mce: CPU supports 6 MCE banks
[133705.866886] CPU1: Thermal monitoring enabled (TM2)
[133705.866886] x86 PAT enabled: cpu 1, old 0x7040600070406, new 0x7010600070106
[133705.969834] CPU1: Intel(R) Core(TM)2 Duo CPU     P7350  @ 2.00GHz stepping 06
[133705.969863] kvm: enabling virtualization on CPU1
[133705.969926] CPU0 attaching NULL sched-domain.
[133705.973013] Switched to high resolution mode on CPU 1
[133705.984025] CPU0 attaching sched-domain:
[133705.984028]  domain 0: span 0-1 level MC
[133705.984030]   groups: 0 1
[133705.984034] CPU1 attaching sched-domain:
[133705.984036]  domain 0: span 0-1 level MC
[133705.984038]   groups: 1 0
[133705.985015] CPU1 is up
[133705.985018] ACPI: Waking up from system sleep state S3
[133706.408216] pci 0000:00:03.0: restoring config space at offset 0xf (was 0x0, writing 0xff)
[133706.408267] nForce2_smbus 0000:00:03.2: restoring config space at offset 0xf (was 0x100, writing 0x10f)
[133706.408275] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x9 (was 0xa01, writing 0x2101)
[133706.408279] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x8 (was 0xb01, writing 0x2141)
[133706.408286] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x4 (was 0x1, writing 0x2181)
[133706.408398] pci 0000:00:03.5: restoring config space at offset 0x4 (was 0x0, writing 0x93300000)
[133706.408408] pci 0000:00:03.5: restoring config space at offset 0x1 (was 0xa00000, writing 0xa00006)
[133706.408426] ohci_hcd 0000:00:04.0: restoring config space at offset 0xf (was 0x1030100, writing 0x103010b)
[133706.408450] ohci_hcd 0000:00:04.0: PME# disabled
[133706.408460] ehci_hcd 0000:00:04.1: restoring config space at offset 0xf (was 0x1030200, writing 0x103020a)
[133706.408472] ehci_hcd 0000:00:04.1: restoring config space at offset 0x4 (was 0x0, writing 0x93389200)
[133706.408477] ehci_hcd 0000:00:04.1: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[133706.408494] ehci_hcd 0000:00:04.1: PME# disabled
[133706.408501] ohci_hcd 0000:00:06.0: restoring config space at offset 0xf (was 0x1030100, writing 0x1030107)
[133706.408524] ohci_hcd 0000:00:06.0: PME# disabled
[133706.408533] ehci_hcd 0000:00:06.1: restoring config space at offset 0xf (was 0x1030200, writing 0x1030205)
[133706.408545] ehci_hcd 0000:00:06.1: restoring config space at offset 0x4 (was 0x0, writing 0x93389100)
[133706.408551] ehci_hcd 0000:00:06.1: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[133706.408567] ehci_hcd 0000:00:06.1: PME# disabled
[133706.408575] HDA Intel 0000:00:08.0: restoring config space at offset 0xf (was 0x5020100, writing 0x502010f)
[133706.408586] HDA Intel 0000:00:08.0: restoring config space at offset 0x4 (was 0x0, writing 0x93380000)
[133706.408592] HDA Intel 0000:00:08.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[133706.408607] pci 0000:00:09.0: restoring config space at offset 0xf (was 0x2000000, writing 0x20000ff)
[133706.408614] pci 0000:00:09.0: restoring config space at offset 0x9 (was 0x0, writing 0xfff0)
[133706.408618] pci 0000:00:09.0: restoring config space at offset 0x8 (was 0x0, writing 0x93209320)
[133706.408622] pci 0000:00:09.0: restoring config space at offset 0x7 (was 0x22800000, writing 0x28000f0)
[133706.408626] pci 0000:00:09.0: restoring config space at offset 0x6 (was 0x0, writing 0x10100)
[133706.408633] pci 0000:00:09.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00006)
[133706.408651] forcedeth 0000:00:0a.0: restoring config space at offset 0xf (was 0x14010100, writing 0x1401010e)
[133706.408659] forcedeth 0000:00:0a.0: restoring config space at offset 0x7 (was 0x0, writing 0x93389300)
[133706.408662] forcedeth 0000:00:0a.0: restoring config space at offset 0x6 (was 0x0, writing 0x93389000)
[133706.408666] forcedeth 0000:00:0a.0: restoring config space at offset 0x5 (was 0x1, writing 0x21e1)
[133706.408670] forcedeth 0000:00:0a.0: restoring config space at offset 0x4 (was 0x0, writing 0x93386000)
[133706.408675] forcedeth 0000:00:0a.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00007)
[133706.408696] ahci 0000:00:0b.0: restoring config space at offset 0xf (was 0x1030100, writing 0x103010b)
[133706.408703] ahci 0000:00:0b.0: restoring config space at offset 0x9 (was 0x0, writing 0x93384000)
[133706.408707] ahci 0000:00:0b.0: restoring config space at offset 0x8 (was 0xff01, writing 0x21c1)
[133706.408710] ahci 0000:00:0b.0: restoring config space at offset 0x7 (was 0xff2d, writing 0x21e9)
[133706.408714] ahci 0000:00:0b.0: restoring config space at offset 0x6 (was 0xff19, writing 0x21d1)
[133706.408718] ahci 0000:00:0b.0: restoring config space at offset 0x5 (was 0xff25, writing 0x21ed)
[133706.408721] ahci 0000:00:0b.0: restoring config space at offset 0x4 (was 0xff11, writing 0x21d9)
[133706.408726] ahci 0000:00:0b.0: restoring config space at offset 0x1 (was 0xb00001, writing 0xb00407)
[133706.408754] pci 0000:00:10.0: restoring config space at offset 0xf (was 0x0, writing 0x800ff)
[133706.408760] pci 0000:00:10.0: restoring config space at offset 0x9 (was 0x10011, writing 0x91f18001)
[133706.408764] pci 0000:00:10.0: restoring config space at offset 0x8 (was 0x10, writing 0x93009200)
[133706.408768] pci 0000:00:10.0: restoring config space at offset 0x7 (was 0x101, writing 0x1111)
[133706.408771] pci 0000:00:10.0: restoring config space at offset 0x6 (was 0x0, writing 0x20200)
[133706.408776] pci 0000:00:10.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10040)
[133706.408780] pci 0000:00:10.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[133706.408850] pcieport-driver 0000:00:15.0: restoring config space at offset 0xf (was 0x100, writing 0x1ff)
[133706.408871] pcieport-driver 0000:00:15.0: restoring config space at offset 0xa (was 0x0, writing 0xffffffff)
[133706.408879] pcieport-driver 0000:00:15.0: restoring config space at offset 0x9 (was 0x10011, writing 0x1fff1)
[133706.408887] pcieport-driver 0000:00:15.0: restoring config space at offset 0x8 (was 0x10, writing 0x93109310)
[133706.408896] pcieport-driver 0000:00:15.0: restoring config space at offset 0x7 (was 0x101, writing 0x1f1)
[133706.408904] pcieport-driver 0000:00:15.0: restoring config space at offset 0x6 (was 0x0, writing 0x30300)
[133706.408919] pcieport-driver 0000:00:15.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10040)
[133706.408930] pcieport-driver 0000:00:15.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407)
[133706.409035] nvidia 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
[133706.409042] nvidia 0000:02:00.0: restoring config space at offset 0x9 (was 0x1, writing 0x1001)
[133706.409046] nvidia 0000:02:00.0: restoring config space at offset 0x7 (was 0xc, writing 0x9000000c)
[133706.409050] nvidia 0000:02:00.0: restoring config space at offset 0x5 (was 0xc, writing 0x8000000c)
[133706.409054] nvidia 0000:02:00.0: restoring config space at offset 0x4 (was 0x0, writing 0x92000000)
[133706.409058] nvidia 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[133706.409263] wl 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
[133706.409281] wl 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0x93100004)
[133706.409286] wl 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x40)
[133706.409292] wl 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100006)
[133713.704292] nForce2_smbus 0000:00:03.2: PME# disabled
[133713.704299] ohci_hcd 0000:00:04.0: PME# disabled
[133713.704307] ohci_hcd 0000:00:04.0: PCI INT A -> Link[LUS0] -> GSI 19 (level, low) -> IRQ 19
[133713.704312] ohci_hcd 0000:00:04.0: setting latency timer to 64
[133713.728105] ehci_hcd 0000:00:04.1: PME# disabled
[133713.728111] ehci_hcd 0000:00:04.1: PCI INT B -> Link[LUS2] -> GSI 21 (level, low) -> IRQ 21
[133713.728116] ehci_hcd 0000:00:04.1: setting latency timer to 64
[133713.728124] ohci_hcd 0000:00:06.0: PME# disabled
[133713.728129] ohci_hcd 0000:00:06.0: PCI INT A -> Link[Z000] -> GSI 18 (level, low) -> IRQ 18
[133713.728135] ohci_hcd 0000:00:06.0: setting latency timer to 64
[133713.752098] ehci_hcd 0000:00:06.1: PME# disabled
[133713.752104] ehci_hcd 0000:00:06.1: PCI INT B -> Link[Z001] -> GSI 20 (level, low) -> IRQ 20
[133713.752110] ehci_hcd 0000:00:06.1: setting latency timer to 64
[133713.752118] HDA Intel 0000:00:08.0: PCI INT A -> Link[LAZA] -> GSI 23 (level, low) -> IRQ 23
[133713.752124] HDA Intel 0000:00:08.0: setting latency timer to 64
[133713.752147] pci 0000:00:09.0: setting latency timer to 64
[133713.752152] forcedeth 0000:00:0a.0: wake-up capability disabled by ACPI
[133713.752162] forcedeth 0000:00:0a.0: PME# disabled
[133713.816827] ahci 0000:00:0b.0: setting latency timer to 64
[133713.816856] pci 0000:00:10.0: setting latency timer to 64
[133714.309035] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[133714.309052] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[133714.309930] ata1.00: configured for UDMA/100
[133714.319959] ata2.00: configured for UDMA/133
[133714.324737] ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
[133714.324739] ata1: irq_stat 0x00400040, connection status changed
[133714.325626] ata1.00: configured for UDMA/100
[133714.325629] ata1: EH complete
[133714.341039] ata2: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
[133714.341041] ata2: irq_stat 0x40000001
[133714.351019] ata2.00: configured for UDMA/133
[133714.351021] ata2: EH complete
[133718.672817] wl 0000:03:00.0: PCI INT A -> Link[Z00F] -> GSI 23 (level, low) -> IRQ 23
[133718.672823] wl 0000:03:00.0: setting latency timer to 64
[133719.020238] sd 0:0:0:0: [sda] Starting disk
[133719.311361] applesmc: device has already been initialized (0xe0, 0x00).
[133719.311526] PM: resume devices took 12.900 seconds
[133719.311527] ------------[ cut here ]------------
[133719.311534] WARNING: at /build/buildd/linux-2.6.31/kernel/power/suspend_test.c:52 suspend_test_finish+0x80/0x90()
[133719.311537] Hardware name: MacBook5,1
[133719.311538] Component: resume devices
[133719.311539] Modules linked in: nls_utf8 nls_iso8859_1 nls_cp437 ufs qnx4 hfsplus hfs minix ntfs vfat msdos fat jfs xfs exportfs reiserfs binfmt_misc ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT xt_tcpudp ppdev bridge stp bnep kvm_intel kvm snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device nvidia(P) agpgart lib80211_crypt_tkip snd applesmc soundcore uvcvideo joydev nvidia_bl led_class snd_page_alloc shpchp iptable_filter i2c_nforce2 wl(P) bcm5974 input_polldev ip_tables videodev btusb v4l1_compat lib80211 x_tables mbp_nvidia_bl lp parport hid_apple usbhid forcedeth
[133719.311592] Pid: 18071, comm: pm-suspend Tainted: P        W  2.6.31-14-generic #48-Ubuntu
[133719.311594] Call Trace:
[133719.311600]  [<c014518d>] warn_slowpath_common+0x6d/0xa0
[133719.311603]  [<c0174e70>] ? suspend_test_finish+0x80/0x90
[133719.311607]  [<c0174e70>] ? suspend_test_finish+0x80/0x90
[133719.311610]  [<c0145206>] warn_slowpath_fmt+0x26/0x30
[133719.311613]  [<c0174e70>] suspend_test_finish+0x80/0x90
[133719.311617]  [<c0174c5f>] suspend_devices_and_enter+0x9f/0xd0
[133719.311621]  [<c056e41c>] ? printk+0x18/0x1c
[133719.311625]  [<c0174d49>] enter_state+0xb9/0xf0
[133719.311628]  [<c017443d>] state_store+0x6d/0xb0
[133719.311631]  [<c01743d0>] ? state_store+0x0/0xb0
[133719.311635]  [<c0311a70>] kobj_attr_store+0x20/0x30
[133719.311639]  [<c0239290>] sysfs_write_file+0x90/0x100
[133719.311643]  [<c01e747a>] vfs_write+0x9a/0x190
[133719.311646]  [<c0239200>] ? sysfs_write_file+0x0/0x100
[133719.311650]  [<c0572d4b>] ? do_page_fault+0x19b/0x380
[133719.311653]  [<c01e7f3d>] sys_write+0x3d/0x70
[133719.311656]  [<c010336c>] syscall_call+0x7/0xb
[133719.311659] ---[ end trace 4869b1817e9d6975 ]---
[133719.311887] PM: Finishing wakeup.
[133719.311889] Restarting tasks ... done.
[133723.848535] forcedeth 0000:00:0a.0: irq 26 for MSI/MSI-X
[133723.848726] eth0: no link during initialization.
[133723.849190] ADDRCONF(NETDEV_UP): eth0: link is not ready
[133734.588017] eth2: no IPv6 routers present
[145044.778623] PM: Syncing filesystems ... done.
[145044.792821] PM: Preparing system for mem sleep
[145044.792825] Freezing user space processes ... (elapsed 0.00 seconds) done.
[145044.793730] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
[145044.793770] PM: Entering mem sleep
[145044.793783] Suspending console(s) (use no_console_suspend to debug)
[145044.860055] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[145044.860243] sd 0:0:0:0: [sda] Stopping disk
[145045.792426] wl 0000:03:00.0: PCI INT A disabled
[145045.808163] wl 0000:03:00.0: power state changed by ACPI to D3
[145045.860417] forcedeth 0000:00:0a.0: wake-up capability disabled by ACPI
[145045.860422] forcedeth 0000:00:0a.0: PME# disabled
[145045.988046] HDA Intel 0000:00:08.0: PCI INT A disabled
[145046.004096] ehci_hcd 0000:00:06.1: PCI INT B disabled
[145046.004102] ohci_hcd 0000:00:06.0: PCI INT A disabled
[145046.004109] ehci_hcd 0000:00:04.1: PCI INT B disabled
[145046.004115] ohci_hcd 0000:00:04.0: PCI INT A disabled
[145046.004177] PM: suspend devices took 1.212 seconds
[145046.004413] ehci_hcd 0000:00:06.1: PME# disabled
[145046.020035] ohci_hcd 0000:00:06.0: PME# disabled
[145046.036038] ehci_hcd 0000:00:04.1: PME# disabled
[145046.052034] ohci_hcd 0000:00:04.0: PME# disabled
[145046.164069] ACPI: Preparing to enter system sleep state S3
[145046.220207] Disabling non-boot CPUs ...
[145046.222375] kvm: disabling virtualization on CPU1
[145046.324017] CPU 1 is now offline
[145046.324020] SMP alternatives: switching to UP code
[145046.331054] CPU0 attaching NULL sched-domain.
[145046.331057] CPU1 attaching NULL sched-domain.
[145046.331062] CPU0 attaching NULL sched-domain.
[145046.331281] CPU1 is down
[145046.331356] Extended CMOS year: 2000
[145046.331356] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[145046.331356] Back to C!
[145046.331356] CPU0: Thermal LVT vector (0xfa) already installed
[145046.331356] Extended CMOS year: 2000
[145046.335259] Enabling non-boot CPUs ...
[145046.335515] SMP alternatives: switching to SMP code
[145046.342230] Booting processor 1 APIC 0x1 ip 0x6000
[145046.330919] Initializing CPU#1
[145046.330919] Calibrating delay using timer specific routine.. 3980.03 BogoMIPS (lpj=7960076)
[145046.330919] CPU: L1 I cache: 32K, L1 D cache: 32K
[145046.330919] CPU: L2 cache: 3072K
[145046.330919] CPU: Physical Processor ID: 0
[145046.330919] CPU: Processor Core ID: 1
[145046.330919] mce: CPU supports 6 MCE banks
[145046.330919] CPU1: Thermal monitoring enabled (TM2)
[145046.330919] x86 PAT enabled: cpu 1, old 0x7040600070406, new 0x7010600070106
[145046.433875] CPU1: Intel(R) Core(TM)2 Duo CPU     P7350  @ 2.00GHz stepping 06
[145046.433904] kvm: enabling virtualization on CPU1
[145046.433968] CPU0 attaching NULL sched-domain.
[145046.437013] Switched to high resolution mode on CPU 1
[145046.448025] CPU0 attaching sched-domain:
[145046.448028]  domain 0: span 0-1 level MC
[145046.448030]   groups: 0 1
[145046.448034] CPU1 attaching sched-domain:
[145046.448036]  domain 0: span 0-1 level MC
[145046.448038]   groups: 1 0
[145046.449016] CPU1 is up
[145046.449019] ACPI: Waking up from system sleep state S3
[145046.864215] pci 0000:00:03.0: restoring config space at offset 0xf (was 0x0, writing 0xff)
[145046.864267] nForce2_smbus 0000:00:03.2: restoring config space at offset 0xf (was 0x100, writing 0x10f)
[145046.864275] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x9 (was 0xa01, writing 0x2101)
[145046.864279] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x8 (was 0xb01, writing 0x2141)
[145046.864285] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x4 (was 0x1, writing 0x2181)
[145046.864397] pci 0000:00:03.5: restoring config space at offset 0x4 (was 0x0, writing 0x93300000)
[145046.864408] pci 0000:00:03.5: restoring config space at offset 0x1 (was 0xa00000, writing 0xa00006)
[145046.864425] ohci_hcd 0000:00:04.0: restoring config space at offset 0xf (was 0x1030100, writing 0x103010b)
[145046.864449] ohci_hcd 0000:00:04.0: PME# disabled
[145046.864459] ehci_hcd 0000:00:04.1: restoring config space at offset 0xf (was 0x1030200, writing 0x103020a)
[145046.864471] ehci_hcd 0000:00:04.1: restoring config space at offset 0x4 (was 0x0, writing 0x93389200)
[145046.864477] ehci_hcd 0000:00:04.1: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[145046.864493] ehci_hcd 0000:00:04.1: PME# disabled
[145046.864501] ohci_hcd 0000:00:06.0: restoring config space at offset 0xf (was 0x1030100, writing 0x1030107)
[145046.864524] ohci_hcd 0000:00:06.0: PME# disabled
[145046.864533] ehci_hcd 0000:00:06.1: restoring config space at offset 0xf (was 0x1030200, writing 0x1030205)
[145046.864545] ehci_hcd 0000:00:06.1: restoring config space at offset 0x4 (was 0x0, writing 0x93389100)
[145046.864551] ehci_hcd 0000:00:06.1: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[145046.864567] ehci_hcd 0000:00:06.1: PME# disabled
[145046.864575] HDA Intel 0000:00:08.0: restoring config space at offset 0xf (was 0x5020100, writing 0x502010f)
[145046.864587] HDA Intel 0000:00:08.0: restoring config space at offset 0x4 (was 0x0, writing 0x93380000)
[145046.864592] HDA Intel 0000:00:08.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[145046.864607] pci 0000:00:09.0: restoring config space at offset 0xf (was 0x2000000, writing 0x20000ff)
[145046.864615] pci 0000:00:09.0: restoring config space at offset 0x9 (was 0x0, writing 0xfff0)
[145046.864618] pci 0000:00:09.0: restoring config space at offset 0x8 (was 0x0, writing 0x93209320)
[145046.864622] pci 0000:00:09.0: restoring config space at offset 0x7 (was 0x22800000, writing 0x228000f0)
[145046.864626] pci 0000:00:09.0: restoring config space at offset 0x6 (was 0x0, writing 0x10100)
[145046.864633] pci 0000:00:09.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00006)
[145046.864650] forcedeth 0000:00:0a.0: restoring config space at offset 0xf (was 0x14010100, writing 0x1401010e)
[145046.864659] forcedeth 0000:00:0a.0: restoring config space at offset 0x7 (was 0x0, writing 0x93389300)
[145046.864662] forcedeth 0000:00:0a.0: restoring config space at offset 0x6 (was 0x0, writing 0x93389000)
[145046.864666] forcedeth 0000:00:0a.0: restoring config space at offset 0x5 (was 0x1, writing 0x21e1)
[145046.864670] forcedeth 0000:00:0a.0: restoring config space at offset 0x4 (was 0x0, writing 0x93386000)
[145046.864675] forcedeth 0000:00:0a.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00007)
[145046.864696] ahci 0000:00:0b.0: restoring config space at offset 0xf (was 0x1030100, writing 0x103010b)
[145046.864703] ahci 0000:00:0b.0: restoring config space at offset 0x9 (was 0x0, writing 0x93384000)
[145046.864707] ahci 0000:00:0b.0: restoring config space at offset 0x8 (was 0xff01, writing 0x21c1)
[145046.864710] ahci 0000:00:0b.0: restoring config space at offset 0x7 (was 0xff2d, writing 0x21e9)
[145046.864714] ahci 0000:00:0b.0: restoring config space at offset 0x6 (was 0xff19, writing 0x21d1)
[145046.864717] ahci 0000:00:0b.0: restoring config space at offset 0x5 (was 0xff25, writing 0x21ed)
[145046.864721] ahci 0000:00:0b.0: restoring config space at offset 0x4 (was 0xff11, writing 0x21d9)
[145046.864726] ahci 0000:00:0b.0: restoring config space at offset 0x1 (was 0xb00001, writing 0xb00407)
[145046.864754] pci 0000:00:10.0: restoring config space at offset 0xf (was 0x0, writing 0x800ff)
[145046.864760] pci 0000:00:10.0: restoring config space at offset 0x9 (was 0x10011, writing 0x91f18001)
[145046.864764] pci 0000:00:10.0: restoring config space at offset 0x8 (was 0x10, writing 0x93009200)
[145046.864767] pci 0000:00:10.0: restoring config space at offset 0x7 (was 0x101, writing 0x1111)
[145046.864771] pci 0000:00:10.0: restoring config space at offset 0x6 (was 0x0, writing 0x20200)
[145046.864775] pci 0000:00:10.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10040)
[145046.864780] pci 0000:00:10.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[145046.864850] pcieport-driver 0000:00:15.0: restoring config space at offset 0xf (was 0x100, writing 0x1ff)
[145046.864871] pcieport-driver 0000:00:15.0: restoring config space at offset 0xa (was 0x0, writing 0xffffffff)
[145046.864879] pcieport-driver 0000:00:15.0: restoring config space at offset 0x9 (was 0x10011, writing 0x1fff1)
[145046.864887] pcieport-driver 0000:00:15.0: restoring config space at offset 0x8 (was 0x10, writing 0x93109310)
[145046.864896] pcieport-driver 0000:00:15.0: restoring config space at offset 0x7 (was 0x101, writing 0x1f1)
[145046.864904] pcieport-driver 0000:00:15.0: restoring config space at offset 0x6 (was 0x0, writing 0x30300)
[145046.864919] pcieport-driver 0000:00:15.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10040)
[145046.864930] pcieport-driver 0000:00:15.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407)
[145046.865035] nvidia 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
[145046.865042] nvidia 0000:02:00.0: restoring config space at offset 0x9 (was 0x1, writing 0x1001)
[145046.865046] nvidia 0000:02:00.0: restoring config space at offset 0x7 (was 0xc, writing 0x9000000c)
[145046.865050] nvidia 0000:02:00.0: restoring config space at offset 0x5 (was 0xc, writing 0x8000000c)
[145046.865054] nvidia 0000:02:00.0: restoring config space at offset 0x4 (was 0x0, writing 0x92000000)
[145046.865058] nvidia 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[145046.865263] wl 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
[145046.865281] wl 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0x93100004)
[145046.865286] wl 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x40)
[145046.865292] wl 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100006)
[145054.216291] nForce2_smbus 0000:00:03.2: PME# disabled
[145054.216297] ohci_hcd 0000:00:04.0: PME# disabled
[145054.216305] ohci_hcd 0000:00:04.0: PCI INT A -> Link[LUS0] -> GSI 19 (level, low) -> IRQ 19
[145054.216310] ohci_hcd 0000:00:04.0: setting latency timer to 64
[145054.240102] ehci_hcd 0000:00:04.1: PME# disabled
[145054.240108] ehci_hcd 0000:00:04.1: PCI INT B -> Link[LUS2] -> GSI 21 (level, low) -> IRQ 21
[145054.240114] ehci_hcd 0000:00:04.1: setting latency timer to 64
[145054.240121] ohci_hcd 0000:00:06.0: PME# disabled
[145054.240127] ohci_hcd 0000:00:06.0: PCI INT A -> Link[Z000] -> GSI 18 (level, low) -> IRQ 18
[145054.240132] ohci_hcd 0000:00:06.0: setting latency timer to 64
[145054.264099] ehci_hcd 0000:00:06.1: PME# disabled
[145054.264105] ehci_hcd 0000:00:06.1: PCI INT B -> Link[Z001] -> GSI 20 (level, low) -> IRQ 20
[145054.264110] ehci_hcd 0000:00:06.1: setting latency timer to 64
[145054.264119] HDA Intel 0000:00:08.0: PCI INT A -> Link[LAZA] -> GSI 23 (level, low) -> IRQ 23
[145054.264124] HDA Intel 0000:00:08.0: setting latency timer to 64
[145054.264148] pci 0000:00:09.0: setting latency timer to 64
[145054.264152] forcedeth 0000:00:0a.0: wake-up capability disabled by ACPI
[145054.264163] forcedeth 0000:00:0a.0: PME# disabled
[145054.328820] ahci 0000:00:0b.0: setting latency timer to 64
[145054.328848] pci 0000:00:10.0: setting latency timer to 64
[145054.821037] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[145054.821053] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[145054.821935] ata1.00: configured for UDMA/100
[145054.831974] ata2.00: configured for UDMA/133
[145054.836753] ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
[145054.836755] ata1: irq_stat 0x00400040, connection status changed
[145054.837638] ata1.00: configured for UDMA/100
[145054.837641] ata1: EH complete
[145054.853036] ata2: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
[145054.853038] ata2: irq_stat 0x40000001
[145054.863018] ata2.00: configured for UDMA/133
[145054.863021] ata2: EH complete
[145059.183755] wl 0000:03:00.0: PCI INT A -> Link[Z00F] -> GSI 23 (level, low) -> IRQ 23
[145059.183761] wl 0000:03:00.0: setting latency timer to 64
[145059.536284] sd 0:0:0:0: [sda] Starting disk
[145059.826421] applesmc: device has already been initialized (0xe0, 0x00).
[145059.826586] PM: resume devices took 12.960 seconds
[145059.826588] ------------[ cut here ]------------
[145059.826595] WARNING: at /build/buildd/linux-2.6.31/kernel/power/suspend_test.c:52 suspend_test_finish+0x80/0x90()
[145059.826597] Hardware name: MacBook5,1
[145059.826598] Component: resume devices
[145059.826600] Modules linked in: nls_utf8 nls_iso8859_1 nls_cp437 ufs qnx4 hfsplus hfs minix ntfs vfat msdos fat jfs xfs exportfs reiserfs binfmt_misc ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT xt_tcpudp ppdev bridge stp bnep kvm_intel kvm snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device nvidia(P) agpgart lib80211_crypt_tkip snd applesmc soundcore uvcvideo joydev nvidia_bl led_class snd_page_alloc shpchp iptable_filter i2c_nforce2 wl(P) bcm5974 input_polldev ip_tables videodev btusb v4l1_compat lib80211 x_tables mbp_nvidia_bl lp parport hid_apple usbhid forcedeth
[145059.826652] Pid: 25829, comm: pm-suspend Tainted: P        W  2.6.31-14-generic #48-Ubuntu
[145059.826655] Call Trace:
[145059.826660]  [<c014518d>] warn_slowpath_common+0x6d/0xa0
[145059.826664]  [<c0174e70>] ? suspend_test_finish+0x80/0x90
[145059.826667]  [<c0174e70>] ? suspend_test_finish+0x80/0x90
[145059.826670]  [<c0145206>] warn_slowpath_fmt+0x26/0x30
[145059.826674]  [<c0174e70>] suspend_test_finish+0x80/0x90
[145059.826677]  [<c0174c5f>] suspend_devices_and_enter+0x9f/0xd0
[145059.826682]  [<c056e41c>] ? printk+0x18/0x1c
[145059.826685]  [<c0174d49>] enter_state+0xb9/0xf0
[145059.826688]  [<c017443d>] state_store+0x6d/0xb0
[145059.826691]  [<c01743d0>] ? state_store+0x0/0xb0
[145059.826695]  [<c0311a70>] kobj_attr_store+0x20/0x30
[145059.826699]  [<c0239290>] sysfs_write_file+0x90/0x100
[145059.826703]  [<c01e747a>] vfs_write+0x9a/0x190
[145059.826706]  [<c0239200>] ? sysfs_write_file+0x0/0x100
[145059.826710]  [<c0572d4b>] ? do_page_fault+0x19b/0x380
[145059.826713]  [<c01e7f3d>] sys_write+0x3d/0x70
[145059.826716]  [<c010336c>] syscall_call+0x7/0xb
[145059.826719] ---[ end trace 4869b1817e9d6976 ]---
[145059.826944] PM: Finishing wakeup.
[145059.826946] Restarting tasks ... done.
[145064.647261] forcedeth 0000:00:0a.0: irq 26 for MSI/MSI-X
[145064.647452] eth0: no link during initialization.
[145064.647916] ADDRCONF(NETDEV_UP): eth0: link is not ready
[145074.559281] PM: Syncing filesystems ... done.
[145074.573613] PM: Preparing system for mem sleep
[145074.573617] Freezing user space processes ... (elapsed 0.00 seconds) done.
[145074.574720] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
[145074.574763] PM: Entering mem sleep
[145074.574776] Suspending console(s) (use no_console_suspend to debug)
[145074.640055] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[145074.646987] sd 0:0:0:0: [sda] Stopping disk
[145075.580426] wl 0000:03:00.0: PCI INT A disabled
[145075.596162] wl 0000:03:00.0: power state changed by ACPI to D3
[145075.644417] forcedeth 0000:00:0a.0: wake-up capability disabled by ACPI
[145075.644422] forcedeth 0000:00:0a.0: PME# disabled
[145075.660110] HDA Intel 0000:00:08.0: PCI INT A disabled
[145075.676100] ehci_hcd 0000:00:06.1: PCI INT B disabled
[145075.676106] ohci_hcd 0000:00:06.0: PCI INT A disabled
[145075.676112] ehci_hcd 0000:00:04.1: PCI INT B disabled
[145075.676118] ohci_hcd 0000:00:04.0: PCI INT A disabled
[145075.676179] PM: suspend devices took 1.104 seconds
[145075.676416] ehci_hcd 0000:00:06.1: PME# disabled
[145075.692035] ohci_hcd 0000:00:06.0: PME# disabled
[145075.708038] ehci_hcd 0000:00:04.1: PME# disabled
[145075.724034] ohci_hcd 0000:00:04.0: PME# disabled
[145075.836069] ACPI: Preparing to enter system sleep state S3
[145075.884206] Disabling non-boot CPUs ...
[145075.886373] kvm: disabling virtualization on CPU1
[145075.988017] CPU 1 is now offline
[145075.988020] SMP alternatives: switching to UP code
[145075.995020] CPU0 attaching NULL sched-domain.
[145075.995023] CPU1 attaching NULL sched-domain.
[145075.995028] CPU0 attaching NULL sched-domain.
[145075.995245] CPU1 is down
[145075.995318] Extended CMOS year: 2000
[145075.995318] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[145075.995318] Back to C!
[145075.995318] CPU0: Thermal LVT vector (0xfa) already installed
[145075.995318] Extended CMOS year: 2000
[145075.999219] Enabling non-boot CPUs ...
[145075.999476] SMP alternatives: switching to SMP code
[145076.006215] Booting processor 1 APIC 0x1 ip 0x6000
[145075.994886] Initializing CPU#1
[145075.994886] Calibrating delay using timer specific routine.. 3980.03 BogoMIPS (lpj=7960062)
[145075.994886] CPU: L1 I cache: 32K, L1 D cache: 32K
[145075.994886] CPU: L2 cache: 3072K
[145075.994886] CPU: Physical Processor ID: 0
[145075.994886] CPU: Processor Core ID: 1
[145075.994886] mce: CPU supports 6 MCE banks
[145075.994886] CPU1: Thermal monitoring enabled (TM2)
[145075.994886] x86 PAT enabled: cpu 1, old 0x7040600070406, new 0x7010600070106
[145076.097862] CPU1: Intel(R) Core(TM)2 Duo CPU     P7350  @ 2.00GHz stepping 06
[145076.097892] kvm: enabling virtualization on CPU1
[145076.097966] CPU0 attaching NULL sched-domain.
[145076.101013] Switched to high resolution mode on CPU 1
[145076.112025] CPU0 attaching sched-domain:
[145076.112028]  domain 0: span 0-1 level MC
[145076.112030]   groups: 0 1
[145076.112034] CPU1 attaching sched-domain:
[145076.112036]  domain 0: span 0-1 level MC
[145076.112038]   groups: 1 0
[145076.113015] CPU1 is up
[145076.113018] ACPI: Waking up from system sleep state S3
[145076.544215] pci 0000:00:03.0: restoring config space at offset 0xf (was 0x0, writing 0xff)
[145076.544266] nForce2_smbus 0000:00:03.2: restoring config space at offset 0xf (was 0x100, writing 0x10f)
[145076.544274] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x9 (was 0xa01, writing 0x2101)
[145076.544278] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x8 (was 0xb01, writing 0x2141)
[145076.544285] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x4 (was 0x1, writing 0x2181)
[145076.544397] pci 0000:00:03.5: restoring config space at offset 0x4 (was 0x0, writing 0x93300000)
[145076.544407] pci 0000:00:03.5: restoring config space at offset 0x1 (was 0xa00000, writing 0xa00006)
[145076.544425] ohci_hcd 0000:00:04.0: restoring config space at offset 0xf (was 0x1030100, writing 0x103010b)
[145076.544449] ohci_hcd 0000:00:04.0: PME# disabled
[145076.544459] ehci_hcd 0000:00:04.1: restoring config space at offset 0xf (was 0x1030200, writing 0x103020a)
[145076.544470] ehci_hcd 0000:00:04.1: restoring config space at offset 0x4 (was 0x0, writing 0x93389200)
[145076.544476] ehci_hcd 0000:00:04.1: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[145076.544492] ehci_hcd 0000:00:04.1: PME# disabled
[145076.544500] ohci_hcd 0000:00:06.0: restoring config space at offset 0xf (was 0x1030100, writing 0x1030107)
[145076.544523] ohci_hcd 0000:00:06.0: PME# disabled
[145076.544532] ehci_hcd 0000:00:06.1: restoring config space at offset 0xf (was 0x1030200, writing 0x1030205)
[145076.544544] ehci_hcd 0000:00:06.1: restoring config space at offset 0x4 (was 0x0, writing 0x93389100)
[145076.544549] ehci_hcd 0000:00:06.1: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[145076.544566] ehci_hcd 0000:00:06.1: PME# disabled
[145076.544574] HDA Intel 0000:00:08.0: restoring config space at offset 0xf (was 0x5020100, writing 0x502010f)
[145076.544586] HDA Intel 0000:00:08.0: restoring config space at offset 0x4 (was 0x0, writing 0x93380000)
[145076.544591] HDA Intel 0000:00:08.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[145076.544606] pci 0000:00:09.0: restoring config space at offset 0xf (was 0x2000000, writing 0x20000ff)
[145076.544614] pci 0000:00:09.0: restoring config space at offset 0x9 (was 0x0, writing 0xfff0)
[145076.544618] pci 0000:00:09.0: restoring config space at offset 0x8 (was 0x0, writing 0x93209320)
[145076.544622] pci 0000:00:09.0: restoring config space at offset 0x7 (was 0x22800000, writing 0x28000f0)
[145076.544625] pci 0000:00:09.0: restoring config space at offset 0x6 (was 0x0, writing 0x10100)
[145076.544632] pci 0000:00:09.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00006)
[145076.544650] forcedeth 0000:00:0a.0: restoring config space at offset 0xf (was 0x14010100, writing 0x1401010e)
[145076.544658] forcedeth 0000:00:0a.0: restoring config space at offset 0x7 (was 0x0, writing 0x93389300)
[145076.544662] forcedeth 0000:00:0a.0: restoring config space at offset 0x6 (was 0x0, writing 0x93389000)
[145076.544666] forcedeth 0000:00:0a.0: restoring config space at offset 0x5 (was 0x1, writing 0x21e1)
[145076.544669] forcedeth 0000:00:0a.0: restoring config space at offset 0x4 (was 0x0, writing 0x93386000)
[145076.544674] forcedeth 0000:00:0a.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00007)
[145076.544696] ahci 0000:00:0b.0: restoring config space at offset 0xf (was 0x1030100, writing 0x103010b)
[145076.544703] ahci 0000:00:0b.0: restoring config space at offset 0x9 (was 0x0, writing 0x93384000)
[145076.544706] ahci 0000:00:0b.0: restoring config space at offset 0x8 (was 0xff01, writing 0x21c1)
[145076.544710] ahci 0000:00:0b.0: restoring config space at offset 0x7 (was 0xff2d, writing 0x21e9)
[145076.544714] ahci 0000:00:0b.0: restoring config space at offset 0x6 (was 0xff19, writing 0x21d1)
[145076.544717] ahci 0000:00:0b.0: restoring config space at offset 0x5 (was 0xff25, writing 0x21ed)
[145076.544721] ahci 0000:00:0b.0: restoring config space at offset 0x4 (was 0xff11, writing 0x21d9)
[145076.544726] ahci 0000:00:0b.0: restoring config space at offset 0x1 (was 0xb00001, writing 0xb00407)
[145076.544753] pci 0000:00:10.0: restoring config space at offset 0xf (was 0x0, writing 0x800ff)
[145076.544760] pci 0000:00:10.0: restoring config space at offset 0x9 (was 0x10011, writing 0x91f18001)
[145076.544763] pci 0000:00:10.0: restoring config space at offset 0x8 (was 0x10, writing 0x93009200)
[145076.544767] pci 0000:00:10.0: restoring config space at offset 0x7 (was 0x101, writing 0x1111)
[145076.544770] pci 0000:00:10.0: restoring config space at offset 0x6 (was 0x0, writing 0x20200)
[145076.544775] pci 0000:00:10.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10040)
[145076.544779] pci 0000:00:10.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[145076.544849] pcieport-driver 0000:00:15.0: restoring config space at offset 0xf (was 0x100, writing 0x1ff)
[145076.544870] pcieport-driver 0000:00:15.0: restoring config space at offset 0xa (was 0x0, writing 0xffffffff)
[145076.544878] pcieport-driver 0000:00:15.0: restoring config space at offset 0x9 (was 0x10011, writing 0x1fff1)
[145076.544887] pcieport-driver 0000:00:15.0: restoring config space at offset 0x8 (was 0x10, writing 0x93109310)
[145076.544895] pcieport-driver 0000:00:15.0: restoring config space at offset 0x7 (was 0x101, writing 0x1f1)
[145076.544903] pcieport-driver 0000:00:15.0: restoring config space at offset 0x6 (was 0x0, writing 0x30300)
[145076.544918] pcieport-driver 0000:00:15.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10040)
[145076.544929] pcieport-driver 0000:00:15.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407)
[145076.545034] nvidia 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
[145076.545041] nvidia 0000:02:00.0: restoring config space at offset 0x9 (was 0x1, writing 0x1001)
[145076.545046] nvidia 0000:02:00.0: restoring config space at offset 0x7 (was 0xc, writing 0x9000000c)
[145076.545050] nvidia 0000:02:00.0: restoring config space at offset 0x5 (was 0xc, writing 0x8000000c)
[145076.545053] nvidia 0000:02:00.0: restoring config space at offset 0x4 (was 0x0, writing 0x92000000)
[145076.545058] nvidia 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[145076.545263] wl 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
[145076.545281] wl 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0x93100004)
[145076.545286] wl 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x40)
[145076.545292] wl 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100006)
[145083.832291] nForce2_smbus 0000:00:03.2: PME# disabled
[145083.832298] ohci_hcd 0000:00:04.0: PME# disabled
[145083.832306] ohci_hcd 0000:00:04.0: PCI INT A -> Link[LUS0] -> GSI 19 (level, low) -> IRQ 19
[145083.832311] ohci_hcd 0000:00:04.0: setting latency timer to 64
[145083.856103] ehci_hcd 0000:00:04.1: PME# disabled
[145083.856110] ehci_hcd 0000:00:04.1: PCI INT B -> Link[LUS2] -> GSI 21 (level, low) -> IRQ 21
[145083.856115] ehci_hcd 0000:00:04.1: setting latency timer to 64
[145083.856123] ohci_hcd 0000:00:06.0: PME# disabled
[145083.856128] ohci_hcd 0000:00:06.0: PCI INT A -> Link[Z000] -> GSI 18 (level, low) -> IRQ 18
[145083.856133] ohci_hcd 0000:00:06.0: setting latency timer to 64
[145083.880096] ehci_hcd 0000:00:06.1: PME# disabled
[145083.880102] ehci_hcd 0000:00:06.1: PCI INT B -> Link[Z001] -> GSI 20 (level, low) -> IRQ 20
[145083.880108] ehci_hcd 0000:00:06.1: setting latency timer to 64
[145083.880116] HDA Intel 0000:00:08.0: PCI INT A -> Link[LAZA] -> GSI 23 (level, low) -> IRQ 23
[145083.880122] HDA Intel 0000:00:08.0: setting latency timer to 64
[145083.880144] pci 0000:00:09.0: setting latency timer to 64
[145083.880149] forcedeth 0000:00:0a.0: wake-up capability disabled by ACPI
[145083.880154] forcedeth 0000:00:0a.0: PME# disabled
[145083.944826] ahci 0000:00:0b.0: setting latency timer to 64
[145083.944854] pci 0000:00:10.0: setting latency timer to 64
[145084.437040] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[145084.437057] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[145084.437936] ata1.00: configured for UDMA/100
[145084.447978] ata2.00: configured for UDMA/133
[145084.452753] ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
[145084.452755] ata1: irq_stat 0x00400040, connection status changed
[145084.453637] ata1.00: configured for UDMA/100
[145084.453640] ata1: EH complete
[145084.469037] ata2: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
[145084.469040] ata2: irq_stat 0x40000001
[145084.479018] ata2.00: configured for UDMA/133
[145084.479021] ata2: EH complete
[145088.800440] wl 0000:03:00.0: PCI INT A -> Link[Z00F] -> GSI 23 (level, low) -> IRQ 23
[145088.800446] wl 0000:03:00.0: setting latency timer to 64
[145089.148236] sd 0:0:0:0: [sda] Starting disk
[145089.446115] applesmc: device has already been initialized (0xe0, 0x00).
[145089.446282] PM: resume devices took 12.900 seconds
[145089.446284] ------------[ cut here ]------------
[145089.446290] WARNING: at /build/buildd/linux-2.6.31/kernel/power/suspend_test.c:52 suspend_test_finish+0x80/0x90()
[145089.446293] Hardware name: MacBook5,1
[145089.446294] Component: resume devices
[145089.446296] Modules linked in: nls_utf8 nls_iso8859_1 nls_cp437 ufs qnx4 hfsplus hfs minix ntfs vfat msdos fat jfs xfs exportfs reiserfs binfmt_misc ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT xt_tcpudp ppdev bridge stp bnep kvm_intel kvm snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device nvidia(P) agpgart lib80211_crypt_tkip snd applesmc soundcore uvcvideo joydev nvidia_bl led_class snd_page_alloc shpchp iptable_filter i2c_nforce2 wl(P) bcm5974 input_polldev ip_tables videodev btusb v4l1_compat lib80211 x_tables mbp_nvidia_bl lp parport hid_apple usbhid forcedeth
[145089.446348] Pid: 26790, comm: pm-suspend Tainted: P        W  2.6.31-14-generic #48-Ubuntu
[145089.446350] Call Trace:
[145089.446356]  [<c014518d>] warn_slowpath_common+0x6d/0xa0
[145089.446360]  [<c0174e70>] ? suspend_test_finish+0x80/0x90
[145089.446363]  [<c0174e70>] ? suspend_test_finish+0x80/0x90
[145089.446367]  [<c0145206>] warn_slowpath_fmt+0x26/0x30
[145089.446370]  [<c0174e70>] suspend_test_finish+0x80/0x90
[145089.446373]  [<c0174c5f>] suspend_devices_and_enter+0x9f/0xd0
[145089.446378]  [<c056e41c>] ? printk+0x18/0x1c
[145089.446381]  [<c0174d49>] enter_state+0xb9/0xf0
[145089.446384]  [<c017443d>] state_store+0x6d/0xb0
[145089.446387]  [<c01743d0>] ? state_store+0x0/0xb0
[145089.446392]  [<c0311a70>] kobj_attr_store+0x20/0x30
[145089.446396]  [<c0239290>] sysfs_write_file+0x90/0x100
[145089.446401]  [<c01e747a>] vfs_write+0x9a/0x190
[145089.446404]  [<c0239200>] ? sysfs_write_file+0x0/0x100
[145089.446407]  [<c0572d4b>] ? do_page_fault+0x19b/0x380
[145089.446410]  [<c01e7f3d>] sys_write+0x3d/0x70
[145089.446414]  [<c010336c>] syscall_call+0x7/0xb
[145089.446416] ---[ end trace 4869b1817e9d6977 ]---
[145089.446644] PM: Finishing wakeup.
[145089.446646] Restarting tasks ... done.
[145093.533852] forcedeth 0000:00:0a.0: irq 26 for MSI/MSI-X
[145093.534043] eth0: no link during initialization.
[145093.534506] ADDRCONF(NETDEV_UP): eth0: link is not ready
[145104.033014] eth2: no IPv6 routers present
[145776.598248] PM: Syncing filesystems ... done.
[145776.612547] PM: Preparing system for mem sleep
[145776.612551] Freezing user space processes ... (elapsed 0.00 seconds) done.
[145776.613621] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
[145776.613664] PM: Entering mem sleep
[145776.613677] Suspending console(s) (use no_console_suspend to debug)
[145776.680054] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[145776.680250] sd 0:0:0:0: [sda] Stopping disk
[145777.572426] wl 0000:03:00.0: PCI INT A disabled
[145777.588163] wl 0000:03:00.0: power state changed by ACPI to D3
[145777.644414] forcedeth 0000:00:0a.0: wake-up capability disabled by ACPI
[145777.644419] forcedeth 0000:00:0a.0: PME# disabled
[145777.772102] HDA Intel 0000:00:08.0: PCI INT A disabled
[145777.788101] ehci_hcd 0000:00:06.1: PCI INT B disabled
[145777.788107] ohci_hcd 0000:00:06.0: PCI INT A disabled
[145777.788113] ehci_hcd 0000:00:04.1: PCI INT B disabled
[145777.788119] ohci_hcd 0000:00:04.0: PCI INT A disabled
[145777.788181] PM: suspend devices took 1.176 seconds
[145777.788418] ehci_hcd 0000:00:06.1: PME# disabled
[145777.804035] ohci_hcd 0000:00:06.0: PME# disabled
[145777.820038] ehci_hcd 0000:00:04.1: PME# disabled
[145777.836034] ohci_hcd 0000:00:04.0: PME# disabled
[145777.940069] ACPI: Preparing to enter system sleep state S3
[145777.988207] Disabling non-boot CPUs ...
[145777.990371] kvm: disabling virtualization on CPU1
[145778.092014] CPU 1 is now offline
[145778.092017] SMP alternatives: switching to UP code
[145778.099030] CPU0 attaching NULL sched-domain.
[145778.099033] CPU1 attaching NULL sched-domain.
[145778.099038] CPU0 attaching NULL sched-domain.
[145778.099257] CPU1 is down
[145778.099331] Extended CMOS year: 2000
[145778.099331] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[145778.099331] Back to C!
[145778.099331] CPU0: Thermal LVT vector (0xfa) already installed
[145778.099331] Extended CMOS year: 2000
[145778.103225] Enabling non-boot CPUs ...
[145778.103481] SMP alternatives: switching to SMP code
[145778.110197] Booting processor 1 APIC 0x1 ip 0x6000
[145778.098895] Initializing CPU#1
[145778.098895] Calibrating delay using timer specific routine.. 3980.05 BogoMIPS (lpj=7960103)
[145778.098895] CPU: L1 I cache: 32K, L1 D cache: 32K
[145778.098895] CPU: L2 cache: 3072K
[145778.098895] CPU: Physical Processor ID: 0
[145778.098895] CPU: Processor Core ID: 1
[145778.098895] mce: CPU supports 6 MCE banks
[145778.098895] CPU1: Thermal monitoring enabled (TM2)
[145778.098895] x86 PAT enabled: cpu 1, old 0x7040600070406, new 0x7010600070106
[145778.201846] CPU1: Intel(R) Core(TM)2 Duo CPU     P7350  @ 2.00GHz stepping 06
[145778.201876] kvm: enabling virtualization on CPU1
[145778.201942] CPU0 attaching NULL sched-domain.
[145778.205014] Switched to high resolution mode on CPU 1
[145778.216025] CPU0 attaching sched-domain:
[145778.216028]  domain 0: span 0-1 level MC
[145778.216030]   groups: 0 1
[145778.216034] CPU1 attaching sched-domain:
[145778.216036]  domain 0: span 0-1 level MC
[145778.216038]   groups: 1 0
[145778.217016] CPU1 is up
[145778.217019] ACPI: Waking up from system sleep state S3
[145778.640217] pci 0000:00:03.0: restoring config space at offset 0xf (was 0x0, writing 0xff)
[145778.640269] nForce2_smbus 0000:00:03.2: restoring config space at offset 0xf (was 0x100, writing 0x10f)
[145778.640277] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x9 (was 0xa01, writing 0x2101)
[145778.640281] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x8 (was 0xb01, writing 0x2141)
[145778.640287] nForce2_smbus 0000:00:03.2: restoring config space at offset 0x4 (was 0x1, writing 0x2181)
[145778.640400] pci 0000:00:03.5: restoring config space at offset 0x4 (was 0x0, writing 0x93300000)
[145778.640410] pci 0000:00:03.5: restoring config space at offset 0x1 (was 0xa00000, writing 0xa00006)
[145778.640428] ohci_hcd 0000:00:04.0: restoring config space at offset 0xf (was 0x1030100, writing 0x103010b)
[145778.640452] ohci_hcd 0000:00:04.0: PME# disabled
[145778.640462] ehci_hcd 0000:00:04.1: restoring config space at offset 0xf (was 0x1030200, writing 0x103020a)
[145778.640473] ehci_hcd 0000:00:04.1: restoring config space at offset 0x4 (was 0x0, writing 0x93389200)
[145778.640479] ehci_hcd 0000:00:04.1: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[145778.640495] ehci_hcd 0000:00:04.1: PME# disabled
[145778.640503] ohci_hcd 0000:00:06.0: restoring config space at offset 0xf (was 0x1030100, writing 0x1030107)
[145778.640525] ohci_hcd 0000:00:06.0: PME# disabled
[145778.640535] ehci_hcd 0000:00:06.1: restoring config space at offset 0xf (was 0x1030200, writing 0x1030205)
[145778.640547] ehci_hcd 0000:00:06.1: restoring config space at offset 0x4 (was 0x0, writing 0x93389100)
[145778.640552] ehci_hcd 0000:00:06.1: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[145778.640569] ehci_hcd 0000:00:06.1: PME# disabled
[145778.640576] HDA Intel 0000:00:08.0: restoring config space at offset 0xf (was 0x5020100, writing 0x502010f)
[145778.640588] HDA Intel 0000:00:08.0: restoring config space at offset 0x4 (was 0x0, writing 0x93380000)
[145778.640594] HDA Intel 0000:00:08.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00002)
[145778.640609] pci 0000:00:09.0: restoring config space at offset 0xf (was 0x2000000, writing 0x20000ff)
[145778.640616] pci 0000:00:09.0: restoring config space at offset 0x9 (was 0x0, writing 0xfff0)
[145778.640620] pci 0000:00:09.0: restoring config space at offset 0x8 (was 0x0, writing 0x93209320)
[145778.640624] pci 0000:00:09.0: restoring config space at offset 0x7 (was 0x22800000, writing 0x228000f0)
[145778.640628] pci 0000:00:09.0: restoring config space at offset 0x6 (was 0x0, writing 0x10100)
[145778.640635] pci 0000:00:09.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00006)
[145778.640652] forcedeth 0000:00:0a.0: restoring config space at offset 0xf (was 0x14010100, writing 0x1401010e)
[145778.640661] forcedeth 0000:00:0a.0: restoring config space at offset 0x7 (was 0x0, writing 0x93389300)
[145778.640664] forcedeth 0000:00:0a.0: restoring config space at offset 0x6 (was 0x0, writing 0x93389000)
[145778.640668] forcedeth 0000:00:0a.0: restoring config space at offset 0x5 (was 0x1, writing 0x21e1)
[145778.640672] forcedeth 0000:00:0a.0: restoring config space at offset 0x4 (was 0x0, writing 0x93386000)
[145778.640677] forcedeth 0000:00:0a.0: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00007)
[145778.640698] ahci 0000:00:0b.0: restoring config space at offset 0xf (was 0x1030100, writing 0x103010b)
[145778.640705] ahci 0000:00:0b.0: restoring config space at offset 0x9 (was 0x0, writing 0x93384000)
[145778.640709] ahci 0000:00:0b.0: restoring config space at offset 0x8 (was 0xff01, writing 0x21c1)
[145778.640713] ahci 0000:00:0b.0: restoring config space at offset 0x7 (was 0xff2d, writing 0x21e9)
[145778.640716] ahci 0000:00:0b.0: restoring config space at offset 0x6 (was 0xff19, writing 0x21d1)
[145778.640720] ahci 0000:00:0b.0: restoring config space at offset 0x5 (was 0xff25, writing 0x21ed)
[145778.640723] ahci 0000:00:0b.0: restoring config space at offset 0x4 (was 0xff11, writing 0x21d9)
[145778.640728] ahci 0000:00:0b.0: restoring config space at offset 0x1 (was 0xb00001, writing 0xb00407)
[145778.640756] pci 0000:00:10.0: restoring config space at offset 0xf (was 0x0, writing 0x800ff)
[145778.640763] pci 0000:00:10.0: restoring config space at offset 0x9 (was 0x10011, writing 0x91f18001)
[145778.640766] pci 0000:00:10.0: restoring config space at offset 0x8 (was 0x10, writing 0x93009200)
[145778.640770] pci 0000:00:10.0: restoring config space at offset 0x7 (was 0x101, writing 0x1111)
[145778.640773] pci 0000:00:10.0: restoring config space at offset 0x6 (was 0x0, writing 0x20200)
[145778.640778] pci 0000:00:10.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10040)
[145778.640782] pci 0000:00:10.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[145778.640852] pcieport-driver 0000:00:15.0: restoring config space at offset 0xf (was 0x100, writing 0x1ff)
[145778.640873] pcieport-driver 0000:00:15.0: restoring config space at offset 0xa (was 0x0, writing 0xffffffff)
[145778.640881] pcieport-driver 0000:00:15.0: restoring config space at offset 0x9 (was 0x10011, writing 0x1fff1)
[145778.640889] pcieport-driver 0000:00:15.0: restoring config space at offset 0x8 (was 0x10, writing 0x93109310)
[145778.640898] pcieport-driver 0000:00:15.0: restoring config space at offset 0x7 (was 0x101, writing 0x1f1)
[145778.640906] pcieport-driver 0000:00:15.0: restoring config space at offset 0x6 (was 0x0, writing 0x30300)
[145778.640921] pcieport-driver 0000:00:15.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10040)
[145778.640932] pcieport-driver 0000:00:15.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407)
[145778.641037] nvidia 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
[145778.641043] nvidia 0000:02:00.0: restoring config space at offset 0x9 (was 0x1, writing 0x1001)
[145778.641048] nvidia 0000:02:00.0: restoring config space at offset 0x7 (was 0xc, writing 0x9000000c)
[145778.641052] nvidia 0000:02:00.0: restoring config space at offset 0x5 (was 0xc, writing 0x8000000c)
[145778.641056] nvidia 0000:02:00.0: restoring config space at offset 0x4 (was 0x0, writing 0x92000000)
[145778.641060] nvidia 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[145778.641265] wl 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
[145778.641283] wl 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0x93100004)
[145778.641288] wl 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x40)
[145778.641294] wl 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100006)
[145786.368278] nForce2_smbus 0000:00:03.2: PME# disabled
[145786.368285] ohci_hcd 0000:00:04.0: PME# disabled
[145786.368293] ohci_hcd 0000:00:04.0: PCI INT A -> Link[LUS0] -> GSI 19 (level, low) -> IRQ 19
[145786.368298] ohci_hcd 0000:00:04.0: setting latency timer to 64
[145786.392104] ehci_hcd 0000:00:04.1: PME# disabled
[145786.392110] ehci_hcd 0000:00:04.1: PCI INT B -> Link[LUS2] -> GSI 21 (level, low) -> IRQ 21
[145786.392115] ehci_hcd 0000:00:04.1: setting latency timer to 64
[145786.392123] ohci_hcd 0000:00:06.0: PME# disabled
[145786.392128] ohci_hcd 0000:00:06.0: PCI INT A -> Link[Z000] -> GSI 18 (level, low) -> IRQ 18
[145786.392134] ohci_hcd 0000:00:06.0: setting latency timer to 64
[145786.416099] ehci_hcd 0000:00:06.1: PME# disabled
[145786.416105] ehci_hcd 0000:00:06.1: PCI INT B -> Link[Z001] -> GSI 20 (level, low) -> IRQ 20
[145786.416110] ehci_hcd 0000:00:06.1: setting latency timer to 64
[145786.416126] HDA Intel 0000:00:08.0: PCI INT A -> Link[LAZA] -> GSI 23 (level, low) -> IRQ 23
[145786.416132] HDA Intel 0000:00:08.0: setting latency timer to 64
[145786.416155] pci 0000:00:09.0: setting latency timer to 64
[145786.416164] forcedeth 0000:00:0a.0: wake-up capability disabled by ACPI
[145786.416169] forcedeth 0000:00:0a.0: PME# disabled
[145786.480827] ahci 0000:00:0b.0: setting latency timer to 64
[145786.480855] pci 0000:00:10.0: setting latency timer to 64
[145786.973038] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[145786.973055] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[145786.973938] ata1.00: configured for UDMA/100
[145786.983976] ata2.00: configured for UDMA/133
[145786.988754] ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
[145786.988756] ata1: irq_stat 0x00400040, connection status changed
[145786.989631] ata1.00: configured for UDMA/100
[145786.989634] ata1: EH complete
[145787.005037] ata2: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
[145787.005039] ata2: irq_stat 0x40000001
[145787.015018] ata2.00: configured for UDMA/133
[145787.015021] ata2: EH complete
[145791.343779] wl 0000:03:00.0: PCI INT A -> Link[Z00F] -> GSI 23 (level, low) -> IRQ 23
[145791.343784] wl 0000:03:00.0: setting latency timer to 64
[145791.796204] sd 0:0:0:0: [sda] Starting disk
[145792.050870] applesmc: device has already been initialized (0xe0, 0x00).
[145792.051041] PM: resume devices took 13.408 seconds
[145792.051042] ------------[ cut here ]------------
[145792.051049] WARNING: at /build/buildd/linux-2.6.31/kernel/power/suspend_test.c:52 suspend_test_finish+0x80/0x90()
[145792.051051] Hardware name: MacBook5,1
[145792.051053] Component: resume devices
[145792.051054] Modules linked in: nls_utf8 nls_iso8859_1 nls_cp437 ufs qnx4 hfsplus hfs minix ntfs vfat msdos fat jfs xfs exportfs reiserfs binfmt_misc ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT xt_tcpudp ppdev bridge stp bnep kvm_intel kvm snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device nvidia(P) agpgart lib80211_crypt_tkip snd applesmc soundcore uvcvideo joydev nvidia_bl led_class snd_page_alloc shpchp iptable_filter i2c_nforce2 wl(P) bcm5974 input_polldev ip_tables videodev btusb v4l1_compat lib80211 x_tables mbp_nvidia_bl lp parport hid_apple usbhid forcedeth
[145792.051106] Pid: 28541, comm: pm-suspend Tainted: P        W  2.6.31-14-generic #48-Ubuntu
[145792.051109] Call Trace:
[145792.051115]  [<c014518d>] warn_slowpath_common+0x6d/0xa0
[145792.051118]  [<c0174e70>] ? suspend_test_finish+0x80/0x90
[145792.051121]  [<c0174e70>] ? suspend_test_finish+0x80/0x90
[145792.051125]  [<c0145206>] warn_slowpath_fmt+0x26/0x30
[145792.051128]  [<c0174e70>] suspend_test_finish+0x80/0x90
[145792.051132]  [<c0174c5f>] suspend_devices_and_enter+0x9f/0xd0
[145792.051136]  [<c056e41c>] ? printk+0x18/0x1c
[145792.051139]  [<c0174d49>] enter_state+0xb9/0xf0
[145792.051142]  [<c017443d>] state_store+0x6d/0xb0
[145792.051145]  [<c01743d0>] ? state_store+0x0/0xb0
[145792.051149]  [<c0311a70>] kobj_attr_store+0x20/0x30
[145792.051154]  [<c0239290>] sysfs_write_file+0x90/0x100
[145792.051158]  [<c01e747a>] vfs_write+0x9a/0x190
[145792.051161]  [<c0239200>] ? sysfs_write_file+0x0/0x100
[145792.051164]  [<c0572d4b>] ? do_page_fault+0x19b/0x380
[145792.051167]  [<c01e7f3d>] sys_write+0x3d/0x70
[145792.051170]  [<c010336c>] syscall_call+0x7/0xb
[145792.051173] ---[ end trace 4869b1817e9d6978 ]---
[145792.051417] PM: Finishing wakeup.
[145792.051419] Restarting tasks ... done.
[145792.176035] usb 2-2: new high speed USB device using ehci_hcd and address 9
[145792.317951] forcedeth 0000:00:0a.0: irq 26 for MSI/MSI-X
[145792.326553] usb 2-2: configuration #1 chosen from 1 choice
[145792.326976] hub 2-2:1.0: USB hub found
[145792.327109] hub 2-2:1.0: 3 ports detected
[145792.608226] usb 2-2.2: new low speed USB device using ehci_hcd and address 10
[145792.714681] usb 2-2.2: configuration #1 chosen from 1 choice
[145792.718646] input: Apple, Inc Apple Keyboard as /devices/pci0000:00/0000:00:06.1/usb2/2-2/2-2.2/2-2.2:1.0/input/input18
[145792.718718] apple 0003:05AC:0221.000C: input,hidraw3: USB HID v1.11 Keyboard [Apple, Inc Apple Keyboard] on usb-0000:00:06.1-2.2/input0
[145792.721770] input: Apple, Inc Apple Keyboard as /devices/pci0000:00/0000:00:06.1/usb2/2-2/2-2.2/2-2.2:1.1/input/input19
[145792.721843] apple 0003:05AC:0221.000D: input,hidraw4: USB HID v1.11 Device [Apple, Inc Apple Keyboard] on usb-0000:00:06.1-2.2/input1
[145792.795215] usb 2-2.3: new low speed USB device using ehci_hcd and address 11
[145792.904528] usb 2-2.3: configuration #1 chosen from 1 choice
[145792.910904] input: Microsoft Microsoft Basic Optical Mouse as /devices/pci0000:00/0000:00:06.1/usb2/2-2/2-2.3/2-2.3:1.0/input/input20
[145792.910987] generic-usb 0003:045E:0084.000E: input,hidraw5: USB HID v1.11 Mouse [Microsoft Microsoft Basic Optical Mouse] on usb-0000:00:06.1-2.3/input0
[145802.380022] eth2: no IPv6 routers present
[150888.347203] type=1505 audit(1257939536.791:29): operation="profile_replace" pid=8383 name=/usr/share/gdm/guest-session/Xsession
[150888.382269] type=1505 audit(1257939536.827:30): operation="profile_replace" pid=8384 name=/sbin/dhclient3
[150888.383190] type=1505 audit(1257939536.827:31): operation="profile_replace" pid=8384 name=/usr/lib/NetworkManager/nm-dhcp-client.action
[150888.383684] type=1505 audit(1257939536.827:32): operation="profile_replace" pid=8384 name=/usr/lib/connman/scripts/dhclient-script
[150888.433215] type=1505 audit(1257939536.880:33): operation="profile_replace" pid=8385 name=/usr/bin/evince
[150888.447233] type=1505 audit(1257939536.891:34): operation="profile_replace" pid=8385 name=/usr/bin/evince-previewer
[150888.454088] type=1505 audit(1257939536.899:35): operation="profile_replace" pid=8385 name=/usr/bin/evince-thumbnailer
[150888.486943] type=1505 audit(1257939536.931:36): operation="profile_replace" pid=8387 name=/usr/bin/virt-aa-helper
[150888.500283] type=1505 audit(1257939536.947:37): operation="profile_replace" pid=8388 name=/usr/lib/cups/backend/cups-pdf
[150899.442820] __ratelimit: 21 callbacks suppressed
[150899.442824] type=1505 audit(1257939547.887:45): operation="profile_replace" pid=8422 name=/usr/bin/evince
[150899.443780] type=1505 audit(1257939547.887:46): operation="profile_replace" pid=8422 name=/usr/bin/evince-previewer
[150899.444527] type=1505 audit(1257939547.891:47): operation="profile_replace" pid=8422 name=/usr/bin/evince-thumbnailer
[150899.521417] type=1505 audit(1257939547.967:48): operation="profile_replace" pid=8425 name=/usr/bin/virt-aa-helper
[150899.792824] type=1505 audit(1257939548.239:49): operation="profile_replace" pid=8426 name=/usr/lib/cups/backend/cups-pdf
[150899.792988] type=1505 audit(1257939548.239:50): operation="profile_replace" pid=8426 name=/usr/sbin/cupsd
[150899.868546] type=1505 audit(1257939548.312:51): operation="profile_replace" pid=8427 name=/usr/sbin/libvirtd
[150899.947375] type=1505 audit(1257939548.391:52): operation="profile_replace" pid=8428 name=/usr/sbin/tcpdump
[150904.916666] type=1505 audit(1257939553.363:53): operation="profile_replace" pid=8540 name=/usr/lib/cups/backend/cups-pdf
[150904.917546] type=1505 audit(1257939553.363:54): operation="profile_replace" pid=8540 name=/usr/sbin/cupsd
[150958.692223] type=1505 audit(1257939607.139:55): operation="profile_replace" pid=10272 name=/usr/sbin/libvirtd
[150958.726163] type=1505 audit(1257939607.171:56): operation="profile_replace" pid=10274 name=/usr/bin/virt-aa-helper
[150961.677811] lo: Disabled Privacy Extensions
[162644.947920] type=1505 audit(1257951293.391:57): operation="profile_replace" pid=26974 name=/usr/bin/evince
[162644.948942] type=1505 audit(1257951293.395:58): operation="profile_replace" pid=26974 name=/usr/bin/evince-previewer
[162644.949708] type=1505 audit(1257951293.395:59): operation="profile_replace" pid=26974 name=/usr/bin/evince-thumbnailer