[ 16.998994] vboxdrv: Trying to deactivate the NMI watchdog permanently... [ 16.999002] vboxdrv: Successfully done. [ 16.999004] vboxdrv: Found 1 processor cores. [ 17.002754] vboxdrv: TSC mode is 'synchronous', kernel timer mode is 'normal'. [ 17.002760] vboxdrv: Successfully loaded version 3.1.6 (interface 0x00100001). [ 18.816163] b44: eth0: Link is up at 100 Mbps, full duplex. [ 18.816168] b44: eth0: Flow control is off for TX and off for RX. [ 18.867893] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 21.928057] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 29.760011] eth0: no IPv6 routers present [ 29.928019] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 37.928020] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 45.928033] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 53.929597] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 61.928021] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 69.928023] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 77.928019] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 78.216029] end_request: I/O error, dev fd0, sector 0 [ 84.608065] end_request: I/O error, dev fd0, sector 0 [ 84.632059] end_request: I/O error, dev fd0, sector 0 [ 85.928038] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 93.928024] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 94.304028] end_request: I/O error, dev fd0, sector 0 [ 101.928019] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 109.928021] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 110.071905] usb 5-1: device descriptor read/all, error -71 [ 110.184014] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 110.327892] usb 5-1: device descriptor read/all, error -71 [ 110.440020] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 110.471886] usb 5-1: device descriptor read/8, error -71 [ 110.599880] usb 5-1: device descriptor read/8, error -71 [ 110.812015] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 118.928020] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 119.071545] usb 5-1: device descriptor read/all, error -71 [ 119.184022] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 119.327536] usb 5-1: device descriptor read/all, error -71 [ 119.440064] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 119.471527] usb 5-1: device descriptor read/8, error -71 [ 119.599503] usb 5-1: device descriptor read/8, error -71 [ 119.812029] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 127.928036] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 128.071164] usb 5-1: device descriptor read/all, error -71 [ 128.184083] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 128.327171] usb 5-1: device descriptor read/all, error -71 [ 128.440034] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 128.471167] usb 5-1: device descriptor read/8, error -71 [ 128.599129] usb 5-1: device descriptor read/8, error -71 [ 128.812034] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 136.928251] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 144.928096] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [ 3798.198682] render error detected, EIR: 0x00000010 [ 3798.198692] [drm:i915_handle_error] *ERROR* EIR stuck: 0x00000010, masking [ 3798.198712] render error detected, EIR: 0x00000010 [13366.112026] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13374.112027] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13382.112027] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13390.112221] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13398.112040] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13406.112030] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13414.112656] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13414.259496] usb 5-1: device descriptor read/all, error -71 [13414.372030] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13414.515497] usb 5-1: device descriptor read/all, error -71 [13414.628021] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13414.659475] usb 5-1: device descriptor read/8, error -71 [13414.787472] usb 5-1: device descriptor read/8, error -71 [13415.000153] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13423.112028] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13424.343045] b44: eth0: powering down PHY [13424.756290] PM: Syncing filesystems ... done. [13424.792020] PM: Preparing system for mem sleep [13424.792027] Freezing user space processes ... (elapsed 0.00 seconds) done. [13424.794360] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [13424.794542] PM: Entering mem sleep [13424.794564] Suspending console(s) (use no_console_suspend to debug) [13424.860324] sd 0:0:0:0: [sda] Synchronizing SCSI cache [13424.875991] sd 0:0:0:0: [sda] Stopping disk [13425.039680] PM: suspend of drv:sd dev:0:0:0:0 complete after 179.358 msecs [13425.040362] parport_pc 00:08: disabled [13425.040809] serial 00:07: disabled [13425.040874] b44 0000:01:09.0: PCI INT A disabled [13425.040881] ACPI handle has no context! [13425.056027] ehci_hcd 0000:01:04.2: PCI INT C disabled [13425.056035] ohci_hcd 0000:01:04.1: PCI INT B disabled [13425.056042] ohci_hcd 0000:01:04.0: PCI INT A disabled [13425.056242] Intel ICH 0000:00:1f.5: PCI INT B disabled [13425.056389] ata_piix 0000:00:1f.1: PCI INT A disabled [13425.056400] ehci_hcd 0000:00:1d.7: PCI INT D disabled [13425.056409] uhci_hcd 0000:00:1d.2: PCI INT C disabled [13425.056416] uhci_hcd 0000:00:1d.1: PCI INT B disabled [13425.056423] uhci_hcd 0000:00:1d.0: PCI INT A disabled [13425.078124] i915 0000:00:02.0: PCI INT A disabled [13425.092199] PM: suspend of devices complete after 297.367 msecs [13425.092204] PM: suspend devices took 0.300 seconds [13425.156117] PM: late suspend of devices complete after 63.907 msecs [13425.156331] ACPI: Preparing to enter system sleep state S3 [13425.157206] Disabling non-boot CPUs ... [13425.157231] Back to C! [13425.157231] CPU0: Thermal monitoring enabled (TM1) [13425.157231] ACPI: Waking up from system sleep state S3 [13425.163378] agpgart-intel 0000:00:00.0: restoring config space at offset 0x1 (was 0x900006, writing 0x20900106) [13425.163397] i915 0000:00:02.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [13425.163412] i915 0000:00:02.0: restoring config space at offset 0x5 (was 0x0, writing 0xfeb80000) [13425.163417] i915 0000:00:02.0: restoring config space at offset 0x4 (was 0x8, writing 0xe8000008) [13425.163425] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) [13425.163438] uhci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [13425.163459] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [13425.163470] uhci_hcd 0000:00:1d.1: restoring config space at offset 0xf (was 0x200, writing 0x20a) [13425.163491] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [13425.163502] uhci_hcd 0000:00:1d.2: restoring config space at offset 0xf (was 0x300, writing 0x309) [13425.163523] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [13425.163542] ehci_hcd 0000:00:1d.7: restoring config space at offset 0xf (was 0x400, writing 0x405) [13425.163561] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x4 (was 0x0, writing 0xffa80800) [13425.163570] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [13425.163588] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x0, writing 0x20000) [13425.163599] pci 0000:00:1e.0: restoring config space at offset 0x9 (was 0xfff0, writing 0x40004000) [13425.163605] pci 0000:00:1e.0: restoring config space at offset 0x8 (was 0xfff0, writing 0xfea0fe90) [13425.163611] pci 0000:00:1e.0: restoring config space at offset 0x7 (was 0x28000f0, writing 0x280d0d0) [13425.163616] pci 0000:00:1e.0: restoring config space at offset 0x6 (was 0x10100, writing 0x20010100) [13425.163627] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x80800005, writing 0x88800107) [13425.163654] pci 0000:00:1f.0: restoring config space at offset 0x1 (was 0x280000f, writing 0x280010f) [13425.163664] ata_piix 0000:00:1f.1: restoring config space at offset 0xf (was 0x100, writing 0x109) [13425.163676] ata_piix 0000:00:1f.1: restoring config space at offset 0x9 (was 0x0, writing 0xfeb7fc00) [13425.163695] pci 0000:00:1f.3: restoring config space at offset 0xf (was 0x200, writing 0x203) [13425.163708] pci 0000:00:1f.3: restoring config space at offset 0x8 (was 0x8c1, writing 0xeda1) [13425.163730] Intel ICH 0000:00:1f.5: restoring config space at offset 0xf (was 0x200, writing 0x203) [13425.163750] Intel ICH 0000:00:1f.5: restoring config space at offset 0x1 (was 0x2900007, writing 0x2900003) [13425.163767] ohci_hcd 0000:01:04.0: restoring config space at offset 0xf (was 0x2a010100, writing 0x2a01010b) [13425.163783] ohci_hcd 0000:01:04.0: restoring config space at offset 0x4 (was 0x0, writing 0xfe9cc000) [13425.163789] ohci_hcd 0000:01:04.0: restoring config space at offset 0x3 (was 0x800800, writing 0x804010) [13425.163795] ohci_hcd 0000:01:04.0: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100112) [13425.163813] ohci_hcd 0000:01:04.1: restoring config space at offset 0xf (was 0x2a010200, writing 0x2a010203) [13425.163829] ohci_hcd 0000:01:04.1: restoring config space at offset 0x4 (was 0x0, writing 0xfe9cd000) [13425.163834] ohci_hcd 0000:01:04.1: restoring config space at offset 0x3 (was 0x800, writing 0x4010) [13425.163841] ohci_hcd 0000:01:04.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100112) [13425.163858] ehci_hcd 0000:01:04.2: restoring config space at offset 0xf (was 0x22100300, writing 0x22100309) [13425.163875] ehci_hcd 0000:01:04.2: restoring config space at offset 0x4 (was 0x0, writing 0xfe9cbf00) [13425.163880] ehci_hcd 0000:01:04.2: restoring config space at offset 0x3 (was 0x4400, writing 0x4020) [13425.163886] ehci_hcd 0000:01:04.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100112) [13425.163905] pci 0000:01:05.0: restoring config space at offset 0xf (was 0x100, writing 0x103) [13425.163924] pci 0000:01:05.0: restoring config space at offset 0x5 (was 0xc0000000, writing 0xfe9e0000) [13425.163930] pci 0000:01:05.0: restoring config space at offset 0x4 (was 0xd0000000, writing 0xfe9d0000) [13425.163935] pci 0000:01:05.0: restoring config space at offset 0x3 (was 0x0, writing 0x4010) [13425.163942] pci 0000:01:05.0: restoring config space at offset 0x1 (was 0x2b00000, writing 0x2b00107) [13425.163961] pci 0000:01:06.0: restoring config space at offset 0xf (was 0x100, writing 0x109) [13425.163976] pci 0000:01:06.0: restoring config space at offset 0x5 (was 0x1, writing 0xdff9) [13425.163981] pci 0000:01:06.0: restoring config space at offset 0x4 (was 0x0, writing 0xfe9f0000) [13425.163986] pci 0000:01:06.0: restoring config space at offset 0x3 (was 0x0, writing 0x4000) [13425.163993] pci 0000:01:06.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900107) [13425.180024] b44 0000:01:09.0: restoring config space at offset 0xf (was 0x100, writing 0x103) [13425.180032] b44 0000:01:09.0: restoring config space at offset 0xc (was 0x0, writing 0xfea00000) [13425.180046] b44 0000:01:09.0: restoring config space at offset 0x3 (was 0x0, writing 0x4000) [13425.180052] b44 0000:01:09.0: restoring config space at offset 0x1 (was 0x100000, writing 0x8100106) [13425.180134] PM: early resume of devices complete after 16.784 msecs [13425.182404] i915 0000:00:02.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [13425.182411] i915 0000:00:02.0: setting latency timer to 64 [13425.280074] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [13425.280082] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [13425.280104] usb usb5: root hub lost power or was reset [13425.280138] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [13425.280146] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [13425.280165] usb usb6: root hub lost power or was reset [13425.280182] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [13425.280188] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [13425.280207] usb usb7: root hub lost power or was reset [13425.280224] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 23 (level, low) -> IRQ 23 [13425.280230] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [13425.280243] pci 0000:00:1e.0: setting latency timer to 64 [13425.280254] ata_piix 0000:00:1f.1: PCI INT A -> GSI 18 (level, low) -> IRQ 18 [13425.280259] ata_piix 0000:00:1f.1: setting latency timer to 64 [13425.280350] Intel ICH 0000:00:1f.5: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [13425.280356] Intel ICH 0000:00:1f.5: setting latency timer to 64 [13425.289865] ohci_hcd 0000:01:04.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [13425.849989] usb usb3: root hub lost power or was reset [13425.849997] PM: resume of drv:ohci_hcd dev:0000:01:04.0 complete after 560.135 msecs [13425.850096] ohci_hcd 0000:01:04.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [13426.410000] usb usb4: root hub lost power or was reset [13426.410006] PM: resume of drv:ohci_hcd dev:0000:01:04.1 complete after 559.913 msecs [13426.410224] ehci_hcd 0000:01:04.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [13426.410231] usb usb2: root hub lost power or was reset [13426.432180] b44 0000:01:09.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17 [13426.440201] ata2.00: configured for UDMA/33 [13426.455072] serial 00:07: activated [13426.456479] ata2.01: configured for UDMA/33 [13426.461279] parport_pc 00:08: activated [13426.764019] PM: resume of drv:usb dev:usb2 complete after 251.979 msecs [13426.764030] sd 0:0:0:0: [sda] Starting disk [13430.328012] ata1: link is slow to respond, please be patient (ready=0) [13431.832736] ata1.00: configured for UDMA/100 [13431.873604] PM: resume of drv:sd dev:0:0:0:0 complete after 5109.575 msecs [13432.572026] PM: resume of drv:usb dev:usb4 complete after 698.393 msecs [13432.820025] PM: resume of drv:usb dev:usb5 complete after 247.991 msecs [13435.820005] [13435.820007] floppy driver state [13435.820009] ------------------- [13435.820013] now=3283955 last interrupt=4294915867 diff=3335384 last called handler=f818b2a0 [13435.820015] timeout_message=lock fdc [13435.820017] last output bytes: [13435.820020] 4 80 4294911844 [13435.820022] 0 90 4294911844 [13435.820024] f 80 4294911844 [13435.820026] 0 90 4294911844 [13435.820028] 0 90 4294911844 [13435.820030] 8 81 4294911845 [13435.820032] f 80 4294913442 [13435.820034] 0 90 4294913442 [13435.820036] 1 90 4294913442 [13435.820038] 8 81 4294913443 [13435.820040] 4 80 4294913448 [13435.820042] 0 90 4294913448 [13435.820044] f 80 4294913448 [13435.820046] 0 90 4294913448 [13435.820048] 0 90 4294913448 [13435.820050] 8 81 4294913449 [13435.820052] f 80 4294915866 [13435.820054] 0 90 4294915866 [13435.820056] 1 90 4294915866 [13435.820058] 8 81 4294915867 [13435.820060] last result at 4294915867 [13435.820062] last redo_fd_request at 4294915872 [13435.820064] 20 1 [13435.820073] status=0 [13435.820075] fdc_busy=1 [13435.820077] do_floppy=f81865b0 [13435.820079] cont=f818d290 [13435.820081] current_req=(null) [13435.820083] command_status=-1 [13435.820084] [13435.820088] floppy0: floppy timeout called [13435.820099] PM: resume of drv:floppy dev:floppy.0 complete after 2999.596 msecs [13435.952021] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13436.111890] PM: resume of drv:usb dev:5-1 complete after 271.871 msecs [13436.368019] usb 5-2: reset low speed USB device using uhci_hcd and address 3 [13436.743860] PM: resume of drv:usb dev:5-2 complete after 631.957 msecs [13437.064014] usb 4-1: reset low speed USB device using ohci_hcd and address 2 [13437.421587] PM: resume of drv:usb dev:4-1 complete after 677.681 msecs [13437.421650] PM: resume of devices complete after 12241.482 msecs [13437.421841] PM: resume devices took 12.240 seconds [13437.421843] ------------[ cut here ]------------ [13437.421856] WARNING: at /build/buildd/linux-2.6.32/kernel/power/suspend_test.c:53 suspend_test_finish+0x89/0x90() [13437.421859] Hardware name: Dimension 2400 [13437.421862] Component: resume devices, time: 12240 [13437.421864] Modules linked in: dm_crypt isofs udf crc_itu_t binfmt_misc vboxnetadp vboxnetflt vboxdrv snd_intel8x0 snd_ac97_codec ac97_bus 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 ppdev snd dell_wmi dcdbas psmouse soundcore snd_page_alloc serio_raw shpchp parport_pc lp parport dm_raid45 xor hid_belkin usbhid hid fbcon tileblit font bitblit softcursor vga16fb vgastate i915 drm_kms_helper drm i2c_algo_bit b44 intel_agp video floppy usb_storage ssb mii output agpgart ramzswap xvmalloc lzo_decompress lzo_compress [13437.421917] Pid: 3060, comm: pm-suspend Not tainted 2.6.32-19-generic #28-Ubuntu [13437.421919] Call Trace: [13437.421928] [] warn_slowpath_common+0x72/0xa0 [13437.421933] [] ? suspend_test_finish+0x89/0x90 [13437.421937] [] ? suspend_test_finish+0x89/0x90 [13437.421941] [] warn_slowpath_fmt+0x2b/0x30 [13437.421945] [] suspend_test_finish+0x89/0x90 [13437.421949] [] suspend_devices_and_enter+0xa1/0xd0 [13437.421957] [] ? printk+0x1d/0x22 [13437.421961] [] enter_state+0xbd/0xf0 [13437.421965] [] state_store+0x75/0xc0 [13437.421969] [] ? state_store+0x0/0xc0 [13437.421975] [] kobj_attr_store+0x20/0x30 [13437.421981] [] sysfs_write_file+0x95/0x100 [13437.421987] [] vfs_write+0xa2/0x1a0 [13437.421991] [] ? sysfs_write_file+0x0/0x100 [13437.421996] [] ? do_page_fault+0x160/0x3a0 [13437.422001] [] sys_write+0x42/0x70 [13437.422006] [] syscall_call+0x7/0xb [13437.422009] ---[ end trace c3fb73b2c12de4a9 ]--- [13437.422053] PM: Finishing wakeup. [13437.422055] Restarting tasks ... done. [13438.031301] ADDRCONF(NETDEV_UP): eth0: link is not ready [13441.000171] b44: eth0: Link is up at 100 Mbps, full duplex. [13441.000177] b44: eth0: Flow control is off for TX and off for RX. [13441.000413] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [13446.112039] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13451.656021] eth0: no IPv6 routers present [13454.112060] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13462.112029] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13470.112027] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13478.112039] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13486.112065] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13494.112029] usb 5-1: reset full speed USB device using uhci_hcd and address 2 [13502.112035] usb 5-1: reset full speed USB device using uhci_hcd and address 2