[ 13.481176] r8712u: 2 RCR=0x553f00e [ 13.600646] ADDRCONF(NETDEV_UP): wlan0: link is not ready [ 13.687513] Bridge firewalling registered [ 13.712411] ip_tables: (C) 2000-2006 Netfilter Core Team [ 13.745025] nf_conntrack version 0.5.0 (16384 buckets, 65536 max) [ 13.967078] ip6_tables: (C) 2000-2006 Netfilter Core Team [ 14.620939] e1000e: eth0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: None [ 14.620944] e1000e 0000:00:19.0: eth0: 10/100 speed: disabling TSO [ 14.621407] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 14.713506] EXT4-fs (sda3): re-mounted. Opts: errors=remount-ro,commit=0 [ 14.770359] EXT4-fs (sda5): re-mounted. Opts: errors=remount-ro,commit=0 [ 15.221599] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0 [ 15.301461] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro,commit=0 [ 15.864584] Intel AES-NI instructions are not detected. [ 15.901311] padlock_aes: VIA PadLock not detected. [ 16.085531] 2:3:1: cannot get freq at ep 0x84 [ 17.077784] 2:3:1: cannot get freq at ep 0x84 [ 18.072032] 2:3:1: cannot get freq at ep 0x84 [ 24.087034] 2:3:1: cannot get freq at ep 0x84 [ 25.079284] 2:3:1: cannot get freq at ep 0x84 [ 25.420008] eth0: no IPv6 routers present [ 26.073527] 2:3:1: cannot get freq at ep 0x84 [ 4700.246264] EXT4-fs (sda3): re-mounted. Opts: errors=remount-ro,commit=0 [ 4700.257701] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0 [ 4700.261687] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro,commit=0 [ 4701.413906] PM: Syncing filesystems ... done. [ 4701.415553] PM: Preparing system for mem sleep [ 4701.850157] Freezing user space processes ... (elapsed 0.01 seconds) done. [ 4701.870053] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [ 4701.890034] PM: Entering mem sleep [ 4701.890082] Suspending console(s) (use no_console_suspend to debug) [ 4701.890654] sd 3:0:0:0: [sda] Synchronizing SCSI cache [ 4701.890796] r8712: suspending... [ 4701.890798] r8712: unable to suspend [ 4701.890809] sd 3:0:0:0: [sda] Stopping disk [ 4701.891148] uhci_hcd 0000:00:1d.2: PCI INT C disabled [ 4701.891164] uhci_hcd 0000:00:1d.1: PCI INT B disabled [ 4701.891168] uhci_hcd 0000:00:1d.0: PCI INT A disabled [ 4701.891191] uhci_hcd 0000:00:1a.2: PCI INT D disabled [ 4701.891395] e1000e 0000:00:19.0: PCI INT A disabled [ 4701.891400] e1000e 0000:00:19.0: PME# enabled [ 4701.891406] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [ 4701.910074] uhci_hcd 0000:00:1a.0: PCI INT A disabled [ 4701.910108] uhci_hcd 0000:00:1a.1: PCI INT B disabled [ 4701.930030] ehci_hcd 0000:00:1d.7: PCI INT A disabled [ 4701.930034] ehci_hcd 0000:00:1a.7: PCI INT C disabled [ 4702.001048] HDA Intel 0000:00:1b.0: PCI INT A disabled [ 4702.001072] ACPI handle has no context! [ 4702.020014] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 128.843 msecs [ 4702.621048] PM: suspend of drv:sd dev:3:0:0:0 complete after 730.395 msecs [ 4702.621063] PM: suspend of drv:scsi dev:target3:0:0 complete after 730.369 msecs [ 4702.621078] PM: suspend of drv:scsi dev:host3 complete after 730.247 msecs [ 4702.640016] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 748.900 msecs [ 4702.640032] PM: suspend of drv: dev:pci0000:00 complete after 748.322 msecs [ 4702.640043] PM: suspend of devices complete after 749.535 msecs [ 4702.640047] PM: suspend devices took 0.750 seconds [ 4702.680180] PM: late suspend of devices complete after 40.126 msecs [ 4702.680642] ACPI: Preparing to enter system sleep state S3 [ 4702.681821] PM: Saving platform NVS memory [ 4702.682246] Disabling non-boot CPUs ... [ 4702.830027] CPU 1 is now offline [ 4702.830386] Extended CMOS year: 2000 [ 4702.830386] Back to C! [ 4702.830386] PM: Restoring platform NVS memory [ 4702.830386] Extended CMOS year: 2000 [ 4702.830386] Enabling non-boot CPUs ... [ 4702.830386] Booting Node 0 Processor 1 APIC 0x1 [ 4703.020009] Switched to NOHz mode on CPU #1 [ 4703.020072] CPU1 is up [ 4703.020827] ACPI: Waking up from system sleep state S3 [ 4703.021734] i915 0000:00:02.0: restoring config space at offset 0x8 (was 0x1, writing 0xec01) [ 4703.021739] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [ 4703.021759] pci 0000:00:02.1: restoring config space at offset 0x4 (was 0x4, writing 0xfe600004) [ 4703.021762] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) [ 4703.021812] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 4703.021837] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 4703.021862] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 4703.021893] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [ 4703.021925] HDA Intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [ 4703.021929] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100006, writing 0x100002) [ 4703.021956] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 4703.021981] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 4703.022006] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 4703.022037] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [ 4703.022054] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x60000, writing 0x600ff) [ 4703.022120] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [ 4703.022322] PM: early resume of devices complete after 0.654 msecs [ 4703.022373] i915 0000:00:02.0: setting latency timer to 64 [ 4703.022480] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [ 4703.022484] e1000e 0000:00:19.0: PME# disabled [ 4703.022542] e1000e 0000:00:19.0: irq 41 for MSI/MSI-X [ 4703.024596] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 4703.024601] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [ 4703.024621] usb usb3: root hub lost power or was reset [ 4703.024631] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [ 4703.024635] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [ 4703.024654] usb usb4: root hub lost power or was reset [ 4703.024663] uhci_hcd 0000:00:1a.2: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [ 4703.024667] uhci_hcd 0000:00:1a.2: setting latency timer to 64 [ 4703.024686] usb usb5: root hub lost power or was reset [ 4703.024695] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 4703.024700] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [ 4703.024736] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [ 4703.024741] HDA Intel 0000:00:1b.0: setting latency timer to 64 [ 4703.024769] HDA Intel 0000:00:1b.0: irq 42 for MSI/MSI-X [ 4703.024800] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [ 4703.024804] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [ 4703.024823] usb usb6: root hub lost power or was reset [ 4703.024831] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [ 4703.024836] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [ 4703.024855] usb usb7: root hub lost power or was reset [ 4703.024863] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 4703.024867] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [ 4703.024886] usb usb8: root hub lost power or was reset [ 4703.024895] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [ 4703.024900] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [ 4703.024933] pci 0000:00:1e.0: setting latency timer to 64 [ 4703.024945] ahci 0000:00:1f.2: setting latency timer to 64 [ 4703.025956] sd 3:0:0:0: [sda] Starting disk [ 4703.110097] No ACPI video bus found [ 4703.150079] PM: resume of drv:usb dev:usb5 complete after 124.749 msecs [ 4703.150093] PM: resume of drv:hub dev:5-0:1.0 complete after 124.759 msecs [ 4703.150105] PM: resume of drv: dev:ep_00 complete after 124.761 msecs [ 4703.150110] PM: resume of drv:usb dev:usb6 complete after 124.725 msecs [ 4703.150114] PM: resume of drv: dev:ep_81 complete after 124.775 msecs [ 4703.150132] PM: resume of drv: dev:ep_00 complete after 124.701 msecs [ 4703.150136] PM: resume of drv:usb dev:usb7 complete after 124.688 msecs [ 4703.150148] PM: resume of drv:hub dev:6-0:1.0 complete after 124.749 msecs [ 4703.150158] PM: resume of drv: dev:ep_81 complete after 124.743 msecs [ 4703.150167] PM: resume of drv:usb dev:usb8 complete after 124.654 msecs [ 4703.150174] PM: resume of drv: dev:ep_00 complete after 124.677 msecs [ 4703.150180] PM: resume of drv:hub dev:7-0:1.0 complete after 124.715 msecs [ 4703.150190] PM: resume of drv: dev:ep_00 complete after 124.629 msecs [ 4703.150194] PM: resume of drv:hub dev:8-0:1.0 complete after 124.662 msecs [ 4703.150206] PM: resume of drv: dev:ep_81 complete after 124.727 msecs [ 4703.150208] PM: resume of drv: dev:ep_81 complete after 124.663 msecs [ 4703.170017] PM: resume of drv:usb dev:usb2 complete after 144.766 msecs [ 4703.170033] PM: resume of drv:hub dev:2-0:1.0 complete after 144.775 msecs [ 4703.170044] PM: resume of drv: dev:ep_00 complete after 144.778 msecs [ 4703.170051] PM: resume of drv: dev:ep_81 complete after 144.788 msecs [ 4703.200013] PM: resume of drv:usb dev:usb1 complete after 175.021 msecs [ 4703.200025] PM: resume of drv:hub dev:1-0:1.0 complete after 174.787 msecs [ 4703.200036] PM: resume of drv: dev:ep_00 complete after 174.789 msecs [ 4703.200041] PM: resume of drv: dev:ep_81 complete after 174.797 msecs [ 4703.245793] PM: resume of drv:usb dev:2-4 complete after 220.089 msecs [ 4703.245810] PM: resume of drv:uvcvideo dev:2-4:1.0 complete after 220.090 msecs [ 4703.245815] PM: resume of drv:snd-usb-audio dev:2-4:1.3 complete after 220.030 msecs [ 4703.245822] PM: resume of drv:uvcvideo dev:2-4:1.1 complete after 220.071 msecs [ 4703.245826] PM: resume of drv: dev:ep_00 complete after 220.025 msecs [ 4703.245830] PM: resume of drv:snd-usb-audio dev:2-4:1.2 complete after 220.063 msecs [ 4703.245834] PM: resume of drv: dev:ep_83 complete after 220.099 msecs [ 4703.260017] PM: resume of drv:usb dev:usb3 complete after 234.745 msecs [ 4703.260029] PM: resume of drv:hub dev:3-0:1.0 complete after 234.741 msecs [ 4703.260040] PM: resume of drv: dev:ep_00 complete after 234.743 msecs [ 4703.260047] PM: resume of drv: dev:ep_81 complete after 234.756 msecs [ 4703.260052] PM: resume of drv:usb dev:usb4 complete after 234.749 msecs [ 4703.260067] PM: resume of drv: dev:ep_00 complete after 234.746 msecs [ 4703.260071] PM: resume of drv:hub dev:4-0:1.0 complete after 234.754 msecs [ 4703.260092] PM: resume of drv: dev:ep_81 complete after 234.774 msecs [ 4703.290066] usb 2-6: reset high speed USB device using ehci_hcd and address 3 [ 4703.370022] ata2: SATA link down (SStatus 0 SControl 300) [ 4703.390027] ata3: SATA link down (SStatus 0 SControl 300) [ 4703.410020] ata5: SATA link down (SStatus 0 SControl 300) [ 4703.447667] PM: resume of drv:usb dev:2-6 complete after 421.852 msecs [ 4703.447680] PM: resume of drv:usb-storage dev:2-6:1.0 complete after 421.850 msecs [ 4703.447694] PM: resume of drv: dev:ep_00 complete after 421.816 msecs [ 4703.447702] PM: resume of drv: dev:ep_01 complete after 421.857 msecs [ 4703.447706] PM: resume of drv: dev:ep_82 complete after 421.841 msecs [ 4703.447711] PM: resume of drv:scsi dev:host6 complete after 421.638 msecs [ 4703.447722] PM: resume of drv:scsi_host dev:host6 complete after 421.634 msecs [ 4703.447734] PM: resume of drv:scsi dev:target6:0:0 complete after 421.536 msecs [ 4703.447751] PM: resume of drv:sd dev:6:0:0:0 complete after 421.536 msecs [ 4703.447762] PM: resume of drv:scsi_device dev:6:0:0:0 complete after 421.532 msecs [ 4703.450019] ata6: SATA link down (SStatus 0 SControl 300) [ 4703.560015] usb 1-2: reset high speed USB device using ehci_hcd and address 3 [ 4703.711658] PM: resume of drv:usb dev:1-2 complete after 686.063 msecs [ 4703.711670] PM: resume of drv:r8712u dev:1-2:1.0 complete after 686.060 msecs [ 4703.711683] PM: resume of drv: dev:ep_00 complete after 685.994 msecs [ 4703.711693] PM: resume of drv: dev:ep_83 complete after 686.067 msecs [ 4703.711697] PM: resume of drv: dev:ep_04 complete after 686.054 msecs [ 4703.711701] PM: resume of drv: dev:ep_06 complete after 686.044 msecs [ 4703.711714] PM: resume of drv: dev:ep_0d complete after 686.041 msecs [ 4703.830022] usb 3-1: reset full speed USB device using uhci_hcd and address 2 [ 4703.870019] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 4703.873236] ata1.00: configured for UDMA/100 [ 4703.960014] usb 3-1: device descriptor read/64, error -71 [ 4704.229288] PM: resume of drv:usb dev:3-1 complete after 1203.395 msecs [ 4704.229302] PM: resume of drv:usbhid dev:3-1:1.0 complete after 1203.393 msecs [ 4704.229315] PM: resume of drv: dev:ep_00 complete after 1203.374 msecs [ 4704.229330] PM: resume of drv: dev:ep_81 complete after 1203.407 msecs [ 4704.480014] usb 4-1: reset low speed USB device using uhci_hcd and address 2 [ 4704.792318] PM: resume of drv:usb dev:4-1 complete after 1766.307 msecs [ 4704.792331] PM: resume of drv:usbhid dev:4-1:1.0 complete after 1766.305 msecs [ 4704.792343] PM: resume of drv: dev:ep_00 complete after 1766.286 msecs [ 4704.792358] PM: resume of drv: dev:ep_81 complete after 1766.316 msecs [ 4705.040021] usb 4-2: reset low speed USB device using uhci_hcd and address 3 [ 4705.359319] PM: resume of drv:usb dev:4-2 complete after 2333.215 msecs [ 4705.359333] PM: resume of drv:usbhid dev:4-2:1.0 complete after 2333.214 msecs [ 4705.359337] PM: resume of drv: dev:ep_00 complete after 2333.155 msecs [ 4705.359344] PM: resume of drv:usbhid dev:4-2:1.1 complete after 2333.195 msecs [ 4705.359348] PM: resume of drv: dev:ep_81 complete after 2333.212 msecs [ 4705.359365] PM: resume of drv: dev:ep_82 complete after 2333.199 msecs [ 4708.470010] ata4: link is slow to respond, please be patient (ready=0) [ 4713.090010] ata4: COMRESET failed (errno=-16) [ 4714.340017] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 4714.476859] ata4.00: configured for UDMA/133 [ 4714.496841] PM: resume of drv:sd dev:3:0:0:0 complete after 11470.882 msecs [ 4714.496856] PM: resume of drv:scsi_device dev:3:0:0:0 complete after 11470.861 msecs [ 4714.496874] PM: resume of drv:scsi_disk dev:3:0:0:0 complete after 11394.871 msecs [ 4714.497025] PM: resume of devices complete after 11474.671 msecs [ 4714.497301] PM: resume devices took 11.470 seconds [ 4714.497302] ------------[ cut here ]------------ [ 4714.497307] WARNING: at /build/buildd/linux-2.6.38/kernel/power/suspend_test.c:53 suspend_test_finish+0x86/0x90() [ 4714.497308] Hardware name: Aspire Z3731 [ 4714.497309] Component: resume devices, time: 11470 [ 4714.497310] Modules linked in: cryptd aes_x86_64 aes_generic ip6table_filter ip6_tables binfmt_misc ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT xt_CHECKSUM iptable_mangle xt_tcpudp iptable_filter ip_tables x_tables bridge stp kvm_intel kvm parport_pc ppdev snd_hda_codec_realtek snd_usb_audio snd_usbmidi_lib i915 snd_hda_intel snd_hda_codec snd_hwdep snd_pcm uvcvideo videodev snd_seq_midi snd_rawmidi r8712u(C) drm_kms_helper v4l2_compat_ioctl32 snd_seq_midi_event snd_seq drm snd_timer snd_seq_device psmouse snd serio_raw joydev i2c_algo_bit soundcore snd_page_alloc video lp parport usb_storage usbhid hid uas e1000e ahci libahci [ 4714.497343] Pid: 2128, comm: pm-suspend Tainted: G C 2.6.38-8-generic #42-Ubuntu [ 4714.497345] Call Trace: [ 4714.497349] [] ? warn_slowpath_common+0x7f/0xc0 [ 4714.497352] [] ? warn_slowpath_fmt+0x46/0x50 [ 4714.497353] [] ? suspend_test_finish+0x86/0x90 [ 4714.497355] [] ? suspend_devices_and_enter+0xac/0x170 [ 4714.497357] [] ? enter_state+0x120/0x150 [ 4714.497360] [] ? state_store+0xc6/0x100 [ 4714.497363] [] ? kobj_attr_store+0x17/0x20 [ 4714.497366] [] ? sysfs_write_file+0xde/0x160 [ 4714.497369] [] ? vfs_write+0xc6/0x180 [ 4714.497370] [] ? sys_write+0x51/0x90 [ 4714.497373] [] ? system_call_fastpath+0x16/0x1b [ 4714.497374] ---[ end trace 75f01fc7ac77de6d ]--- [ 4714.497418] PM: Finishing wakeup. [ 4714.497419] Restarting tasks ... done. [ 4714.700265] e1000e 0000:00:19.0: irq 41 for MSI/MSI-X [ 4714.760041] e1000e 0000:00:19.0: irq 41 for MSI/MSI-X [ 4714.760455] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 4714.870665] ADDRCONF(NETDEV_UP): wlan0: link is not ready [ 4714.948595] r8712u: in r8711_wx_set_scan: bDriverStopped=1 [ 4715.003585] r8712u: in r8711_wx_set_scan: bDriverStopped=1 [ 4715.491250] EXT4-fs (sda3): re-mounted. Opts: errors=remount-ro,commit=0 [ 4715.498215] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0 [ 4715.501921] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro,commit=0 [ 4716.460879] e1000e: eth0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: None [ 4716.460887] e1000e 0000:00:19.0: eth0: 10/100 speed: disabling TSO [ 4716.461616] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 4727.420010] eth0: no IPv6 routers present [ 4735.002494] r8712u: in r8711_wx_set_scan: bDriverStopped=1 [ 4765.003004] r8712u: in r8711_wx_set_scan: bDriverStopped=1 [ 4805.003510] r8712u: in r8711_wx_set_scan: bDriverStopped=1 [ 4855.002728] r8712u: in r8711_wx_set_scan: bDriverStopped=1 [ 4858.240231] ACPI Warning: Incorrect checksum in table [OEMB] - 0xE9, should be 0xD4 (20110112/tbutils-314) [ 4858.240396] ACPI Warning: Incorrect checksum in table [OEMB] - 0xE9, should be 0xD4 (20110112/tbutils-314) [ 4858.240540] ACPI Warning: Incorrect checksum in table [GSCI] - 0x1B, should be 0x0A (20110112/tbutils-314) [ 4858.245848] ACPI Warning: Incorrect checksum in table [GSCI] - 0x1B, should be 0x0A (20110112/tbutils-314) [ 4858.251129] ACPI Warning: Incorrect checksum in table [GSCI] - 0x1B, should be 0x0A (20110112/tbutils-314) [ 4858.251186] ACPI Warning: Incorrect checksum in table [GSCI] - 0x1B, should be 0x0A (20110112/tbutils-314) [ 4915.001181] r8712u: in r8711_wx_set_scan: bDriverStopped=1 [ 4975.002689] r8712u: in r8711_wx_set_scan: bDriverStopped=1 [ 5035.003188] r8712u: in r8711_wx_set_scan: bDriverStopped=1 [ 5095.002658] r8712u: in r8711_wx_set_scan: bDriverStopped=1 [ 5155.003247] r8712u: in r8711_wx_set_scan: bDriverStopped=1