[ 18.727142] EXT4-fs (sda5): re-mounted. Opts: user_xattr,commit=0 [ 20.841956] EXT4-fs (sda5): re-mounted. Opts: user_xattr,commit=0 [ 22.142568] hda-intel: IRQ timing workaround is activated for card #2. Suggest a bigger bdl_pos_adj. [ 24.142362] serial8250_interrupt: 116 callbacks suppressed [ 24.142366] serial8250: too much work for irq18 [ 24.158661] serial8250: too much work for irq18 [ 25.142291] serial8250: too much work for irq18 [ 25.158477] serial8250: too much work for irq18 [ 26.142205] serial8250: too much work for irq18 [ 26.158354] serial8250: too much work for irq18 [ 28.192028] sis190 0000:00:04.0: eth0: mii ext = 0000 [ 28.216049] sis190 0000:00:04.0: eth0: mii lpa=41e1 adv=01e1 exp=0003 [ 28.216056] sis190 0000:00:04.0: eth0: link on 100 Mbps Full Duplex mode [ 28.216264] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 38.560023] eth0: no IPv6 routers present [ 69.180823] process `skype' is using obsolete setsockopt SO_BSDCOMPAT [ 166.846610] usb 1-8: USB disconnect, address 4 [ 178.274477] EXT4-fs (sda5): re-mounted. Opts: user_xattr,commit=0 [ 180.329300] PM: Syncing filesystems ... done. [ 180.331897] PM: Preparing system for mem sleep [ 180.331918] Freezing user space processes ... (elapsed 0.01 seconds) done. [ 180.348075] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [ 180.364051] PM: Entering mem sleep [ 180.364090] Suspending console(s) (use no_console_suspend to debug) [ 180.364682] sd 3:0:0:0: [sda] Synchronizing SCSI cache [ 180.368547] serial 00:0c: disabled [ 180.368556] serial 00:0c: wake-up capability disabled by ACPI [ 180.368566] i8042 kbd 00:0a: wake-up capability enabled by ACPI [ 180.368781] parport_pc 00:05: disabled [ 180.369065] ACPI handle has no context! [ 180.377462] EMU10K1_Audigy 0000:00:0d.0: PCI INT A disabled [ 180.377500] ACPI handle has no context! [ 180.377825] sis190 0000:00:04.0: PCI INT A disabled [ 180.377849] ehci_hcd 0000:00:03.3: PCI INT C disabled [ 180.380076] ohci_hcd 0000:00:03.1: PCI INT B disabled [ 180.380153] ohci_hcd 0000:00:03.0: PCI INT A disabled [ 180.472105] HDA Intel 0000:00:0f.0: PCI INT A disabled [ 180.488026] PM: suspend of drv:HDA Intel dev:0000:00:0f.0 complete after 119.218 msecs [ 180.500898] sd 3:0:0:0: [sda] Stopping disk [ 180.822202] PM: suspend of drv:sd dev:3:0:0:0 complete after 457.527 msecs [ 180.822218] PM: suspend of drv:scsi dev:target3:0:0 complete after 457.508 msecs [ 180.822245] PM: suspend of drv:scsi dev:host3 complete after 457.448 msecs [ 180.822267] sata_sis 0000:00:05.0: PCI INT A disabled [ 180.822272] PM: suspend of drv:sata_sis dev:0000:00:05.0 complete after 444.494 msecs [ 180.822287] PM: suspend of drv: dev:pci0000:00 complete after 444.217 msecs [ 180.822302] PM: suspend of devices complete after 457.782 msecs [ 180.822305] PM: suspend devices took 0.456 seconds [ 180.836166] PM: late suspend of devices complete after 13.856 msecs [ 180.836288] ACPI: Preparing to enter system sleep state S3 [ 180.836568] PM: Saving platform NVS memory [ 180.836946] Disabling non-boot CPUs ... [ 180.940033] CPU 1 is now offline [ 180.940449] Back to C! [ 180.940449] PM: Restoring platform NVS memory [ 180.940449] Enabling non-boot CPUs ... [ 180.940449] Booting Node 0 Processor 1 APIC 0x1 [ 180.838249] Initializing CPU#1 [ 181.052016] Switched to NOHz mode on CPU #1 [ 181.052085] CPU1 is up [ 181.052684] ACPI: Waking up from system sleep state S3 [ 181.712618] ohci_hcd 0000:00:03.0: restoring config space at offset 0x1 (was 0x2800117, writing 0x2800113) [ 181.712647] ohci_hcd 0000:00:03.1: restoring config space at offset 0x1 (was 0x2800117, writing 0x2800113) [ 181.728031] ehci_hcd 0000:00:03.3: BAR 0: set to [mem 0xf9ffd000-0xf9ffdfff] (PCI address [0xf9ffd000-0xf9ffdfff]) [ 181.728065] ehci_hcd 0000:00:03.3: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [ 181.728110] sis190 0000:00:04.0: restoring config space at offset 0x1 (was 0x2100007, writing 0x2100003) [ 181.728144] sata_sis 0000:00:05.0: restoring config space at offset 0x9 (was 0x1, writing 0xc401) [ 181.728157] sata_sis 0000:00:05.0: restoring config space at offset 0x1 (was 0x2100005, writing 0x2100001) [ 181.728201] pcieport 0000:00:06.0: restoring config space at offset 0x1 (was 0x100104, writing 0x100504) [ 181.728266] pcieport 0000:00:07.0: restoring config space at offset 0x1 (was 0x100104, writing 0x100504) [ 181.728322] EMU10K1_Audigy 0000:00:0d.0: restoring config space at offset 0x1 (was 0x2900105, writing 0x2900101) [ 181.728357] Emu10k1_gameport 0000:00:0d.1: restoring config space at offset 0x1 (was 0x2900105, writing 0x2900101) [ 181.728428] HDA Intel 0000:00:0f.0: restoring config space at offset 0x1 (was 0x2100006, writing 0x2100002) [ 181.728455] nvidia 0000:01:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a) [ 181.728466] nvidia 0000:01:00.0: restoring config space at offset 0x9 (was 0x1, writing 0xec01) [ 181.728473] nvidia 0000:01:00.0: restoring config space at offset 0x7 (was 0x4, writing 0xfa000004) [ 181.728479] nvidia 0000:01:00.0: restoring config space at offset 0x5 (was 0xc, writing 0xf000000c) [ 181.728484] nvidia 0000:01:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xfd000000) [ 181.728492] nvidia 0000:01:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) [ 181.728727] PM: early resume of devices complete after 16.306 msecs [ 181.728828] ohci_hcd 0000:00:03.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [ 181.728836] ohci_hcd 0000:00:03.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [ 181.729411] ACPI Exception: AE_AML_PACKAGE_LIMIT, Index (0x00000000FFFFFFFF) is beyond end of object (20110112/exoparg2-418) [ 181.729422] ACPI Error: Method parse/execution failed [\_SB_.PCI0.IDEC.IDE0._STM] (Node f4027258), AE_AML_PACKAGE_LIMIT (20110112/psparse-536) [ 181.729436] ata1: ACPI set timing mode failed (status=0x300b) [ 181.729599] ata1: port disabled. ignoring. [ 181.729644] ehci_hcd 0000:00:03.3: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [ 181.729665] sis190 0000:00:04.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19 [ 181.729671] sis190 0000:00:04.0: setting latency timer to 64 [ 181.729679] sata_sis 0000:00:05.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17 [ 181.729705] EMU10K1_Audigy 0000:00:0d.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19 [ 181.729740] ata2: port disabled. ignoring. [ 181.730794] parport_pc 00:05: activated [ 181.730808] i8042 kbd 00:0a: wake-up capability disabled by ACPI [ 181.731485] serial 00:0c: activated [ 181.731687] HDA Intel 0000:00:0f.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18 [ 181.731694] HDA Intel 0000:00:0f.0: setting latency timer to 64 [ 181.739261] sd 3:0:0:0: [sda] Starting disk [ 181.964034] PM: resume of drv:usb dev:usb2 complete after 224.840 msecs [ 181.964051] PM: resume of drv:hub dev:2-0:1.0 complete after 224.856 msecs [ 181.964058] PM: resume of drv: dev:ep_00 complete after 224.849 msecs [ 181.964080] PM: resume of drv: dev:ep_81 complete after 224.879 msecs [ 181.964088] PM: resume of drv:usb dev:usb3 complete after 224.873 msecs [ 181.964098] PM: resume of drv:hub dev:3-0:1.0 complete after 224.876 msecs [ 181.964105] PM: resume of drv: dev:ep_00 complete after 224.869 msecs [ 181.964117] PM: resume of drv: dev:ep_81 complete after 224.887 msecs [ 182.054738] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 323.016 msecs [ 182.140026] usb 2-2: reset full speed USB device using ohci_hcd and address 2 [ 182.347034] STV06xx 2-2:1.0: no reset_resume for driver STV06xx? [ 182.347039] snd-usb-audio 2-2:1.1: no reset_resume for driver snd-usb-audio? [ 182.347044] snd-usb-audio 2-2:1.2: no reset_resume for driver snd-usb-audio? [ 182.347058] STV06xx: Disconnecting the stv06xx device [ 182.347063] gspca: video0 disconnect [ 182.348277] gspca: video0 released [ 182.348422] PM: resume of drv:usb dev:2-2 complete after 573.932 msecs [ 182.348439] PM: resume of drv:usb dev:2-2:1.0 complete after 573.926 msecs [ 182.348442] PM: resume of drv:usb dev:2-2:1.2 complete after 573.832 msecs [ 182.348450] PM: resume of drv: dev:ep_83 complete after 573.796 msecs [ 182.348452] PM: resume of drv:usb dev:2-2:1.1 complete after 573.865 msecs [ 182.348475] PM: resume of drv: dev:ep_82 complete after 573.914 msecs [ 182.348478] PM: resume of drv: dev:ep_00 complete after 573.844 msecs [ 182.348485] PM: resume of drv: dev:ep_81 complete after 573.948 msecs [ 182.660024] usb 3-2: reset low speed USB device using ohci_hcd and address 2 [ 183.018031] PM: resume of drv:usb dev:3-2 complete after 1246.583 msecs [ 183.018046] PM: resume of drv:usbhid dev:3-2:1.0 complete after 1246.570 msecs [ 183.018059] PM: resume of drv: dev:ep_00 complete after 1243.593 msecs [ 183.018064] PM: resume of drv: dev:ep_81 complete after 1243.622 msecs [ 184.776019] [ 184.776022] floppy driver state [ 184.776024] ------------------- [ 184.776043] now=4294938490 last interrupt=4294892576 diff=45914 last called handler=reset_interrupt [ 184.776046] timeout_message=lock fdc [ 184.776048] last output bytes: [ 184.776052] 8 80 4294892570 [ 184.776054] 8 80 4294892571 [ 184.776057] 8 80 4294892571 [ 184.776060] 8 80 4294892575 [ 184.776063] 8 80 4294892575 [ 184.776065] 8 80 4294892575 [ 184.776068] 8 80 4294892575 [ 184.776070] e 80 4294892575 [ 184.776073] 13 80 4294892575 [ 184.776075] 0 90 4294892575 [ 184.776078] 1a 90 4294892575 [ 184.776081] 0 90 4294892575 [ 184.776083] 12 90 4294892575 [ 184.776086] 0 90 4294892575 [ 184.776089] 14 90 4294892575 [ 184.776091] 18 80 4294892575 [ 184.776094] 8 80 4294892576 [ 184.776097] 8 80 4294892576 [ 184.776099] 8 80 4294892576 [ 184.776102] 8 80 4294892576 [ 184.776104] last result at 4294892576 [ 184.776107] last redo_fd_request at 4294892576 [ 184.776116] status=0 [ 184.776118] fdc_busy=1 [ 184.776128] do_floppy=reset_interrupt [ 184.776130] cont=f805e060 [ 184.776133] current_req= (null) [ 184.776135] command_status=-1 [ 184.776137] [ 184.776142] floppy0: floppy timeout called [ 184.776164] PM: resume of drv:floppy dev:floppy.0 complete after 3001.821 msecs [ 211.872025] ata4: lost interrupt (Status 0x50) [ 211.872045] ata4.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen [ 211.872050] ata4.00: failed command: READ VERIFY SECTOR(S) [ 211.872060] ata4.00: cmd 40/00:01:00:00:00/00:00:00:00:00/e0 tag 0 [ 211.872062] res 40/00:01:00:00:00/00:00:00:00:00/40 Emask 0x4 (timeout) [ 211.872066] ata4.00: status: { DRDY } [ 211.872096] ata4: soft resetting link [ 212.044347] ata4.00: configured for UDMA/133 [ 212.044353] ata4.00: device reported invalid CHS sector 0 [ 212.044377] sd 3:0:0:0: [sda] START_STOP FAILED [ 212.044380] ata4: EH complete [ 212.044384] sd 3:0:0:0: [sda] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE [ 212.044389] sd 3:0:0:0: [sda] Sense Key : Aborted Command [current] [descriptor] [ 212.044396] sd 3:0:0:0: [sda] Add. Sense: No additional sense information [ 212.044414] pm_op(): scsi_bus_resume_common+0x0/0x50 returns 134217730 [ 212.044424] PM: Device 3:0:0:0 failed to resume async: error 134217730 [ 212.044434] PM: resume of drv:scsi_device dev:3:0:0:0 complete after 30273.007 msecs [ 212.044443] PM: resume of drv:scsi_disk dev:3:0:0:0 complete after 27268.269 msecs [ 212.044525] PM: resume of devices complete after 30315.761 msecs [ 212.044570] STV06xx: Probing for a stv06xx device [ 212.044572] gspca: probing 046d:08f6 [ 212.044577] STV06xx: Configuring camera [ 212.044578] STV06xx: st6422 sensor detected [ 212.044581] STV06xx: Initializing camera [ 212.388153] input: STV06xx as /devices/pci0000:00/0000:00:03.0/usb2/2-2/input/input5 [ 212.388272] gspca: video0 created [ 212.388291] STV06xx: Probing for a stv06xx device [ 212.457507] PM: resume devices took 30.728 seconds [ 212.457510] ------------[ cut here ]------------ [ 212.457517] WARNING: at /build/buildd/linux-2.6.38/kernel/power/suspend_test.c:53 suspend_test_finish+0x89/0x90() [ 212.457520] Hardware name: System Product Name [ 212.457522] Component: resume devices, time: 30728 [ 212.457523] Modules linked in: binfmt_misc snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_usb_audio snd_usbmidi_lib nvidia(P) snd_emu10k1_synth snd_emux_synth snd_seq_virmidi snd_seq_midi_emul snd_emu10k1 snd_ac97_codec ac97_bus snd_pcm snd_util_mem snd_hwdep snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq ppdev snd_timer snd_seq_device gspca_stv06xx gspca_main emu10k1_gp videodev snd_page_alloc snd gameport soundcore sis_agp parport_pc asus_atk0110 lp parport usbhid hid usb_storage uas floppy sis190 sata_sis [ 212.457558] Pid: 1600, comm: pm-suspend Tainted: P 2.6.38-7-generic #39-Ubuntu [ 212.457561] Call Trace: [ 212.457567] [] ? warn_slowpath_common+0x72/0xa0 [ 212.457571] [] ? suspend_test_finish+0x89/0x90 [ 212.457574] [] ? suspend_test_finish+0x89/0x90 [ 212.457577] [] ? warn_slowpath_fmt+0x33/0x40 [ 212.457580] [] ? suspend_test_finish+0x89/0x90 [ 212.457584] [] ? suspend_devices_and_enter+0x9e/0x150 [ 212.457587] [] ? enter_state+0x101/0x120 [ 212.457590] [] ? state_store+0x86/0xc0 [ 212.457593] [] ? state_store+0x0/0xc0 [ 212.457597] [] ? kobj_attr_store+0x20/0x30 [ 212.457602] [] ? sysfs_write_file+0x9e/0x100 [ 212.457606] [] ? vfs_write+0xa2/0x170 [ 212.457609] [] ? sysfs_write_file+0x0/0x100 [ 212.457612] [] ? sys_write+0x42/0x70 [ 212.457618] [] ? syscall_call+0x7/0xb [ 212.457621] ---[ end trace 9e98a775124e74a5 ]--- [ 212.458068] PM: Finishing wakeup. [ 212.458070] Restarting tasks ... done. [ 214.640120] EXT4-fs (sda5): re-mounted. Opts: user_xattr,commit=0 [ 223.856009] eth0: no IPv6 routers present