[ 23.066122] nvidia: module license 'NVIDIA' taints kernel. [ 23.066126] Disabling lock debugging due to kernel taint [ 23.735233] ACPI: PCI Interrupt Link [LAZA] enabled at IRQ 22 [ 23.735239] HDA Intel 0000:00:05.0: PCI INT B -> Link[LAZA] -> GSI 22 (level, low) -> IRQ 22 [ 23.735242] hda_intel: Disable MSI for Nvidia chipset [ 23.735267] HDA Intel 0000:00:05.0: setting latency timer to 64 [ 24.137892] vboxdrv: Trying to deactivate the NMI watchdog permanently... [ 24.137895] vboxdrv: Successfully done. [ 24.137897] vboxdrv: Found 2 processor cores. [ 24.138365] VBoxDrv: dbg - g_abExecMemory=ffffffffa0c54d60 [ 24.138379] vboxdrv: fAsync=1 offMin=0xa417 offMax=0xa417 [ 24.139013] vboxdrv: TSC mode is 'asynchronous', kernel timer mode is 'normal'. [ 24.139015] vboxdrv: Successfully loaded version 3.2.8_OSE (interface 0x00140001). [ 24.985520] EXT4-fs (sda5): re-mounted. Opts: errors=remount-ro,commit=0 [ 25.552602] ACPI: PCI Interrupt Link [LMC9] enabled at IRQ 21 [ 25.552608] nvidia 0000:00:0d.0: PCI INT A -> Link[LMC9] -> GSI 21 (level, low) -> IRQ 21 [ 25.552615] nvidia 0000:00:0d.0: setting latency timer to 64 [ 25.552619] vgaarb: device changed decodes: PCI:0000:00:0d.0,olddecodes=io+mem,decodes=none:owns=io+mem [ 25.552848] NVRM: loading NVIDIA UNIX x86_64 Kernel Module 260.19.06 Mon Sep 13 04:29:19 PDT 2010 [ 28.052957] EXT4-fs (sda5): re-mounted. Opts: errors=remount-ro,commit=0 [ 32.590011] eth0: no IPv6 routers present [ 5314.405931] lo: Disabled Privacy Extensions [11642.748677] lo: Disabled Privacy Extensions [13295.388885] lo: Disabled Privacy Extensions [13514.540681] lo: Disabled Privacy Extensions [13631.697132] lo: Disabled Privacy Extensions [14546.603948] EXT4-fs (sda5): re-mounted. Opts: errors=remount-ro,commit=0 [14550.745707] forcedeth 0000:00:07.0: irq 43 for MSI/MSI-X [14569.906899] PM: Syncing filesystems ... done. [14570.321465] PM: Preparing system for mem sleep [14570.321477] Freezing user space processes ... (elapsed 0.20 seconds) done. [14570.522567] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [14570.540065] PM: Entering mem sleep [14570.540090] Suspending console(s) (use no_console_suspend to debug) [14570.540471] sd 2:0:0:0: [sda] Synchronizing SCSI cache [14570.549115] ACPI handle has no context! [14570.549597] serial 00:0c: disabled [14570.549993] parport_pc 00:06: disabled [14570.551367] forcedeth 0000:00:07.0: PME# enabled [14570.551372] forcedeth 0000:00:07.0: wake-up capability enabled by ACPI [14570.551377] forcedeth 0000:00:07.0: PCI INT A disabled [14570.551457] sata_nv 0000:00:08.1: PCI INT B disabled [14570.551481] ehci_hcd 0000:00:02.1: PCI INT B disabled [14570.562535] sd 2:0:0:0: [sda] Stopping disk [14570.562621] ohci_hcd 0000:00:02.0: PCI INT A disabled [14570.572560] ACPI handle has no context! [14570.673352] PM: suspend of drv:sd dev:2:0:0:0 complete after 132.890 msecs [14570.673358] PM: suspend of drv:scsi dev:target2:0:0 complete after 132.861 msecs [14570.673363] PM: suspend of drv:scsi dev:host2 complete after 132.770 msecs [14570.673407] sata_nv 0000:00:08.0: PCI INT A disabled [14570.692516] PM: suspend of drv:sata_nv dev:0000:00:08.0 complete after 141.500 msecs [14570.711605] PM: suspend of drv:nvidia dev:0000:00:0d.0 complete after 161.339 msecs [14570.920031] HDA Intel 0000:00:05.0: PCI INT B disabled [14570.940015] PM: suspend of drv:HDA Intel dev:0000:00:05.0 complete after 388.608 msecs [14570.940031] PM: suspend of drv: dev:pci0000:00 complete after 377.501 msecs [14570.940041] PM: suspend of devices complete after 399.777 msecs [14570.940043] PM: suspend devices took 0.400 seconds [14570.980153] PM: late suspend of devices complete after 40.106 msecs [14570.980497] ACPI: Preparing to enter system sleep state S3 [14570.982038] PM: Saving platform NVS memory [14570.983305] Disabling non-boot CPUs ... [14571.100022] CPU 1 is now offline [14571.100024] SMP alternatives: switching to UP code [14571.104370] Back to C! [14571.104370] PM: Restoring platform NVS memory [14571.104370] Enabling non-boot CPUs ... [14571.104370] SMP alternatives: switching to SMP code [14571.104370] Booting Node 0 Processor 1 APIC 0x1 [14571.330198] CPU1 is up [14571.330363] ACPI: Waking up from system sleep state S3 [14571.332064] pci 0000:00:01.0: restoring config space at offset 0x4 (was 0x0, writing 0x901) [14571.332114] ohci_hcd 0000:00:02.0: restoring config space at offset 0x1 (was 0xb00007, writing 0xb00003) [14571.332135] ehci_hcd 0000:00:02.1: restoring config space at offset 0x1 (was 0xb00006, writing 0xb00002) [14571.332196] pci 0000:00:00.0: Found enabled HT MSI Mapping [14571.332211] HDA Intel 0000:00:05.0: restoring config space at offset 0x1 (was 0xb00006, writing 0xb00002) [14571.332258] pci 0000:00:00.0: Found enabled HT MSI Mapping [14571.332338] pci 0000:00:00.0: Found enabled HT MSI Mapping [14571.332400] pci 0000:00:00.0: Found enabled HT MSI Mapping [14571.332462] pci 0000:00:00.0: Found enabled HT MSI Mapping [14571.332474] pcieport 0000:00:09.0: restoring config space at offset 0x1 (was 0x100004, writing 0x100404) [14571.332541] pci 0000:00:00.0: Found enabled HT MSI Mapping [14571.332553] pcieport 0000:00:0b.0: restoring config space at offset 0x1 (was 0x100004, writing 0x100404) [14571.332616] pci 0000:00:00.0: Found enabled HT MSI Mapping [14571.332628] pcieport 0000:00:0c.0: restoring config space at offset 0x1 (was 0x100004, writing 0x100404) [14571.332695] pci 0000:00:00.0: Found enabled HT MSI Mapping [14571.332701] nvidia 0000:00:0d.0: restoring config space at offset 0xc (was 0xdfec0000, writing 0x0) [14571.333045] PM: early resume of devices complete after 1.135 msecs [14571.333410] ohci_hcd 0000:00:02.0: PCI INT A -> Link[LUB0] -> GSI 20 (level, low) -> IRQ 20 [14571.333416] ohci_hcd 0000:00:02.0: setting latency timer to 64 [14571.333429] ehci_hcd 0000:00:02.1: PCI INT B -> Link[LUB2] -> GSI 21 (level, low) -> IRQ 21 [14571.333433] ehci_hcd 0000:00:02.1: setting latency timer to 64 [14571.333448] pci 0000:00:04.0: setting latency timer to 64 [14571.333457] HDA Intel 0000:00:05.0: PCI INT B -> Link[LAZA] -> GSI 22 (level, low) -> IRQ 22 [14571.333461] HDA Intel 0000:00:05.0: setting latency timer to 64 [14571.342805] pata_amd 0000:00:06.0: setting latency timer to 64 [14571.342850] sata_nv 0000:00:08.0: PCI INT A -> Link[LSA0] -> GSI 23 (level, low) -> IRQ 23 [14571.342852] sata_nv 0000:00:08.0: setting latency timer to 64 [14571.342922] forcedeth 0000:00:07.0: wake-up capability disabled by ACPI [14571.342926] forcedeth 0000:00:07.0: PME# disabled [14571.343541] sata_nv 0000:00:08.1: PCI INT B -> Link[LSA1] -> GSI 22 (level, low) -> IRQ 22 [14571.343544] sata_nv 0000:00:08.1: setting latency timer to 64 [14571.345573] parport_pc 00:06: activated [14571.345630] sd 2:0:0:0: [sda] Starting disk [14571.347066] serial 00:0c: activated [14571.347458] ata2: port disabled. ignoring. [14571.520339] ata1.00: ACPI cmd ef/03:44:00:00:00:a0 (SET FEATURES) filtered out [14571.520343] ata1.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [14571.520368] ata1: nv_mode_filter: 0x1f39f&0x1f39f->0x1f39f, BIOS=0x1f000 (0xc5000000) ACPI=0x1f01f (30:900:0x11) [14571.530029] PM: resume of drv:usb dev:usb1 complete after 196.503 msecs [14571.530040] PM: resume of drv:hub dev:1-0:1.0 complete after 196.511 msecs [14571.530049] PM: resume of drv: dev:ep_81 complete after 159.982 msecs [14571.560251] ata1.00: configured for UDMA/66 [14571.592537] firewire_core: skipped bus generations, destroying all nodes [14571.600015] PM: resume of drv:usb dev:usb2 complete after 266.484 msecs [14571.600024] PM: resume of drv:hub dev:2-0:1.0 complete after 266.490 msecs [14571.612512] PM: resume of drv:firewire_ohci dev:0000:01:07.0 complete after 278.992 msecs [14571.790073] PM: resume of drv:HDA Intel dev:0000:00:05.0 complete after 456.618 msecs [14571.870208] PM: resume of drv:forcedeth dev:0000:00:07.0 complete after 536.723 msecs [14571.930015] usb 2-4: reset low speed USB device using ohci_hcd and address 2 [14572.100315] ata6: SATA link down (SStatus 0 SControl 300) [14572.110562] ata5: SATA link down (SStatus 0 SControl 300) [14572.110589] firewire_core: rediscovered device fw0 [14572.120895] ata4: SATA link down (SStatus 0 SControl 300) [14572.304024] PM: resume of drv:usb dev:2-4 complete after 961.313 msecs [14572.304032] PM: resume of drv:usbhid dev:2-4:1.0 complete after 961.286 msecs [14574.602508] [14574.602509] floppy driver state [14574.602509] ------------------- [14574.602520] now=4296394756 last interrupt=4294937413 diff=1457343 last called handler=reset_interrupt [14574.602521] timeout_message=lock fdc [14574.602522] last output bytes: [14574.602524] 8 80 4294937411 [14574.602525] 8 80 4294937411 [14574.602526] 8 80 4294937411 [14574.602527] 8 80 4294937413 [14574.602528] 8 80 4294937413 [14574.602530] 8 80 4294937413 [14574.602531] 8 80 4294937413 [14574.602532] e 80 4294937413 [14574.602533] 13 80 4294937413 [14574.602535] 0 90 4294937413 [14574.602536] 1a 90 4294937413 [14574.602537] 0 90 4294937413 [14574.602538] 12 90 4294937413 [14574.602540] 0 90 4294937413 [14574.602541] 14 90 4294937413 [14574.602542] 18 80 4294937413 [14574.602543] 8 80 4294937413 [14574.602544] 8 80 4294937413 [14574.602546] 8 80 4294937413 [14574.602547] 8 80 4294937413 [14574.602548] last result at 4294937413 [14574.602549] last redo_fd_request at 4294937413 [14574.602560] status=0 [14574.602561] fdc_busy=1 [14574.602564] do_floppy=reset_interrupt [14574.602566] cont=ffffffffa0051720 [14574.602567] current_req=(null) [14574.602568] command_status=-1 [14574.602568] [14574.602570] floppy0: floppy timeout called [14574.602577] PM: resume of drv:floppy dev:floppy.0 complete after 3002.426 msecs [14576.870012] ata3: link is slow to respond, please be patient (ready=0) [14581.370012] ata3: SRST failed (errno=-16) [14581.860030] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [14581.880113] ata3.00: ACPI cmd ef/03:46:00:00:00:a0 (SET FEATURES) filtered out [14581.880116] ata3.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [14582.180586] ata3.00: configured for UDMA/133 [14582.228528] PM: resume of drv:sd dev:2:0:0:0 complete after 10885.790 msecs [14582.228538] PM: resume of drv:scsi_device dev:2:0:0:0 complete after 10885.796 msecs [14582.228546] PM: resume of drv:scsi_disk dev:2:0:0:0 complete after 7625.944 msecs [14582.228666] PM: resume of devices complete after 10895.528 msecs [14582.228806] PM: resume devices took 10.890 seconds [14582.228808] ------------[ cut here ]------------ [14582.228814] WARNING: at /build/buildd/linux-2.6.35/kernel/power/suspend_test.c:53 suspend_test_finish+0x88/0x90() [14582.228816] Hardware name: System Product Name [14582.228817] Component: resume devices, time: 10890 [14582.228819] Modules linked in: snd_hda_codec_analog binfmt_misc vboxnetadp vboxnetflt vboxdrv snd_hda_intel nvidia(P) snd_hda_codec snd_hwdep snd_pcm snd_seq_midi snd_rawmidi ipx p8023 snd_seq_midi_event snd_seq snd_timer snd_seq_device snd soundcore snd_page_alloc edac_core edac_mce_amd usbhid firewire_ohci k8temp i2c_nforce2 asus_atk0110 lp ppdev parport_pc parport hid floppy firewire_core forcedeth crc_itu_t sata_nv pata_amd [14582.228841] Pid: 7724, comm: pm-suspend Tainted: P 2.6.35-23-generic #40-Ubuntu [14582.228842] Call Trace: [14582.228848] [] warn_slowpath_common+0x7f/0xc0 [14582.228851] [] warn_slowpath_fmt+0x46/0x50 [14582.228854] [] suspend_test_finish+0x88/0x90 [14582.228857] [] suspend_devices_and_enter+0xd1/0x110 [14582.228860] [] enter_state+0xd8/0x110 [14582.228862] [] state_store+0x9a/0x100 [14582.228867] [] kobj_attr_store+0x17/0x20 [14582.228870] [] sysfs_write_file+0xf2/0x180 [14582.228873] [] vfs_write+0xb8/0x1a0 [14582.228875] [] sys_write+0x51/0x80 [14582.228879] [] system_call_fastpath+0x16/0x1b [14582.228881] ---[ end trace d5aa885978623675 ]--- [14582.228954] PM: Finishing wakeup. [14582.228955] Restarting tasks ... done. [14592.214988] forcedeth 0000:00:07.0: irq 43 for MSI/MSI-X [14592.253077] forcedeth 0000:00:07.0: irq 43 for MSI/MSI-X [14602.643918] eth0: no IPv6 routers present [14618.368316] EXT4-fs (sda5): re-mounted. Opts: errors=remount-ro,commit=0