Apr 16 09:58:59 blackice kernel: [21450.060520] type=1400 audit(1302958739.018:25): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24160 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:58:59 blackice kernel: [21450.460101] type=1400 audit(1302958739.418:26): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24161 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:58:59 blackice kernel: [21450.623420] type=1400 audit(1302958739.578:27): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24163 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:58:59 blackice kernel: [21450.815412] type=1400 audit(1302958739.770:28): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24164 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:00 blackice kernel: [21451.036570] type=1400 audit(1302958739.994:29): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24165 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:00 blackice kernel: [21451.260642] type=1400 audit(1302958740.218:30): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24166 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:00 blackice kernel: [21451.589424] type=1400 audit(1302958740.546:31): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24167 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:00 blackice kernel: [21451.802832] type=1400 audit(1302958740.758:32): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24168 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:01 blackice kernel: [21452.107910] type=1400 audit(1302958741.062:33): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24169 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:01 blackice kernel: [21452.740782] type=1400 audit(1302958741.698:34): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24172 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:04 blackice kernel: [21455.584141] audit_printk_skb: 15 callbacks suppressed Apr 16 09:59:04 blackice kernel: [21455.584146] type=1400 audit(1302958744.542:40): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24185 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:05 blackice kernel: [21456.340368] type=1400 audit(1302958745.298:41): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24186 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:07 blackice kernel: [21458.985398] type=1400 audit(1302958747.942:42): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24187 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:08 blackice kernel: [21459.197619] type=1400 audit(1302958748.154:43): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24188 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:08 blackice kernel: [21459.386772] type=1400 audit(1302958748.342:44): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24189 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:08 blackice kernel: [21459.625411] type=1400 audit(1302958748.582:45): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24190 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:09 blackice kernel: [21460.608459] type=1400 audit(1302958749.566:46): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24194 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:10 blackice kernel: [21461.690877] type=1400 audit(1302958750.646:47): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24197 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:11 blackice kernel: [21462.097079] type=1400 audit(1302958751.054:48): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24198 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:11 blackice kernel: [21462.882920] type=1400 audit(1302958751.838:49): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24199 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:12 blackice kernel: [21463.170525] type=1400 audit(1302958752.126:50): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24200 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:12 blackice kernel: [21463.417631] type=1400 audit(1302958752.374:51): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24201 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:12 blackice kernel: [21463.968268] type=1400 audit(1302958752.926:52): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24202 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:13 blackice kernel: [21464.626282] type=1400 audit(1302958753.582:53): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24203 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:13 blackice kernel: [21464.885155] type=1400 audit(1302958753.842:54): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24204 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:14 blackice kernel: [21465.253013] type=1400 audit(1302958754.210:55): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24205 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:16 blackice kernel: [21467.088918] type=1400 audit(1302958756.046:56): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24206 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:16 blackice kernel: [21467.681359] type=1400 audit(1302958756.638:57): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24209 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 09:59:16 blackice kernel: [21467.957540] type=1400 audit(1302958756.914:58): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24210 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 Apr 16 11:01:34 blackice kernel: [25205.192711] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=600 Apr 16 11:01:34 blackice kernel: [25205.537949] EXT4-fs (sda5): re-mounted. Opts: commit=600 Apr 16 11:01:34 blackice kernel: [25205.759471] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=600 Apr 16 11:01:50 blackice kernel: [25221.531269] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0 Apr 16 11:01:50 blackice kernel: [25221.909925] EXT4-fs (sda5): re-mounted. Opts: commit=0 Apr 16 11:01:51 blackice kernel: [25222.049888] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=0 Apr 16 11:19:25 blackice NetworkManager[903]: sleep requested (sleeping: no enabled: yes) Apr 16 11:19:25 blackice NetworkManager[903]: sleeping or disabling... Apr 16 11:19:25 blackice NetworkManager[903]: (eth0): now unmanaged Apr 16 11:19:25 blackice NetworkManager[903]: (eth0): device state change: 2 -> 1 (reason 37) Apr 16 11:19:25 blackice NetworkManager[903]: (eth0): cleaning up... Apr 16 11:19:25 blackice NetworkManager[903]: (eth0): taking down device. Apr 16 11:19:25 blackice NetworkManager[903]: (wlan0): now unmanaged Apr 16 11:19:25 blackice NetworkManager[903]: (wlan0): device state change: 8 -> 1 (reason 37) Apr 16 11:19:25 blackice NetworkManager[903]: (wlan0): deactivating device (reason: 37). Apr 16 11:19:25 blackice NetworkManager[903]: (wlan0): canceled DHCP transaction, DHCP client pid 20970 Apr 16 11:19:25 blackice NetworkManager[903]: (wlan0): cleaning up... Apr 16 11:19:25 blackice NetworkManager[903]: (wlan0): taking down device. Apr 16 11:19:25 blackice kernel: [26276.977086] wlan0: deauthenticating from 00:26:5a:63:b3:2a by local choice (reason=3) Apr 16 11:19:26 blackice kernel: [26277.101335] cfg80211: All devices are disconnected, going to restore regulatory settings Apr 16 11:19:26 blackice kernel: [26277.101342] cfg80211: Restoring regulatory settings Apr 16 11:19:26 blackice kernel: [26277.101349] cfg80211: Calling CRDA to update world regulatory domain Apr 16 11:19:26 blackice kernel: [26277.107007] cfg80211: Updating information on frequency 2412 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:19:26 blackice kernel: [26277.107012] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:19:26 blackice kernel: [26277.107014] cfg80211: Updating information on frequency 2417 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:19:26 blackice kernel: [26277.107017] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:19:26 blackice kernel: [26277.107020] cfg80211: Updating information on frequency 2422 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:19:26 blackice kernel: [26277.107023] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:19:26 blackice kernel: [26277.107025] cfg80211: Updating information on frequency 2427 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:19:26 blackice kernel: [26277.107028] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:19:26 blackice kernel: [26277.107030] cfg80211: Updating information on frequency 2432 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:19:26 blackice kernel: [26277.107033] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:19:26 blackice kernel: [26277.107035] cfg80211: Updating information on frequency 2437 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:19:26 blackice kernel: [26277.107038] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:19:26 blackice kernel: [26277.107040] cfg80211: Updating information on frequency 2442 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:19:26 blackice kernel: [26277.107043] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:19:26 blackice kernel: [26277.107045] cfg80211: Updating information on frequency 2447 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:19:26 blackice kernel: [26277.107048] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:19:26 blackice kernel: [26277.107050] cfg80211: Updating information on frequency 2452 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:19:26 blackice kernel: [26277.107053] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:19:26 blackice kernel: [26277.107055] cfg80211: Updating information on frequency 2457 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:19:26 blackice kernel: [26277.107058] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:19:26 blackice kernel: [26277.107060] cfg80211: Updating information on frequency 2462 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:19:26 blackice kernel: [26277.107063] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:19:26 blackice kernel: [26277.107065] cfg80211: Updating information on frequency 2467 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:19:26 blackice kernel: [26277.107068] cfg80211: 2457000 KHz - 2482000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:19:26 blackice kernel: [26277.107070] cfg80211: Updating information on frequency 2472 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:19:26 blackice kernel: [26277.107073] cfg80211: 2457000 KHz - 2482000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:19:26 blackice kernel: [26277.107076] cfg80211: Updating information on frequency 2484 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:19:26 blackice kernel: [26277.107078] cfg80211: 2474000 KHz - 2494000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:19:26 blackice kernel: [26277.107081] cfg80211: World regulatory domain updated: Apr 16 11:19:26 blackice kernel: [26277.107083] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) Apr 16 11:19:26 blackice kernel: [26277.107086] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Apr 16 11:19:26 blackice kernel: [26277.107089] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) Apr 16 11:19:26 blackice kernel: [26277.107091] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) Apr 16 11:19:26 blackice kernel: [26277.107094] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Apr 16 11:19:26 blackice kernel: [26277.107096] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Apr 16 11:19:27 blackice kernel: [26278.333579] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0 Apr 16 11:19:27 blackice kernel: [26278.578689] EXT4-fs (sda5): re-mounted. Opts: commit=0 Apr 16 11:19:27 blackice kernel: [26278.958556] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=0 Apr 16 11:20:13 blackice kernel: [26285.603222] PM: Syncing filesystems ... done. Apr 16 11:20:13 blackice kernel: [26285.631443] PM: Preparing system for mem sleep Apr 16 11:20:13 blackice kernel: [26285.631464] Freezing user space processes ... (elapsed 0.01 seconds) done. Apr 16 11:20:13 blackice kernel: [26285.648125] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. Apr 16 11:20:13 blackice kernel: [26285.664101] PM: Entering mem sleep Apr 16 11:20:13 blackice kernel: [26285.664123] Suspending console(s) (use no_console_suspend to debug) Apr 16 11:20:13 blackice kernel: [26285.664696] sd 2:0:0:0: [sda] Synchronizing SCSI cache Apr 16 11:20:13 blackice kernel: [26285.671977] sd 2:0:0:0: [sda] Stopping disk Apr 16 11:20:13 blackice kernel: [26285.706629] ACPI handle has no context! Apr 16 11:20:13 blackice kernel: [26285.706642] sdhci-pci 0000:03:00.0: PCI INT A disabled Apr 16 11:20:13 blackice kernel: [26285.706651] ACPI handle has no context! Apr 16 11:20:13 blackice kernel: [26285.706738] ACPI handle has no context! Apr 16 11:20:13 blackice kernel: [26285.706746] jmb38x_ms 0000:03:00.3: PCI INT A disabled Apr 16 11:20:13 blackice kernel: [26285.706755] ACPI handle has no context! Apr 16 11:20:13 blackice kernel: [26285.706873] sis190 0000:00:04.0: PCI INT A disabled Apr 16 11:20:13 blackice kernel: [26285.706919] ohci_hcd 0000:00:03.1: PCI INT B disabled Apr 16 11:20:13 blackice kernel: [26285.706930] ohci_hcd 0000:00:03.0: PCI INT A disabled Apr 16 11:20:13 blackice kernel: [26285.707081] pata_sis 0000:00:02.5: PCI INT A disabled Apr 16 11:20:13 blackice kernel: [26285.792016] usb 1-2: reset high speed USB device using ehci_hcd and address 2 Apr 16 11:20:13 blackice kernel: [26285.912069] HDA Intel 0000:00:0f.0: PCI INT A disabled Apr 16 11:20:13 blackice kernel: [26285.912096] ACPI handle has no context! Apr 16 11:20:13 blackice kernel: [26285.928014] PM: suspend of drv:HDA Intel dev:0000:00:0f.0 complete after 221.238 msecs Apr 16 11:20:13 blackice kernel: [26285.940013] PM: suspend of drv:usb dev:1-2 complete after 275.125 msecs Apr 16 11:20:13 blackice kernel: [26285.940475] PM: suspend of drv:usb dev:usb1 complete after 234.364 msecs Apr 16 11:20:13 blackice kernel: [26285.956023] ehci_hcd 0000:00:03.3: PCI INT C disabled Apr 16 11:20:13 blackice kernel: [26285.956026] PM: suspend of drv:ehci_hcd dev:0000:00:03.3 complete after 249.147 msecs Apr 16 11:20:13 blackice kernel: [26286.707472] PM: suspend of drv:sd dev:2:0:0:0 complete after 1042.783 msecs Apr 16 11:20:13 blackice kernel: [26286.707483] PM: suspend of drv:scsi dev:target2:0:0 complete after 1042.688 msecs Apr 16 11:20:13 blackice kernel: [26286.707501] PM: suspend of drv:scsi dev:host2 complete after 1042.467 msecs Apr 16 11:20:13 blackice kernel: [26286.707511] sata_sis 0000:00:05.0: PCI INT A disabled Apr 16 11:20:13 blackice kernel: [26286.707514] PM: suspend of drv:sata_sis dev:0000:00:05.0 complete after 1000.679 msecs Apr 16 11:20:13 blackice kernel: [26286.707522] PM: suspend of drv: dev:pci0000:00 complete after 1000.520 msecs Apr 16 11:20:13 blackice kernel: [26286.707530] PM: suspend of devices complete after 1043.011 msecs Apr 16 11:20:13 blackice kernel: [26286.707533] PM: suspend devices took 1.040 seconds Apr 16 11:20:13 blackice kernel: [26286.724156] PM: late suspend of devices complete after 16.618 msecs Apr 16 11:20:13 blackice kernel: [26286.724353] ACPI: Preparing to enter system sleep state S3 Apr 16 11:20:13 blackice kernel: [26286.765328] PM: Saving platform NVS memory Apr 16 11:20:13 blackice kernel: [26286.765355] Disabling non-boot CPUs ... Apr 16 11:20:13 blackice kernel: [26286.868030] CPU 1 is now offline Apr 16 11:20:13 blackice kernel: [26286.868485] Extended CMOS year: 2000 Apr 16 11:20:13 blackice kernel: [26286.868485] Back to C! Apr 16 11:20:13 blackice kernel: [26286.868485] PM: Restoring platform NVS memory Apr 16 11:20:13 blackice kernel: [26286.868485] Extended CMOS year: 2000 Apr 16 11:20:13 blackice kernel: [26286.868485] Enabling non-boot CPUs ... Apr 16 11:20:13 blackice kernel: [26286.868485] Booting Node 0 Processor 1 APIC 0x1 Apr 16 11:20:13 blackice kernel: [26286.766643] Initializing CPU#1 Apr 16 11:20:13 blackice kernel: [26286.956623] CPU1 is up Apr 16 11:20:13 blackice kernel: [26286.957063] ACPI: Waking up from system sleep state S3 Apr 16 11:20:13 blackice kernel: [26286.960022] Switched to NOHz mode on CPU #1 Apr 16 11:20:13 blackice kernel: [26287.212182] pci 0000:00:01.0: restoring config space at offset 0x7 (was 0x209090, writing 0x20209090) Apr 16 11:20:13 blackice kernel: [26287.212227] pata_sis 0000:00:02.5: restoring config space at offset 0xf (was 0x1ff, writing 0x0) Apr 16 11:20:13 blackice kernel: [26287.212239] pata_sis 0000:00:02.5: restoring config space at offset 0x7 (was 0x375, writing 0x1) Apr 16 11:20:13 blackice kernel: [26287.212244] pata_sis 0000:00:02.5: restoring config space at offset 0x6 (was 0x171, writing 0x1) Apr 16 11:20:13 blackice kernel: [26287.212248] pata_sis 0000:00:02.5: restoring config space at offset 0x5 (was 0x3f5, writing 0x1) Apr 16 11:20:13 blackice kernel: [26287.212253] pata_sis 0000:00:02.5: restoring config space at offset 0x4 (was 0x1f1, writing 0x1) Apr 16 11:20:13 blackice kernel: [26287.212258] pata_sis 0000:00:02.5: restoring config space at offset 0x2 (was 0x1018a01, writing 0x1018001) Apr 16 11:20:13 blackice kernel: [26287.212289] ohci_hcd 0000:00:03.0: restoring config space at offset 0x1 (was 0x2800017, writing 0x2800013) Apr 16 11:20:13 blackice kernel: [26287.212315] ohci_hcd 0000:00:03.1: restoring config space at offset 0x1 (was 0x2800017, writing 0x2800013) Apr 16 11:20:13 blackice kernel: [26287.228019] ehci_hcd 0000:00:03.3: BAR 0: set to [mem 0xd8206000-0xd8206fff] (PCI address [0xd8206000-0xd8206fff]) Apr 16 11:20:13 blackice kernel: [26287.228044] ehci_hcd 0000:00:03.3: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) Apr 16 11:20:13 blackice kernel: [26287.228078] sis190 0000:00:04.0: restoring config space at offset 0x1 (was 0x2100007, writing 0x2100003) Apr 16 11:20:13 blackice kernel: [26287.228111] sata_sis 0000:00:05.0: restoring config space at offset 0x1 (was 0x2100005, writing 0x2100001) Apr 16 11:20:13 blackice kernel: [26287.228145] pcieport 0000:00:06.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xd861d851) Apr 16 11:20:13 blackice kernel: [26287.228155] pcieport 0000:00:06.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004) Apr 16 11:20:13 blackice kernel: [26287.228160] pcieport 0000:00:06.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) Apr 16 11:20:13 blackice kernel: [26287.228203] pcieport 0000:00:07.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xd881d871) Apr 16 11:20:13 blackice kernel: [26287.228208] pcieport 0000:00:07.0: restoring config space at offset 0x7 (was 0x200001f1, writing 0x20002121) Apr 16 11:20:13 blackice kernel: [26287.228216] pcieport 0000:00:07.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004) Apr 16 11:20:13 blackice kernel: [26287.228222] pcieport 0000:00:07.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) Apr 16 11:20:13 blackice kernel: [26287.228264] HDA Intel 0000:00:0f.0: restoring config space at offset 0x1 (was 0x2100006, writing 0x2100002) Apr 16 11:20:13 blackice kernel: [26287.228287] pcieport 0000:00:1f.0: restoring config space at offset 0xf (was 0x0, writing 0x400ff) Apr 16 11:20:13 blackice kernel: [26287.228298] pcieport 0000:00:1f.0: restoring config space at offset 0x9 (was 0x1ff01, writing 0x1fff1) Apr 16 11:20:13 blackice kernel: [26287.228304] pcieport 0000:00:1f.0: restoring config space at offset 0x7 (was 0x101, writing 0x1f1) Apr 16 11:20:13 blackice kernel: [26287.228308] pcieport 0000:00:1f.0: restoring config space at offset 0x6 (was 0x0, writing 0x40400) Apr 16 11:20:13 blackice kernel: [26287.228315] pcieport 0000:00:1f.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004) Apr 16 11:20:13 blackice kernel: [26287.228321] pcieport 0000:00:1f.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) Apr 16 11:20:13 blackice kernel: [26287.228349] pci 0000:01:00.0: restoring config space at offset 0xf (was 0x100, writing 0x109) Apr 16 11:20:13 blackice kernel: [26287.228360] pci 0000:01:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x9001) Apr 16 11:20:13 blackice kernel: [26287.228363] pci 0000:01:00.0: restoring config space at offset 0x5 (was 0x0, writing 0xd8000000) Apr 16 11:20:13 blackice kernel: [26287.228367] pci 0000:01:00.0: restoring config space at offset 0x4 (was 0x8, writing 0xc0000008) Apr 16 11:20:13 blackice kernel: [26287.228372] pci 0000:01:00.0: restoring config space at offset 0x1 (was 0x2300000, writing 0x2300003) Apr 16 11:20:13 blackice kernel: [26287.228432] sdhci-pci 0000:03:00.0: restoring config space at offset 0x3 (was 0x800000, writing 0x800004) Apr 16 11:20:13 blackice kernel: [26287.228509] pci 0000:03:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x800004) Apr 16 11:20:13 blackice kernel: [26287.228517] pci 0000:03:00.2: restoring config space at offset 0x1 (was 0x100007, writing 0x100003) Apr 16 11:20:13 blackice kernel: [26287.228590] jmb38x_ms 0000:03:00.3: restoring config space at offset 0x3 (was 0x800000, writing 0x800004) Apr 16 11:20:13 blackice kernel: [26287.229026] PM: early resume of devices complete after 16.965 msecs Apr 16 11:20:13 blackice kernel: [26287.229108] pci 0000:00:01.0: setting latency timer to 64 Apr 16 11:20:13 blackice kernel: [26287.229127] pata_sis 0000:00:02.5: PCI INT A -> GSI 16 (level, low) -> IRQ 16 Apr 16 11:20:13 blackice kernel: [26287.229154] ohci_hcd 0000:00:03.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 Apr 16 11:20:13 blackice kernel: [26287.229215] ohci_hcd 0000:00:03.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 Apr 16 11:20:13 blackice kernel: [26287.229233] ehci_hcd 0000:00:03.3: PCI INT C -> GSI 22 (level, low) -> IRQ 22 Apr 16 11:20:13 blackice kernel: [26287.229266] sis190 0000:00:04.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19 Apr 16 11:20:13 blackice kernel: [26287.229272] sis190 0000:00:04.0: setting latency timer to 64 Apr 16 11:20:13 blackice kernel: [26287.229280] sata_sis 0000:00:05.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17 Apr 16 11:20:13 blackice kernel: [26287.229298] HDA Intel 0000:00:0f.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18 Apr 16 11:20:13 blackice kernel: [26287.229303] HDA Intel 0000:00:0f.0: setting latency timer to 64 Apr 16 11:20:13 blackice kernel: [26287.229357] sdhci-pci 0000:03:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 Apr 16 11:20:13 blackice kernel: [26287.229372] sdhci-pci 0000:03:00.0: setting latency timer to 64 Apr 16 11:20:13 blackice kernel: [26287.229396] jmb38x_ms 0000:03:00.3: PCI INT A -> GSI 16 (level, low) -> IRQ 16 Apr 16 11:20:13 blackice kernel: [26287.229403] jmb38x_ms 0000:03:00.3: setting latency timer to 64 Apr 16 11:20:13 blackice kernel: [26287.229434] ata2: port disabled. ignoring. Apr 16 11:20:13 blackice kernel: [26287.229994] sd 2:0:0:0: [sda] Starting disk Apr 16 11:20:13 blackice kernel: [26287.340053] PM: resume of drv:usb dev:usb2 complete after 110.592 msecs Apr 16 11:20:13 blackice kernel: [26287.340074] PM: resume of drv:hub dev:2-0:1.0 complete after 110.607 msecs Apr 16 11:20:13 blackice kernel: [26287.340083] PM: resume of drv:usb dev:usb3 complete after 110.601 msecs Apr 16 11:20:13 blackice kernel: [26287.340088] PM: resume of drv: dev:ep_00 complete after 110.611 msecs Apr 16 11:20:13 blackice kernel: [26287.340096] PM: resume of drv:hub dev:3-0:1.0 complete after 110.609 msecs Apr 16 11:20:13 blackice kernel: [26287.340100] PM: resume of drv: dev:ep_81 complete after 110.627 msecs Apr 16 11:20:13 blackice kernel: [26287.340108] PM: resume of drv: dev:ep_81 complete after 110.554 msecs Apr 16 11:20:13 blackice kernel: [26287.340112] PM: resume of drv: dev:ep_00 complete after 110.525 msecs Apr 16 11:20:13 blackice kernel: [26287.394399] PM: resume of drv:usb dev:usb1 complete after 164.961 msecs Apr 16 11:20:13 blackice kernel: [26287.394411] PM: resume of drv:hub dev:1-0:1.0 complete after 164.969 msecs Apr 16 11:20:13 blackice kernel: [26287.394428] PM: resume of drv: dev:ep_00 complete after 164.976 msecs Apr 16 11:20:13 blackice kernel: [26287.394441] PM: resume of drv: dev:ep_81 complete after 164.994 msecs Apr 16 11:20:13 blackice kernel: [26287.442500] PM: resume of drv:usb dev:1-7 complete after 180.462 msecs Apr 16 11:20:13 blackice kernel: [26287.442517] PM: resume of drv:uvcvideo dev:1-7:1.0 complete after 180.458 msecs Apr 16 11:20:13 blackice kernel: [26287.442539] PM: resume of drv: dev:ep_00 complete after 180.461 msecs Apr 16 11:20:13 blackice kernel: [26287.442543] PM: resume of drv:uvcvideo dev:1-7:1.1 complete after 180.468 msecs Apr 16 11:20:13 blackice kernel: [26287.442557] PM: resume of drv: dev:ep_82 complete after 180.491 msecs Apr 16 11:20:13 blackice kernel: [26287.504037] usb 1-2: reset high speed USB device using ehci_hcd and address 2 Apr 16 11:20:13 blackice kernel: [26287.638880] PM: resume of drv:usb dev:1-2 complete after 409.245 msecs Apr 16 11:20:13 blackice kernel: [26287.638911] PM: resume of drv:usb dev:1-2:1.0 complete after 409.268 msecs Apr 16 11:20:13 blackice kernel: [26287.638916] PM: resume of drv: dev:ep_00 complete after 408.953 msecs Apr 16 11:20:13 blackice kernel: [26287.638941] PM: resume of drv: dev:ep_83 complete after 409.268 msecs Apr 16 11:20:13 blackice kernel: [26287.638945] PM: resume of drv: dev:ep_04 complete after 409.240 msecs Apr 16 11:20:13 blackice kernel: [26287.638949] PM: resume of drv: dev:ep_05 complete after 409.214 msecs Apr 16 11:20:13 blackice kernel: [26287.638953] PM: resume of drv: dev:ep_07 complete after 409.154 msecs Apr 16 11:20:13 blackice kernel: [26287.638957] PM: resume of drv: dev:ep_06 complete after 409.189 msecs Apr 16 11:20:13 blackice kernel: [26287.638961] PM: resume of drv: dev:ep_0a complete after 409.097 msecs Apr 16 11:20:13 blackice kernel: [26287.638966] PM: resume of drv: dev:ep_89 complete after 409.133 msecs Apr 16 11:20:13 blackice kernel: [26287.638970] PM: resume of drv: dev:ep_0c complete after 409.044 msecs Apr 16 11:20:13 blackice kernel: [26287.638973] PM: resume of drv: dev:ep_0b complete after 409.079 msecs Apr 16 11:20:13 blackice kernel: [26318.048043] ata3: lost interrupt (Status 0x50) Apr 16 11:20:13 blackice kernel: [26318.048061] ata3.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen Apr 16 11:20:13 blackice kernel: [26318.048066] ata3.00: failed command: READ VERIFY SECTOR(S) Apr 16 11:20:13 blackice kernel: [26318.048075] ata3.00: cmd 40/00:01:00:00:00/00:00:00:00:00/e0 tag 0 Apr 16 11:20:13 blackice kernel: [26318.048077] res 40/00:ff:00:00:00/00:00:00:00:00/40 Emask 0x4 (timeout) Apr 16 11:20:13 blackice kernel: [26318.048081] ata3.00: status: { DRDY } Apr 16 11:20:13 blackice kernel: [26318.048107] ata3: soft resetting link Apr 16 11:20:13 blackice kernel: [26318.252361] ata3.00: configured for UDMA/133 Apr 16 11:20:13 blackice kernel: [26318.252367] ata3.00: device reported invalid CHS sector 0 Apr 16 11:20:13 blackice kernel: [26318.252387] ata3: EH complete Apr 16 11:20:13 blackice kernel: [26318.252394] sd 2:0:0:0: [sda] START_STOP FAILED Apr 16 11:20:13 blackice kernel: [26318.252398] sd 2:0:0:0: [sda] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE Apr 16 11:20:13 blackice kernel: [26318.252404] sd 2:0:0:0: [sda] Sense Key : Aborted Command [current] [descriptor] Apr 16 11:20:13 blackice kernel: [26318.252411] sd 2:0:0:0: [sda] Add. Sense: No additional sense information Apr 16 11:20:13 blackice kernel: [26318.252431] pm_op(): scsi_bus_resume_common+0x0/0x50 returns 134217730 Apr 16 11:20:13 blackice kernel: [26318.252445] PM: Device 2:0:0:0 failed to resume async: error 134217730 Apr 16 11:20:13 blackice kernel: [26318.252450] PM: resume of drv:scsi_disk dev:2:0:0:0 complete after 30953.869 msecs Apr 16 11:20:13 blackice kernel: [26318.252455] PM: resume of drv:scsi_device dev:2:0:0:0 complete after 31022.435 msecs Apr 16 11:20:13 blackice kernel: [26318.252576] PM: resume of devices complete after 31023.506 msecs Apr 16 11:20:13 blackice kernel: [26318.528207] cfg80211: Updating information on frequency 2412 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:20:13 blackice kernel: [26318.528212] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:20:13 blackice kernel: [26318.528216] cfg80211: Updating information on frequency 2417 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:20:13 blackice kernel: [26318.528221] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:20:13 blackice kernel: [26318.528225] cfg80211: Updating information on frequency 2422 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:20:13 blackice kernel: [26318.528229] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:20:13 blackice kernel: [26318.528232] cfg80211: Updating information on frequency 2427 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:20:13 blackice kernel: [26318.528237] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:20:13 blackice kernel: [26318.528240] cfg80211: Updating information on frequency 2432 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:20:13 blackice kernel: [26318.528245] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:20:13 blackice kernel: [26318.528248] cfg80211: Updating information on frequency 2437 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:20:13 blackice kernel: [26318.528253] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:20:13 blackice kernel: [26318.528257] cfg80211: Updating information on frequency 2442 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:20:13 blackice kernel: [26318.528261] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:20:13 blackice kernel: [26318.528265] cfg80211: Updating information on frequency 2447 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:20:13 blackice kernel: [26318.528269] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:20:13 blackice kernel: [26318.528273] cfg80211: Updating information on frequency 2452 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:20:13 blackice kernel: [26318.528277] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:20:13 blackice kernel: [26318.528281] cfg80211: Updating information on frequency 2457 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:20:13 blackice kernel: [26318.528285] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:20:13 blackice kernel: [26318.528289] cfg80211: Updating information on frequency 2462 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:20:13 blackice kernel: [26318.528293] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:20:13 blackice kernel: [26318.528297] cfg80211: Updating information on frequency 2467 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:20:13 blackice kernel: [26318.528301] cfg80211: 2457000 KHz - 2482000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:20:13 blackice kernel: [26318.528305] cfg80211: Updating information on frequency 2472 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:20:13 blackice kernel: [26318.528309] cfg80211: 2457000 KHz - 2482000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:20:13 blackice kernel: [26318.528313] cfg80211: Updating information on frequency 2484 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:20:13 blackice kernel: [26318.528317] cfg80211: 2474000 KHz - 2494000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:20:13 blackice kernel: [26318.528476] ieee80211 phy2: Selected rate control algorithm 'minstrel_ht' Apr 16 11:20:13 blackice kernel: [26318.529129] ieee80211 phy2: hwaddr 1c:4b:d6:26:6d:9b, RTL8187BvE V0 + rtl8225z2, rfkill mask 2 Apr 16 11:20:13 blackice kernel: [26318.550639] rtl8187: Customer ID is 0x00 Apr 16 11:20:13 blackice kernel: [26318.550696] Registered led device: rtl8187-phy2::radio Apr 16 11:20:13 blackice kernel: [26318.550737] Registered led device: rtl8187-phy2::tx Apr 16 11:20:13 blackice kernel: [26318.550783] Registered led device: rtl8187-phy2::rx Apr 16 11:20:13 blackice kernel: [26318.551503] rtl8187: wireless switch is on Apr 16 11:20:13 blackice kernel: [26318.551833] PM: resume devices took 31.320 seconds Apr 16 11:20:13 blackice kernel: [26318.551835] ------------[ cut here ]------------ Apr 16 11:20:13 blackice kernel: [26318.551843] WARNING: at /build/buildd/linux-2.6.38/kernel/power/suspend_test.c:53 suspend_test_finish+0x89/0x90() Apr 16 11:20:13 blackice kernel: [26318.551845] Hardware name: POSITIVO MOBILE Apr 16 11:20:13 blackice kernel: [26318.551847] Component: resume devices, time: 31320 Apr 16 11:20:13 blackice kernel: [26318.551848] Modules linked in: binfmt_misc parport_pc ppdev dm_crypt vesafb snd_hda_codec_si3054 snd_hda_codec_realtek joydev snd_hda_intel snd_hda_codec arc4 lp uvcvideo videodev snd_hwdep snd_pcm snd_seq_midi psmouse snd_rawmidi jmb38x_ms rtl8187 snd_seq_midi_event mac80211 snd_seq snd_timer snd_seq_device cfg80211 memstick eeprom_93cx6 shpchp serio_raw snd parport soundcore snd_page_alloc video sis190 sdhci_pci sata_sis sis_agp sdhci Apr 16 11:20:13 blackice kernel: [26318.551879] Pid: 26117, comm: pm-suspend Tainted: G W 2.6.38-8-generic-pae #42-Ubuntu Apr 16 11:20:13 blackice kernel: [26318.551882] Call Trace: Apr 16 11:20:13 blackice kernel: [26318.551888] [] ? warn_slowpath_common+0x72/0xa0 Apr 16 11:20:13 blackice kernel: [26318.551892] [] ? suspend_test_finish+0x89/0x90 Apr 16 11:20:13 blackice kernel: [26318.551894] [] ? suspend_test_finish+0x89/0x90 Apr 16 11:20:13 blackice kernel: [26318.551898] [] ? warn_slowpath_fmt+0x33/0x40 Apr 16 11:20:13 blackice kernel: [26318.551900] [] ? suspend_test_finish+0x89/0x90 Apr 16 11:20:13 blackice kernel: [26318.551904] [] ? suspend_devices_and_enter+0x9e/0x150 Apr 16 11:20:13 blackice kernel: [26318.551906] [] ? enter_state+0x101/0x120 Apr 16 11:20:13 blackice kernel: [26318.551909] [] ? state_store+0x86/0xc0 Apr 16 11:20:13 blackice kernel: [26318.551912] [] ? state_store+0x0/0xc0 Apr 16 11:20:13 blackice kernel: [26318.551916] [] ? kobj_attr_store+0x20/0x30 Apr 16 11:20:13 blackice kernel: [26318.551921] [] ? sysfs_write_file+0x9e/0x100 Apr 16 11:20:13 blackice kernel: [26318.551925] [] ? vfs_write+0xa2/0x170 Apr 16 11:20:13 blackice kernel: [26318.551927] [] ? sysfs_write_file+0x0/0x100 Apr 16 11:20:13 blackice kernel: [26318.551930] [] ? sys_write+0x42/0x70 Apr 16 11:20:13 blackice kernel: [26318.551934] [] ? sysenter_do_call+0x12/0x28 Apr 16 11:20:13 blackice kernel: [26318.551936] ---[ end trace b37694dd17db0707 ]--- Apr 16 11:20:13 blackice kernel: [26318.551980] PM: Finishing wakeup. Apr 16 11:20:13 blackice NetworkManager[903]: radio killswitch /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/ieee80211/phy1/rfkill1 disappeared Apr 16 11:20:13 blackice kernel: [26318.551981] Restarting tasks ... done. Apr 16 11:20:13 blackice kernel: [26318.606495] video LNXVIDEO:00: Restoring backlight state Apr 16 11:20:13 blackice NetworkManager[903]: SCPlugin-Ifupdown: devices removed (path: /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/net/wlan0, iface: wlan0) Apr 16 11:20:14 blackice NetworkManager[903]: found WiFi radio killswitch rfkill2 (at /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/ieee80211/phy2/rfkill2) (driver ) Apr 16 11:20:14 blackice NetworkManager[903]: (wlan0): driver supports SSID scans (scan_capa 0x01). Apr 16 11:20:14 blackice NetworkManager[903]: (wlan0): new 802.11 WiFi device (driver: 'rtl8187' ifindex: 9) Apr 16 11:20:14 blackice NetworkManager[903]: (wlan0): exported as /org/freedesktop/NetworkManager/Devices/3 Apr 16 11:20:14 blackice NetworkManager[903]: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/net/wlan0, iface: wlan0) Apr 16 11:20:14 blackice NetworkManager[903]: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/net/wlan0, iface: wlan0): no ifupdown configuration found. Apr 16 11:20:17 blackice NetworkManager[903]: wake requested (sleeping: yes enabled: yes) Apr 16 11:20:17 blackice NetworkManager[903]: waking up and re-enabling... Apr 16 11:20:17 blackice NetworkManager[903]: (eth0): now managed Apr 16 11:20:17 blackice NetworkManager[903]: (eth0): device state change: 1 -> 2 (reason 2) Apr 16 11:20:17 blackice NetworkManager[903]: (eth0): bringing up device. Apr 16 11:20:17 blackice NetworkManager[903]: (eth0): preparing device. Apr 16 11:20:17 blackice NetworkManager[903]: (eth0): deactivating device (reason: 2). Apr 16 11:20:17 blackice NetworkManager[903]: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Apr 16 11:20:17 blackice NetworkManager[903]: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Apr 16 11:20:17 blackice NetworkManager[903]: (wlan0): now managed Apr 16 11:20:17 blackice NetworkManager[903]: (wlan0): device state change: 1 -> 2 (reason 2) Apr 16 11:20:17 blackice NetworkManager[903]: (wlan0): bringing up device. Apr 16 11:20:17 blackice kernel: [26322.204899] ADDRCONF(NETDEV_UP): eth0: link is not ready Apr 16 11:20:21 blackice kernel: [26326.054499] ADDRCONF(NETDEV_UP): wlan0: link is not ready Apr 16 11:20:21 blackice NetworkManager[903]: (wlan0): preparing device. Apr 16 11:20:21 blackice NetworkManager[903]: (wlan0): deactivating device (reason: 2). Apr 16 11:20:21 blackice NetworkManager[903]: (wlan0): supplicant interface state: starting -> ready Apr 16 11:20:21 blackice NetworkManager[903]: (wlan0): device state change: 2 -> 3 (reason 42) Apr 16 11:20:22 blackice kernel: [26327.303265] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0 Apr 16 11:20:22 blackice kernel: [26327.469913] EXT4-fs (sda5): re-mounted. Opts: commit=0 Apr 16 11:20:23 blackice kernel: [26327.755328] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=0 Apr 16 11:20:23 blackice NetworkManager[903]: Activation (wlan0) starting connection 'Auto valdergallo' Apr 16 11:20:23 blackice NetworkManager[903]: (wlan0): device state change: 3 -> 4 (reason 0) Apr 16 11:20:23 blackice NetworkManager[903]: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Apr 16 11:20:23 blackice NetworkManager[903]: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Apr 16 11:20:23 blackice NetworkManager[903]: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Apr 16 11:20:23 blackice NetworkManager[903]: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Apr 16 11:20:23 blackice NetworkManager[903]: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Apr 16 11:20:23 blackice NetworkManager[903]: (wlan0): device state change: 4 -> 5 (reason 0) Apr 16 11:20:23 blackice NetworkManager[903]: Activation (wlan0/wireless): access point 'Auto valdergallo' has security, but secrets are required. Apr 16 11:20:23 blackice NetworkManager[903]: (wlan0): device state change: 5 -> 6 (reason 0) Apr 16 11:20:23 blackice NetworkManager[903]: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Apr 16 11:20:23 blackice NetworkManager[903]: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Apr 16 11:20:23 blackice NetworkManager[903]: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Apr 16 11:20:23 blackice NetworkManager[903]: (wlan0): device state change: 6 -> 4 (reason 0) Apr 16 11:20:23 blackice NetworkManager[903]: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Apr 16 11:20:23 blackice NetworkManager[903]: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Apr 16 11:20:23 blackice NetworkManager[903]: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Apr 16 11:20:23 blackice NetworkManager[903]: (wlan0): device state change: 4 -> 5 (reason 0) Apr 16 11:20:23 blackice NetworkManager[903]: Activation (wlan0/wireless): connection 'Auto valdergallo' has security, and secrets exist. No new secrets needed. Apr 16 11:20:23 blackice NetworkManager[903]: Config: added 'ssid' value 'valdergallo' Apr 16 11:20:23 blackice NetworkManager[903]: Config: added 'scan_ssid' value '1' Apr 16 11:20:23 blackice NetworkManager[903]: Config: added 'key_mgmt' value 'WPA-PSK' Apr 16 11:20:23 blackice NetworkManager[903]: Config: added 'psk' value '' Apr 16 11:20:23 blackice NetworkManager[903]: nm_setting_802_1x_get_pkcs11_engine_path: assertion `NM_IS_SETTING_802_1X (setting)' failed Apr 16 11:20:23 blackice NetworkManager[903]: nm_setting_802_1x_get_pkcs11_module_path: assertion `NM_IS_SETTING_802_1X (setting)' failed Apr 16 11:20:23 blackice NetworkManager[903]: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Apr 16 11:20:23 blackice NetworkManager[903]: Config: set interface ap_scan to 1 Apr 16 11:20:23 blackice NetworkManager[903]: (wlan0): supplicant connection state: inactive -> scanning Apr 16 11:20:24 blackice NetworkManager[903]: sleep requested (sleeping: no enabled: yes) Apr 16 11:20:24 blackice NetworkManager[903]: sleeping or disabling... Apr 16 11:20:24 blackice NetworkManager[903]: (eth0): now unmanaged Apr 16 11:20:24 blackice NetworkManager[903]: (eth0): device state change: 2 -> 1 (reason 37) Apr 16 11:20:24 blackice NetworkManager[903]: (eth0): cleaning up... Apr 16 11:20:24 blackice NetworkManager[903]: (eth0): taking down device. Apr 16 11:20:24 blackice NetworkManager[903]: (wlan0): now unmanaged Apr 16 11:20:24 blackice NetworkManager[903]: (wlan0): device state change: 5 -> 1 (reason 37) Apr 16 11:20:24 blackice NetworkManager[903]: (wlan0): deactivating device (reason: 37). Apr 16 11:20:24 blackice NetworkManager[903]: (wlan0): cleaning up... Apr 16 11:20:24 blackice NetworkManager[903]: (wlan0): taking down device. Apr 16 11:20:25 blackice kernel: [26330.181350] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0 Apr 16 11:20:25 blackice kernel: [26330.287380] EXT4-fs (sda5): re-mounted. Opts: commit=0 Apr 16 11:20:25 blackice kernel: [26330.435528] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=0 Apr 16 11:21:10 blackice kernel: [26336.997181] PM: Syncing filesystems ... done. Apr 16 11:21:10 blackice kernel: [26337.171042] PM: Preparing system for mem sleep Apr 16 11:21:10 blackice kernel: [26337.171061] Freezing user space processes ... (elapsed 0.01 seconds) done. Apr 16 11:21:10 blackice kernel: [26337.188135] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. Apr 16 11:21:10 blackice kernel: [26337.204101] PM: Entering mem sleep Apr 16 11:21:10 blackice kernel: [26337.204125] Suspending console(s) (use no_console_suspend to debug) Apr 16 11:21:10 blackice kernel: [26337.204692] sd 2:0:0:0: [sda] Synchronizing SCSI cache Apr 16 11:21:10 blackice kernel: [26337.204919] sd 2:0:0:0: [sda] Stopping disk Apr 16 11:21:10 blackice kernel: [26337.247255] ACPI handle has no context! Apr 16 11:21:10 blackice kernel: [26337.247270] jmb38x_ms 0000:03:00.3: PCI INT A disabled Apr 16 11:21:10 blackice kernel: [26337.247282] ACPI handle has no context! Apr 16 11:21:10 blackice kernel: [26337.247283] ACPI handle has no context! Apr 16 11:21:10 blackice kernel: [26337.247294] sdhci-pci 0000:03:00.0: PCI INT A disabled Apr 16 11:21:10 blackice kernel: [26337.247302] ACPI handle has no context! Apr 16 11:21:10 blackice kernel: [26337.247345] sis190 0000:00:04.0: PCI INT A disabled Apr 16 11:21:10 blackice kernel: [26337.247775] ohci_hcd 0000:00:03.1: PCI INT B disabled Apr 16 11:21:10 blackice kernel: [26337.247776] ohci_hcd 0000:00:03.0: PCI INT A disabled Apr 16 11:21:10 blackice kernel: [26337.247856] pata_sis 0000:00:02.5: PCI INT A disabled Apr 16 11:21:10 blackice kernel: [26337.332017] usb 1-2: reset high speed USB device using ehci_hcd and address 2 Apr 16 11:21:10 blackice kernel: [26337.452075] HDA Intel 0000:00:0f.0: PCI INT A disabled Apr 16 11:21:10 blackice kernel: [26337.452102] ACPI handle has no context! Apr 16 11:21:10 blackice kernel: [26337.468013] PM: suspend of drv:HDA Intel dev:0000:00:0f.0 complete after 220.684 msecs Apr 16 11:21:10 blackice kernel: [26337.480013] PM: suspend of drv:usb dev:1-2 complete after 275.198 msecs Apr 16 11:21:10 blackice kernel: [26337.480602] PM: suspend of drv:usb dev:usb1 complete after 233.886 msecs Apr 16 11:21:10 blackice kernel: [26337.496022] ehci_hcd 0000:00:03.3: PCI INT C disabled Apr 16 11:21:10 blackice kernel: [26337.496026] PM: suspend of drv:ehci_hcd dev:0000:00:03.3 complete after 248.676 msecs Apr 16 11:21:10 blackice kernel: [26338.201871] PM: suspend of drv:sd dev:2:0:0:0 complete after 997.185 msecs Apr 16 11:21:10 blackice kernel: [26338.201882] PM: suspend of drv:scsi dev:target2:0:0 complete after 997.117 msecs Apr 16 11:21:10 blackice kernel: [26338.201906] PM: suspend of drv:scsi dev:host2 complete after 996.903 msecs Apr 16 11:21:10 blackice kernel: [26338.201918] sata_sis 0000:00:05.0: PCI INT A disabled Apr 16 11:21:10 blackice kernel: [26338.201921] PM: suspend of drv:sata_sis dev:0000:00:05.0 complete after 954.585 msecs Apr 16 11:21:10 blackice kernel: [26338.201930] PM: suspend of drv: dev:pci0000:00 complete after 954.096 msecs Apr 16 11:21:10 blackice kernel: [26338.201938] PM: suspend of devices complete after 997.415 msecs Apr 16 11:21:10 blackice kernel: [26338.201940] PM: suspend devices took 0.996 seconds Apr 16 11:21:10 blackice kernel: [26338.216155] PM: late suspend of devices complete after 14.211 msecs Apr 16 11:21:10 blackice kernel: [26338.216344] ACPI: Preparing to enter system sleep state S3 Apr 16 11:21:10 blackice kernel: [26338.257320] PM: Saving platform NVS memory Apr 16 11:21:10 blackice kernel: [26338.257348] Disabling non-boot CPUs ... Apr 16 11:21:10 blackice kernel: [26338.360031] CPU 1 is now offline Apr 16 11:21:10 blackice kernel: [26338.360453] Extended CMOS year: 2000 Apr 16 11:21:10 blackice kernel: [26338.360453] Back to C! Apr 16 11:21:10 blackice kernel: [26338.360453] PM: Restoring platform NVS memory Apr 16 11:21:10 blackice kernel: [26338.360453] Extended CMOS year: 2000 Apr 16 11:21:10 blackice kernel: [26338.360453] Enabling non-boot CPUs ... Apr 16 11:21:10 blackice kernel: [26338.360453] Booting Node 0 Processor 1 APIC 0x1 Apr 16 11:21:10 blackice kernel: [26338.258637] Initializing CPU#1 Apr 16 11:21:10 blackice kernel: [26338.448550] CPU1 is up Apr 16 11:21:10 blackice kernel: [26338.448992] ACPI: Waking up from system sleep state S3 Apr 16 11:21:10 blackice kernel: [26338.452022] Switched to NOHz mode on CPU #1 Apr 16 11:21:10 blackice kernel: [26338.704183] pci 0000:00:01.0: restoring config space at offset 0x7 (was 0x209090, writing 0x20209090) Apr 16 11:21:10 blackice kernel: [26338.704228] pata_sis 0000:00:02.5: restoring config space at offset 0xf (was 0x1ff, writing 0x0) Apr 16 11:21:10 blackice kernel: [26338.704241] pata_sis 0000:00:02.5: restoring config space at offset 0x7 (was 0x375, writing 0x1) Apr 16 11:21:10 blackice kernel: [26338.704245] pata_sis 0000:00:02.5: restoring config space at offset 0x6 (was 0x171, writing 0x1) Apr 16 11:21:10 blackice kernel: [26338.704250] pata_sis 0000:00:02.5: restoring config space at offset 0x5 (was 0x3f5, writing 0x1) Apr 16 11:21:10 blackice kernel: [26338.704254] pata_sis 0000:00:02.5: restoring config space at offset 0x4 (was 0x1f1, writing 0x1) Apr 16 11:21:10 blackice kernel: [26338.704260] pata_sis 0000:00:02.5: restoring config space at offset 0x2 (was 0x1018a01, writing 0x1018001) Apr 16 11:21:10 blackice kernel: [26338.704291] ohci_hcd 0000:00:03.0: restoring config space at offset 0x1 (was 0x2800017, writing 0x2800013) Apr 16 11:21:10 blackice kernel: [26338.704317] ohci_hcd 0000:00:03.1: restoring config space at offset 0x1 (was 0x2800017, writing 0x2800013) Apr 16 11:21:10 blackice kernel: [26338.720019] ehci_hcd 0000:00:03.3: BAR 0: set to [mem 0xd8206000-0xd8206fff] (PCI address [0xd8206000-0xd8206fff]) Apr 16 11:21:10 blackice kernel: [26338.720044] ehci_hcd 0000:00:03.3: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) Apr 16 11:21:10 blackice kernel: [26338.720078] sis190 0000:00:04.0: restoring config space at offset 0x1 (was 0x2100007, writing 0x2100003) Apr 16 11:21:10 blackice kernel: [26338.720111] sata_sis 0000:00:05.0: restoring config space at offset 0x1 (was 0x2100005, writing 0x2100001) Apr 16 11:21:10 blackice kernel: [26338.720145] pcieport 0000:00:06.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xd861d851) Apr 16 11:21:10 blackice kernel: [26338.720155] pcieport 0000:00:06.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004) Apr 16 11:21:10 blackice kernel: [26338.720161] pcieport 0000:00:06.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) Apr 16 11:21:10 blackice kernel: [26338.720203] pcieport 0000:00:07.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xd881d871) Apr 16 11:21:10 blackice kernel: [26338.720209] pcieport 0000:00:07.0: restoring config space at offset 0x7 (was 0x200001f1, writing 0x2121) Apr 16 11:21:10 blackice kernel: [26338.720216] pcieport 0000:00:07.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004) Apr 16 11:21:10 blackice kernel: [26338.720222] pcieport 0000:00:07.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) Apr 16 11:21:10 blackice kernel: [26338.720264] HDA Intel 0000:00:0f.0: restoring config space at offset 0x1 (was 0x2100006, writing 0x2100002) Apr 16 11:21:10 blackice kernel: [26338.720287] pcieport 0000:00:1f.0: restoring config space at offset 0xf (was 0x0, writing 0x400ff) Apr 16 11:21:10 blackice kernel: [26338.720298] pcieport 0000:00:1f.0: restoring config space at offset 0x9 (was 0x1ff01, writing 0x1fff1) Apr 16 11:21:10 blackice kernel: [26338.720304] pcieport 0000:00:1f.0: restoring config space at offset 0x7 (was 0x101, writing 0x1f1) Apr 16 11:21:10 blackice kernel: [26338.720309] pcieport 0000:00:1f.0: restoring config space at offset 0x6 (was 0x0, writing 0x40400) Apr 16 11:21:10 blackice kernel: [26338.720316] pcieport 0000:00:1f.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004) Apr 16 11:21:10 blackice kernel: [26338.720322] pcieport 0000:00:1f.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) Apr 16 11:21:10 blackice kernel: [26338.720350] pci 0000:01:00.0: restoring config space at offset 0xf (was 0x100, writing 0x109) Apr 16 11:21:10 blackice kernel: [26338.720360] pci 0000:01:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x9001) Apr 16 11:21:10 blackice kernel: [26338.720363] pci 0000:01:00.0: restoring config space at offset 0x5 (was 0x0, writing 0xd8000000) Apr 16 11:21:10 blackice kernel: [26338.720367] pci 0000:01:00.0: restoring config space at offset 0x4 (was 0x8, writing 0xc0000008) Apr 16 11:21:10 blackice kernel: [26338.720373] pci 0000:01:00.0: restoring config space at offset 0x1 (was 0x2300000, writing 0x2300003) Apr 16 11:21:10 blackice kernel: [26338.720433] sdhci-pci 0000:03:00.0: restoring config space at offset 0x3 (was 0x800000, writing 0x800004) Apr 16 11:21:10 blackice kernel: [26338.720509] pci 0000:03:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x800004) Apr 16 11:21:10 blackice kernel: [26338.720518] pci 0000:03:00.2: restoring config space at offset 0x1 (was 0x100007, writing 0x100003) Apr 16 11:21:10 blackice kernel: [26338.720591] jmb38x_ms 0000:03:00.3: restoring config space at offset 0x3 (was 0x800000, writing 0x800004) Apr 16 11:21:10 blackice kernel: [26338.721028] PM: early resume of devices complete after 16.967 msecs Apr 16 11:21:10 blackice kernel: [26338.721116] pci 0000:00:01.0: setting latency timer to 64 Apr 16 11:21:10 blackice kernel: [26338.721135] pata_sis 0000:00:02.5: PCI INT A -> GSI 16 (level, low) -> IRQ 16 Apr 16 11:21:10 blackice kernel: [26338.721161] ohci_hcd 0000:00:03.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 Apr 16 11:21:10 blackice kernel: [26338.721209] ohci_hcd 0000:00:03.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 Apr 16 11:21:10 blackice kernel: [26338.721244] ehci_hcd 0000:00:03.3: PCI INT C -> GSI 22 (level, low) -> IRQ 22 Apr 16 11:21:10 blackice kernel: [26338.721271] sis190 0000:00:04.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19 Apr 16 11:21:10 blackice kernel: [26338.721276] sis190 0000:00:04.0: setting latency timer to 64 Apr 16 11:21:10 blackice kernel: [26338.721285] sata_sis 0000:00:05.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17 Apr 16 11:21:10 blackice kernel: [26338.721307] HDA Intel 0000:00:0f.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18 Apr 16 11:21:10 blackice kernel: [26338.721315] HDA Intel 0000:00:0f.0: setting latency timer to 64 Apr 16 11:21:10 blackice kernel: [26338.721352] sdhci-pci 0000:03:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 Apr 16 11:21:10 blackice kernel: [26338.721367] sdhci-pci 0000:03:00.0: setting latency timer to 64 Apr 16 11:21:10 blackice kernel: [26338.721390] jmb38x_ms 0000:03:00.3: PCI INT A -> GSI 16 (level, low) -> IRQ 16 Apr 16 11:21:10 blackice kernel: [26338.721397] jmb38x_ms 0000:03:00.3: setting latency timer to 64 Apr 16 11:21:10 blackice kernel: [26338.721426] ata2: port disabled. ignoring. Apr 16 11:21:10 blackice kernel: [26338.721586] sd 2:0:0:0: [sda] Starting disk Apr 16 11:21:10 blackice kernel: [26338.824049] PM: resume of drv:usb dev:usb3 complete after 102.557 msecs Apr 16 11:21:10 blackice kernel: [26338.824083] PM: resume of drv:hub dev:3-0:1.0 complete after 102.587 msecs Apr 16 11:21:10 blackice kernel: [26338.824088] PM: resume of drv: dev:ep_00 complete after 102.577 msecs Apr 16 11:21:10 blackice kernel: [26338.824097] PM: resume of drv: dev:ep_81 complete after 102.598 msecs Apr 16 11:21:10 blackice kernel: [26338.832046] PM: resume of drv:usb dev:usb2 complete after 110.579 msecs Apr 16 11:21:10 blackice kernel: [26338.832075] PM: resume of drv:hub dev:2-0:1.0 complete after 110.600 msecs Apr 16 11:21:10 blackice kernel: [26338.832080] PM: resume of drv: dev:ep_00 complete after 110.590 msecs Apr 16 11:21:10 blackice kernel: [26338.832088] PM: resume of drv: dev:ep_81 complete after 110.610 msecs Apr 16 11:21:10 blackice kernel: [26338.884043] PM: resume of drv:usb dev:usb1 complete after 162.607 msecs Apr 16 11:21:10 blackice kernel: [26338.884063] PM: resume of drv:hub dev:1-0:1.0 complete after 162.611 msecs Apr 16 11:21:10 blackice kernel: [26338.884077] PM: resume of drv: dev:ep_00 complete after 162.615 msecs Apr 16 11:21:10 blackice kernel: [26338.884083] PM: resume of drv: dev:ep_81 complete after 162.627 msecs Apr 16 11:21:10 blackice kernel: [26338.934683] PM: resume of drv:usb dev:1-7 complete after 211.788 msecs Apr 16 11:21:10 blackice kernel: [26338.934701] PM: resume of drv:uvcvideo dev:1-7:1.0 complete after 211.801 msecs Apr 16 11:21:10 blackice kernel: [26338.934720] PM: resume of drv: dev:ep_00 complete after 211.740 msecs Apr 16 11:21:10 blackice kernel: [26338.934725] PM: resume of drv:uvcvideo dev:1-7:1.1 complete after 211.773 msecs Apr 16 11:21:10 blackice kernel: [26338.934729] PM: resume of drv: dev:ep_82 complete after 211.818 msecs Apr 16 11:21:10 blackice kernel: [26338.996041] usb 1-2: reset high speed USB device using ehci_hcd and address 2 Apr 16 11:21:10 blackice kernel: [26338.997719] PM: resume of drv:thermal dev:LNXTHERM:00 complete after 252.256 msecs Apr 16 11:21:10 blackice kernel: [26339.130940] PM: resume of drv:usb dev:1-2 complete after 409.409 msecs Apr 16 11:21:10 blackice kernel: [26339.130958] PM: resume of drv:usb dev:1-2:1.0 complete after 409.426 msecs Apr 16 11:21:10 blackice kernel: [26339.130979] PM: resume of drv: dev:ep_00 complete after 409.403 msecs Apr 16 11:21:10 blackice kernel: [26339.130999] PM: resume of drv: dev:ep_83 complete after 409.464 msecs Apr 16 11:21:10 blackice kernel: [26339.131003] PM: resume of drv: dev:ep_04 complete after 409.462 msecs Apr 16 11:21:10 blackice kernel: [26339.131007] PM: resume of drv: dev:ep_05 complete after 409.466 msecs Apr 16 11:21:10 blackice kernel: [26339.131011] PM: resume of drv: dev:ep_06 complete after 409.466 msecs Apr 16 11:21:10 blackice kernel: [26339.131015] PM: resume of drv: dev:ep_89 complete after 409.460 msecs Apr 16 11:21:10 blackice kernel: [26339.131019] PM: resume of drv: dev:ep_07 complete after 409.469 msecs Apr 16 11:21:10 blackice kernel: [26339.131023] PM: resume of drv: dev:ep_0b complete after 409.458 msecs Apr 16 11:21:10 blackice kernel: [26339.131028] PM: resume of drv: dev:ep_0a complete after 409.467 msecs Apr 16 11:21:10 blackice kernel: [26339.131045] PM: resume of drv: dev:ep_0c complete after 409.475 msecs Apr 16 11:21:10 blackice kernel: [26369.000040] ata3: lost interrupt (Status 0x50) Apr 16 11:21:10 blackice kernel: [26369.000061] ata3.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen Apr 16 11:21:10 blackice kernel: [26369.000066] ata3.00: failed command: READ VERIFY SECTOR(S) Apr 16 11:21:10 blackice kernel: [26369.000076] ata3.00: cmd 40/00:01:00:00:00/00:00:00:00:00/e0 tag 0 Apr 16 11:21:10 blackice kernel: [26369.000077] res 40/00:ff:00:00:00/00:00:00:00:00/40 Emask 0x4 (timeout) Apr 16 11:21:10 blackice kernel: [26369.000081] ata3.00: status: { DRDY } Apr 16 11:21:10 blackice kernel: [26369.000110] ata3: soft resetting link Apr 16 11:21:10 blackice kernel: [26369.204363] ata3.00: configured for UDMA/133 Apr 16 11:21:10 blackice kernel: [26369.204369] ata3.00: device reported invalid CHS sector 0 Apr 16 11:21:10 blackice kernel: [26369.204388] ata3: EH complete Apr 16 11:21:10 blackice kernel: [26369.204396] sd 2:0:0:0: [sda] START_STOP FAILED Apr 16 11:21:10 blackice kernel: [26369.204400] sd 2:0:0:0: [sda] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE Apr 16 11:21:10 blackice kernel: [26369.204406] sd 2:0:0:0: [sda] Sense Key : Aborted Command [current] [descriptor] Apr 16 11:21:10 blackice kernel: [26369.204413] sd 2:0:0:0: [sda] Add. Sense: No additional sense information Apr 16 11:21:10 blackice kernel: [26369.204434] pm_op(): scsi_bus_resume_common+0x0/0x50 returns 134217730 Apr 16 11:21:10 blackice kernel: [26369.204449] PM: Device 2:0:0:0 failed to resume async: error 134217730 Apr 16 11:21:10 blackice kernel: [26369.204454] PM: resume of drv:scsi_device dev:2:0:0:0 complete after 30482.867 msecs Apr 16 11:21:10 blackice kernel: [26369.204459] PM: resume of drv:scsi_disk dev:2:0:0:0 complete after 30199.425 msecs Apr 16 11:21:10 blackice kernel: [26369.204588] PM: resume of devices complete after 30483.511 msecs Apr 16 11:21:10 blackice kernel: [26369.480249] cfg80211: Updating information on frequency 2412 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:21:10 blackice kernel: [26369.480254] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:21:10 blackice kernel: [26369.480258] cfg80211: Updating information on frequency 2417 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:21:10 blackice kernel: [26369.480262] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:21:10 blackice kernel: [26369.480266] cfg80211: Updating information on frequency 2422 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:21:10 blackice kernel: [26369.480271] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:21:10 blackice kernel: [26369.480275] cfg80211: Updating information on frequency 2427 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:21:10 blackice kernel: [26369.480279] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:21:10 blackice kernel: [26369.480283] cfg80211: Updating information on frequency 2432 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:21:10 blackice kernel: [26369.480288] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:21:10 blackice kernel: [26369.480292] cfg80211: Updating information on frequency 2437 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:21:10 blackice kernel: [26369.480296] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:21:10 blackice kernel: [26369.480300] cfg80211: Updating information on frequency 2442 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:21:10 blackice kernel: [26369.480304] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:21:10 blackice kernel: [26369.480308] cfg80211: Updating information on frequency 2447 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:21:10 blackice kernel: [26369.480312] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:21:10 blackice kernel: [26369.480316] cfg80211: Updating information on frequency 2452 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:21:10 blackice kernel: [26369.480320] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:21:10 blackice kernel: [26369.480324] cfg80211: Updating information on frequency 2457 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:21:10 blackice kernel: [26369.480328] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:21:10 blackice kernel: [26369.480332] cfg80211: Updating information on frequency 2462 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:21:10 blackice kernel: [26369.480336] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:21:10 blackice kernel: [26369.480340] cfg80211: Updating information on frequency 2467 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:21:10 blackice kernel: [26369.480344] cfg80211: 2457000 KHz - 2482000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:21:10 blackice kernel: [26369.480348] cfg80211: Updating information on frequency 2472 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:21:10 blackice kernel: [26369.480352] cfg80211: 2457000 KHz - 2482000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:21:10 blackice kernel: [26369.480356] cfg80211: Updating information on frequency 2484 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:21:10 blackice kernel: [26369.480361] cfg80211: 2474000 KHz - 2494000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:21:10 blackice kernel: [26369.480518] ieee80211 phy3: Selected rate control algorithm 'minstrel_ht' Apr 16 11:21:10 blackice kernel: [26369.481169] ieee80211 phy3: hwaddr 1c:4b:d6:26:6d:9b, RTL8187BvE V0 + rtl8225z2, rfkill mask 2 Apr 16 11:21:10 blackice kernel: [26369.502679] rtl8187: Customer ID is 0x00 Apr 16 11:21:10 blackice kernel: [26369.502738] Registered led device: rtl8187-phy3::radio Apr 16 11:21:10 blackice kernel: [26369.502780] Registered led device: rtl8187-phy3::tx Apr 16 11:21:10 blackice kernel: [26369.502822] Registered led device: rtl8187-phy3::rx Apr 16 11:21:10 blackice kernel: [26369.503543] rtl8187: wireless switch is on Apr 16 11:21:10 blackice kernel: [26369.503879] PM: resume devices took 30.780 seconds Apr 16 11:21:10 blackice kernel: [26369.503881] ------------[ cut here ]------------ Apr 16 11:21:10 blackice kernel: [26369.503888] WARNING: at /build/buildd/linux-2.6.38/kernel/power/suspend_test.c:53 suspend_test_finish+0x89/0x90() Apr 16 11:21:10 blackice kernel: [26369.503891] Hardware name: POSITIVO MOBILE Apr 16 11:21:10 blackice kernel: [26369.503892] Component: resume devices, time: 30780 Apr 16 11:21:10 blackice kernel: [26369.503894] Modules linked in: binfmt_misc parport_pc ppdev dm_crypt vesafb snd_hda_codec_si3054 snd_hda_codec_realtek joydev snd_hda_intel snd_hda_codec arc4 lp uvcvideo videodev snd_hwdep snd_pcm snd_seq_midi psmouse snd_rawmidi jmb38x_ms rtl8187 snd_seq_midi_event mac80211 snd_seq snd_timer snd_seq_device cfg80211 memstick eeprom_93cx6 shpchp serio_raw snd parport soundcore snd_page_alloc video sis190 sdhci_pci sata_sis sis_agp sdhci Apr 16 11:21:10 blackice kernel: [26369.503926] Pid: 26909, comm: pm-suspend Tainted: G W 2.6.38-8-generic-pae #42-Ubuntu Apr 16 11:21:10 blackice kernel: [26369.503928] Call Trace: Apr 16 11:21:10 blackice kernel: [26369.503934] [] ? warn_slowpath_common+0x72/0xa0 Apr 16 11:21:10 blackice kernel: [26369.503937] [] ? suspend_test_finish+0x89/0x90 Apr 16 11:21:10 blackice kernel: [26369.503940] [] ? suspend_test_finish+0x89/0x90 Apr 16 11:21:10 blackice kernel: [26369.503943] [] ? warn_slowpath_fmt+0x33/0x40 Apr 16 11:21:10 blackice kernel: [26369.503946] [] ? suspend_test_finish+0x89/0x90 Apr 16 11:21:10 blackice kernel: [26369.503949] [] ? suspend_devices_and_enter+0x9e/0x150 Apr 16 11:21:10 blackice kernel: [26369.503952] [] ? enter_state+0x101/0x120 Apr 16 11:21:10 blackice kernel: [26369.503954] [] ? state_store+0x86/0xc0 Apr 16 11:21:10 blackice kernel: [26369.503957] [] ? state_store+0x0/0xc0 Apr 16 11:21:10 blackice kernel: [26369.503961] [] ? kobj_attr_store+0x20/0x30 Apr 16 11:21:10 blackice kernel: [26369.503966] [] ? sysfs_write_file+0x9e/0x100 Apr 16 11:21:10 blackice kernel: [26369.503970] [] ? vfs_write+0xa2/0x170 Apr 16 11:21:10 blackice kernel: [26369.503973] [] ? sysfs_write_file+0x0/0x100 Apr 16 11:21:10 blackice kernel: [26369.503976] [] ? sys_write+0x42/0x70 Apr 16 11:21:10 blackice kernel: [26369.503979] [] ? sysenter_do_call+0x12/0x28 Apr 16 11:21:10 blackice kernel: [26369.503982] ---[ end trace b37694dd17db0708 ]--- Apr 16 11:21:10 blackice kernel: [26369.504055] PM: Finishing wakeup. Apr 16 11:21:10 blackice kernel: [26369.504057] Restarting tasks ... done. Apr 16 11:21:10 blackice kernel: [26369.546742] video LNXVIDEO:00: Restoring backlight state Apr 16 11:21:10 blackice NetworkManager[903]: found WiFi radio killswitch rfkill3 (at /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/ieee80211/phy3/rfkill3) (driver ) Apr 16 11:21:10 blackice NetworkManager[903]: radio killswitch /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/ieee80211/phy2/rfkill2 disappeared Apr 16 11:21:10 blackice NetworkManager[903]: SCPlugin-Ifupdown: devices removed (path: /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/net/wlan0, iface: wlan0) Apr 16 11:21:10 blackice NetworkManager[903]: (wlan0): driver supports SSID scans (scan_capa 0x01). Apr 16 11:21:10 blackice NetworkManager[903]: (wlan0): new 802.11 WiFi device (driver: 'rtl8187' ifindex: 10) Apr 16 11:21:10 blackice NetworkManager[903]: (wlan0): exported as /org/freedesktop/NetworkManager/Devices/4 Apr 16 11:21:10 blackice NetworkManager[903]: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/net/wlan0, iface: wlan0) Apr 16 11:21:10 blackice NetworkManager[903]: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/net/wlan0, iface: wlan0): no ifupdown configuration found. Apr 16 11:21:13 blackice NetworkManager[903]: wake requested (sleeping: yes enabled: yes) Apr 16 11:21:13 blackice NetworkManager[903]: waking up and re-enabling... Apr 16 11:21:13 blackice NetworkManager[903]: (eth0): now managed Apr 16 11:21:13 blackice NetworkManager[903]: (eth0): device state change: 1 -> 2 (reason 2) Apr 16 11:21:13 blackice NetworkManager[903]: (eth0): bringing up device. Apr 16 11:21:13 blackice NetworkManager[903]: (eth0): preparing device. Apr 16 11:21:13 blackice NetworkManager[903]: (eth0): deactivating device (reason: 2). Apr 16 11:21:13 blackice NetworkManager[903]: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Apr 16 11:21:13 blackice NetworkManager[903]: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Apr 16 11:21:13 blackice NetworkManager[903]: (wlan0): now managed Apr 16 11:21:13 blackice NetworkManager[903]: (wlan0): device state change: 1 -> 2 (reason 2) Apr 16 11:21:13 blackice NetworkManager[903]: (wlan0): bringing up device. Apr 16 11:21:13 blackice kernel: [26372.977358] ADDRCONF(NETDEV_UP): eth0: link is not ready Apr 16 11:21:17 blackice kernel: [26376.854564] ADDRCONF(NETDEV_UP): wlan0: link is not ready Apr 16 11:21:17 blackice NetworkManager[903]: (wlan0): preparing device. Apr 16 11:21:17 blackice NetworkManager[903]: (wlan0): deactivating device (reason: 2). Apr 16 11:21:17 blackice NetworkManager[903]: (wlan0): supplicant interface state: starting -> ready Apr 16 11:21:17 blackice NetworkManager[903]: (wlan0): device state change: 2 -> 3 (reason 42) Apr 16 11:21:18 blackice kernel: [26377.742072] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0 Apr 16 11:21:18 blackice kernel: [26378.061634] EXT4-fs (sda5): re-mounted. Opts: commit=0 Apr 16 11:21:18 blackice kernel: [26378.405825] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=0 Apr 16 11:21:19 blackice NetworkManager[903]: Activation (wlan0) starting connection 'Auto valdergallo' Apr 16 11:21:19 blackice NetworkManager[903]: (wlan0): device state change: 3 -> 4 (reason 0) Apr 16 11:21:19 blackice NetworkManager[903]: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Apr 16 11:21:19 blackice NetworkManager[903]: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Apr 16 11:21:19 blackice NetworkManager[903]: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Apr 16 11:21:19 blackice NetworkManager[903]: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Apr 16 11:21:19 blackice NetworkManager[903]: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Apr 16 11:21:19 blackice NetworkManager[903]: (wlan0): device state change: 4 -> 5 (reason 0) Apr 16 11:21:19 blackice NetworkManager[903]: Activation (wlan0/wireless): connection 'Auto valdergallo' has security, and secrets exist. No new secrets needed. Apr 16 11:21:19 blackice NetworkManager[903]: Config: added 'ssid' value 'valdergallo' Apr 16 11:21:19 blackice NetworkManager[903]: Config: added 'scan_ssid' value '1' Apr 16 11:21:19 blackice NetworkManager[903]: Config: added 'key_mgmt' value 'WPA-PSK' Apr 16 11:21:19 blackice NetworkManager[903]: Config: added 'psk' value '' Apr 16 11:21:19 blackice NetworkManager[903]: nm_setting_802_1x_get_pkcs11_engine_path: assertion `NM_IS_SETTING_802_1X (setting)' failed Apr 16 11:21:19 blackice NetworkManager[903]: nm_setting_802_1x_get_pkcs11_module_path: assertion `NM_IS_SETTING_802_1X (setting)' failed Apr 16 11:21:19 blackice NetworkManager[903]: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Apr 16 11:21:19 blackice NetworkManager[903]: Config: set interface ap_scan to 1 Apr 16 11:21:19 blackice NetworkManager[903]: (wlan0): supplicant connection state: inactive -> scanning Apr 16 11:21:20 blackice NetworkManager[903]: sleep requested (sleeping: no enabled: yes) Apr 16 11:21:20 blackice NetworkManager[903]: sleeping or disabling... Apr 16 11:21:20 blackice NetworkManager[903]: (eth0): now unmanaged Apr 16 11:21:20 blackice NetworkManager[903]: (eth0): device state change: 2 -> 1 (reason 37) Apr 16 11:21:20 blackice NetworkManager[903]: (eth0): cleaning up... Apr 16 11:21:20 blackice NetworkManager[903]: (eth0): taking down device. Apr 16 11:21:20 blackice NetworkManager[903]: (wlan0): now unmanaged Apr 16 11:21:20 blackice NetworkManager[903]: (wlan0): device state change: 5 -> 1 (reason 37) Apr 16 11:21:20 blackice NetworkManager[903]: (wlan0): deactivating device (reason: 37). Apr 16 11:21:20 blackice NetworkManager[903]: (wlan0): cleaning up... Apr 16 11:21:20 blackice NetworkManager[903]: (wlan0): taking down device. Apr 16 11:21:21 blackice kernel: [26380.755319] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0 Apr 16 11:21:21 blackice kernel: [26380.848425] EXT4-fs (sda5): re-mounted. Opts: commit=0 Apr 16 11:21:21 blackice kernel: [26381.119778] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=0 Apr 16 11:22:03 blackice kernel: [26387.500194] PM: Syncing filesystems ... done. Apr 16 11:22:03 blackice kernel: [26387.528917] PM: Preparing system for mem sleep Apr 16 11:22:03 blackice kernel: [26387.528937] Freezing user space processes ... (elapsed 0.01 seconds) done. Apr 16 11:22:03 blackice kernel: [26387.544129] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. Apr 16 11:22:03 blackice kernel: [26387.560100] PM: Entering mem sleep Apr 16 11:22:03 blackice kernel: [26387.560123] Suspending console(s) (use no_console_suspend to debug) Apr 16 11:22:03 blackice kernel: [26387.560685] sd 2:0:0:0: [sda] Synchronizing SCSI cache Apr 16 11:22:03 blackice kernel: [26387.623945] ACPI handle has no context! Apr 16 11:22:03 blackice kernel: [26387.623960] jmb38x_ms 0000:03:00.3: PCI INT A disabled Apr 16 11:22:03 blackice kernel: [26387.623964] ACPI handle has no context! Apr 16 11:22:03 blackice kernel: [26387.623971] ACPI handle has no context! Apr 16 11:22:03 blackice kernel: [26387.623977] sdhci-pci 0000:03:00.0: PCI INT A disabled Apr 16 11:22:03 blackice kernel: [26387.623986] ACPI handle has no context! Apr 16 11:22:03 blackice kernel: [26387.624462] sis190 0000:00:04.0: PCI INT A disabled Apr 16 11:22:03 blackice kernel: [26387.624478] ohci_hcd 0000:00:03.1: PCI INT B disabled Apr 16 11:22:03 blackice kernel: [26387.624481] ohci_hcd 0000:00:03.0: PCI INT A disabled Apr 16 11:22:03 blackice kernel: [26387.624556] pata_sis 0000:00:02.5: PCI INT A disabled Apr 16 11:22:03 blackice kernel: [26387.628382] sd 2:0:0:0: [sda] Stopping disk Apr 16 11:22:03 blackice kernel: [26387.712015] usb 1-2: reset high speed USB device using ehci_hcd and address 2 Apr 16 11:22:03 blackice kernel: [26387.832065] HDA Intel 0000:00:0f.0: PCI INT A disabled Apr 16 11:22:03 blackice kernel: [26387.832093] ACPI handle has no context! Apr 16 11:22:03 blackice kernel: [26387.848014] PM: suspend of drv:HDA Intel dev:0000:00:0f.0 complete after 223.997 msecs Apr 16 11:22:03 blackice kernel: [26387.860012] PM: suspend of drv:usb dev:1-2 complete after 299.224 msecs Apr 16 11:22:03 blackice kernel: [26387.861122] PM: suspend of drv:usb dev:usb1 complete after 237.720 msecs Apr 16 11:22:03 blackice kernel: [26387.876023] ehci_hcd 0000:00:03.3: PCI INT C disabled Apr 16 11:22:03 blackice kernel: [26387.876026] PM: suspend of drv:ehci_hcd dev:0000:00:03.3 complete after 251.559 msecs Apr 16 11:22:03 blackice kernel: [26388.654495] PM: suspend of drv:sd dev:2:0:0:0 complete after 1093.815 msecs Apr 16 11:22:03 blackice kernel: [26388.654506] PM: suspend of drv:scsi dev:target2:0:0 complete after 1093.748 msecs Apr 16 11:22:03 blackice kernel: [26388.654521] PM: suspend of drv:scsi dev:host2 complete after 1093.599 msecs Apr 16 11:22:03 blackice kernel: [26388.654535] sata_sis 0000:00:05.0: PCI INT A disabled Apr 16 11:22:03 blackice kernel: [26388.654538] PM: suspend of drv:sata_sis dev:0000:00:05.0 complete after 1030.078 msecs Apr 16 11:22:03 blackice kernel: [26388.654546] PM: suspend of drv: dev:pci0000:00 complete after 1030.018 msecs Apr 16 11:22:03 blackice kernel: [26388.654554] PM: suspend of devices complete after 1094.039 msecs Apr 16 11:22:03 blackice kernel: [26388.654557] PM: suspend devices took 1.092 seconds Apr 16 11:22:03 blackice kernel: [26388.668158] PM: late suspend of devices complete after 13.596 msecs Apr 16 11:22:03 blackice kernel: [26388.668340] ACPI: Preparing to enter system sleep state S3 Apr 16 11:22:03 blackice kernel: [26388.709319] PM: Saving platform NVS memory Apr 16 11:22:03 blackice kernel: [26388.709346] Disabling non-boot CPUs ... Apr 16 11:22:03 blackice kernel: [26388.812017] CPU 1 is now offline Apr 16 11:22:03 blackice kernel: [26388.812431] Extended CMOS year: 2000 Apr 16 11:22:03 blackice kernel: [26388.812431] Back to C! Apr 16 11:22:03 blackice kernel: [26388.812431] PM: Restoring platform NVS memory Apr 16 11:22:03 blackice kernel: [26388.812431] Extended CMOS year: 2000 Apr 16 11:22:03 blackice kernel: [26388.812431] Enabling non-boot CPUs ... Apr 16 11:22:03 blackice kernel: [26388.812431] Booting Node 0 Processor 1 APIC 0x1 Apr 16 11:22:03 blackice kernel: [26388.710633] Initializing CPU#1 Apr 16 11:22:03 blackice kernel: [26388.900586] CPU1 is up Apr 16 11:22:03 blackice kernel: [26388.901035] ACPI: Waking up from system sleep state S3 Apr 16 11:22:03 blackice kernel: [26388.904022] Switched to NOHz mode on CPU #1 Apr 16 11:22:03 blackice kernel: [26389.156183] pci 0000:00:01.0: restoring config space at offset 0x7 (was 0x209090, writing 0x20209090) Apr 16 11:22:03 blackice kernel: [26389.156228] pata_sis 0000:00:02.5: restoring config space at offset 0xf (was 0x1ff, writing 0x0) Apr 16 11:22:03 blackice kernel: [26389.156241] pata_sis 0000:00:02.5: restoring config space at offset 0x7 (was 0x375, writing 0x1) Apr 16 11:22:03 blackice kernel: [26389.156245] pata_sis 0000:00:02.5: restoring config space at offset 0x6 (was 0x171, writing 0x1) Apr 16 11:22:03 blackice kernel: [26389.156250] pata_sis 0000:00:02.5: restoring config space at offset 0x5 (was 0x3f5, writing 0x1) Apr 16 11:22:03 blackice kernel: [26389.156254] pata_sis 0000:00:02.5: restoring config space at offset 0x4 (was 0x1f1, writing 0x1) Apr 16 11:22:03 blackice kernel: [26389.156260] pata_sis 0000:00:02.5: restoring config space at offset 0x2 (was 0x1018a01, writing 0x1018001) Apr 16 11:22:03 blackice kernel: [26389.156291] ohci_hcd 0000:00:03.0: restoring config space at offset 0x1 (was 0x2800017, writing 0x2800013) Apr 16 11:22:03 blackice kernel: [26389.156317] ohci_hcd 0000:00:03.1: restoring config space at offset 0x1 (was 0x2800017, writing 0x2800013) Apr 16 11:22:03 blackice kernel: [26389.172019] ehci_hcd 0000:00:03.3: BAR 0: set to [mem 0xd8206000-0xd8206fff] (PCI address [0xd8206000-0xd8206fff]) Apr 16 11:22:03 blackice kernel: [26389.172044] ehci_hcd 0000:00:03.3: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) Apr 16 11:22:03 blackice kernel: [26389.172078] sis190 0000:00:04.0: restoring config space at offset 0x1 (was 0x2100007, writing 0x2100003) Apr 16 11:22:03 blackice kernel: [26389.172111] sata_sis 0000:00:05.0: restoring config space at offset 0x1 (was 0x2100005, writing 0x2100001) Apr 16 11:22:03 blackice kernel: [26389.172145] pcieport 0000:00:06.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xd861d851) Apr 16 11:22:03 blackice kernel: [26389.172155] pcieport 0000:00:06.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004) Apr 16 11:22:03 blackice kernel: [26389.172160] pcieport 0000:00:06.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) Apr 16 11:22:03 blackice kernel: [26389.172203] pcieport 0000:00:07.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xd881d871) Apr 16 11:22:03 blackice kernel: [26389.172208] pcieport 0000:00:07.0: restoring config space at offset 0x7 (was 0x200001f1, writing 0x20002121) Apr 16 11:22:03 blackice kernel: [26389.172216] pcieport 0000:00:07.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004) Apr 16 11:22:03 blackice kernel: [26389.172222] pcieport 0000:00:07.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) Apr 16 11:22:03 blackice kernel: [26389.172264] HDA Intel 0000:00:0f.0: restoring config space at offset 0x1 (was 0x2100006, writing 0x2100002) Apr 16 11:22:03 blackice kernel: [26389.172287] pcieport 0000:00:1f.0: restoring config space at offset 0xf (was 0x0, writing 0x400ff) Apr 16 11:22:03 blackice kernel: [26389.172298] pcieport 0000:00:1f.0: restoring config space at offset 0x9 (was 0x1ff01, writing 0x1fff1) Apr 16 11:22:03 blackice kernel: [26389.172304] pcieport 0000:00:1f.0: restoring config space at offset 0x7 (was 0x101, writing 0x1f1) Apr 16 11:22:03 blackice kernel: [26389.172309] pcieport 0000:00:1f.0: restoring config space at offset 0x6 (was 0x0, writing 0x40400) Apr 16 11:22:03 blackice kernel: [26389.172316] pcieport 0000:00:1f.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004) Apr 16 11:22:03 blackice kernel: [26389.172322] pcieport 0000:00:1f.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) Apr 16 11:22:03 blackice kernel: [26389.172350] pci 0000:01:00.0: restoring config space at offset 0xf (was 0x100, writing 0x109) Apr 16 11:22:03 blackice kernel: [26389.172360] pci 0000:01:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x9001) Apr 16 11:22:03 blackice kernel: [26389.172364] pci 0000:01:00.0: restoring config space at offset 0x5 (was 0x0, writing 0xd8000000) Apr 16 11:22:03 blackice kernel: [26389.172367] pci 0000:01:00.0: restoring config space at offset 0x4 (was 0x8, writing 0xc0000008) Apr 16 11:22:03 blackice kernel: [26389.172373] pci 0000:01:00.0: restoring config space at offset 0x1 (was 0x2300000, writing 0x2300003) Apr 16 11:22:03 blackice kernel: [26389.172433] sdhci-pci 0000:03:00.0: restoring config space at offset 0x3 (was 0x800000, writing 0x800004) Apr 16 11:22:03 blackice kernel: [26389.172510] pci 0000:03:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x800004) Apr 16 11:22:03 blackice kernel: [26389.172518] pci 0000:03:00.2: restoring config space at offset 0x1 (was 0x100007, writing 0x100003) Apr 16 11:22:03 blackice kernel: [26389.172591] jmb38x_ms 0000:03:00.3: restoring config space at offset 0x3 (was 0x800000, writing 0x800004) Apr 16 11:22:03 blackice kernel: [26389.173029] PM: early resume of devices complete after 16.967 msecs Apr 16 11:22:03 blackice kernel: [26389.173111] pci 0000:00:01.0: setting latency timer to 64 Apr 16 11:22:03 blackice kernel: [26389.173130] pata_sis 0000:00:02.5: PCI INT A -> GSI 16 (level, low) -> IRQ 16 Apr 16 11:22:03 blackice kernel: [26389.173157] ohci_hcd 0000:00:03.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 Apr 16 11:22:03 blackice kernel: [26389.173202] ohci_hcd 0000:00:03.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 Apr 16 11:22:03 blackice kernel: [26389.173219] ehci_hcd 0000:00:03.3: PCI INT C -> GSI 22 (level, low) -> IRQ 22 Apr 16 11:22:03 blackice kernel: [26389.173238] sis190 0000:00:04.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19 Apr 16 11:22:03 blackice kernel: [26389.173243] sis190 0000:00:04.0: setting latency timer to 64 Apr 16 11:22:03 blackice kernel: [26389.173252] sata_sis 0000:00:05.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17 Apr 16 11:22:03 blackice kernel: [26389.173270] HDA Intel 0000:00:0f.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18 Apr 16 11:22:03 blackice kernel: [26389.173276] HDA Intel 0000:00:0f.0: setting latency timer to 64 Apr 16 11:22:03 blackice kernel: [26389.173314] sdhci-pci 0000:03:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 Apr 16 11:22:03 blackice kernel: [26389.173330] sdhci-pci 0000:03:00.0: setting latency timer to 64 Apr 16 11:22:03 blackice kernel: [26389.173354] jmb38x_ms 0000:03:00.3: PCI INT A -> GSI 16 (level, low) -> IRQ 16 Apr 16 11:22:03 blackice kernel: [26389.173361] jmb38x_ms 0000:03:00.3: setting latency timer to 64 Apr 16 11:22:03 blackice kernel: [26389.173413] ata2: port disabled. ignoring. Apr 16 11:22:03 blackice kernel: [26389.173565] sd 2:0:0:0: [sda] Starting disk Apr 16 11:22:03 blackice kernel: [26389.284047] PM: resume of drv:usb dev:usb3 complete after 110.593 msecs Apr 16 11:22:03 blackice kernel: [26389.284054] PM: resume of drv:usb dev:usb2 complete after 110.621 msecs Apr 16 11:22:03 blackice kernel: [26389.284064] PM: resume of drv: dev:ep_00 complete after 110.597 msecs Apr 16 11:22:03 blackice kernel: [26389.284069] PM: resume of drv:hub dev:3-0:1.0 complete after 110.613 msecs Apr 16 11:22:03 blackice kernel: [26389.284094] PM: resume of drv: dev:ep_00 complete after 110.648 msecs Apr 16 11:22:03 blackice kernel: [26389.284098] PM: resume of drv:hub dev:2-0:1.0 complete after 110.663 msecs Apr 16 11:22:03 blackice kernel: [26389.284102] PM: resume of drv: dev:ep_81 complete after 110.641 msecs Apr 16 11:22:03 blackice kernel: [26389.284110] PM: resume of drv: dev:ep_81 complete after 110.671 msecs Apr 16 11:22:03 blackice kernel: [26389.336039] PM: resume of drv:usb dev:usb1 complete after 162.646 msecs Apr 16 11:22:03 blackice kernel: [26389.336057] PM: resume of drv:hub dev:1-0:1.0 complete after 162.659 msecs Apr 16 11:22:03 blackice kernel: [26389.336078] PM: resume of drv: dev:ep_00 complete after 162.654 msecs Apr 16 11:22:03 blackice kernel: [26389.336095] PM: resume of drv: dev:ep_81 complete after 162.692 msecs Apr 16 11:22:03 blackice kernel: [26389.386670] PM: resume of drv:usb dev:1-7 complete after 213.090 msecs Apr 16 11:22:03 blackice kernel: [26389.386687] PM: resume of drv:uvcvideo dev:1-7:1.0 complete after 181.105 msecs Apr 16 11:22:03 blackice kernel: [26389.386703] PM: resume of drv: dev:ep_00 complete after 181.105 msecs Apr 16 11:22:03 blackice kernel: [26389.386707] PM: resume of drv:uvcvideo dev:1-7:1.1 complete after 181.112 msecs Apr 16 11:22:03 blackice kernel: [26389.386722] PM: resume of drv: dev:ep_82 complete after 181.131 msecs Apr 16 11:22:03 blackice kernel: [26389.448204] usb 1-2: reset high speed USB device using ehci_hcd and address 2 Apr 16 11:22:03 blackice kernel: [26389.582926] PM: resume of drv:usb dev:1-2 complete after 409.433 msecs Apr 16 11:22:03 blackice kernel: [26389.582957] PM: resume of drv:usb dev:1-2:1.0 complete after 409.462 msecs Apr 16 11:22:03 blackice kernel: [26389.582961] PM: resume of drv: dev:ep_00 complete after 409.402 msecs Apr 16 11:22:03 blackice kernel: [26389.582984] PM: resume of drv: dev:ep_04 complete after 409.470 msecs Apr 16 11:22:03 blackice kernel: [26389.582989] PM: resume of drv: dev:ep_06 complete after 409.464 msecs Apr 16 11:22:03 blackice kernel: [26389.582993] PM: resume of drv: dev:ep_05 complete after 409.474 msecs Apr 16 11:22:03 blackice kernel: [26389.582997] PM: resume of drv: dev:ep_0b complete after 409.453 msecs Apr 16 11:22:03 blackice kernel: [26389.583001] PM: resume of drv: dev:ep_07 complete after 409.473 msecs Apr 16 11:22:03 blackice kernel: [26389.583005] PM: resume of drv: dev:ep_89 complete after 409.471 msecs Apr 16 11:22:03 blackice kernel: [26389.583010] PM: resume of drv: dev:ep_0a complete after 409.471 msecs Apr 16 11:22:03 blackice kernel: [26389.583014] PM: resume of drv: dev:ep_83 complete after 409.499 msecs Apr 16 11:22:03 blackice kernel: [26389.583018] PM: resume of drv: dev:ep_0c complete after 409.469 msecs Apr 16 11:22:03 blackice kernel: [26420.004044] ata3: lost interrupt (Status 0x50) Apr 16 11:22:03 blackice kernel: [26420.004062] ata3.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen Apr 16 11:22:03 blackice kernel: [26420.004067] ata3.00: failed command: READ VERIFY SECTOR(S) Apr 16 11:22:03 blackice kernel: [26420.004077] ata3.00: cmd 40/00:01:00:00:00/00:00:00:00:00/e0 tag 0 Apr 16 11:22:03 blackice kernel: [26420.004079] res 40/00:ff:00:00:00/00:00:00:00:00/40 Emask 0x4 (timeout) Apr 16 11:22:03 blackice kernel: [26420.004083] ata3.00: status: { DRDY } Apr 16 11:22:03 blackice kernel: [26420.004110] ata3: soft resetting link Apr 16 11:22:03 blackice kernel: [26420.208363] ata3.00: configured for UDMA/133 Apr 16 11:22:03 blackice kernel: [26420.208369] ata3.00: device reported invalid CHS sector 0 Apr 16 11:22:03 blackice kernel: [26420.208386] ata3: EH complete Apr 16 11:22:03 blackice kernel: [26420.208395] sd 2:0:0:0: [sda] START_STOP FAILED Apr 16 11:22:03 blackice kernel: [26420.208399] sd 2:0:0:0: [sda] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE Apr 16 11:22:03 blackice kernel: [26420.208405] sd 2:0:0:0: [sda] Sense Key : Aborted Command [current] [descriptor] Apr 16 11:22:03 blackice kernel: [26420.208412] sd 2:0:0:0: [sda] Add. Sense: No additional sense information Apr 16 11:22:03 blackice kernel: [26420.208431] pm_op(): scsi_bus_resume_common+0x0/0x50 returns 134217730 Apr 16 11:22:03 blackice kernel: [26420.208444] PM: Device 2:0:0:0 failed to resume async: error 134217730 Apr 16 11:22:03 blackice kernel: [26420.208449] PM: resume of drv:scsi_disk dev:2:0:0:0 complete after 30964.720 msecs Apr 16 11:22:03 blackice kernel: [26420.208454] PM: resume of drv:scsi_device dev:2:0:0:0 complete after 31034.889 msecs Apr 16 11:22:03 blackice kernel: [26420.208574] PM: resume of devices complete after 31035.500 msecs Apr 16 11:22:03 blackice kernel: [26420.484252] cfg80211: Updating information on frequency 2412 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:22:03 blackice kernel: [26420.484257] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:22:03 blackice kernel: [26420.484261] cfg80211: Updating information on frequency 2417 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:22:03 blackice kernel: [26420.484265] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:22:03 blackice kernel: [26420.484269] cfg80211: Updating information on frequency 2422 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:22:03 blackice kernel: [26420.484274] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:22:03 blackice kernel: [26420.484278] cfg80211: Updating information on frequency 2427 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:22:03 blackice kernel: [26420.484282] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:22:03 blackice kernel: [26420.484286] cfg80211: Updating information on frequency 2432 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:22:03 blackice kernel: [26420.484290] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:22:03 blackice kernel: [26420.484294] cfg80211: Updating information on frequency 2437 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:22:03 blackice kernel: [26420.484298] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:22:03 blackice kernel: [26420.484302] cfg80211: Updating information on frequency 2442 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:22:03 blackice kernel: [26420.484306] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:22:03 blackice kernel: [26420.484310] cfg80211: Updating information on frequency 2447 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:22:03 blackice kernel: [26420.484315] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:22:03 blackice kernel: [26420.484319] cfg80211: Updating information on frequency 2452 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:22:03 blackice kernel: [26420.484323] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:22:03 blackice kernel: [26420.484327] cfg80211: Updating information on frequency 2457 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:22:03 blackice kernel: [26420.484331] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:22:03 blackice kernel: [26420.484335] cfg80211: Updating information on frequency 2462 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:22:03 blackice kernel: [26420.484339] cfg80211: 2402000 KHz - 2472000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:22:03 blackice kernel: [26420.484343] cfg80211: Updating information on frequency 2467 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:22:03 blackice kernel: [26420.484348] cfg80211: 2457000 KHz - 2482000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:22:03 blackice kernel: [26420.484351] cfg80211: Updating information on frequency 2472 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:22:03 blackice kernel: [26420.484356] cfg80211: 2457000 KHz - 2482000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:22:03 blackice kernel: [26420.484360] cfg80211: Updating information on frequency 2484 MHz for a 20 MHz width channel with regulatory rule: Apr 16 11:22:03 blackice kernel: [26420.484364] cfg80211: 2474000 KHz - 2494000 KHz @ KHz), (300 mBi, 2000 mBm) Apr 16 11:22:03 blackice kernel: [26420.484517] ieee80211 phy4: Selected rate control algorithm 'minstrel_ht' Apr 16 11:22:03 blackice kernel: [26420.485165] ieee80211 phy4: hwaddr 1c:4b:d6:26:6d:9b, RTL8187BvE V0 + rtl8225z2, rfkill mask 2 Apr 16 11:22:03 blackice kernel: [26420.506684] rtl8187: Customer ID is 0x00 Apr 16 11:22:03 blackice kernel: [26420.506742] Registered led device: rtl8187-phy4::radio Apr 16 11:22:03 blackice kernel: [26420.506786] Registered led device: rtl8187-phy4::tx Apr 16 11:22:03 blackice kernel: [26420.506830] Registered led device: rtl8187-phy4::rx Apr 16 11:22:03 blackice kernel: [26420.507548] rtl8187: wireless switch is on Apr 16 11:22:03 blackice kernel: [26420.507879] PM: resume devices took 31.332 seconds Apr 16 11:22:03 blackice kernel: [26420.507880] ------------[ cut here ]------------ Apr 16 11:22:03 blackice kernel: [26420.507888] WARNING: at /build/buildd/linux-2.6.38/kernel/power/suspend_test.c:53 suspend_test_finish+0x89/0x90() Apr 16 11:22:03 blackice kernel: [26420.507890] Hardware name: POSITIVO MOBILE Apr 16 11:22:03 blackice kernel: [26420.507892] Component: resume devices, time: 31332 Apr 16 11:22:03 blackice kernel: [26420.507893] Modules linked in: binfmt_misc parport_pc ppdev dm_crypt vesafb snd_hda_codec_si3054 snd_hda_codec_realtek joydev snd_hda_intel snd_hda_codec arc4 lp uvcvideo videodev snd_hwdep snd_pcm snd_seq_midi psmouse snd_rawmidi jmb38x_ms rtl8187 snd_seq_midi_event mac80211 snd_seq snd_timer snd_seq_device cfg80211 memstick eeprom_93cx6 shpchp serio_raw snd parport soundcore snd_page_alloc video sis190 sdhci_pci sata_sis sis_agp sdhci Apr 16 11:22:03 blackice kernel: [26420.507924] Pid: 27680, comm: pm-suspend Tainted: G W 2.6.38-8-generic-pae #42-Ubuntu Apr 16 11:22:03 blackice kernel: [26420.507926] Call Trace: Apr 16 11:22:03 blackice kernel: [26420.507933] [] ? warn_slowpath_common+0x72/0xa0 Apr 16 11:22:03 blackice kernel: [26420.507936] [] ? suspend_test_finish+0x89/0x90 Apr 16 11:22:03 blackice kernel: [26420.507939] [] ? suspend_test_finish+0x89/0x90 Apr 16 11:22:03 blackice kernel: [26420.507942] [] ? warn_slowpath_fmt+0x33/0x40 Apr 16 11:22:03 blackice kernel: [26420.507944] [] ? suspend_test_finish+0x89/0x90 Apr 16 11:22:03 blackice kernel: [26420.507947] [] ? suspend_devices_and_enter+0x9e/0x150 Apr 16 11:22:03 blackice kernel: [26420.507950] [] ? enter_state+0x101/0x120 Apr 16 11:22:03 blackice kernel: [26420.507953] [] ? state_store+0x86/0xc0 Apr 16 11:22:03 blackice kernel: [26420.507955] [] ? state_store+0x0/0xc0 Apr 16 11:22:03 blackice kernel: [26420.507960] [] ? kobj_attr_store+0x20/0x30 Apr 16 11:22:03 blackice kernel: [26420.507964] [] ? sysfs_write_file+0x9e/0x100 Apr 16 11:22:03 blackice kernel: [26420.507968] [] ? vfs_write+0xa2/0x170 Apr 16 11:22:03 blackice kernel: [26420.507971] [] ? sysfs_write_file+0x0/0x100 Apr 16 11:22:03 blackice kernel: [26420.507974] [] ? sys_write+0x42/0x70 Apr 16 11:22:03 blackice kernel: [26420.507977] [] ? sysenter_do_call+0x12/0x28 Apr 16 11:22:03 blackice kernel: [26420.507980] ---[ end trace b37694dd17db0709 ]--- Apr 16 11:22:03 blackice kernel: [26420.508054] PM: Finishing wakeup. Apr 16 11:22:03 blackice NetworkManager[903]: radio killswitch /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/ieee80211/phy3/rfkill3 disappeared Apr 16 11:22:03 blackice kernel: [26420.508056] Restarting tasks ... done. Apr 16 11:22:03 blackice kernel: [26420.547336] video LNXVIDEO:00: Restoring backlight state Apr 16 11:22:03 blackice NetworkManager[903]: found WiFi radio killswitch rfkill4 (at /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/ieee80211/phy4/rfkill4) (driver ) Apr 16 11:22:03 blackice NetworkManager[903]: SCPlugin-Ifupdown: devices removed (path: /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/net/wlan0, iface: wlan0) Apr 16 11:22:03 blackice NetworkManager[903]: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/net/wlan0, iface: wlan0) Apr 16 11:22:03 blackice NetworkManager[903]: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/net/wlan0, iface: wlan0): no ifupdown configuration found. Apr 16 11:22:03 blackice NetworkManager[903]: (wlan0): driver supports SSID scans (scan_capa 0x01). Apr 16 11:22:03 blackice NetworkManager[903]: (wlan0): new 802.11 WiFi device (driver: 'rtl8187' ifindex: 11) Apr 16 11:22:03 blackice NetworkManager[903]: (wlan0): exported as /org/freedesktop/NetworkManager/Devices/5 Apr 16 11:22:07 blackice NetworkManager[903]: wake requested (sleeping: yes enabled: yes) Apr 16 11:22:07 blackice NetworkManager[903]: waking up and re-enabling... Apr 16 11:22:07 blackice NetworkManager[903]: (eth0): now managed Apr 16 11:22:07 blackice NetworkManager[903]: (eth0): device state change: 1 -> 2 (reason 2) Apr 16 11:22:07 blackice NetworkManager[903]: (eth0): bringing up device. Apr 16 11:22:07 blackice NetworkManager[903]: (eth0): preparing device. Apr 16 11:22:07 blackice NetworkManager[903]: (eth0): deactivating device (reason: 2). Apr 16 11:22:07 blackice NetworkManager[903]: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Apr 16 11:22:07 blackice NetworkManager[903]: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Apr 16 11:22:07 blackice NetworkManager[903]: (wlan0): now managed Apr 16 11:22:07 blackice NetworkManager[903]: (wlan0): device state change: 1 -> 2 (reason 2) Apr 16 11:22:07 blackice NetworkManager[903]: (wlan0): bringing up device. Apr 16 11:22:07 blackice kernel: [26424.692486] ADDRCONF(NETDEV_UP): eth0: link is not ready Apr 16 11:22:11 blackice kernel: [26428.550579] ADDRCONF(NETDEV_UP): wlan0: link is not ready Apr 16 11:22:11 blackice NetworkManager[903]: (wlan0): preparing device. Apr 16 11:22:11 blackice NetworkManager[903]: (wlan0): deactivating device (reason: 2). Apr 16 11:22:11 blackice NetworkManager[903]: (wlan0): supplicant interface state: starting -> ready Apr 16 11:22:11 blackice NetworkManager[903]: (wlan0): device state change: 2 -> 3 (reason 42) Apr 16 11:22:12 blackice kernel: [26429.732385] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0 Apr 16 11:22:12 blackice kernel: [26429.931750] EXT4-fs (sda5): re-mounted. Opts: commit=0 Apr 16 11:22:12 blackice kernel: [26430.108662] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=0 Apr 16 11:22:13 blackice NetworkManager[903]: Activation (wlan0) starting connection 'Auto valdergallo' Apr 16 11:22:13 blackice NetworkManager[903]: (wlan0): device state change: 3 -> 4 (reason 0) Apr 16 11:22:13 blackice NetworkManager[903]: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Apr 16 11:22:13 blackice NetworkManager[903]: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Apr 16 11:22:13 blackice NetworkManager[903]: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Apr 16 11:22:13 blackice NetworkManager[903]: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Apr 16 11:22:13 blackice NetworkManager[903]: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Apr 16 11:22:13 blackice NetworkManager[903]: (wlan0): device state change: 4 -> 5 (reason 0) Apr 16 11:22:13 blackice NetworkManager[903]: Activation (wlan0/wireless): connection 'Auto valdergallo' has security, and secrets exist. No new secrets needed. Apr 16 11:22:13 blackice NetworkManager[903]: Config: added 'ssid' value 'valdergallo' Apr 16 11:22:13 blackice NetworkManager[903]: Config: added 'scan_ssid' value '1' Apr 16 11:22:13 blackice NetworkManager[903]: Config: added 'key_mgmt' value 'WPA-PSK' Apr 16 11:22:13 blackice NetworkManager[903]: Config: added 'psk' value '' Apr 16 11:22:13 blackice NetworkManager[903]: nm_setting_802_1x_get_pkcs11_engine_path: assertion `NM_IS_SETTING_802_1X (setting)' failed Apr 16 11:22:13 blackice NetworkManager[903]: nm_setting_802_1x_get_pkcs11_module_path: assertion `NM_IS_SETTING_802_1X (setting)' failed Apr 16 11:22:13 blackice NetworkManager[903]: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Apr 16 11:22:13 blackice NetworkManager[903]: Config: set interface ap_scan to 1 Apr 16 11:22:13 blackice NetworkManager[903]: (wlan0): supplicant connection state: inactive -> scanning Apr 16 11:22:15 blackice NetworkManager[903]: (wlan0): supplicant connection state: scanning -> associating Apr 16 11:22:16 blackice kernel: [26433.393347] wlan0: authenticate with 00:26:5a:63:b3:2a (try 1) Apr 16 11:22:16 blackice kernel: [26433.395462] wlan0: authenticated Apr 16 11:22:16 blackice kernel: [26433.533225] wlan0: associate with 00:26:5a:63:b3:2a (try 1) Apr 16 11:22:16 blackice kernel: [26433.535486] wlan0: RX AssocResp from 00:26:5a:63:b3:2a (capab=0x431 status=0 aid=1) Apr 16 11:22:16 blackice kernel: [26433.535490] wlan0: associated Apr 16 11:22:16 blackice NetworkManager[903]: (wlan0): supplicant connection state: associating -> associated Apr 16 11:22:16 blackice kernel: [26433.541164] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Apr 16 11:22:16 blackice NetworkManager[903]: (wlan0): supplicant connection state: associated -> 4-way handshake Apr 16 11:22:16 blackice NetworkManager[903]: (wlan0): supplicant connection state: 4-way handshake -> group handshake Apr 16 11:22:16 blackice NetworkManager[903]: (wlan0): supplicant connection state: group handshake -> completed Apr 16 11:22:16 blackice NetworkManager[903]: Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'valdergallo'. Apr 16 11:22:16 blackice NetworkManager[903]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled. Apr 16 11:22:16 blackice NetworkManager[903]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) started... Apr 16 11:22:16 blackice NetworkManager[903]: (wlan0): device state change: 5 -> 7 (reason 0) Apr 16 11:22:16 blackice NetworkManager[903]: Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds) Apr 16 11:22:16 blackice NetworkManager[903]: dhclient started with pid 28434 Apr 16 11:22:16 blackice NetworkManager[903]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete. Apr 16 11:22:16 blackice dhclient: Internet Systems Consortium DHCP Client 4.1.1-P1 Apr 16 11:22:16 blackice dhclient: Copyright 2004-2010 Internet Systems Consortium. Apr 16 11:22:16 blackice dhclient: All rights reserved. Apr 16 11:22:16 blackice dhclient: For info, please visit https://www.isc.org/software/dhcp/ Apr 16 11:22:16 blackice dhclient: Apr 16 11:22:16 blackice NetworkManager[903]: (wlan0): DHCPv4 state changed nbi -> preinit Apr 16 11:22:16 blackice dhclient: Listening on LPF/wlan0/1c:4b:d6:26:6d:9b Apr 16 11:22:16 blackice dhclient: Sending on LPF/wlan0/1c:4b:d6:26:6d:9b Apr 16 11:22:16 blackice dhclient: Sending on Socket/fallback Apr 16 11:22:16 blackice dhclient: DHCPREQUEST of 10.100.10.112 on wlan0 to 255.255.255.255 port 67 Apr 16 11:22:16 blackice dhclient: DHCPACK of 10.100.10.112 from 10.100.10.1 Apr 16 11:22:16 blackice dhclient: bound to 10.100.10.112 -- renewal in 75865 seconds. Apr 16 11:22:16 blackice NetworkManager[903]: (wlan0): DHCPv4 state changed preinit -> reboot Apr 16 11:22:16 blackice NetworkManager[903]: Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled... Apr 16 11:22:16 blackice NetworkManager[903]: Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started... Apr 16 11:22:16 blackice NetworkManager[903]: address 10.100.10.112 Apr 16 11:22:16 blackice NetworkManager[903]: prefix 24 (255.255.255.0) Apr 16 11:22:16 blackice NetworkManager[903]: gateway 10.100.10.1 Apr 16 11:22:16 blackice NetworkManager[903]: nameserver '189.4.0.131' Apr 16 11:22:16 blackice NetworkManager[903]: nameserver '189.4.0.148' Apr 16 11:22:16 blackice NetworkManager[903]: nameserver '10.100.10.1' Apr 16 11:22:16 blackice NetworkManager[903]: domain name 'valderdlink' Apr 16 11:22:16 blackice NetworkManager[903]: Scheduling stage 5 Apr 16 11:22:16 blackice NetworkManager[903]: Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled... Apr 16 11:22:16 blackice NetworkManager[903]: Done scheduling stage 5 Apr 16 11:22:16 blackice NetworkManager[903]: Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete. Apr 16 11:22:16 blackice NetworkManager[903]: Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started... Apr 16 11:22:17 blackice NetworkManager[903]: (wlan0): device state change: 7 -> 8 (reason 0) Apr 16 11:22:17 blackice NetworkManager[903]: Policy set 'Auto valdergallo' (wlan0) as default for IPv4 routing and DNS. Apr 16 11:22:17 blackice NetworkManager[903]: Activation (wlan0) successful, device activated. Apr 16 11:22:17 blackice NetworkManager[903]: Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete. Apr 16 11:22:26 blackice kernel: [26443.592025] wlan0: no IPv6 routers present