[ 32.879533] vboxdrv: Trying to deactivate the NMI watchdog permanently... [ 32.879536] vboxdrv: Successfully done. [ 32.879538] vboxdrv: Found 2 processor cores. [ 32.879720] vboxdrv: fAsync=0 offMin=0x193 offMax=0x8f0 [ 32.880292] vboxdrv: TSC mode is 'synchronous', kernel timer mode is 'normal'. [ 32.880295] vboxdrv: Successfully loaded version 3.2.8_OSE (interface 0x00140001). [ 36.064014] eth0: no IPv6 routers present [ 43.468850] ata3.00: configured for UDMA/133 [ 43.484686] ata3.01: configured for UDMA/100 [ 43.484689] ata3: EH complete [ 43.900819] ata4.01: configured for UDMA/133 [ 43.900826] ata4: EH complete [ 483.596965] lo: Disabled Privacy Extensions [16078.460881] ata3.00: configured for UDMA/133 [16078.476702] ata3.01: configured for UDMA/100 [16078.476710] ata3: EH complete [16078.796818] ata4.01: configured for UDMA/133 [16078.796826] ata4: EH complete [16081.889205] PM: Syncing filesystems ... done. [16081.892262] PM: Preparing system for mem sleep [16081.892270] Freezing user space processes ... (elapsed 0.01 seconds) done. [16081.908052] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [16081.924022] PM: Entering mem sleep [16081.924052] Suspending console(s) (use no_console_suspend to debug) [16081.924608] sd 3:0:1:0: [sdb] Synchronizing SCSI cache [16081.924681] sd 2:0:0:0: [sda] Synchronizing SCSI cache [16081.924789] sd 3:0:1:0: [sdb] Stopping disk [16081.925192] ACPI handle has no context! [16081.925739] serial 00:10: disabled [16081.926231] parport_pc 00:07: disabled [16081.933120] SB-XFi 0000:04:01.0: PCI INT A disabled [16081.933150] ACPI handle has no context! [16081.933862] ata_piix 0000:00:1f.1: PCI INT A disabled [16081.933947] uhci_hcd 0000:00:1d.3: PCI INT D disabled [16081.940114] uhci_hcd 0000:00:1d.0: PCI INT A disabled [16081.940142] uhci_hcd 0000:00:1d.2: PCI INT C disabled [16081.948564] uhci_hcd 0000:00:1d.1: PCI INT B disabled [16081.956528] ehci_hcd 0000:00:1d.7: PCI INT A disabled [16082.032008] usb_submit_urb: -113 [16082.032012] usb_submit_urb: -113 [16082.032014] usb_submit_urb: -113 [16082.032017] usb_submit_urb: -113 [16082.032020] usb_submit_urb: -113 [16082.032023] usb_submit_urb: -113 [16082.032025] usb_submit_urb: -113 [16082.138454] sd 2:0:0:0: [sda] Stopping disk [16082.543624] PM: suspend of drv:sd dev:2:0:0:0 complete after 618.941 msecs [16082.543637] PM: suspend of drv:scsi dev:target2:0:0 complete after 618.908 msecs [16082.543650] PM: suspend of drv:scsi dev:host2 complete after 618.601 msecs [16082.543761] ata_piix 0000:00:1f.2: PCI INT B disabled [16082.556015] PM: suspend of drv:ata_piix dev:0000:00:1f.2 complete after 622.798 msecs [16082.556027] PM: suspend of drv: dev:pci0000:00 complete after 620.366 msecs [16082.556037] PM: suspend of devices complete after 631.682 msecs [16082.556042] PM: suspend devices took 0.632 seconds [16082.556222] r8169 0000:02:00.0: PME# enabled [16082.556234] pcieport 0000:00:1c.1: wake-up capability enabled by ACPI [16082.588140] PM: late suspend of devices complete after 32.092 msecs [16082.588620] ACPI: Preparing to enter system sleep state S3 [16082.589104] PM: Saving platform NVS memory [16082.593630] Disabling non-boot CPUs ... [16082.596673] Broke affinity for irq 18 [16082.596687] Broke affinity for irq 23 [16082.700021] CPU 1 is now offline [16082.700024] SMP alternatives: switching to UP code [16082.704991] Back to C! [16082.704991] PM: Restoring platform NVS memory [16082.704991] Enabling non-boot CPUs ... [16082.704991] SMP alternatives: switching to SMP code [16082.709708] Booting Node 0 Processor 1 APIC 0x1 [16082.704705] Initializing CPU#1 [16082.836475] CPU1 is up [16082.836971] ACPI: Waking up from system sleep state S3 [16082.837425] pcieport 0000:00:01.0: restoring config space at offset 0xf (was 0xa0100, writing 0xa010b) [16082.837435] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [16082.837458] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x20100, writing 0x2010b) [16082.837466] pcieport 0000:00:1c.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xe031e021) [16082.837470] pcieport 0000:00:1c.0: restoring config space at offset 0x8 (was 0xfff0, writing 0xe010e000) [16082.837474] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0xf0, writing 0x1010) [16082.837480] pcieport 0000:00:1c.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810008) [16082.837484] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100104, writing 0x100507) [16082.837513] pcieport 0000:00:1c.1: restoring config space at offset 0xf (was 0x20200, writing 0x2020a) [16082.837525] pcieport 0000:00:1c.1: restoring config space at offset 0x3 (was 0x810000, writing 0x810008) [16082.837530] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [16082.837566] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [16082.837587] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [16082.837608] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [16082.837629] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [16082.837658] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [16082.837674] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x20000, writing 0x200ff) [16082.837796] nvidia 0000:01:00.0: restoring config space at offset 0xc (was 0xf7e80000, writing 0x0) [16082.837805] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x8, writing 0x0) [16082.837850] r8169 0000:02:00.0: restoring config space at offset 0xf (was 0x1ff, writing 0x10a) [16082.837858] r8169 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xf7ff0000) [16082.837867] r8169 0000:02:00.0: restoring config space at offset 0x8 (was 0xc, writing 0xeefe000c) [16082.837873] r8169 0000:02:00.0: restoring config space at offset 0x6 (was 0xc, writing 0xeefff00c) [16082.837879] r8169 0000:02:00.0: restoring config space at offset 0x4 (was 0x1, writing 0xd801) [16082.837883] r8169 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [16082.837889] r8169 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [16082.837949] SB-XFi 0000:04:01.0: restoring config space at offset 0x1 (was 0x2100007, writing 0x2100003) [16082.838017] PM: early resume of devices complete after 0.631 msecs [16082.838221] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [16082.838227] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [16082.838246] usb usb2: root hub lost power or was reset [16082.838263] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [16082.838267] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [16082.838285] usb usb3: root hub lost power or was reset [16082.838301] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [16082.838305] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [16082.838323] usb usb4: root hub lost power or was reset [16082.838339] uhci_hcd 0000:00:1d.3: PCI INT D -> GSI 16 (level, low) -> IRQ 16 [16082.838343] uhci_hcd 0000:00:1d.3: setting latency timer to 64 [16082.838361] usb usb5: root hub lost power or was reset [16082.838376] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [16082.838380] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [16082.838398] pci 0000:00:1e.0: setting latency timer to 64 [16082.838413] ata_piix 0000:00:1f.2: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [16082.838418] ata_piix 0000:00:1f.2: setting latency timer to 64 [16082.838419] ata_piix 0000:00:1f.1: PCI INT A -> GSI 18 (level, low) -> IRQ 18 [16082.838426] ata_piix 0000:00:1f.1: setting latency timer to 64 [16082.838439] pcieport 0000:00:1c.1: wake-up capability disabled by ACPI [16082.838445] r8169 0000:02:00.0: PME# disabled [16082.838452] SB-XFi 0000:04:01.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [16082.868647] sd 2:0:0:0: [sda] Starting disk [16082.970829] sd 3:0:1:0: [sdb] Starting disk [16083.062399] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 223.967 msecs [16083.076541] PM: resume of drv: dev:platform complete after 238.147 msecs [16083.078219] parport_pc 00:07: activated [16083.079348] serial 00:10: activated [16083.164555] PM: resume of drv:usb dev:usb5 complete after 295.913 msecs [16083.164560] PM: resume of drv:SB-XFi dev:0000:04:01.0 complete after 326.109 msecs [16083.164564] PM: resume of drv:usb dev:usb1 complete after 295.935 msecs [16083.164569] PM: resume of drv:hub dev:5-0:1.0 complete after 295.925 msecs [16083.164572] PM: resume of drv:hub dev:1-0:1.0 complete after 295.942 msecs [16083.212626] PM: resume of drv:usb dev:1-4 complete after 136.106 msecs [16083.212635] PM: resume of drv:ums-onetouch dev:1-4:1.0 complete after 136.114 msecs [16083.212645] PM: resume of drv:scsi dev:host4 complete after 136.123 msecs [16083.212655] PM: resume of drv:scsi_host dev:host4 complete after 136.131 msecs [16083.212660] PM: resume of drv:scsi dev:target4:0:0 complete after 133.106 msecs [16083.212675] PM: resume of drv:sd dev:4:0:0:0 complete after 133.110 msecs [16083.212685] PM: resume of drv:scsi_device dev:4:0:0:0 complete after 133.109 msecs [16083.268516] PM: resume of drv:usb dev:usb4 complete after 399.876 msecs [16083.268527] PM: resume of drv:hub dev:4-0:1.0 complete after 399.884 msecs [16083.268532] PM: resume of drv:usb dev:usb3 complete after 399.893 msecs [16083.268543] PM: resume of drv:hub dev:3-0:1.0 complete after 399.905 msecs [16083.268562] PM: resume of drv:usb dev:usb2 complete after 399.930 msecs [16083.268573] PM: resume of drv:hub dev:2-0:1.0 complete after 399.937 msecs [16083.268586] PM: resume of drv: dev:ep_81 complete after 104.004 msecs [16083.276521] usb 1-5: reset high speed USB device using ehci_hcd and address 5 [16083.408704] usb 1-5: device firmware changed [16083.408718] PM: resume of drv:usb dev:1-5 complete after 332.193 msecs [16083.408728] PM: resume of drv:usb-storage dev:1-5:1.0 complete after 332.200 msecs [16083.408738] PM: resume of drv:scsi dev:host5 complete after 332.206 msecs [16083.408747] PM: resume of drv:scsi_host dev:host5 complete after 332.214 msecs [16083.408752] PM: resume of drv:scsi dev:target5:0:0 complete after 329.164 msecs [16083.408764] PM: resume of drv:sd dev:5:0:0:0 complete after 329.166 msecs [16083.408769] PM: resume of drv:sd dev:5:0:0:1 complete after 329.148 msecs [16083.408774] PM: resume of drv:scsi_device dev:5:0:0:0 complete after 329.164 msecs [16083.408780] PM: resume of drv:scsi_device dev:5:0:0:1 complete after 329.156 msecs [16083.668514] usb 4-2: reset low speed USB device using uhci_hcd and address 2 [16083.979534] PM: resume of drv:usb dev:4-2 complete after 900.000 msecs [16083.979544] PM: resume of drv:usbhid dev:4-2:1.0 complete after 899.999 msecs [16084.220515] usb 3-1: reset low speed USB device using uhci_hcd and address 2 [16084.528431] PM: resume of drv:usb dev:3-1 complete after 1448.919 msecs [16084.528441] PM: resume of drv:usbhid dev:3-1:1.0 complete after 1448.918 msecs [16084.620514] usb 2-2: reset full speed USB device using uhci_hcd and address 2 [16084.769373] snd-usb-audio 2-2:1.0: no reset_resume for driver snd-usb-audio? [16084.769377] snd-usb-audio 2-2:1.1: no reset_resume for driver snd-usb-audio? [16084.769589] PM: resume of drv:usb dev:2-2 complete after 1693.055 msecs [16084.769595] PM: resume of drv:usb dev:2-2:1.0 complete after 1690.106 msecs [16084.769598] PM: resume of drv:usb dev:2-2:1.1 complete after 1690.099 msecs [16087.908513] ata3: link is slow to respond, please be patient (ready=0) [16088.988546] ata4.01: NODEV after polling detection [16088.988550] ata4.01: revalidation failed (errno=-2) [16092.328648] ata4.01: ACPI cmd ef/03:45:00:00:00:b0 (SET FEATURES) filtered out [16092.328654] ata4.01: ACPI cmd ef/03:0c:00:00:00:b0 (SET FEATURES) filtered out [16092.328767] ata4.01: ACPI cmd c6/00:10:00:00:00:b0 (SET MULTIPLE MODE) succeeded [16092.328773] ata4.01: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [16092.352816] ata4.01: configured for UDMA/133 [16092.376831] ata4.01: configured for UDMA/133 [16092.376835] ata4: EH complete [16092.394794] PM: resume of drv:sd dev:3:0:1:0 complete after 9423.962 msecs [16092.394811] PM: resume of drv:scsi_device dev:3:0:1:0 complete after 9332.401 msecs [16092.892511] ata3: device not ready (errno=-16), forcing hardreset [16093.296648] ata3.01: ACPI cmd ef/03:45:00:00:00:b0 (SET FEATURES) filtered out [16093.296655] ata3.01: ACPI cmd ef/03:0c:00:00:00:b0 (SET FEATURES) filtered out [16093.296661] ata3.01: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [16093.324132] ata3.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [16093.324139] ata3.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [16093.324318] ata3.00: ACPI cmd c6/00:10:00:00:00:a0 (SET MULTIPLE MODE) succeeded [16093.324324] ata3.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [16093.344849] ata3.00: configured for UDMA/133 [16093.376682] ata3.01: configured for UDMA/100 [16093.456849] ata3.00: configured for UDMA/133 [16093.488682] ata3.01: configured for UDMA/100 [16093.488685] ata3: EH complete [16093.503476] PM: resume of drv:sd dev:2:0:0:0 complete after 10634.828 msecs [16093.503489] PM: resume of drv:scsi_disk dev:2:0:0:0 complete after 10234.275 msecs [16093.503495] PM: resume of drv:scsi_device dev:2:0:0:0 complete after 10532.667 msecs [16096.500006] [16096.500009] floppy driver state [16096.500011] ------------------- [16096.500029] now=3949125 last interrupt=4294892532 diff=4023889 last called handler=reset_interrupt [16096.500032] timeout_message=lock fdc [16096.500034] last output bytes: [16096.500038] 8 80 4294892527 [16096.500041] 8 80 4294892527 [16096.500043] 8 80 4294892527 [16096.500046] 8 80 4294892531 [16096.500049] 8 90 4294892531 [16096.500051] 8 90 4294892531 [16096.500054] 8 90 4294892531 [16096.500056] e 80 4294892531 [16096.500059] 13 90 4294892531 [16096.500067] 0 90 4294892531 [16096.500068] 1a 90 4294892531 [16096.500069] 0 90 4294892531 [16096.500071] 12 90 4294892531 [16096.500072] 0 90 4294892531 [16096.500073] 14 90 4294892531 [16096.500074] 18 90 4294892531 [16096.500075] 8 80 4294892532 [16096.500077] 8 80 4294892532 [16096.500078] 8 80 4294892532 [16096.500079] 8 80 4294892532 [16096.500080] last result at 4294892532 [16096.500081] last redo_fd_request at 4294892532 [16096.500087] status=0 [16096.500088] fdc_busy=1 [16096.500092] do_floppy=reset_interrupt [16096.500093] cont=f808d4d4 [16096.500095] current_req=(null) [16096.500096] command_status=-1 [16096.500097] [16096.500099] floppy0: floppy timeout called [16096.500106] PM: resume of drv:floppy dev:floppy.0 complete after 2996.592 msecs [16096.500173] PM: resume of devices complete after 13662.116 msecs [16096.504647] PM: resume devices took 13.668 seconds [16096.504649] ------------[ cut here ]------------ [16096.504654] WARNING: at /build/buildd/linux-2.6.35/kernel/power/suspend_test.c:53 suspend_test_finish+0x89/0x90() [16096.504655] Hardware name: System Product Name [16096.504657] Component: resume devices, time: 13668 [16096.504658] Modules linked in: nls_iso8859_1 nls_cp437 vfat fat binfmt_misc vboxnetadp vboxnetflt vboxdrv ipt_MASQUERADE xt_DSCP iptable_nat iptable_mangle ipt_REJECT ipt_LOG xt_limit xt_tcpudp ipt_addrtype xt_state snd_usb_audio snd_ctxfi snd_hwdep ip6table_filter ip6_tables snd_usbmidi_lib snd_pcm snd_seq_midi nf_nat_irc snd_rawmidi snd_seq_midi_event snd_seq nf_conntrack_irc nvidia(P) nf_nat_ftp nf_nat snd_timer snd_seq_device nf_conntrack_ipv4 nf_defrag_ipv4 snd nf_conntrack_ftp nf_conntrack led_class intel_agp snd_page_alloc iptable_filter ip_tables x_tables soundcore psmouse serio_raw ppdev parport_pc asus_atk0110 agpgart lp parport usbhid hid ums_onetouch usb_storage floppy r8169 mii [16096.504691] Pid: 11874, comm: pm-suspend Tainted: P 2.6.35-19-generic #28-Ubuntu [16096.504692] Call Trace: [16096.504697] [] warn_slowpath_common+0x72/0xa0 [16096.504700] [] ? suspend_test_finish+0x89/0x90 [16096.504702] [] ? suspend_test_finish+0x89/0x90 [16096.504705] [] warn_slowpath_fmt+0x33/0x40 [16096.504707] [] suspend_test_finish+0x89/0x90 [16096.504709] [] suspend_devices_and_enter+0xbf/0xf0 [16096.504712] [] enter_state+0xbd/0xf0 [16096.504714] [] state_store+0x6d/0xb0 [16096.504716] [] ? state_store+0x0/0xb0 [16096.504719] [] kobj_attr_store+0x20/0x30 [16096.504723] [] sysfs_write_file+0x99/0xf0 [16096.504726] [] vfs_write+0xa2/0x190 [16096.504729] [] ? sysfs_write_file+0x0/0xf0 [16096.504731] [] sys_write+0x42/0x70 [16096.504735] [] syscall_call+0x7/0xb [16096.504737] ---[ end trace 4c14b19f8a32b1e4 ]--- [16096.505091] PM: Finishing wakeup. [16096.505093] Restarting tasks ... [16096.505132] usb 1-5: USB disconnect, address 5 [16096.505497] done. [16096.587724] VFS: busy inodes on changed media or resized disk sdd [16096.700544] usb 1-5: new high speed USB device using ehci_hcd and address 7 [16096.836149] scsi6 : usb-storage 1-5:1.0 [16097.181663] r8169 0000:02:00.0: eth0: link up [16097.852305] scsi 6:0:0:0: Direct-Access Sony DSC 1.00 PQ: 0 ANSI: 0 [16097.853054] sd 6:0:0:0: Attached scsi generic sg4 type 0 [16097.857234] sd 6:0:0:0: [sde] 31326208 512-byte logical blocks: (16.0 GB/14.9 GiB) [16097.857977] sd 6:0:0:0: [sde] Write Protect is off [16097.857980] sd 6:0:0:0: [sde] Mode Sense: 03 00 00 00 [16097.857982] sd 6:0:0:0: [sde] Assuming drive cache: write through [16097.860609] sd 6:0:0:0: [sde] Assuming drive cache: write through [16097.860616] sde: sde1 [16097.864602] sd 6:0:0:0: [sde] Assuming drive cache: write through [16097.864606] sd 6:0:0:0: [sde] Attached SCSI removable disk [16099.208853] ata3.00: configured for UDMA/133 [16099.225331] ata3.01: configured for UDMA/100 [16099.225340] ata3: EH complete [16100.948326] ata4.01: configured for UDMA/133 [16100.948333] ata4: EH complete [16107.960510] eth0: no IPv6 routers present [16188.737807] usb 1-5: USB disconnect, address 7 [16205.732523] usb 1-5: new high speed USB device using ehci_hcd and address 8 [16205.866503] scsi7 : usb-storage 1-5:1.0 [16206.865154] scsi 7:0:0:0: Direct-Access Sony DSC 1.00 PQ: 0 ANSI: 0 [16206.866642] sd 7:0:0:0: Attached scsi generic sg4 type 0 [16206.867385] sd 7:0:0:0: [sdd] 31326208 512-byte logical blocks: (16.0 GB/14.9 GiB) [16206.868136] sd 7:0:0:0: [sdd] Write Protect is off [16206.868139] sd 7:0:0:0: [sdd] Mode Sense: 03 00 00 00 [16206.868141] sd 7:0:0:0: [sdd] Assuming drive cache: write through [16206.870381] sd 7:0:0:0: [sdd] Assuming drive cache: write through [16206.870385] sdd: sdd1 [16206.874650] sd 7:0:0:0: [sdd] Assuming drive cache: write through [16206.874658] sd 7:0:0:0: [sdd] Attached SCSI removable disk [16227.673312] usb 1-5: USB disconnect, address 8