[ 60.756646] vboxdrv: fAsync=0 offMin=0x1c3 offMax=0x1238 [ 60.756704] vboxdrv: TSC mode is 'synchronous', kernel timer mode is 'normal'. [ 60.756707] vboxdrv: Successfully loaded version 4.0.4 (interface 0x00160000). [ 61.008318] Bluetooth: L2CAP ver 2.15 [ 61.008321] Bluetooth: L2CAP socket layer initialized [ 61.015737] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 [ 61.015740] Bluetooth: BNEP filters: protocol multicast [ 61.022168] Bluetooth: SCO (Voice Link) ver 0.6 [ 61.022171] Bluetooth: SCO socket layer initialized [ 61.110179] Bluetooth: RFCOMM TTY layer initialized [ 61.110190] Bluetooth: RFCOMM socket layer initialized [ 61.110192] Bluetooth: RFCOMM ver 1.11 [ 62.352579] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro,commit=0 [ 62.840109] hda-intel: IRQ timing workaround is activated for card #1. Suggest a bigger bdl_pos_adj. [ 63.672692] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro,commit=0 [ 70.390029] eth1: no IPv6 routers present [ 76.294979] Bluetooth: HIDP (Human Interface Emulation) ver 1.2 [ 84.340208] CE: hpet increased min_delta_ns to 20113 nsec [ 1932.638791] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro,commit=0 [ 1934.332729] PM: Syncing filesystems ... done. [ 1934.465223] PM: Preparing system for mem sleep [ 1934.960675] Freezing user space processes ... (elapsed 0.01 seconds) done. [ 1934.980134] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [ 1935.000104] PM: Entering mem sleep [ 1935.000155] Suspending console(s) (use no_console_suspend to debug) [ 1935.001056] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 1935.001178] sd 0:0:0:0: [sda] Stopping disk [ 1935.020074] ACPI handle has no context! [ 1935.021965] ACPI handle has no context! [ 1935.021978] sdhci-pci 0000:09:01.1: PCI INT B disabled [ 1935.021993] ACPI handle has no context! [ 1935.022023] wl 0000:04:00.0: PCI INT A disabled [ 1935.022140] ehci_hcd 0000:00:1d.7: PCI INT A disabled [ 1935.022166] uhci_hcd 0000:00:1d.2: PCI INT C disabled [ 1935.022173] uhci_hcd 0000:00:1d.1: PCI INT B disabled [ 1935.022182] uhci_hcd 0000:00:1d.0: PCI INT A disabled [ 1935.023024] ehci_hcd 0000:00:1a.7: PCI INT C disabled [ 1935.023048] uhci_hcd 0000:00:1a.2: PCI INT C disabled [ 1935.023054] uhci_hcd 0000:00:1a.1: PCI INT B disabled [ 1935.040257] uhci_hcd 0000:00:1a.0: PCI INT A disabled [ 1935.130083] HDA Intel 0000:01:00.1: PCI INT B disabled [ 1935.130133] ACPI handle has no context! [ 1935.150019] PM: suspend of drv:HDA Intel dev:0000:01:00.1 complete after 127.978 msecs [ 1935.150347] HDA Intel 0000:00:1b.0: PCI INT A disabled [ 1935.170018] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 147.824 msecs [ 1935.497806] PM: suspend of drv:sd dev:0:0:0:0 complete after 496.753 msecs [ 1935.497815] PM: suspend of drv:scsi dev:target0:0:0 complete after 496.701 msecs [ 1935.497828] PM: suspend of drv:scsi dev:host0 complete after 496.587 msecs [ 1935.510016] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 487.948 msecs [ 1936.604673] [drm] Disabling audio support [ 1936.700028] PM: suspend of drv:radeon dev:0000:01:00.0 complete after 1677.985 msecs [ 1936.700047] PM: suspend of drv:pcieport dev:0000:00:01.0 complete after 1676.984 msecs [ 1936.700077] PM: suspend of drv: dev:pci0000:00 complete after 1676.981 msecs [ 1936.700086] PM: suspend of devices complete after 1699.454 msecs [ 1936.700089] PM: suspend devices took 1.700 seconds [ 1936.760245] PM: late suspend of devices complete after 60.151 msecs [ 1936.760576] ACPI: Preparing to enter system sleep state S3 [ 1936.790461] PM: Saving platform NVS memory [ 1936.791427] Disabling non-boot CPUs ... [ 1936.900029] CPU 1 is now offline [ 1936.900404] Extended CMOS year: 2000 [ 1936.900404] Back to C! [ 1936.900404] PM: Restoring platform NVS memory [ 1936.900404] Extended CMOS year: 2000 [ 1936.900404] Enabling non-boot CPUs ... [ 1936.900404] Booting Node 0 Processor 1 APIC 0x1 [ 1937.070020] Switched to NOHz mode on CPU #1 [ 1937.070454] CPU1 is up [ 1937.071117] ACPI: Waking up from system sleep state S3 [ 1937.100689] pcieport 0000:00:01.0: restoring config space at offset 0x7 (was 0x20002020, writing 0x2020) [ 1937.100693] pcieport 0000:00:01.0: restoring config space at offset 0x6 (was 0x0, writing 0x10100) [ 1937.100698] pcieport 0000:00:01.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10010) [ 1937.100702] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 1937.100750] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 1937.100792] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 1937.100814] uhci_hcd 0000:00:1a.2: restoring config space at offset 0xf (was 0x300, writing 0x30a) [ 1937.100828] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x8 (was 0x1, writing 0x1841) [ 1937.100841] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900001) [ 1937.100890] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [ 1937.100927] HDA Intel 0000:00:1b.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [ 1937.100947] HDA Intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) [ 1937.100954] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [ 1937.100988] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x40100, writing 0x4010a) [ 1937.101000] pcieport 0000:00:1c.0: restoring config space at offset 0x9 (was 0x10001, writing 0xf1f1f001) [ 1937.101005] pcieport 0000:00:1c.0: restoring config space at offset 0x8 (was 0x0, writing 0xf7f0f600) [ 1937.101010] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0x0, writing 0x3030) [ 1937.101015] pcieport 0000:00:1c.0: restoring config space at offset 0x6 (was 0x0, writing 0x30200) [ 1937.101023] pcieport 0000:00:1c.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) [ 1937.101029] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100507) [ 1937.101082] pcieport 0000:00:1c.1: restoring config space at offset 0xf (was 0x40200, writing 0x40205) [ 1937.101097] pcieport 0000:00:1c.1: restoring config space at offset 0x7 (was 0x3030, writing 0x4040) [ 1937.101106] pcieport 0000:00:1c.1: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) [ 1937.101113] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100507) [ 1937.101165] pcieport 0000:00:1c.3: restoring config space at offset 0xf (was 0x40400, writing 0x4040a) [ 1937.101177] pcieport 0000:00:1c.3: restoring config space at offset 0x9 (was 0x10001, writing 0xf5f1f401) [ 1937.101183] pcieport 0000:00:1c.3: restoring config space at offset 0x7 (was 0x20000000, writing 0x5050) [ 1937.101193] pcieport 0000:00:1c.3: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) [ 1937.101199] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100000, writing 0x100507) [ 1937.101251] pcieport 0000:00:1c.5: restoring config space at offset 0xf (was 0x40200, writing 0x40205) [ 1937.101263] pcieport 0000:00:1c.5: restoring config space at offset 0x9 (was 0x10001, writing 0xc011c001) [ 1937.101270] pcieport 0000:00:1c.5: restoring config space at offset 0x7 (was 0x20000000, writing 0x6060) [ 1937.101279] pcieport 0000:00:1c.5: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) [ 1937.101285] pcieport 0000:00:1c.5: restoring config space at offset 0x1 (was 0x100000, writing 0x100507) [ 1937.101351] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 1937.101393] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 1937.101434] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 1937.101483] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [ 1937.101511] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x40000, writing 0x400ff) [ 1937.101523] pci 0000:00:1e.0: restoring config space at offset 0x9 (was 0x10001, writing 0x1fff1) [ 1937.101528] pci 0000:00:1e.0: restoring config space at offset 0x8 (was 0x0, writing 0xfc10fc10) [ 1937.101533] pci 0000:00:1e.0: restoring config space at offset 0x7 (was 0x22800000, writing 0x228000f0) [ 1937.101545] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100107) [ 1937.101625] ahci 0000:00:1f.2: restoring config space at offset 0xf (was 0x200, writing 0x20a) [ 1937.101648] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [ 1937.101703] pci 0000:00:1f.3: restoring config space at offset 0x4 (was 0x4, writing 0xc0200004) [ 1937.101740] radeon 0000:01:00.0: restoring config space at offset 0xf (was 0x1ff, writing 0x105) [ 1937.101757] radeon 0000:01:00.0: restoring config space at offset 0x6 (was 0x0, writing 0xcfef0000) [ 1937.101762] radeon 0000:01:00.0: restoring config space at offset 0x5 (was 0x1, writing 0x2001) [ 1937.101767] radeon 0000:01:00.0: restoring config space at offset 0x4 (was 0x8, writing 0xd0000008) [ 1937.101772] radeon 0000:01:00.0: restoring config space at offset 0x3 (was 0x800000, writing 0x800010) [ 1937.101779] radeon 0000:01:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x40100507) [ 1937.101837] HDA Intel 0000:01:00.1: restoring config space at offset 0xf (was 0x2ff, writing 0x20a) [ 1937.101858] HDA Intel 0000:01:00.1: restoring config space at offset 0x4 (was 0x0, writing 0xcfeec000) [ 1937.101863] HDA Intel 0000:01:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x800010) [ 1937.101870] HDA Intel 0000:01:00.1: restoring config space at offset 0x1 (was 0x100000, writing 0x40100103) [ 1937.101938] wl 0000:04:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a) [ 1937.101978] wl 0000:04:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xf8000004) [ 1937.101987] wl 0000:04:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) [ 1937.101999] wl 0000:04:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100106) [ 1937.102151] tg3 0000:08:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) [ 1937.102163] tg3 0000:08:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100106) [ 1937.102227] firewire_ohci 0000:09:01.0: restoring config space at offset 0xf (was 0x4020100, writing 0x402010a) [ 1937.102251] firewire_ohci 0000:09:01.0: restoring config space at offset 0x4 (was 0x0, writing 0xfc100000) [ 1937.102257] firewire_ohci 0000:09:01.0: restoring config space at offset 0x3 (was 0x800000, writing 0x802010) [ 1937.102264] firewire_ohci 0000:09:01.0: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [ 1937.102287] firewire_ohci 0000:09:01.0: proprietary Ricoh MMC controller disabled (via firewire function) [ 1937.102289] firewire_ohci 0000:09:01.0: MMC cards are now supported by standard SDHCI controller [ 1937.102305] sdhci-pci 0000:09:01.1: restoring config space at offset 0xf (was 0x200, writing 0x20a) [ 1937.102329] sdhci-pci 0000:09:01.1: restoring config space at offset 0x4 (was 0x0, writing 0xfc100800) [ 1937.102335] sdhci-pci 0000:09:01.1: restoring config space at offset 0x3 (was 0x804000, writing 0x804010) [ 1937.102343] sdhci-pci 0000:09:01.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [ 1937.102370] pci 0000:09:01.2: restoring config space at offset 0xf (was 0x200, writing 0x20a) [ 1937.102394] pci 0000:09:01.2: restoring config space at offset 0x4 (was 0x0, writing 0xfc101000) [ 1937.102400] pci 0000:09:01.2: restoring config space at offset 0x3 (was 0x804000, writing 0x804010) [ 1937.102407] pci 0000:09:01.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [ 1937.102434] r852 0000:09:01.3: restoring config space at offset 0xf (was 0x200, writing 0x20a) [ 1937.102458] r852 0000:09:01.3: restoring config space at offset 0x4 (was 0x0, writing 0xfc101400) [ 1937.102464] r852 0000:09:01.3: restoring config space at offset 0x3 (was 0x804000, writing 0x804010) [ 1937.102472] r852 0000:09:01.3: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [ 1937.103172] PM: early resume of devices complete after 2.632 msecs [ 1937.103244] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 1937.103252] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [ 1937.103280] usb usb3: root hub lost power or was reset [ 1937.103297] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [ 1937.103306] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [ 1937.103340] usb usb4: root hub lost power or was reset [ 1937.103356] uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 19 (level, low) -> IRQ 19 [ 1937.103366] uhci_hcd 0000:00:1a.2: setting latency timer to 64 [ 1937.103400] usb usb5: root hub lost power or was reset [ 1937.103419] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 19 (level, low) -> IRQ 19 [ 1937.103429] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [ 1937.103489] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [ 1937.103498] HDA Intel 0000:00:1b.0: setting latency timer to 64 [ 1937.103556] HDA Intel 0000:00:1b.0: irq 46 for MSI/MSI-X [ 1937.103565] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [ 1937.103575] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [ 1937.103608] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [ 1937.103606] usb usb6: root hub lost power or was reset [ 1937.103616] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [ 1937.103622] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 1937.103628] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [ 1937.103648] usb usb7: root hub lost power or was reset [ 1937.103668] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [ 1937.103665] usb usb8: root hub lost power or was reset [ 1937.103676] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [ 1937.103683] pci 0000:00:1e.0: setting latency timer to 64 [ 1937.103709] ahci 0000:00:1f.2: setting latency timer to 64 [ 1937.103740] radeon 0000:01:00.0: setting latency timer to 64 [ 1937.103868] HDA Intel 0000:01:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 1937.103877] HDA Intel 0000:01:00.1: setting latency timer to 64 [ 1937.103962] HDA Intel 0000:01:00.1: irq 48 for MSI/MSI-X [ 1937.104024] wl 0000:04:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17 [ 1937.104036] wl 0000:04:00.0: setting latency timer to 64 [ 1937.113925] radeon 0000:01:00.0: WB enabled [ 1937.135282] sdhci-pci 0000:09:01.1: PCI INT B -> GSI 18 (level, low) -> IRQ 18 [ 1937.137075] sd 0:0:0:0: [sda] Starting disk [ 1937.145683] [drm] ring test succeeded in 1 usecs [ 1937.145701] [drm] ib test succeeded in 0 usecs [ 1937.145703] [drm] Enabling audio support [ 1937.197913] firewire_core: skipped bus generations, destroying all nodes [ 1937.260209] PM: resume of drv:usb dev:usb4 complete after 123.696 msecs [ 1937.260238] PM: resume of drv:hub dev:4-0:1.0 complete after 123.713 msecs [ 1937.260243] PM: resume of drv:usb dev:usb5 complete after 123.666 msecs [ 1937.260252] PM: resume of drv: dev:ep_00 complete after 123.716 msecs [ 1937.260265] PM: resume of drv: dev:ep_81 complete after 123.735 msecs [ 1937.260275] PM: resume of drv:usb dev:usb6 complete after 123.610 msecs [ 1937.260278] PM: resume of drv: dev:ep_00 complete after 123.633 msecs [ 1937.260298] PM: resume of drv:usb dev:usb7 complete after 123.542 msecs [ 1937.260301] PM: resume of drv: dev:ep_00 complete after 123.567 msecs [ 1937.260311] PM: resume of drv: dev:ep_00 complete after 123.487 msecs [ 1937.260319] PM: resume of drv:usb dev:usb8 complete after 123.475 msecs [ 1937.260321] PM: resume of drv:hub dev:7-0:1.0 complete after 123.539 msecs [ 1937.260326] PM: resume of drv:hub dev:5-0:1.0 complete after 123.727 msecs [ 1937.260329] PM: resume of drv: dev:ep_81 complete after 123.525 msecs [ 1937.260333] PM: resume of drv:hub dev:6-0:1.0 complete after 123.643 msecs [ 1937.260339] PM: resume of drv: dev:ep_81 complete after 123.717 msecs [ 1937.260341] PM: resume of drv:hub dev:8-0:1.0 complete after 123.472 msecs [ 1937.260349] PM: resume of drv: dev:ep_81 complete after 123.637 msecs [ 1937.260351] PM: resume of drv: dev:ep_00 complete after 123.438 msecs [ 1937.260359] PM: resume of drv: dev:ep_81 complete after 123.467 msecs [ 1937.370100] PM: resume of drv:usb dev:usb3 complete after 233.621 msecs [ 1937.370140] PM: resume of drv:hub dev:3-0:1.0 complete after 233.638 msecs [ 1937.370151] PM: resume of drv: dev:ep_00 complete after 233.643 msecs [ 1937.370164] PM: resume of drv: dev:ep_81 complete after 233.660 msecs [ 1937.410095] PM: resume of drv:usb dev:usb1 complete after 273.685 msecs [ 1937.410128] PM: resume of drv:hub dev:1-0:1.0 complete after 273.694 msecs [ 1937.410141] PM: resume of drv: dev:ep_00 complete after 273.694 msecs [ 1937.410155] PM: resume of drv: dev:ep_81 complete after 273.716 msecs [ 1937.480285] ata6: SATA link down (SStatus 0 SControl 300) [ 1937.480316] ata5: SATA link down (SStatus 0 SControl 300) [ 1937.530097] usb 3-1: reset full speed USB device using uhci_hcd and address 2 [ 1937.680103] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 1937.690076] firewire_core: rediscovered device fw0 [ 1937.712614] ata2.00: configured for UDMA/100 [ 1937.790117] usb 1-6: reset high speed USB device using ehci_hcd and address 3 [ 1937.900099] PM: resume of drv:usb dev:3-1 complete after 762.940 msecs [ 1937.900126] PM: resume of drv:hub dev:3-1:1.0 complete after 762.944 msecs [ 1937.900148] PM: resume of drv: dev:ep_00 complete after 762.925 msecs [ 1937.900152] PM: resume of drv: dev:ep_81 complete after 762.948 msecs [ 1937.902517] PM: resume of drv:usb dev:3-1.3 complete after 765.103 msecs [ 1937.902534] PM: resume of drv:btusb dev:3-1.3:1.0 complete after 765.100 msecs [ 1937.902539] PM: resume of drv:btusb dev:3-1.3:1.1 complete after 765.020 msecs [ 1937.902552] PM: resume of drv: dev:ep_81 complete after 765.098 msecs [ 1937.902555] PM: resume of drv:usb dev:3-1.3:1.2 complete after 764.978 msecs [ 1937.902559] PM: resume of drv: dev:ep_82 complete after 765.086 msecs [ 1937.902567] PM: resume of drv:usb dev:3-1.3:1.3 complete after 764.929 msecs [ 1937.902584] PM: resume of drv: dev:ep_04 complete after 764.969 msecs [ 1937.902588] PM: resume of drv: dev:ep_00 complete after 764.927 msecs [ 1937.902602] PM: resume of drv: dev:ep_84 complete after 765.006 msecs [ 1937.902606] PM: resume of drv: dev:ep_83 complete after 765.066 msecs [ 1937.902618] PM: resume of drv: dev:ep_03 complete after 765.063 msecs [ 1937.902622] PM: resume of drv: dev:ep_02 complete after 765.123 msecs [ 1937.946724] PM: resume of drv:usb dev:1-6 complete after 809.750 msecs [ 1937.946750] PM: resume of drv: dev:ep_00 complete after 809.692 msecs [ 1937.946755] PM: resume of drv:uvcvideo dev:1-6:1.0 complete after 809.756 msecs [ 1937.946768] PM: resume of drv:uvcvideo dev:1-6:1.1 complete after 809.730 msecs [ 1937.946782] PM: resume of drv: dev:ep_81 complete after 809.766 msecs [ 1938.021514] usb 3-1.1: reset full speed USB device using uhci_hcd and address 3 [ 1938.138506] PM: resume of drv:usb dev:3-1.1 complete after 1001.262 msecs [ 1938.138532] PM: resume of drv:usbhid dev:3-1.1:1.0 complete after 1001.267 msecs [ 1938.138536] PM: resume of drv: dev:ep_00 complete after 1001.226 msecs [ 1938.138552] PM: resume of drv: dev:ep_81 complete after 1001.267 msecs [ 1938.201511] usb 3-1.2: reset full speed USB device using uhci_hcd and address 4 [ 1938.320515] PM: resume of drv:usb dev:3-1.2 complete after 1183.186 msecs [ 1938.320539] PM: resume of drv: dev:ep_00 complete after 1183.146 msecs [ 1938.320544] PM: resume of drv:usb dev:3-1.2:1.0 complete after 1183.190 msecs [ 1938.320562] PM: resume of drv: dev:ep_81 complete after 1183.193 msecs [ 1940.020102] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 1940.027730] ata1.00: configured for UDMA/133 [ 1940.047418] PM: resume of drv:sd dev:0:0:0:0 complete after 2910.334 msecs [ 1940.047446] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 2910.321 msecs [ 1940.047451] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 2782.095 msecs [ 1949.080135] PM: resume of drv:radeon dev:0000:01:00.0 complete after 11976.404 msecs [ 1949.080173] PM: resume of drv:drm dev:controlD64 complete after 8998.779 msecs [ 1949.080325] PM: resume of devices complete after 11977.115 msecs [ 1949.100418] PM: resume devices took 12.000 seconds [ 1949.100420] ------------[ cut here ]------------ [ 1949.100425] WARNING: at /build/buildd/linux-2.6.38/kernel/power/suspend_test.c:53 suspend_test_finish+0x86/0x90() [ 1949.100427] Hardware name: Studio 1537 [ 1949.100429] Component: resume devices, time: 12000 [ 1949.100430] Modules linked in: michael_mic arc4 hidp binfmt_misc rfcomm sco bnep l2cap vboxnetadp vboxnetflt vboxdrv parport_pc ppdev snd_hda_codec_hdmi btusb bluetooth snd_hda_codec_idt joydev radeon snd_hda_intel uvcvideo snd_hda_codec videodev v4l2_compat_ioctl32 snd_hwdep snd_pcm ttm snd_seq_midi drm_kms_helper dell_wmi sparse_keymap snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device lib80211_crypt_tkip wl(P) r852 sm_common dell_laptop dcdbas nand nand_ids nand_ecc snd psmouse drm mtd soundcore i2c_algo_bit lib80211 serio_raw snd_page_alloc video lp parport usbhid hid ahci sdhci_pci firewire_ohci firewire_core tg3 sdhci crc_itu_t libahci [ 1949.100472] Pid: 2414, comm: pm-suspend Tainted: P 2.6.38-8-generic #42-Ubuntu [ 1949.100474] Call Trace: [ 1949.100479] [] ? warn_slowpath_common+0x7f/0xc0 [ 1949.100482] [] ? warn_slowpath_fmt+0x46/0x50 [ 1949.100484] [] ? suspend_test_finish+0x86/0x90 [ 1949.100487] [] ? suspend_devices_and_enter+0xac/0x170 [ 1949.100489] [] ? enter_state+0x120/0x150 [ 1949.100493] [] ? state_store+0xc6/0x100 [ 1949.100497] [] ? kobj_attr_store+0x17/0x20 [ 1949.100500] [] ? sysfs_write_file+0xde/0x160 [ 1949.100503] [] ? vfs_write+0xc6/0x180 [ 1949.100506] [] ? sys_write+0x51/0x90 [ 1949.100509] [] ? system_call_fastpath+0x16/0x1b [ 1949.100511] ---[ end trace 41fac857d319bb5c ]--- [ 1949.100561] PM: Finishing wakeup. [ 1949.100562] Restarting tasks ... [ 1949.109271] usb 3-1.3: USB disconnect, address 5 [ 1949.116707] done. [ 1949.116716] video LNXVIDEO:00: Restoring backlight state [ 1949.130179] [drm:drm_mode_getfb] *ERROR* invalid framebuffer id [ 1949.427982] tg3 0000:08:00.0: irq 49 for MSI/MSI-X [ 1949.530840] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 1949.711513] usb 3-1.3: new full speed USB device using uhci_hcd and address 6 [ 1950.618936] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro,commit=0 [ 1959.660116] eth1: no IPv6 routers present [ 3197.723169] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro,commit=600 [ 3233.830074] usb 2-2: new high speed USB device using ehci_hcd and address 2 [ 3234.041216] usblp0: USB Bidirectional printer dev 2 if 0 alt 0 proto 2 vid 0x132B pid 0x2033 [ 3234.041473] usbcore: registered new interface driver usblp [ 3235.453549] usb 2-2: usbfs: interface 0 claimed by usblp while 'usb' sets config #1 [ 3275.200902] usb 2-2: USB disconnect, address 2 [ 3275.201150] usblp0: removed [ 3316.184519] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro,commit=0 [ 4720.642017] warning: `VirtualBox' uses 32-bit capabilities (legacy support in use) [ 7476.357152] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro,commit=0 [ 7477.238153] PM: Syncing filesystems ... done. [ 7477.243140] PM: Preparing system for mem sleep [ 7477.930448] Freezing user space processes ... (elapsed 0.01 seconds) done. [ 7477.950162] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [ 7477.970144] PM: Entering mem sleep [ 7477.970194] Suspending console(s) (use no_console_suspend to debug) [ 7477.971111] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 7477.971222] sd 0:0:0:0: [sda] Stopping disk [ 7477.985315] ACPI handle has no context! [ 7477.985324] sdhci-pci 0000:09:01.1: PCI INT B disabled [ 7477.985331] ACPI handle has no context! [ 7477.985400] ACPI handle has no context! [ 7477.985678] wl 0000:04:00.0: PCI INT A disabled [ 7477.985741] ehci_hcd 0000:00:1d.7: PCI INT A disabled [ 7477.985766] uhci_hcd 0000:00:1d.2: PCI INT C disabled [ 7477.985773] uhci_hcd 0000:00:1d.1: PCI INT B disabled [ 7477.985782] uhci_hcd 0000:00:1d.0: PCI INT A disabled [ 7477.986626] ehci_hcd 0000:00:1a.7: PCI INT C disabled [ 7477.986650] uhci_hcd 0000:00:1a.2: PCI INT C disabled [ 7477.986657] uhci_hcd 0000:00:1a.1: PCI INT B disabled [ 7478.010059] uhci_hcd 0000:00:1a.0: PCI INT A disabled [ 7478.090046] HDA Intel 0000:01:00.1: PCI INT B disabled [ 7478.090095] ACPI handle has no context! [ 7478.110018] PM: suspend of drv:HDA Intel dev:0000:01:00.1 complete after 124.423 msecs [ 7478.110286] HDA Intel 0000:00:1b.0: PCI INT A disabled [ 7478.130017] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 144.223 msecs [ 7478.463775] PM: suspend of drv:sd dev:0:0:0:0 complete after 492.668 msecs [ 7478.463793] PM: suspend of drv:scsi dev:target0:0:0 complete after 492.630 msecs [ 7478.463802] PM: suspend of drv:scsi dev:host0 complete after 492.532 msecs [ 7478.480027] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 494.356 msecs [ 7479.311297] [drm] Disabling audio support [ 7479.410026] PM: suspend of drv:radeon dev:0000:01:00.0 complete after 1424.401 msecs [ 7479.410044] PM: suspend of drv:pcieport dev:0000:00:01.0 complete after 1423.379 msecs [ 7479.410073] PM: suspend of drv: dev:pci0000:00 complete after 1423.376 msecs [ 7479.410082] PM: suspend of devices complete after 1439.408 msecs [ 7479.410085] PM: suspend devices took 1.440 seconds [ 7479.470256] PM: late suspend of devices complete after 60.166 msecs [ 7479.470580] ACPI: Preparing to enter system sleep state S3 [ 7479.500460] PM: Saving platform NVS memory [ 7479.501427] Disabling non-boot CPUs ... [ 7479.610029] CPU 1 is now offline [ 7479.610409] Extended CMOS year: 2000 [ 7479.610409] Back to C! [ 7479.610409] PM: Restoring platform NVS memory [ 7479.610409] Extended CMOS year: 2000 [ 7479.610409] Enabling non-boot CPUs ... [ 7479.610409] Booting Node 0 Processor 1 APIC 0x1 [ 7479.780024] Switched to NOHz mode on CPU #1 [ 7479.780415] CPU1 is up [ 7479.781088] ACPI: Waking up from system sleep state S3 [ 7489.878831] pcieport 0000:00:01.0: restoring config space at offset 0x6 (was 0x0, writing 0x10100) [ 7489.878842] pcieport 0000:00:01.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10010) [ 7489.878846] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 7489.878897] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 7489.878943] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 7489.878968] uhci_hcd 0000:00:1a.2: restoring config space at offset 0xf (was 0x300, writing 0x30a) [ 7489.878982] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x8 (was 0x1, writing 0x1841) [ 7489.878996] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900001) [ 7489.879046] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [ 7489.879084] HDA Intel 0000:00:1b.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [ 7489.879110] HDA Intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) [ 7489.879118] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [ 7489.879154] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x40100, writing 0x4010a) [ 7489.879167] pcieport 0000:00:1c.0: restoring config space at offset 0x9 (was 0x10001, writing 0xf1f1f001) [ 7489.879173] pcieport 0000:00:1c.0: restoring config space at offset 0x8 (was 0x0, writing 0xf7f0f600) [ 7489.879178] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0x0, writing 0x3030) [ 7489.879184] pcieport 0000:00:1c.0: restoring config space at offset 0x6 (was 0x0, writing 0x30200) [ 7489.879192] pcieport 0000:00:1c.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) [ 7489.879198] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100507) [ 7489.879255] pcieport 0000:00:1c.1: restoring config space at offset 0xf (was 0x40200, writing 0x40205) [ 7489.879271] pcieport 0000:00:1c.1: restoring config space at offset 0x7 (was 0x3030, writing 0x4040) [ 7489.879281] pcieport 0000:00:1c.1: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) [ 7489.879288] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100507) [ 7489.879345] pcieport 0000:00:1c.3: restoring config space at offset 0xf (was 0x40400, writing 0x4040a) [ 7489.879358] pcieport 0000:00:1c.3: restoring config space at offset 0x9 (was 0x10001, writing 0xf5f1f401) [ 7489.879364] pcieport 0000:00:1c.3: restoring config space at offset 0x7 (was 0x20000000, writing 0x20005050) [ 7489.879374] pcieport 0000:00:1c.3: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) [ 7489.879380] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100000, writing 0x100507) [ 7489.879437] pcieport 0000:00:1c.5: restoring config space at offset 0xf (was 0x40200, writing 0x40205) [ 7489.879450] pcieport 0000:00:1c.5: restoring config space at offset 0x9 (was 0x10001, writing 0xc011c001) [ 7489.879456] pcieport 0000:00:1c.5: restoring config space at offset 0x7 (was 0x20000000, writing 0x20006060) [ 7489.879466] pcieport 0000:00:1c.5: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) [ 7489.879472] pcieport 0000:00:1c.5: restoring config space at offset 0x1 (was 0x100000, writing 0x100507) [ 7489.879543] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 7489.879585] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 7489.879630] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 7489.879682] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [ 7489.879713] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x40000, writing 0x400ff) [ 7489.879726] pci 0000:00:1e.0: restoring config space at offset 0x9 (was 0x10001, writing 0x1fff1) [ 7489.879731] pci 0000:00:1e.0: restoring config space at offset 0x8 (was 0x0, writing 0xfc10fc10) [ 7489.879736] pci 0000:00:1e.0: restoring config space at offset 0x7 (was 0x22800000, writing 0x228000f0) [ 7489.879749] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100107) [ 7489.879834] ahci 0000:00:1f.2: restoring config space at offset 0xf (was 0x200, writing 0x20a) [ 7489.879858] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [ 7489.879916] pci 0000:00:1f.3: restoring config space at offset 0x4 (was 0x4, writing 0xc0200004) [ 7489.879954] radeon 0000:01:00.0: restoring config space at offset 0xf (was 0x1ff, writing 0x105) [ 7489.879972] radeon 0000:01:00.0: restoring config space at offset 0x6 (was 0x0, writing 0xcfef0000) [ 7489.879977] radeon 0000:01:00.0: restoring config space at offset 0x5 (was 0x1, writing 0x2001) [ 7489.879983] radeon 0000:01:00.0: restoring config space at offset 0x4 (was 0x8, writing 0xd0000008) [ 7489.879988] radeon 0000:01:00.0: restoring config space at offset 0x3 (was 0x800000, writing 0x800010) [ 7489.879994] radeon 0000:01:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100507) [ 7489.880073] HDA Intel 0000:01:00.1: restoring config space at offset 0xf (was 0x2ff, writing 0x20a) [ 7489.880094] HDA Intel 0000:01:00.1: restoring config space at offset 0x4 (was 0x0, writing 0xcfeec000) [ 7489.880099] HDA Intel 0000:01:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x800010) [ 7489.880106] HDA Intel 0000:01:00.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100103) [ 7489.880184] wl 0000:04:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a) [ 7489.880229] wl 0000:04:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xf8000004) [ 7489.880237] wl 0000:04:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) [ 7489.880251] wl 0000:04:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100106) [ 7489.880429] tg3 0000:08:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) [ 7489.880441] tg3 0000:08:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100106) [ 7489.880515] firewire_ohci 0000:09:01.0: restoring config space at offset 0xf (was 0x4020100, writing 0x402010a) [ 7489.880546] firewire_ohci 0000:09:01.0: restoring config space at offset 0x4 (was 0x0, writing 0xfc100000) [ 7489.880551] firewire_ohci 0000:09:01.0: restoring config space at offset 0x3 (was 0x800000, writing 0x802010) [ 7489.880559] firewire_ohci 0000:09:01.0: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [ 7489.880583] firewire_ohci 0000:09:01.0: proprietary Ricoh MMC controller disabled (via firewire function) [ 7489.880584] firewire_ohci 0000:09:01.0: MMC cards are now supported by standard SDHCI controller [ 7489.880602] sdhci-pci 0000:09:01.1: restoring config space at offset 0xf (was 0x200, writing 0x20a) [ 7489.880631] sdhci-pci 0000:09:01.1: restoring config space at offset 0x4 (was 0x0, writing 0xfc100800) [ 7489.880637] sdhci-pci 0000:09:01.1: restoring config space at offset 0x3 (was 0x804000, writing 0x804010) [ 7489.880647] sdhci-pci 0000:09:01.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [ 7489.880675] pci 0000:09:01.2: restoring config space at offset 0xf (was 0x200, writing 0x20a) [ 7489.880719] pci 0000:09:01.2: restoring config space at offset 0x4 (was 0x0, writing 0xfc101000) [ 7489.880730] pci 0000:09:01.2: restoring config space at offset 0x3 (was 0x804000, writing 0x804010) [ 7489.880737] pci 0000:09:01.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [ 7489.880766] r852 0000:09:01.3: restoring config space at offset 0xf (was 0x200, writing 0x20a) [ 7489.880792] r852 0000:09:01.3: restoring config space at offset 0x4 (was 0x0, writing 0xfc101400) [ 7489.880798] r852 0000:09:01.3: restoring config space at offset 0x3 (was 0x804000, writing 0x804010) [ 7489.880807] r852 0000:09:01.3: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [ 7489.881612] PM: early resume of devices complete after 2.943 msecs [ 7489.881760] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 7489.881767] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [ 7489.881797] usb usb3: root hub lost power or was reset [ 7489.881812] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [ 7489.881818] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [ 7489.881844] usb usb4: root hub lost power or was reset [ 7489.881859] uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 19 (level, low) -> IRQ 19 [ 7489.881866] uhci_hcd 0000:00:1a.2: setting latency timer to 64 [ 7489.881892] usb usb5: root hub lost power or was reset [ 7489.881910] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 19 (level, low) -> IRQ 19 [ 7489.881918] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [ 7489.881969] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [ 7489.881979] HDA Intel 0000:00:1b.0: setting latency timer to 64 [ 7489.882034] HDA Intel 0000:00:1b.0: irq 46 for MSI/MSI-X [ 7489.882093] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [ 7489.882100] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [ 7489.882129] usb usb6: root hub lost power or was reset [ 7489.882142] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [ 7489.882150] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [ 7489.882179] usb usb7: root hub lost power or was reset [ 7489.882193] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 7489.882200] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [ 7489.882230] usb usb8: root hub lost power or was reset [ 7489.882246] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [ 7489.882252] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [ 7489.882301] pci 0000:00:1e.0: setting latency timer to 64 [ 7489.882320] ahci 0000:00:1f.2: setting latency timer to 64 [ 7489.882387] radeon 0000:01:00.0: setting latency timer to 64 [ 7489.883535] HDA Intel 0000:01:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 7489.883542] HDA Intel 0000:01:00.1: setting latency timer to 64 [ 7489.883597] HDA Intel 0000:01:00.1: irq 48 for MSI/MSI-X [ 7489.883640] wl 0000:04:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17 [ 7489.883649] wl 0000:04:00.0: setting latency timer to 64 [ 7489.891670] radeon 0000:01:00.0: WB enabled [ 7489.914508] sdhci-pci 0000:09:01.1: PCI INT B -> GSI 18 (level, low) -> IRQ 18 [ 7489.916454] sd 0:0:0:0: [sda] Starting disk [ 7489.924048] [drm] ring test succeeded in 1 usecs [ 7489.924066] [drm] ib test succeeded in 0 usecs [ 7489.924068] [drm] Enabling audio support [ 7489.977641] firewire_core: skipped bus generations, destroying all nodes [ 7490.040197] PM: resume of drv:usb dev:usb4 complete after 124.401 msecs [ 7490.040227] PM: resume of drv:hub dev:4-0:1.0 complete after 124.420 msecs [ 7490.040232] PM: resume of drv:usb dev:usb5 complete after 124.404 msecs [ 7490.040240] PM: resume of drv: dev:ep_00 complete after 124.418 msecs [ 7490.040248] PM: resume of drv: dev:ep_81 complete after 124.435 msecs [ 7490.040261] PM: resume of drv:usb dev:usb6 complete after 124.356 msecs [ 7490.040267] PM: resume of drv: dev:ep_00 complete after 124.385 msecs [ 7490.040284] PM: resume of drv: dev:ep_00 complete after 124.265 msecs [ 7490.040290] PM: resume of drv:usb dev:usb7 complete after 124.250 msecs [ 7490.040302] PM: resume of drv:hub dev:6-0:1.0 complete after 124.326 msecs [ 7490.040313] PM: resume of drv: dev:ep_81 complete after 124.315 msecs [ 7490.040321] PM: resume of drv:usb dev:usb8 complete after 124.190 msecs [ 7490.040330] PM: resume of drv: dev:ep_00 complete after 124.221 msecs [ 7490.040335] PM: resume of drv:hub dev:5-0:1.0 complete after 124.497 msecs [ 7490.040351] PM: resume of drv: dev:ep_00 complete after 124.147 msecs [ 7490.040353] PM: resume of drv:hub dev:7-0:1.0 complete after 124.290 msecs [ 7490.040361] PM: resume of drv: dev:ep_81 complete after 124.521 msecs [ 7490.040363] PM: resume of drv:hub dev:8-0:1.0 complete after 124.207 msecs [ 7490.040372] PM: resume of drv: dev:ep_81 complete after 124.287 msecs [ 7490.040374] PM: resume of drv: dev:ep_81 complete after 124.196 msecs [ 7490.150045] PM: resume of drv:usb dev:usb3 complete after 234.296 msecs [ 7490.150079] PM: resume of drv:hub dev:3-0:1.0 complete after 234.309 msecs [ 7490.150090] PM: resume of drv: dev:ep_00 complete after 234.304 msecs [ 7490.150104] PM: resume of drv: dev:ep_81 complete after 234.326 msecs [ 7490.190046] PM: resume of drv:usb dev:usb1 complete after 274.476 msecs [ 7490.190076] PM: resume of drv:hub dev:1-0:1.0 complete after 274.497 msecs [ 7490.190088] PM: resume of drv: dev:ep_00 complete after 274.368 msecs [ 7490.190102] PM: resume of drv: dev:ep_81 complete after 274.401 msecs [ 7490.240075] ata6: SATA link down (SStatus 0 SControl 300) [ 7490.240107] ata5: SATA link down (SStatus 0 SControl 300) [ 7490.310043] usb 3-1: reset full speed USB device using uhci_hcd and address 2 [ 7490.440049] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 7490.440075] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 7490.442417] ata1.00: configured for UDMA/133 [ 7490.468250] ata2.00: configured for UDMA/100 [ 7490.470067] firewire_core: rediscovered device fw0 [ 7490.570051] usb 1-6: reset high speed USB device using ehci_hcd and address 3 [ 7490.680045] PM: resume of drv:usb dev:3-1 complete after 763.507 msecs [ 7490.680083] PM: resume of drv:hub dev:3-1:1.0 complete after 763.525 msecs [ 7490.680103] PM: resume of drv: dev:ep_81 complete after 763.524 msecs [ 7490.680107] PM: resume of drv: dev:ep_00 complete after 763.506 msecs [ 7490.682788] PM: resume of drv:usb dev:3-1.3 complete after 765.999 msecs [ 7490.682808] PM: resume of drv:btusb dev:3-1.3:1.0 complete after 765.998 msecs [ 7490.682813] PM: resume of drv:btusb dev:3-1.3:1.1 complete after 765.913 msecs [ 7490.682826] PM: resume of drv: dev:ep_81 complete after 765.995 msecs [ 7490.682829] PM: resume of drv:usb dev:3-1.3:1.2 complete after 765.865 msecs [ 7490.682833] PM: resume of drv: dev:ep_82 complete after 765.977 msecs [ 7490.682841] PM: resume of drv: dev:ep_02 complete after 765.965 msecs [ 7490.682845] PM: resume of drv:usb dev:3-1.3:1.3 complete after 765.819 msecs [ 7490.682861] PM: resume of drv: dev:ep_04 complete after 765.858 msecs [ 7490.682865] PM: resume of drv: dev:ep_00 complete after 765.818 msecs [ 7490.682877] PM: resume of drv: dev:ep_84 complete after 765.895 msecs [ 7490.682881] PM: resume of drv: dev:ep_83 complete after 765.960 msecs [ 7490.682894] PM: resume of drv: dev:ep_03 complete after 765.955 msecs [ 7490.726759] PM: resume of drv:usb dev:1-6 complete after 810.498 msecs [ 7490.726787] PM: resume of drv: dev:ep_00 complete after 810.351 msecs [ 7490.726792] PM: resume of drv:uvcvideo dev:1-6:1.0 complete after 810.506 msecs [ 7490.726805] PM: resume of drv:uvcvideo dev:1-6:1.1 complete after 810.391 msecs [ 7490.726820] PM: resume of drv: dev:ep_81 complete after 810.428 msecs [ 7490.801784] usb 3-1.2: reset full speed USB device using uhci_hcd and address 4 [ 7490.804063] PM: resume of drv:sd dev:0:0:0:0 complete after 887.602 msecs [ 7490.804091] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 887.589 msecs [ 7490.804096] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 758.573 msecs [ 7490.920794] PM: resume of drv:usb dev:3-1.2 complete after 1004.091 msecs [ 7490.920818] PM: resume of drv: dev:ep_00 complete after 1004.052 msecs [ 7490.920823] PM: resume of drv:usb dev:3-1.2:1.0 complete after 1004.096 msecs [ 7490.920841] PM: resume of drv: dev:ep_81 complete after 1004.096 msecs [ 7490.981783] usb 3-1.1: reset full speed USB device using uhci_hcd and address 3 [ 7491.098789] PM: resume of drv:usb dev:3-1.1 complete after 1182.170 msecs [ 7491.098812] PM: resume of drv: dev:ep_00 complete after 1182.131 msecs [ 7491.098817] PM: resume of drv:usbhid dev:3-1.1:1.0 complete after 1182.176 msecs [ 7491.098841] PM: resume of drv: dev:ep_81 complete after 1182.180 msecs [ 7491.098845] PM: resume of drv:generic-usb dev:0003:413C:8157.0001 complete after 294.697 msecs [ 7501.860134] PM: resume of drv:radeon dev:0000:01:00.0 complete after 11977.748 msecs [ 7501.860171] PM: resume of drv:drm dev:controlD64 complete after 10728.286 msecs [ 7501.860324] PM: resume of devices complete after 11978.673 msecs [ 7501.880418] PM: resume devices took 12.000 seconds [ 7501.880420] ------------[ cut here ]------------ [ 7501.880425] WARNING: at /build/buildd/linux-2.6.38/kernel/power/suspend_test.c:53 suspend_test_finish+0x86/0x90() [ 7501.880427] Hardware name: Studio 1537 [ 7501.880428] Component: resume devices, time: 12000 [ 7501.880430] Modules linked in: usblp michael_mic arc4 hidp binfmt_misc rfcomm sco bnep l2cap vboxnetadp vboxnetflt vboxdrv parport_pc ppdev snd_hda_codec_hdmi btusb bluetooth snd_hda_codec_idt joydev radeon snd_hda_intel uvcvideo snd_hda_codec videodev v4l2_compat_ioctl32 snd_hwdep snd_pcm ttm snd_seq_midi drm_kms_helper dell_wmi sparse_keymap snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device lib80211_crypt_tkip wl(P) r852 sm_common dell_laptop dcdbas nand nand_ids nand_ecc snd psmouse drm mtd soundcore i2c_algo_bit lib80211 serio_raw snd_page_alloc video lp parport usbhid hid ahci sdhci_pci firewire_ohci firewire_core tg3 sdhci crc_itu_t libahci [ 7501.880471] Pid: 5398, comm: pm-suspend Tainted: P W 2.6.38-8-generic #42-Ubuntu [ 7501.880473] Call Trace: [ 7501.880478] [] ? warn_slowpath_common+0x7f/0xc0 [ 7501.880481] [] ? warn_slowpath_fmt+0x46/0x50 [ 7501.880484] [] ? suspend_test_finish+0x86/0x90 [ 7501.880486] [] ? suspend_devices_and_enter+0xac/0x170 [ 7501.880489] [] ? enter_state+0x120/0x150 [ 7501.880492] [] ? state_store+0xc6/0x100 [ 7501.880496] [] ? kobj_attr_store+0x17/0x20 [ 7501.880499] [] ? sysfs_write_file+0xde/0x160 [ 7501.880503] [] ? vfs_write+0xc6/0x180 [ 7501.880505] [] ? sys_write+0x51/0x90 [ 7501.880508] [] ? system_call_fastpath+0x16/0x1b [ 7501.880510] ---[ end trace 41fac857d319bb5d ]--- [ 7501.880561] PM: Finishing wakeup. [ 7501.880563] Restarting tasks ... [ 7501.887724] usb 3-1.3: USB disconnect, address 6 [ 7501.915872] done. [ 7501.915888] video LNXVIDEO:00: Restoring backlight state [ 7501.916150] [drm:drm_mode_getfb] *ERROR* invalid framebuffer id [ 7502.221929] tg3 0000:08:00.0: irq 49 for MSI/MSI-X [ 7502.341146] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 7502.512777] usb 3-1.3: new full speed USB device using uhci_hcd and address 7 [ 7503.804616] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro,commit=0 [ 7512.790040] eth1: no IPv6 routers present [16196.470607] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro,commit=600 [21151.703499] oom_kill_process: 6 callbacks suppressed [21151.703503] firefox-bin invoked oom-killer: gfp_mask=0x201da, order=0, oom_adj=0, oom_score_adj=0 [21151.703506] firefox-bin cpuset=/ mems_allowed=0 [21151.703510] Pid: 1842, comm: firefox-bin Tainted: P W 2.6.38-8-generic #42-Ubuntu [21151.703512] Call Trace: [21151.703519] [] ? cpuset_print_task_mems_allowed+0x9d/0xb0 [21151.703524] [] ? dump_header+0x91/0x1e0 [21151.703527] [] ? oom_kill_process+0x8d/0x190 [21151.703530] [] ? out_of_memory+0x102/0x240 [21151.703533] [] ? __alloc_pages_nodemask+0x7c9/0x840 [21151.703538] [] ? alloc_pages_current+0xa5/0x110 [21151.703542] [] ? __page_cache_alloc+0x8f/0xa0 [21151.703545] [] ? __do_page_cache_readahead+0xd5/0x220 [21151.703548] [] ? ra_submit+0x21/0x30 [21151.703551] [] ? filemap_fault+0x485/0x4a0 [21151.703555] [] ? __do_fault+0x54/0x520 [21151.703561] [] ? handle_pte_fault+0xfa/0x210 [21151.703564] [] ? __free_pages+0x2d/0x40 [21151.703566] [] ? __pte_alloc+0xdf/0x100 [21151.703569] [] ? handle_mm_fault+0x16d/0x250 [21151.703573] [] ? do_page_fault+0x1a7/0x540 [21151.703577] [] ? do_futex+0x181/0x210 [21151.703580] [] ? sys_futex+0x7b/0x180 [21151.703583] [] ? vfs_read+0x120/0x180 [21151.703586] [] ? page_fault+0x25/0x30 [21151.703588] Mem-Info: [21151.703590] Node 0 DMA per-cpu: [21151.703592] CPU 0: hi: 0, btch: 1 usd: 0 [21151.703594] CPU 1: hi: 0, btch: 1 usd: 0 [21151.703596] Node 0 DMA32 per-cpu: [21151.703598] CPU 0: hi: 186, btch: 31 usd: 0 [21151.703600] CPU 1: hi: 186, btch: 31 usd: 0 [21151.703601] Node 0 Normal per-cpu: [21151.703604] CPU 0: hi: 186, btch: 31 usd: 0 [21151.703606] CPU 1: hi: 186, btch: 31 usd: 0 [21151.703610] active_anon:453618 inactive_anon:180680 isolated_anon:192 [21151.703611] active_file:556 inactive_file:971 isolated_file:11 [21151.703612] unevictable:9 dirty:4 writeback:410 unstable:0 [21151.703613] free:6985 slab_reclaimable:4837 slab_unreclaimable:7107 [21151.703614] mapped:301751 shmem:2859 pagetables:11101 bounce:0 [21151.703616] Node 0 DMA free:15856kB min:28kB low:32kB high:40kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15672kB mlocked:0kB dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:0kB slab_unreclaimable:8kB kernel_stack:0kB pagetables:0kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? yes [21151.703625] lowmem_reserve[]: 0 2995 4005 4005 [21151.703629] Node 0 DMA32 free:10040kB min:6044kB low:7552kB high:9064kB active_anon:1455944kB inactive_anon:364008kB active_file:568kB inactive_file:1696kB unevictable:20kB isolated(anon):128kB isolated(file):44kB present:3067364kB mlocked:20kB dirty:0kB writeback:508kB mapped:1121324kB shmem:10084kB slab_reclaimable:5952kB slab_unreclaimable:9200kB kernel_stack:1032kB pagetables:21704kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:11836 all_unreclaimable? yes [21151.703638] lowmem_reserve[]: 0 0 1010 1010 [21151.703642] Node 0 Normal free:2044kB min:2036kB low:2544kB high:3052kB active_anon:358528kB inactive_anon:358712kB active_file:1656kB inactive_file:2188kB unevictable:16kB isolated(anon):640kB isolated(file):0kB present:1034240kB mlocked:16kB dirty:16kB writeback:1132kB mapped:85680kB shmem:1352kB slab_reclaimable:13396kB slab_unreclaimable:19220kB kernel_stack:2432kB pagetables:22700kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:5966 all_unreclaimable? yes [21151.703651] lowmem_reserve[]: 0 0 0 0 [21151.703654] Node 0 DMA: 0*4kB 0*8kB 1*16kB 1*32kB 1*64kB 1*128kB 1*256kB 0*512kB 1*1024kB 1*2048kB 3*4096kB = 15856kB [21151.703664] Node 0 DMA32: 972*4kB 5*8kB 2*16kB 0*32kB 1*64kB 1*128kB 1*256kB 1*512kB 1*1024kB 0*2048kB 1*4096kB = 10040kB [21151.703672] Node 0 Normal: 183*4kB 32*8kB 20*16kB 5*32kB 3*64kB 1*128kB 1*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 2044kB [21151.703681] 85743 total pagecache pages [21151.703683] 81301 pages in swap cache [21151.703685] Swap cache stats: add 583386, delete 502085, find 23063/30456 [21151.703687] Free swap = 0kB [21151.703688] Total swap = 2048220kB [21151.716901] 1048560 pages RAM [21151.716903] 335074 pages reserved [21151.716905] 14326 pages shared [21151.716906] 703413 pages non-shared [21151.716908] [ pid ] uid tgid total_vm rss cpu oom_adj oom_score_adj name [21151.716919] [ 302] 0 302 4263 34 1 0 0 upstart-udev-br [21151.716924] [ 313] 0 313 5435 86 1 -17 -1000 udevd [21151.716928] [ 618] 0 618 3751 4 1 0 0 upstart-socket- [21151.716932] [ 783] 0 783 4569 18 0 0 0 mount.ntfs [21151.716937] [ 883] 102 883 6337 321 0 0 0 dbus-daemon [21151.716941] [ 894] 101 894 30030 0 0 0 0 rsyslogd [21151.716944] [ 915] 0 915 19867 118 1 0 0 gdm-binary [21151.716948] [ 918] 0 918 39616 219 0 0 0 NetworkManager [21151.716951] [ 920] 104 920 8033 57 0 0 0 avahi-daemon [21151.716955] [ 922] 104 922 8002 1 1 0 0 avahi-daemon [21151.716959] [ 925] 0 925 31390 69 0 0 0 console-kit-dae [21151.716962] [ 926] 0 926 16164 83 0 0 0 modem-manager [21151.716966] [ 928] 0 928 32441 406 1 0 0 polkitd [21151.716969] [ 1035] 0 1035 19007 47 1 0 0 cupsd [21151.716973] [ 1055] 0 1055 23494 74 1 0 0 gdm-simple-slav [21151.716977] [ 1070] 0 1070 1549 14 0 0 0 getty [21151.716980] [ 1074] 0 1074 7235 23 0 0 0 wpa_supplicant [21151.716984] [ 1078] 0 1078 1549 14 1 0 0 getty [21151.716988] [ 1095] 0 1095 1549 14 0 0 0 getty [21151.716991] [ 1103] 0 1103 1549 14 1 0 0 getty [21151.716995] [ 1106] 0 1106 1549 14 0 0 0 getty [21151.716998] [ 1107] 0 1107 43570 4331 1 0 0 Xorg [21151.717002] [ 1124] 0 1124 1104 80 0 0 0 acpid [21151.717005] [ 1127] 0 1127 4732 30 0 0 0 cron [21151.717009] [ 1128] 0 1128 4182 0 0 0 0 atd [21151.717012] [ 1154] 0 1154 1067 6 1 0 0 hddtemp [21151.717016] [ 1164] 109 1164 5662 61 0 0 0 kerneloops [21151.717020] [ 1216] 0 1216 5801 42 0 -17 -1000 bluetoothd [21151.717023] [ 1233] 0 1233 324635 515 0 0 0 java [21151.717027] [ 1254] 65534 1254 4812 54 1 0 0 LCDd [21151.717031] [ 1384] 0 1384 41982 66 1 0 0 gdm-session-wor [21151.717034] [ 1389] 0 1389 19505 312 0 0 0 upowerd [21151.717038] [ 1407] 111 1407 9407 20 0 0 0 rtkit-daemon [21151.717041] [ 1463] 0 1463 1549 14 0 0 0 getty [21151.717045] [ 1549] 1000 1549 38631 51 0 0 0 gnome-keyring-d [21151.717049] [ 1568] 1000 1568 60007 197 0 0 0 gnome-session [21151.717052] [ 1606] 1000 1606 3023 7 0 0 0 ssh-agent [21151.717056] [ 1609] 1000 1609 6600 0 1 0 0 dbus-launch [21151.717059] [ 1610] 1000 1610 6936 566 0 0 0 dbus-daemon [21151.717063] [ 1615] 1000 1615 14503 349 1 0 0 gconfd-2 [21151.717067] [ 1625] 1000 1625 97951 419 0 0 0 gnome-settings- [21151.717070] [ 1629] 1000 1629 13130 85 1 0 0 gvfsd [21151.717074] [ 1634] 1000 1634 20253 0 0 0 0 gvfs-fuse-daemo [21151.717126] [ 1637] 1000 1637 155331 4610 1 0 0 compiz [21151.717130] [ 1641] 1000 1641 123727 1092 1 0 0 pulseaudio [21151.717134] [ 1646] 1000 1646 45344 14 0 0 0 gconf-helper [21151.717137] [ 1649] 1000 1649 6170 23 1 0 0 syndaemon [21151.717141] [ 1651] 1000 1651 17117 75 0 0 0 gvfs-gdu-volume [21151.717145] [ 1653] 0 1653 31918 151 0 0 0 udisks-daemon [21151.717148] [ 1654] 0 1654 11292 19 0 0 0 udisks-daemon [21151.717152] [ 1657] 1000 1657 14899 7 1 0 0 gvfs-gphoto2-vo [21151.717156] [ 1659] 1000 1659 18348 36 0 0 0 gvfs-afc-volume [21151.717160] [ 1661] 1000 1661 152776 515 1 0 0 nautilus [21151.717163] [ 1662] 1000 1662 72762 181 0 0 0 vino-server [21151.717167] [ 1664] 1000 1664 39709 81 0 0 0 zeitgeist-datah [21151.717171] [ 1665] 1000 1665 79276 206 1 0 0 bluetooth-apple [21151.717174] [ 1669] 1000 1669 57184 91 0 0 0 polkit-gnome-au [21151.717178] [ 1670] 1000 1670 98992 1021 0 0 0 nm-applet [21151.717181] [ 1673] 1000 1673 45961 307 0 0 0 zeitgeist-daemo [21151.717185] [ 1674] 1000 1674 85984 117 0 0 0 evolution-alarm [21151.717189] [ 1678] 1000 1678 67058 570 1 0 0 gnome-power-man [21151.717192] [ 1688] 1000 1688 1721 9 0 0 0 cat [21151.717196] [ 1694] 1000 1694 62287 992 1 0 0 notify-osd [21151.717199] [ 1697] 1000 1697 81961 299 0 0 0 e-calendar-fact [21151.717204] [ 1714] 1000 1714 84274 21 0 0 0 e-addressbook-f [21151.717207] [ 1717] 1000 1717 14695 197 0 0 0 mission-control [21151.717211] [ 1724] 1000 1724 14265 72 0 0 0 gvfsd-trash [21151.717214] [ 1730] 1000 1730 30617 55 1 0 0 dconf-service [21151.717218] [ 1739] 1000 1739 1055 13 1 0 0 sh [21151.717222] [ 1740] 1000 1740 62903 1297 1 0 0 unity-window-de [21151.717225] [ 1743] 1000 1743 113167 4301 0 0 0 unity-panel-ser [21151.717229] [ 1745] 1000 1745 44286 184 1 0 0 unity-files-dae [21151.717232] [ 1747] 1000 1747 50899 265 0 0 0 unity-applicati [21151.717236] [ 1781] 1000 1781 13133 51 0 0 0 gvfsd-burn [21151.717240] [ 1785] 1000 1785 11702 280 0 0 0 gvfsd-metadata [21151.717243] [ 1787] 1000 1787 84058 192 0 0 0 indicator-sound [21151.717247] [ 1793] 1000 1793 77943 464 0 0 0 indicator-datet [21151.717251] [ 1794] 1000 1794 53919 190 1 0 0 indicator-appli [21151.717254] [ 1795] 1000 1795 60498 121 1 0 0 indicator-me-se [21151.717258] [ 1796] 1000 1796 59145 190 0 0 0 indicator-sessi [21151.717262] [ 1797] 1000 1797 60844 412 0 0 0 indicator-messa [21151.717265] [ 1826] 1000 1826 23446 153 0 0 0 geoclue-master [21151.717269] [ 1840] 1000 1840 44178 181 0 0 0 gnome-screensav [21151.717273] [ 1842] 1000 1842 281513 29845 0 0 0 firefox-bin [21151.717276] [ 1857] 1000 1857 44261 158 0 0 0 gdu-notificatio [21151.717280] [ 1965] 1000 1965 149626 442 0 0 0 plugin-containe [21151.717283] [ 1974] 1000 1974 104494 1555 0 0 0 gwibber-service [21151.717287] [ 1978] 1000 1978 58517 393 0 0 0 applet.py [21151.717291] [ 1998] 1000 1998 97725 1672 0 0 0 gwibber-service [21151.717294] [ 1999] 1000 1999 97725 1536 0 0 0 gwibber-service [21151.717298] [ 2028] 1000 2028 78133 1758 1 0 0 ubuntuone-syncd [21151.717301] [ 2083] 1000 2083 63772 365 0 0 0 update-notifier [21151.717305] [ 2262] 1000 2262 60617 62 1 0 0 gvfsd-http [21151.717308] [ 2341] 1000 2341 203090 422 1 0 0 emesene [21151.717312] [ 3675] 1000 3675 55599 374 1 0 0 bamfdaemon [21151.717315] [ 3709] 1000 3709 89452 248 0 0 0 scp-dbus-servic [21151.717319] [ 5311] 1000 5311 69171 133 1 0 0 plugin-containe [21151.717324] [ 6290] 0 6290 1770 17 0 0 0 dhclient [21151.717327] [ 7518] 1000 7518 144769 209 0 0 0 emesene [21151.717331] [ 7808] 1000 7808 1055 2 1 0 0 thunderbird [21151.717335] [ 7815] 1000 7815 1055 1 1 0 0 run-mozilla.sh [21151.717338] [ 7819] 1000 7819 156100 3419 0 0 0 thunderbird-bin [21151.717342] [ 7860] 1000 7860 40757 31 0 0 0 telepathy-logge [21151.717345] [ 7988] 1000 7988 150192 2537 1 0 0 pidgin [21151.717349] [ 8161] 1000 8161 937512 478321 1 0 0 vlc [21151.717353] [ 8198] 1000 8198 1055 19 0 0 0 xdg-screensaver [21151.717356] [ 8206] 1000 8206 1055 6 0 0 0 xdg-screensaver [21151.717360] [ 8210] 1000 8210 4880 0 0 0 0 xprop [21151.717364] [ 8876] 1000 8876 497605 302637 0 0 0 VirtualBox [21151.717368] [ 8895] 1000 8895 21632 32 1 0 0 VBoxXPCOMIPCD [21151.717371] [ 8901] 1000 8901 54737 98 1 0 0 VBoxSVC [21151.717375] [ 8980] 1000 8980 144685 190 1 0 0 emesene [21151.717379] [ 9107] 1000 9107 69456 491 0 0 0 plugin-containe [21151.717382] [ 9110] 1000 9110 176118 1587 0 0 0 totem-plugin-vi [21151.717387] [ 9220] 1000 9220 1716 35 1 0 0 sleep [21151.717390] [ 9251] 0 9251 1055 57 0 0 0 pm-powersave [21151.717394] [ 9293] 1000 9293 35581 277 0 0 0 pkexec [21151.717397] [ 9298] 0 9298 5434 77 0 -17 -1000 udevd [21151.717400] [ 9302] 0 9302 5434 77 1 -17 -1000 udevd [21151.717404] [ 9309] 0 9309 1034 43 0 0 0 anacron [21151.717407] [ 9310] 0 9310 1055 43 1 0 0 pm-powersave [21151.717410] Out of memory: Kill process 8161 (vlc) score 502 or sacrifice child [21151.717415] Killed process 8161 (vlc) total-vm:3750048kB, anon-rss:1912792kB, file-rss:492kB [21152.901070] ubuntuone-syncd invoked oom-killer: gfp_mask=0x84d0, order=0, oom_adj=0, oom_score_adj=0 [21152.901075] ubuntuone-syncd cpuset=/ mems_allowed=0 [21152.901078] Pid: 2028, comm: ubuntuone-syncd Tainted: P W 2.6.38-8-generic #42-Ubuntu [21152.901081] Call Trace: [21152.901088] [] ? cpuset_print_task_mems_allowed+0x9d/0xb0 [21152.901093] [] ? dump_header+0x91/0x1e0 [21152.901097] [] ? security_real_capable_noaudit+0x3c/0x70 [21152.901101] [] ? oom_kill_process+0x8d/0x190 [21152.901104] [] ? out_of_memory+0x102/0x240 [21152.901107] [] ? __alloc_pages_nodemask+0x7c9/0x840 [21152.901112] [] ? alloc_pages_current+0xa5/0x110 [21152.901115] [] ? pte_alloc_one+0x1b/0x50 [21152.901118] [] ? __pte_alloc+0x35/0x100 [21152.901122] [] ? pollwake+0x0/0x60 [21152.901125] [] ? handle_mm_fault+0x129/0x250 [21152.901128] [] ? pollwake+0x0/0x60 [21152.901132] [] ? do_page_fault+0x1a7/0x540 [21152.901135] [] ? security_file_permission+0x93/0xb0 [21152.901139] [] ? ktime_get_ts+0xb1/0xf0 [21152.901142] [] ? poll_select_set_timeout+0x82/0x90 [21152.901145] [] ? page_fault+0x25/0x30 [21152.901147] Mem-Info: [21152.901149] Node 0 DMA per-cpu: [21152.901152] CPU 0: hi: 0, btch: 1 usd: 0 [21152.901154] CPU 1: hi: 0, btch: 1 usd: 0 [21152.901155] Node 0 DMA32 per-cpu: [21152.901158] CPU 0: hi: 186, btch: 31 usd: 0 [21152.901160] CPU 1: hi: 186, btch: 31 usd: 90 [21152.901161] Node 0 Normal per-cpu: [21152.901163] CPU 0: hi: 186, btch: 31 usd: 0 [21152.901165] CPU 1: hi: 186, btch: 31 usd: 33 [21152.901170] active_anon:453576 inactive_anon:180634 isolated_anon:0 [21152.901171] active_file:388 inactive_file:1607 isolated_file:9 [21152.901172] unevictable:9 dirty:1 writeback:467 unstable:0 [21152.901173] free:7007 slab_reclaimable:4802 slab_unreclaimable:7111 [21152.901174] mapped:301662 shmem:2859 pagetables:11123 bounce:0 [21152.901176] Node 0 DMA free:15856kB min:28kB low:32kB high:40kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15672kB mlocked:0kB dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:0kB slab_unreclaimable:8kB kernel_stack:0kB pagetables:0kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? yes [21152.901185] lowmem_reserve[]: 0 2995 4005 4005 [21152.901188] Node 0 DMA32 free:10092kB min:6044kB low:7552kB high:9064kB active_anon:1455648kB inactive_anon:363768kB active_file:408kB inactive_file:2536kB unevictable:20kB isolated(anon):0kB isolated(file):36kB present:3067364kB mlocked:20kB dirty:0kB writeback:508kB mapped:1121404kB shmem:10084kB slab_reclaimable:5860kB slab_unreclaimable:9200kB kernel_stack:1016kB pagetables:21740kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no [21152.901198] lowmem_reserve[]: 0 0 1010 1010 [21152.901201] Node 0 Normal free:2080kB min:2036kB low:2544kB high:3052kB active_anon:358656kB inactive_anon:358768kB active_file:1144kB inactive_file:3892kB unevictable:16kB isolated(anon):0kB isolated(file):0kB present:1034240kB mlocked:16kB dirty:4kB writeback:1360kB mapped:85244kB shmem:1352kB slab_reclaimable:13348kB slab_unreclaimable:19236kB kernel_stack:2432kB pagetables:22752kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no [21152.901211] lowmem_reserve[]: 0 0 0 0 [21152.901214] Node 0 DMA: 0*4kB 0*8kB 1*16kB 1*32kB 1*64kB 1*128kB 1*256kB 0*512kB 1*1024kB 1*2048kB 3*4096kB = 15856kB [21152.901223] Node 0 DMA32: 987*4kB 13*8kB 4*16kB 0*32kB 1*64kB 1*128kB 1*256kB 1*512kB 1*1024kB 0*2048kB 1*4096kB = 10196kB [21152.901232] Node 0 Normal: 201*4kB 32*8kB 18*16kB 5*32kB 3*64kB 1*128kB 1*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 2084kB [21152.901241] 85937 total pagecache pages [21152.901243] 81017 pages in swap cache [21152.901245] Swap cache stats: add 583447, delete 502432, find 23074/30474 [21152.901247] Free swap = 4784kB [21152.901248] Total swap = 2048220kB [21152.918308] 1048560 pages RAM [21152.918311] 335074 pages reserved [21152.918312] 10771 pages shared [21152.918314] 689753 pages non-shared [21152.918315] [ pid ] uid tgid total_vm rss cpu oom_adj oom_score_adj name [21152.918327] [ 302] 0 302 4263 34 1 0 0 upstart-udev-br [21152.918331] [ 313] 0 313 5435 79 1 -17 -1000 udevd [21152.918336] [ 618] 0 618 3751 3 1 0 0 upstart-socket- [21152.918340] [ 783] 0 783 4569 14 0 0 0 mount.ntfs [21152.918345] [ 883] 102 883 6337 317 0 0 0 dbus-daemon [21152.918349] [ 894] 101 894 30030 36 0 0 0 rsyslogd [21152.918352] [ 915] 0 915 19867 90 1 0 0 gdm-binary [21152.918356] [ 918] 0 918 39616 187 0 0 0 NetworkManager [21152.918359] [ 920] 104 920 8033 51 0 0 0 avahi-daemon [21152.918363] [ 922] 104 922 8002 1 1 0 0 avahi-daemon [21152.918367] [ 925] 0 925 31390 49 0 0 0 console-kit-dae [21152.918370] [ 926] 0 926 16164 69 0 0 0 modem-manager [21152.918374] [ 928] 0 928 32441 353 1 0 0 polkitd [21152.918377] [ 1035] 0 1035 19007 41 1 0 0 cupsd [21152.918381] [ 1055] 0 1055 23494 55 1 0 0 gdm-simple-slav [21152.918385] [ 1070] 0 1070 1549 12 0 0 0 getty [21152.918389] [ 1074] 0 1074 7235 23 0 0 0 wpa_supplicant [21152.918393] [ 1078] 0 1078 1549 12 1 0 0 getty [21152.918396] [ 1095] 0 1095 1549 12 0 0 0 getty [21152.918400] [ 1103] 0 1103 1549 12 1 0 0 getty [21152.918404] [ 1106] 0 1106 1549 12 0 0 0 getty [21152.918408] [ 1107] 0 1107 43570 4318 1 0 0 Xorg [21152.918412] [ 1124] 0 1124 1104 76 0 0 0 acpid [21152.918415] [ 1127] 0 1127 4732 25 0 0 0 cron [21152.918418] [ 1128] 0 1128 4182 0 0 0 0 atd [21152.918422] [ 1154] 0 1154 1067 2 1 0 0 hddtemp [21152.918426] [ 1164] 109 1164 5662 55 0 0 0 kerneloops [21152.918430] [ 1216] 0 1216 5801 34 0 -17 -1000 bluetoothd [21152.918433] [ 1233] 0 1233 324635 515 0 0 0 java [21152.918437] [ 1254] 65534 1254 4812 46 0 0 0 LCDd [21152.918441] [ 1384] 0 1384 41982 49 1 0 0 gdm-session-wor [21152.918445] [ 1389] 0 1389 19505 263 0 0 0 upowerd [21152.918449] [ 1407] 111 1407 9407 14 0 0 0 rtkit-daemon [21152.918452] [ 1463] 0 1463 1549 12 0 0 0 getty [21152.918456] [ 1549] 1000 1549 38631 51 0 0 0 gnome-keyring-d [21152.918460] [ 1568] 1000 1568 60007 174 0 0 0 gnome-session [21152.918463] [ 1606] 1000 1606 3023 7 0 0 0 ssh-agent [21152.918467] [ 1609] 1000 1609 6600 0 1 0 0 dbus-launch [21152.918470] [ 1610] 1000 1610 6936 561 0 0 0 dbus-daemon [21152.918474] [ 1615] 1000 1615 14503 325 1 0 0 gconfd-2 [21152.918478] [ 1625] 1000 1625 97951 361 1 0 0 gnome-settings- [21152.918482] [ 1629] 1000 1629 13130 72 1 0 0 gvfsd [21152.918485] [ 1634] 1000 1634 20253 0 0 0 0 gvfs-fuse-daemo [21152.918489] [ 1637] 1000 1637 155331 4484 1 0 0 compiz [21152.918493] [ 1641] 1000 1641 123727 1079 1 0 0 pulseaudio [21152.918497] [ 1646] 1000 1646 45344 2 0 0 0 gconf-helper [21152.918501] [ 1649] 1000 1649 6170 23 1 0 0 syndaemon [21152.918504] [ 1651] 1000 1651 17117 50 0 0 0 gvfs-gdu-volume [21152.918508] [ 1653] 0 1653 31918 133 0 0 0 udisks-daemon [21152.918511] [ 1654] 0 1654 11292 19 0 0 0 udisks-daemon [21152.918515] [ 1657] 1000 1657 14899 0 1 0 0 gvfs-gphoto2-vo [21152.918518] [ 1659] 1000 1659 18348 22 0 0 0 gvfs-afc-volume [21152.918522] [ 1661] 1000 1661 152776 461 0 0 0 nautilus [21152.918526] [ 1662] 1000 1662 72762 145 0 0 0 vino-server [21152.918530] [ 1664] 1000 1664 39709 49 0 0 0 zeitgeist-datah [21152.918534] [ 1665] 1000 1665 79276 155 1 0 0 bluetooth-apple [21152.918537] [ 1669] 1000 1669 57184 62 0 0 0 polkit-gnome-au [21152.918541] [ 1670] 1000 1670 98992 933 0 0 0 nm-applet [21152.918544] [ 1673] 1000 1673 45961 293 0 0 0 zeitgeist-daemo [21152.918548] [ 1674] 1000 1674 85984 92 0 0 0 evolution-alarm [21152.918552] [ 1678] 1000 1678 67058 493 1 0 0 gnome-power-man [21152.918555] [ 1688] 1000 1688 1721 9 0 0 0 cat [21152.918559] [ 1694] 1000 1694 62287 885 1 0 0 notify-osd [21152.918563] [ 1697] 1000 1697 81961 262 0 0 0 e-calendar-fact [21152.918567] [ 1714] 1000 1714 84274 9 0 0 0 e-addressbook-f [21152.918570] [ 1717] 1000 1717 14695 182 0 0 0 mission-control [21152.918574] [ 1724] 1000 1724 14265 47 0 0 0 gvfsd-trash [21152.918578] [ 1730] 1000 1730 30617 43 1 0 0 dconf-service [21152.918582] [ 1739] 1000 1739 1055 11 1 0 0 sh [21152.918586] [ 1740] 1000 1740 62903 1171 1 0 0 unity-window-de [21152.918589] [ 1743] 1000 1743 113167 4145 1 0 0 unity-panel-ser [21152.918593] [ 1745] 1000 1745 44286 137 1 0 0 unity-files-dae [21152.918597] [ 1747] 1000 1747 50899 219 0 0 0 unity-applicati [21152.918601] [ 1781] 1000 1781 13133 38 0 0 0 gvfsd-burn [21152.918604] [ 1785] 1000 1785 11702 267 0 0 0 gvfsd-metadata [21152.918608] [ 1787] 1000 1787 84058 135 0 0 0 indicator-sound [21152.918612] [ 1793] 1000 1793 77943 401 0 0 0 indicator-datet [21152.918615] [ 1794] 1000 1794 53919 145 1 0 0 indicator-appli [21152.918619] [ 1795] 1000 1795 60498 99 1 0 0 indicator-me-se [21152.918623] [ 1796] 1000 1796 59145 137 0 0 0 indicator-sessi [21152.918627] [ 1797] 1000 1797 60844 352 0 0 0 indicator-messa [21152.918631] [ 1826] 1000 1826 23446 139 0 0 0 geoclue-master [21152.918634] [ 1840] 1000 1840 44178 149 0 0 0 gnome-screensav [21152.918639] [ 1842] 1000 1842 281513 29738 0 0 0 firefox-bin [21152.918642] [ 1857] 1000 1857 44261 126 0 0 0 gdu-notificatio [21152.918646] [ 1965] 1000 1965 149626 402 0 0 0 plugin-containe [21152.918650] [ 1974] 1000 1974 104494 1502 0 0 0 gwibber-service [21152.918654] [ 1978] 1000 1978 58517 367 0 0 0 applet.py [21152.918657] [ 1998] 1000 1998 97725 1662 0 0 0 gwibber-service [21152.918661] [ 1999] 1000 1999 97725 1532 0 0 0 gwibber-service [21152.918665] [ 2028] 1000 2028 78133 1716 0 0 0 ubuntuone-syncd [21152.918668] [ 2083] 1000 2083 63772 314 0 0 0 update-notifier [21152.918672] [ 2262] 1000 2262 60617 42 1 0 0 gvfsd-http [21152.918676] [ 2341] 1000 2341 203090 370 0 0 0 emesene [21152.918679] [ 3675] 1000 3675 55599 328 1 0 0 bamfdaemon [21152.918683] [ 3709] 1000 3709 89452 200 0 0 0 scp-dbus-servic [21152.918687] [ 5311] 1000 5311 69171 110 1 0 0 plugin-containe [21152.918693] [ 6290] 0 6290 1770 13 0 0 0 dhclient [21152.918696] [ 7518] 1000 7518 144769 159 0 0 0 emesene [21152.918700] [ 7808] 1000 7808 1055 0 1 0 0 thunderbird [21152.918703] [ 7815] 1000 7815 1055 0 1 0 0 run-mozilla.sh [21152.918707] [ 7819] 1000 7819 156100 3316 0 0 0 thunderbird-bin [21152.918711] [ 7860] 1000 7860 40757 6 0 0 0 telepathy-logge [21152.918715] [ 7988] 1000 7988 150192 2391 1 0 0 pidgin [21152.918719] [ 8198] 1000 8198 1055 19 0 0 0 xdg-screensaver [21152.918723] [ 8206] 1000 8206 1055 5 0 0 0 xdg-screensaver [21152.918727] [ 8210] 1000 8210 4880 0 0 0 0 xprop [21152.918731] [ 8876] 1000 8876 497605 302569 0 0 0 VirtualBox [21152.918735] [ 8895] 1000 8895 21632 30 1 0 0 VBoxXPCOMIPCD [21152.918738] [ 8901] 1000 8901 54737 98 1 0 0 VBoxSVC [21152.918742] [ 8980] 1000 8980 144685 140 1 0 0 emesene [21152.918746] [ 9107] 1000 9107 69456 465 0 0 0 plugin-containe [21152.918749] [ 9110] 1000 9110 176118 1459 0 0 0 totem-plugin-vi [21152.918754] [ 9220] 1000 9220 1716 28 1 0 0 sleep [21152.918757] [ 9251] 0 9251 1055 53 0 0 0 pm-powersave [21152.918761] [ 9293] 1000 9293 35581 233 0 0 0 pkexec [21152.918764] [ 9298] 0 9298 5434 73 0 -17 -1000 udevd [21152.918768] [ 9302] 0 9302 5434 73 1 -17 -1000 udevd [21152.918772] [ 9309] 0 9309 1034 36 0 0 0 anacron [21152.918775] [ 9310] 0 9310 1055 43 1 0 0 pm-powersave [21152.918778] Out of memory: Kill process 8876 (VirtualBox) score 213 or sacrifice child [21152.918784] Killed process 8876 (VirtualBox) total-vm:1990420kB, anon-rss:9900kB, file-rss:1200376kB [21175.145577] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro,commit=0 [23042.857970] CPU1: Core temperature above threshold, cpu clock throttled (total events = 1) [23042.858623] CPU1: Core temperature/speed normal [23080.010026] [Hardware Error]: Machine check events logged [23788.889578] CPU1: Core temperature above threshold, cpu clock throttled (total events = 11153) [23788.890298] CPU1: Core temperature/speed normal [23830.010114] [Hardware Error]: Machine check events logged [24089.786327] CPU1: Core temperature above threshold, cpu clock throttled (total events = 19183) [24089.786979] CPU1: Core temperature/speed normal [24205.721652] type=1400 audit(1302594493.539:17): apparmor="STATUS" operation="profile_replace" name="/sbin/dhclient" pid=11977 comm="apparmor_parser" [24205.721802] type=1400 audit(1302594493.539:18): apparmor="STATUS" operation="profile_replace" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=11977 comm="apparmor_parser" [24205.721893] type=1400 audit(1302594493.539:19): apparmor="STATUS" operation="profile_replace" name="/usr/lib/connman/scripts/dhclient-script" pid=11977 comm="apparmor_parser" [24247.579420] SGI XFS with ACLs, security attributes, realtime, large block/inode numbers, no debug enabled [24247.583019] SGI XFS Quota Management subsystem [24247.660413] JFS: nTxBlock = 8192, nTxLock = 65536 [24247.745428] NTFS driver 2.1.30 [Flags: R/O MODULE]. [24247.882973] QNX4 filesystem 0.2.3 registered. [24248.013782] Btrfs loaded [24280.010057] [Hardware Error]: Machine check events logged [24487.203712] CPU1: Core temperature above threshold, cpu clock throttled (total events = 45393) [24487.204361] CPU1: Core temperature/speed normal [24600.040043] [Hardware Error]: Machine check events logged [26057.280028] CPU1: Core temperature above threshold, cpu clock throttled (total events = 45394) [26057.280668] CPU1: Core temperature/speed normal [26230.010046] [Hardware Error]: Machine check events logged [26956.917288] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro,commit=0 [26959.011491] PM: Syncing filesystems ... done. [26959.154637] PM: Preparing system for mem sleep [26959.743449] Freezing user space processes ... (elapsed 0.15 seconds) done. [26959.900157] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [26959.920154] PM: Entering mem sleep [26959.920200] Suspending console(s) (use no_console_suspend to debug) [26959.921076] sd 0:0:0:0: [sda] Synchronizing SCSI cache [26959.921192] sd 0:0:0:0: [sda] Stopping disk [26959.935280] ACPI handle has no context! [26959.935288] sdhci-pci 0000:09:01.1: PCI INT B disabled [26959.935295] ACPI handle has no context! [26959.935364] ACPI handle has no context! [26959.935644] wl 0000:04:00.0: PCI INT A disabled [26959.935711] ehci_hcd 0000:00:1d.7: PCI INT A disabled [26959.935737] uhci_hcd 0000:00:1d.2: PCI INT C disabled [26959.935744] uhci_hcd 0000:00:1d.1: PCI INT B disabled [26959.935752] uhci_hcd 0000:00:1d.0: PCI INT A disabled [26959.936610] ehci_hcd 0000:00:1a.7: PCI INT C disabled [26959.936633] uhci_hcd 0000:00:1a.2: PCI INT C disabled [26959.936642] uhci_hcd 0000:00:1a.1: PCI INT B disabled [26959.960060] uhci_hcd 0000:00:1a.0: PCI INT A disabled [26960.040052] HDA Intel 0000:01:00.1: PCI INT B disabled [26960.040102] ACPI handle has no context! [26960.060280] HDA Intel 0000:00:1b.0: PCI INT A disabled [26960.060335] PM: suspend of drv:HDA Intel dev:0000:01:00.1 complete after 124.776 msecs [26960.080018] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 144.250 msecs [26960.424607] PM: suspend of drv:sd dev:0:0:0:0 complete after 503.533 msecs [26960.424622] PM: suspend of drv:scsi dev:target0:0:0 complete after 503.505 msecs [26960.424631] PM: suspend of drv:scsi dev:host0 complete after 503.420 msecs [26960.440026] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 504.387 msecs [26961.251480] [drm] Disabling audio support [26961.350026] PM: suspend of drv:radeon dev:0000:01:00.0 complete after 1414.435 msecs [26961.350043] PM: suspend of drv:pcieport dev:0000:00:01.0 complete after 1413.392 msecs [26961.350076] PM: suspend of drv: dev:pci0000:00 complete after 1413.392 msecs [26961.350082] PM: suspend of devices complete after 1429.403 msecs [26961.350085] PM: suspend devices took 1.430 seconds [26961.410259] PM: late suspend of devices complete after 60.169 msecs [26961.410568] ACPI: Preparing to enter system sleep state S3 [26961.440461] PM: Saving platform NVS memory [26961.441445] Disabling non-boot CPUs ... [26961.550029] CPU 1 is now offline [26961.550428] Extended CMOS year: 2000 [26961.550428] Back to C! [26961.550428] PM: Restoring platform NVS memory [26961.550428] Extended CMOS year: 2000 [26961.550428] Enabling non-boot CPUs ... [26961.550428] Booting Node 0 Processor 1 APIC 0x1 [26961.720023] Switched to NOHz mode on CPU #1 [26961.720422] CPU1 is up [26961.721089] ACPI: Waking up from system sleep state S3 [26961.750688] pcieport 0000:00:01.0: restoring config space at offset 0x6 (was 0x0, writing 0x10100) [26961.750693] pcieport 0000:00:01.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10010) [26961.750697] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [26961.750746] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [26961.750788] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [26961.750810] uhci_hcd 0000:00:1a.2: restoring config space at offset 0xf (was 0x300, writing 0x30a) [26961.750824] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x8 (was 0x1, writing 0x1841) [26961.750837] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900001) [26961.750886] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [26961.750922] HDA Intel 0000:00:1b.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [26961.750943] HDA Intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) [26961.750949] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [26961.750982] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x40100, writing 0x4010a) [26961.750994] pcieport 0000:00:1c.0: restoring config space at offset 0x9 (was 0x10001, writing 0xf1f1f001) [26961.751000] pcieport 0000:00:1c.0: restoring config space at offset 0x8 (was 0x0, writing 0xf7f0f600) [26961.751005] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0x0, writing 0x3030) [26961.751010] pcieport 0000:00:1c.0: restoring config space at offset 0x6 (was 0x0, writing 0x30200) [26961.751018] pcieport 0000:00:1c.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) [26961.751024] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100507) [26961.751076] pcieport 0000:00:1c.1: restoring config space at offset 0xf (was 0x40200, writing 0x40205) [26961.751091] pcieport 0000:00:1c.1: restoring config space at offset 0x7 (was 0x3030, writing 0x4040) [26961.751101] pcieport 0000:00:1c.1: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) [26961.751107] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100507) [26961.751159] pcieport 0000:00:1c.3: restoring config space at offset 0xf (was 0x40400, writing 0x4040a) [26961.751171] pcieport 0000:00:1c.3: restoring config space at offset 0x9 (was 0x10001, writing 0xf5f1f401) [26961.751178] pcieport 0000:00:1c.3: restoring config space at offset 0x7 (was 0x20000000, writing 0x5050) [26961.751187] pcieport 0000:00:1c.3: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) [26961.751194] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100000, writing 0x100507) [26961.751246] pcieport 0000:00:1c.5: restoring config space at offset 0xf (was 0x40200, writing 0x40205) [26961.751258] pcieport 0000:00:1c.5: restoring config space at offset 0x9 (was 0x10001, writing 0xc011c001) [26961.751264] pcieport 0000:00:1c.5: restoring config space at offset 0x7 (was 0x20000000, writing 0x6060) [26961.751274] pcieport 0000:00:1c.5: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) [26961.751280] pcieport 0000:00:1c.5: restoring config space at offset 0x1 (was 0x100000, writing 0x100507) [26961.751346] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [26961.751388] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [26961.751429] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [26961.751478] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [26961.751506] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x40000, writing 0x400ff) [26961.751518] pci 0000:00:1e.0: restoring config space at offset 0x9 (was 0x10001, writing 0x1fff1) [26961.751523] pci 0000:00:1e.0: restoring config space at offset 0x8 (was 0x0, writing 0xfc10fc10) [26961.751528] pci 0000:00:1e.0: restoring config space at offset 0x7 (was 0x22800000, writing 0x228000f0) [26961.751540] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100107) [26961.751619] ahci 0000:00:1f.2: restoring config space at offset 0xf (was 0x200, writing 0x20a) [26961.751643] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [26961.751697] pci 0000:00:1f.3: restoring config space at offset 0x4 (was 0x4, writing 0xc0200004) [26961.751734] radeon 0000:01:00.0: restoring config space at offset 0xf (was 0x1ff, writing 0x105) [26961.751752] radeon 0000:01:00.0: restoring config space at offset 0x6 (was 0x0, writing 0xcfef0000) [26961.751757] radeon 0000:01:00.0: restoring config space at offset 0x5 (was 0x1, writing 0x2001) [26961.751762] radeon 0000:01:00.0: restoring config space at offset 0x4 (was 0x8, writing 0xd0000008) [26961.751767] radeon 0000:01:00.0: restoring config space at offset 0x3 (was 0x800000, writing 0x800010) [26961.751774] radeon 0000:01:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100507) [26961.751833] HDA Intel 0000:01:00.1: restoring config space at offset 0xf (was 0x2ff, writing 0x20a) [26961.751854] HDA Intel 0000:01:00.1: restoring config space at offset 0x4 (was 0x0, writing 0xcfeec000) [26961.751859] HDA Intel 0000:01:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x800010) [26961.751866] HDA Intel 0000:01:00.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100103) [26961.751933] wl 0000:04:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a) [26961.751973] wl 0000:04:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xf8000004) [26961.751982] wl 0000:04:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) [26961.751994] wl 0000:04:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100106) [26961.752144] tg3 0000:08:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) [26961.752155] tg3 0000:08:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100106) [26961.752216] firewire_ohci 0000:09:01.0: restoring config space at offset 0xf (was 0x4020100, writing 0x402010a) [26961.752240] firewire_ohci 0000:09:01.0: restoring config space at offset 0x4 (was 0x0, writing 0xfc100000) [26961.752246] firewire_ohci 0000:09:01.0: restoring config space at offset 0x3 (was 0x800000, writing 0x802010) [26961.752254] firewire_ohci 0000:09:01.0: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [26961.752276] firewire_ohci 0000:09:01.0: proprietary Ricoh MMC controller disabled (via firewire function) [26961.752278] firewire_ohci 0000:09:01.0: MMC cards are now supported by standard SDHCI controller [26961.752295] sdhci-pci 0000:09:01.1: restoring config space at offset 0xf (was 0x200, writing 0x20a) [26961.752318] sdhci-pci 0000:09:01.1: restoring config space at offset 0x4 (was 0x0, writing 0xfc100800) [26961.752324] sdhci-pci 0000:09:01.1: restoring config space at offset 0x3 (was 0x804000, writing 0x804010) [26961.752332] sdhci-pci 0000:09:01.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [26961.752359] pci 0000:09:01.2: restoring config space at offset 0xf (was 0x200, writing 0x20a) [26961.752382] pci 0000:09:01.2: restoring config space at offset 0x4 (was 0x0, writing 0xfc101000) [26961.752388] pci 0000:09:01.2: restoring config space at offset 0x3 (was 0x804000, writing 0x804010) [26961.752396] pci 0000:09:01.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [26961.752422] r852 0000:09:01.3: restoring config space at offset 0xf (was 0x200, writing 0x20a) [26961.752446] r852 0000:09:01.3: restoring config space at offset 0x4 (was 0x0, writing 0xfc101400) [26961.752452] r852 0000:09:01.3: restoring config space at offset 0x3 (was 0x804000, writing 0x804010) [26961.752459] r852 0000:09:01.3: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [26961.753162] PM: early resume of devices complete after 2.623 msecs [26961.753235] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [26961.753242] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [26961.753269] usb usb3: root hub lost power or was reset [26961.753286] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [26961.753295] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [26961.753329] usb usb4: root hub lost power or was reset [26961.753345] uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 19 (level, low) -> IRQ 19 [26961.753354] uhci_hcd 0000:00:1a.2: setting latency timer to 64 [26961.753387] usb usb5: root hub lost power or was reset [26961.753406] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 19 (level, low) -> IRQ 19 [26961.753416] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [26961.753473] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [26961.753481] HDA Intel 0000:00:1b.0: setting latency timer to 64 [26961.753540] HDA Intel 0000:00:1b.0: irq 46 for MSI/MSI-X [26961.753558] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [26961.753565] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [26961.753591] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [26961.753595] usb usb6: root hub lost power or was reset [26961.753601] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [26961.753610] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [26961.753621] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [26961.753637] usb usb7: root hub lost power or was reset [26961.753656] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [26961.753655] usb usb8: root hub lost power or was reset [26961.753664] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [26961.753671] pci 0000:00:1e.0: setting latency timer to 64 [26961.753697] ahci 0000:00:1f.2: setting latency timer to 64 [26961.753730] radeon 0000:01:00.0: setting latency timer to 64 [26961.753772] HDA Intel 0000:01:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [26961.753857] HDA Intel 0000:01:00.1: setting latency timer to 64 [26961.753946] HDA Intel 0000:01:00.1: irq 48 for MSI/MSI-X [26961.754008] wl 0000:04:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17 [26961.754021] wl 0000:04:00.0: setting latency timer to 64 [26961.764480] radeon 0000:01:00.0: WB enabled [26961.785383] sdhci-pci 0000:09:01.1: PCI INT B -> GSI 18 (level, low) -> IRQ 18 [26961.787101] sd 0:0:0:0: [sda] Starting disk [26961.796197] [drm] ring test succeeded in 1 usecs [26961.796216] [drm] ib test succeeded in 0 usecs [26961.796218] [drm] Enabling audio support [26961.855904] firewire_core: skipped bus generations, destroying all nodes [26961.910157] PM: resume of drv:usb dev:usb4 complete after 123.562 msecs [26961.910164] PM: resume of drv:usb dev:usb8 complete after 123.285 msecs [26961.910180] PM: resume of drv:hub dev:8-0:1.0 complete after 123.281 msecs [26961.910189] PM: resume of drv:usb dev:usb5 complete after 123.567 msecs [26961.910193] PM: resume of drv: dev:ep_00 complete after 123.250 msecs [26961.910201] PM: resume of drv: dev:ep_81 complete after 123.283 msecs [26961.910216] PM: resume of drv:usb dev:usb6 complete after 123.518 msecs [26961.910220] PM: resume of drv: dev:ep_00 complete after 123.545 msecs [26961.910238] PM: resume of drv: dev:ep_00 complete after 123.475 msecs [26961.910243] PM: resume of drv:usb dev:usb7 complete after 123.459 msecs [26961.910254] PM: resume of drv:hub dev:6-0:1.0 complete after 123.532 msecs [26961.910264] PM: resume of drv:hub dev:4-0:1.0 complete after 123.654 msecs [26961.910268] PM: resume of drv: dev:ep_81 complete after 123.527 msecs [26961.910271] PM: resume of drv: dev:ep_00 complete after 123.654 msecs [26961.910278] PM: resume of drv:hub dev:5-0:1.0 complete after 123.651 msecs [26961.910280] PM: resume of drv: dev:ep_81 complete after 123.667 msecs [26961.910284] PM: resume of drv:hub dev:7-0:1.0 complete after 123.476 msecs [26961.910291] PM: resume of drv: dev:ep_81 complete after 123.659 msecs [26961.910293] PM: resume of drv: dev:ep_00 complete after 123.443 msecs [26961.910301] PM: resume of drv: dev:ep_81 complete after 123.472 msecs [26961.980135] PM: resume of drv:ac dev:ACPI0003:00 complete after 194.786 msecs [26962.020045] PM: resume of drv:usb dev:usb3 complete after 233.492 msecs [26962.020063] PM: resume of drv:hub dev:3-0:1.0 complete after 233.488 msecs [26962.020086] PM: resume of drv: dev:ep_81 complete after 233.504 msecs [26962.020090] PM: resume of drv: dev:ep_00 complete after 233.497 msecs [26962.060099] PM: resume of drv:usb dev:usb1 complete after 273.612 msecs [26962.060116] PM: resume of drv:hub dev:1-0:1.0 complete after 273.604 msecs [26962.060129] PM: resume of drv: dev:ep_81 complete after 273.613 msecs [26962.060133] PM: resume of drv: dev:ep_00 complete after 273.605 msecs [26962.130147] ata6: SATA link down (SStatus 0 SControl 300) [26962.130182] ata5: SATA link down (SStatus 0 SControl 300) [26962.180127] usb 1-6: reset high speed USB device using ehci_hcd and address 3 [26962.330048] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [26962.336676] PM: resume of drv:usb dev:1-6 complete after 549.678 msecs [26962.336705] PM: resume of drv:uvcvideo dev:1-6:1.0 complete after 549.685 msecs [26962.336710] PM: resume of drv: dev:ep_00 complete after 549.621 msecs [26962.336718] PM: resume of drv:uvcvideo dev:1-6:1.1 complete after 549.656 msecs [26962.336722] PM: resume of drv: dev:ep_81 complete after 549.679 msecs [26962.350082] firewire_core: rediscovered device fw0 [26962.357956] ata2.00: configured for UDMA/100 [26962.450125] usb 3-1: reset full speed USB device using uhci_hcd and address 2 [26962.820100] PM: resume of drv:usb dev:3-1 complete after 1032.917 msecs [26962.820130] PM: resume of drv:hub dev:3-1:1.0 complete after 1032.925 msecs [26962.820145] PM: resume of drv: dev:ep_00 complete after 1032.900 msecs [26962.820150] PM: resume of drv: dev:ep_81 complete after 1032.923 msecs [26962.822594] PM: resume of drv:usb dev:3-1.3 complete after 1035.158 msecs [26962.822614] PM: resume of drv:btusb dev:3-1.3:1.0 complete after 1035.157 msecs [26962.822628] PM: resume of drv: dev:ep_00 complete after 1034.941 msecs [26962.822633] PM: resume of drv:btusb dev:3-1.3:1.1 complete after 1035.093 msecs [26962.822646] PM: resume of drv: dev:ep_02 complete after 1035.127 msecs [26962.822651] PM: resume of drv:usb dev:3-1.3:1.2 complete after 1035.047 msecs [26962.822664] PM: resume of drv: dev:ep_03 complete after 1035.082 msecs [26962.822669] PM: resume of drv:usb dev:3-1.3:1.3 complete after 1035.003 msecs [26962.822681] PM: resume of drv: dev:ep_04 complete after 1035.036 msecs [26962.822686] PM: resume of drv: dev:ep_81 complete after 1035.204 msecs [26962.822698] PM: resume of drv: dev:ep_84 complete after 1035.074 msecs [26962.822703] PM: resume of drv: dev:ep_82 complete after 1035.201 msecs [26962.822716] PM: resume of drv: dev:ep_83 complete after 1035.155 msecs [26962.901588] usb 3-1.1: reset full speed USB device using uhci_hcd and address 3 [26963.018593] PM: resume of drv:usb dev:3-1.1 complete after 1231.326 msecs [26963.018618] PM: resume of drv: dev:ep_00 complete after 1231.287 msecs [26963.018623] PM: resume of drv:usbhid dev:3-1.1:1.0 complete after 1231.332 msecs [26963.018641] PM: resume of drv: dev:ep_81 complete after 1231.331 msecs [26963.081588] usb 3-1.2: reset full speed USB device using uhci_hcd and address 4 [26963.200588] PM: resume of drv:usb dev:3-1.2 complete after 1413.235 msecs [26963.200613] PM: resume of drv: dev:ep_00 complete after 1413.199 msecs [26963.200618] PM: resume of drv:usb dev:3-1.2:1.0 complete after 1413.241 msecs [26963.200636] PM: resume of drv: dev:ep_81 complete after 1413.243 msecs [26964.670104] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [26964.680052] ata1.00: configured for UDMA/133 [26964.699730] PM: resume of drv:sd dev:0:0:0:0 complete after 2912.621 msecs [26964.699760] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 2912.610 msecs [26964.699765] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 2691.095 msecs [26973.750137] PM: resume of drv:radeon dev:0000:01:00.0 complete after 11996.415 msecs [26973.750174] PM: resume of drv:drm dev:controlD64 complete after 9017.308 msecs [26973.750331] PM: resume of devices complete after 11997.131 msecs [26973.770421] PM: resume devices took 12.020 seconds [26973.770423] ------------[ cut here ]------------ [26973.770429] WARNING: at /build/buildd/linux-2.6.38/kernel/power/suspend_test.c:53 suspend_test_finish+0x86/0x90() [26973.770431] Hardware name: Studio 1537 [26973.770432] Component: resume devices, time: 12020 [26973.770433] Modules linked in: btrfs zlib_deflate libcrc32c ufs qnx4 hfsplus hfs minix ntfs vfat msdos fat jfs xfs exportfs reiserfs usblp michael_mic arc4 hidp binfmt_misc rfcomm sco bnep l2cap vboxnetadp vboxnetflt vboxdrv parport_pc ppdev snd_hda_codec_hdmi btusb bluetooth snd_hda_codec_idt joydev radeon snd_hda_intel uvcvideo snd_hda_codec videodev v4l2_compat_ioctl32 snd_hwdep snd_pcm ttm snd_seq_midi drm_kms_helper dell_wmi sparse_keymap snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device lib80211_crypt_tkip wl(P) r852 sm_common dell_laptop dcdbas nand nand_ids nand_ecc snd psmouse drm mtd soundcore i2c_algo_bit lib80211 serio_raw snd_page_alloc video lp parport usbhid hid ahci sdhci_pci firewire_ohci firewire_core tg3 sdhci crc_itu_t libahci [26973.770485] Pid: 15570, comm: pm-suspend Tainted: P M W 2.6.38-8-generic #42-Ubuntu [26973.770487] Call Trace: [26973.770492] [] ? warn_slowpath_common+0x7f/0xc0 [26973.770495] [] ? warn_slowpath_fmt+0x46/0x50 [26973.770498] [] ? suspend_test_finish+0x86/0x90 [26973.770500] [] ? suspend_devices_and_enter+0xac/0x170 [26973.770503] [] ? enter_state+0x120/0x150 [26973.770506] [] ? state_store+0xc6/0x100 [26973.770510] [] ? kobj_attr_store+0x17/0x20 [26973.770513] [] ? sysfs_write_file+0xde/0x160 [26973.770517] [] ? vfs_write+0xc6/0x180 [26973.770519] [] ? sys_write+0x51/0x90 [26973.770522] [] ? system_call_fastpath+0x16/0x1b [26973.770524] ---[ end trace 41fac857d319bb5e ]--- [26973.770574] PM: Finishing wakeup. [26973.770576] Restarting tasks ... [26973.774875] usb 3-1.3: USB disconnect, address 7 [26973.787365] done. [26973.787382] video LNXVIDEO:00: Restoring backlight state [26974.005029] [drm:drm_mode_getfb] *ERROR* invalid framebuffer id [26974.785439] tg3 0000:08:00.0: irq 49 for MSI/MSI-X [26974.888132] ADDRCONF(NETDEV_UP): eth0: link is not ready [26975.682587] usb 3-1.3: new full speed USB device using uhci_hcd and address 8 [26977.462913] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro,commit=600 [26979.018351] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro,commit=600 [26985.660062] eth1: no IPv6 routers present