[ 37.327456] ip6_tables: (C) 2000-2006 Netfilter Core Team [ 39.105519] init: plymouth-stop pre-start process (2255) terminated with status 1 [ 41.278689] br-vz0: no IPv6 routers present [ 41.964317] wlan0: authenticate with 00:18:39:c1:67:60 (try 1) [ 41.967355] wlan0: authenticated [ 42.066448] wlan0: associate with 00:18:39:c1:67:60 (try 1) [ 42.069980] wlan0: RX AssocResp from 00:18:39:c1:67:60 (capab=0x411 status=0 aid=1) [ 42.069986] wlan0: associated [ 42.075469] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 53.001331] wlan0: no IPv6 routers present [ 264.712315] audit_printk_skb: 159 callbacks suppressed [ 264.712321] type=1400 audit(1337947827.580:74): apparmor="DENIED" operation="mknod" parent=1 profile="/usr/bin/pidgin" name="/home/simon/.config/indicators/messages/applications-blacklist/pidgin-libnotify.3B3JEW" pid=3230 comm="pidgin" requested_mask="c" denied_mask="c" fsuid=1000 ouid=1000 [ 2088.408653] type=1400 audit(1337949655.909:75): apparmor="DENIED" operation="exec" parent=5202 profile="/usr/lib/thunderbird/thunderbird{,*[^s][^h]}" name="/usr/bin/gedit" pid=5203 comm="thunderbird-bin" requested_mask="x" denied_mask="x" fsuid=1000 ouid=0 [ 2093.526822] type=1400 audit(1337949661.037:76): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/thunderbird/thunderbird{,*[^s][^h]}" name="/tmp/unity_support_test.0" pid=5212 comm="pool" requested_mask="r" denied_mask="r" fsuid=1000 ouid=104 [ 3913.193808] type=1400 audit(1337951485.264:77): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/thunderbird/thunderbird{,*[^s][^h]}" name="/tmp/unity_support_test.0" pid=5717 comm="pool" requested_mask="r" denied_mask="r" fsuid=1000 ouid=104 [ 3913.202600] type=1400 audit(1337951485.272:78): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/thunderbird/thunderbird{,*[^s][^h]}" name="/tmp/unity_support_test.0" pid=5717 comm="pool" requested_mask="r" denied_mask="r" fsuid=1000 ouid=104 [ 5785.630894] type=1400 audit(1337953362.396:79): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/gvfs/gvfsd-http" name="/usr/share/mime/mime.cache" pid=5335 comm="gvfsd-http" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 [ 5785.630919] type=1400 audit(1337953362.396:80): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/gvfs/gvfsd-http" name="/usr/share/mime/globs2" pid=5335 comm="gvfsd-http" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 [ 5785.630939] type=1400 audit(1337953362.396:81): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/gvfs/gvfsd-http" name="/usr/share/mime/magic" pid=5335 comm="gvfsd-http" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 [ 5785.630956] type=1400 audit(1337953362.396:82): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/gvfs/gvfsd-http" name="/usr/share/mime/aliases" pid=5335 comm="gvfsd-http" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 [ 5785.630973] type=1400 audit(1337953362.396:83): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/gvfs/gvfsd-http" name="/usr/share/mime/subclasses" pid=5335 comm="gvfsd-http" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 [ 5785.630990] type=1400 audit(1337953362.396:84): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/gvfs/gvfsd-http" name="/usr/share/mime/icons" pid=5335 comm="gvfsd-http" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 [ 5785.631006] type=1400 audit(1337953362.396:85): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/gvfs/gvfsd-http" name="/usr/share/mime/generic-icons" pid=5335 comm="gvfsd-http" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 [10292.320314] type=1400 audit(1337957880.381:86): apparmor="DENIED" operation="capable" parent=8507 profile="/usr/lib/firefox/firefox{,*[^s][^h]}//sanitized_helper" pid=8526 comm="ps" capability=19 capname="sys_ptrace" [10292.320323] type=1400 audit(1337957880.381:87): apparmor="DENIED" operation="ptrace" parent=8507 profile="/usr/lib/firefox/firefox{,*[^s][^h]}//sanitized_helper" pid=8526 comm="ps" target=5002 [10292.320438] type=1400 audit(1337957880.381:88): apparmor="DENIED" operation="ptrace" parent=8507 profile="/usr/lib/firefox/firefox{,*[^s][^h]}//sanitized_helper" pid=8526 comm="ps" target=5002 [10292.320501] type=1400 audit(1337957880.381:89): apparmor="DENIED" operation="ptrace" parent=8507 profile="/usr/lib/firefox/firefox{,*[^s][^h]}//sanitized_helper" pid=8526 comm="ps" target=5002 [10292.320562] type=1400 audit(1337957880.381:90): apparmor="DENIED" operation="ptrace" parent=8507 profile="/usr/lib/firefox/firefox{,*[^s][^h]}//sanitized_helper" pid=8526 comm="ps" target=5002 [10292.320626] type=1400 audit(1337957880.381:91): apparmor="DENIED" operation="ptrace" parent=8507 profile="/usr/lib/firefox/firefox{,*[^s][^h]}//sanitized_helper" pid=8526 comm="ps" target=5002 [10292.320687] type=1400 audit(1337957880.381:92): apparmor="DENIED" operation="ptrace" parent=8507 profile="/usr/lib/firefox/firefox{,*[^s][^h]}//sanitized_helper" pid=8526 comm="ps" target=5002 [10292.320751] type=1400 audit(1337957880.381:93): apparmor="DENIED" operation="ptrace" parent=8507 profile="/usr/lib/firefox/firefox{,*[^s][^h]}//sanitized_helper" pid=8526 comm="ps" target=5002 [10292.320817] type=1400 audit(1337957880.381:94): apparmor="DENIED" operation="ptrace" parent=8507 profile="/usr/lib/firefox/firefox{,*[^s][^h]}//sanitized_helper" pid=8526 comm="ps" target=5002 [10292.320880] type=1400 audit(1337957880.381:95): apparmor="DENIED" operation="ptrace" parent=8507 profile="/usr/lib/firefox/firefox{,*[^s][^h]}//sanitized_helper" pid=8526 comm="ps" target=5002 [20762.841408] audit_printk_skb: 141 callbacks suppressed [20762.841414] type=1400 audit(1337968377.153:143): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/gvfs/gvfsd-http" name="/usr/share/mime/mime.cache" pid=5335 comm="gvfsd-http" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 [20762.841439] type=1400 audit(1337968377.153:144): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/gvfs/gvfsd-http" name="/usr/share/mime/globs2" pid=5335 comm="gvfsd-http" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 [20762.841463] type=1400 audit(1337968377.153:145): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/gvfs/gvfsd-http" name="/usr/share/mime/magic" pid=5335 comm="gvfsd-http" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 [20762.841483] type=1400 audit(1337968377.153:146): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/gvfs/gvfsd-http" name="/usr/share/mime/aliases" pid=5335 comm="gvfsd-http" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 [20762.841503] type=1400 audit(1337968377.153:147): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/gvfs/gvfsd-http" name="/usr/share/mime/subclasses" pid=5335 comm="gvfsd-http" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 [20762.841524] type=1400 audit(1337968377.153:148): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/gvfs/gvfsd-http" name="/usr/share/mime/icons" pid=5335 comm="gvfsd-http" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 [20762.841544] type=1400 audit(1337968377.153:149): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/gvfs/gvfsd-http" name="/usr/share/mime/generic-icons" pid=5335 comm="gvfsd-http" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0 [28589.437577] type=1400 audit(1337976223.368:150): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/thunderbird/thunderbird{,*[^s][^h]}" name="/tmp/unity_support_test.0" pid=15535 comm="pool" requested_mask="r" denied_mask="r" fsuid=1000 ouid=104 [34726.084316] type=1400 audit(1337982375.400:151): apparmor="DENIED" operation="open" parent=1 profile="/usr/lib/thunderbird/thunderbird{,*[^s][^h]}" name="/tmp/unity_support_test.0" pid=18030 comm="pool" requested_mask="r" denied_mask="r" fsuid=1000 ouid=104 [39524.623605] wlan0: deauthenticating from 00:18:39:c1:67:60 by local choice (reason=3) [39524.691116] cfg80211: All devices are disconnected, going to restore regulatory settings [39524.691126] cfg80211: Restoring regulatory settings [39524.691144] cfg80211: Calling CRDA to update world regulatory domain [39524.743118] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain [39524.743122] cfg80211: World regulatory domain updated: [39524.743123] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) [39524.743125] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) [39524.743127] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) [39524.743129] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) [39524.743131] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) [39524.743133] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) [39527.135726] PM: Syncing filesystems ... done. [39527.149522] PM: Preparing system for mem sleep [39529.217213] Freezing user space processes ... (elapsed 0.01 seconds) done. [39529.231382] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [39529.247333] PM: Entering mem sleep [39529.247474] Suspending console(s) (use no_console_suspend to debug) [39529.409744] PM: suspend of drv:psmouse dev:serio2 complete after 162.458 msecs [39529.410113] sd 0:0:0:0: [sda] Synchronizing SCSI cache [39529.410267] sd 0:0:0:0: [sda] Stopping disk [39529.464036] sdhci-pci 0000:0d:00.0: PCI INT A disabled [39529.466787] ACPI handle has no context! [39529.478709] ehci_hcd 0000:00:1a.0: PCI INT D disabled [39529.502649] i915 0000:00:02.0: power state changed by ACPI to D3 [39529.514579] ehci_hcd 0000:00:1d.0: PCI INT D disabled [39529.528864] e1000e 0000:00:19.0: PCI INT A disabled [39529.528872] e1000e 0000:00:19.0: PME# enabled [39529.528878] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [39529.670377] snd_hda_intel 0000:00:1b.0: PCI INT B disabled [39529.686217] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 223.810 msecs [39529.799295] PM: suspend of drv:sd dev:0:0:0:0 complete after 390.164 msecs [39529.799359] PM: suspend of drv:scsi dev:target0:0:0 complete after 390.071 msecs [39529.799378] PM: suspend of drv:scsi dev:host0 complete after 337.897 msecs [39529.813884] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 351.881 msecs [39529.813946] PM: suspend of drv: dev:pci0000:00 complete after 347.928 msecs [39529.814000] PM: suspend of devices complete after 567.794 msecs [39529.814004] PM: suspend devices took 0.568 seconds [39529.830066] ehci_hcd 0000:00:1d.0: PME# enabled [39529.830071] ehci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI [39529.845806] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [39529.845865] ehci_hcd 0000:00:1a.0: PME# enabled [39529.845873] ehci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI [39529.861766] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D3 [39529.877805] PM: late suspend of devices complete after 63.955 msecs [39529.878028] ACPI: Preparing to enter system sleep state S3 [39530.057321] PM: Saving platform NVS memory [39530.061536] Disabling non-boot CPUs ... [39530.165017] CPU 1 is now offline [39530.268745] CPU 2 is now offline [39530.372492] CPU 3 is now offline [39530.372957] Extended CMOS year: 2000 [39530.373202] ACPI: Low-level resume complete [39530.373267] PM: Restoring platform NVS memory [39530.373998] Extended CMOS year: 2000 [39530.374068] Enabling non-boot CPUs ... [39530.374220] Booting Node 0 Processor 1 APIC 0x1 [39530.374221] smpboot cpu 1: start_ip = 99000 [39530.385225] Calibrating delay loop (skipped) already calibrated this CPU [39530.405652] NMI watchdog enabled, takes one hw-pmu counter. [39530.469392] CPU1 is up [39530.469561] Booting Node 0 Processor 2 APIC 0x4 [39530.469564] smpboot cpu 2: start_ip = 99000 [39530.480569] Calibrating delay loop (skipped) already calibrated this CPU [39530.501078] NMI watchdog enabled, takes one hw-pmu counter. [39530.565164] CPU2 is up [39530.565322] Booting Node 0 Processor 3 APIC 0x5 [39530.565326] smpboot cpu 3: start_ip = 99000 [39530.576330] Calibrating delay loop (skipped) already calibrated this CPU [39530.596900] NMI watchdog enabled, takes one hw-pmu counter. [39530.661019] CPU3 is up [39530.663981] ACPI: Waking up from system sleep state S3 [39530.976173] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [39530.976254] e1000e 0000:00:19.0: restoring config space at offset 0x1 (was 0x100103, writing 0x100107) [39530.976283] ehci_hcd 0000:00:1a.0: restoring config space at offset 0xf (was 0x400, writing 0x40b) [39530.976301] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2828000) [39530.976308] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [39530.976328] ehci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI [39530.976333] ehci_hcd 0000:00:1a.0: PME# disabled [39530.976336] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0 [39530.976339] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0 [39530.976468] pcieport 0000:00:1c.1: restoring config space at offset 0x7 (was 0xf0, writing 0x200000f0) [39530.976563] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x100, writing 0x4010b) [39530.976573] pcieport 0000:00:1c.4: restoring config space at offset 0x9 (was 0x10001, writing 0x1fff1) [39530.976578] pcieport 0000:00:1c.4: restoring config space at offset 0x8 (was 0x0, writing 0xf250f250) [39530.976582] pcieport 0000:00:1c.4: restoring config space at offset 0x7 (was 0x20000000, writing 0x200000f0) [39530.976590] pcieport 0000:00:1c.4: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) [39530.976596] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100000, writing 0x100107) [39530.976629] ehci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x400, writing 0x40b) [39530.976646] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2828400) [39530.976653] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [39530.976669] ehci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI [39530.976673] ehci_hcd 0000:00:1d.0: PME# disabled [39530.976675] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [39530.976678] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [39530.976779] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [39530.976844] intel ips 0000:00:1f.6: restoring config space at offset 0xf (was 0x400, writing 0x40b) [39530.976865] intel ips 0000:00:1f.6: restoring config space at offset 0x1 (was 0x100000, writing 0x100002) [39530.977351] PM: early resume of devices complete after 1.309 msecs [39530.977498] i915 0000:00:02.0: power state changed by ACPI to D0 [39530.977502] i915 0000:00:02.0: power state changed by ACPI to D0 [39530.977517] i915 0000:00:02.0: setting latency timer to 64 [39530.977552] mei 0000:00:16.0: irq 42 for MSI/MSI-X [39530.977579] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [39530.977591] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0 [39530.977594] e1000e 0000:00:19.0: PME# disabled [39530.977661] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0 [39530.977671] ehci_hcd 0000:00:1a.0: PCI INT D -> GSI 23 (level, low) -> IRQ 23 [39530.977729] ehci_hcd 0000:00:1a.0: setting latency timer to 64 [39530.977740] sdhci-pci 0000:0d:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [39530.977793] pci 0000:00:1e.0: setting latency timer to 64 [39530.977811] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [39530.977829] e1000e 0000:00:19.0: irq 43 for MSI/MSI-X [39530.977832] snd_hda_intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [39530.977842] snd_hda_intel 0000:00:1b.0: setting latency timer to 64 [39530.977884] snd_hda_intel 0000:00:1b.0: irq 44 for MSI/MSI-X [39530.977929] ahci 0000:00:1f.2: setting latency timer to 64 [39530.978090] sd 0:0:0:0: [sda] Starting disk [39530.978122] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [39530.978129] ehci_hcd 0000:00:1d.0: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [39530.978137] ehci_hcd 0000:00:1d.0: setting latency timer to 64 [39530.998606] Extended CMOS year: 2000 [39531.109420] firewire_core: skipped bus generations, destroying all nodes [39531.155593] PM: resume of drv: dev:ep_00 complete after 177.958 msecs [39531.155610] PM: resume of drv:hub dev:1-1:1.0 complete after 177.979 msecs [39531.155642] PM: resume of drv: dev:ep_81 complete after 178.011 msecs [39531.157171] PM: resume of drv:usbhid dev:1-1.2:1.1 complete after 179.322 msecs [39531.157177] PM: resume of drv:usbhid dev:1-1.2:1.0 complete after 179.365 msecs [39531.157185] PM: resume of drv: dev:ep_00 complete after 179.293 msecs [39531.157198] PM: resume of drv: dev:ep_81 complete after 179.367 msecs [39531.157204] PM: resume of drv: dev:ep_82 complete after 179.340 msecs [39531.204124] PM: resume of drv:usbhid dev:1-1.1:1.0 complete after 226.520 msecs [39531.204129] PM: resume of drv: dev:ep_00 complete after 226.478 msecs [39531.204169] PM: resume of drv: dev:ep_81 complete after 226.546 msecs [39531.227618] usb 1-1.6: reset high-speed USB device number 5 using ehci_hcd [39531.311195] ata5: SATA link down (SStatus 0 SControl 300) [39531.319170] ata6: SATA link down (SStatus 0 SControl 300) [39531.328691] PM: resume of drv:uvcvideo dev:1-1.6:1.1 complete after 351.163 msecs [39531.328698] PM: resume of drv: dev:ep_00 complete after 351.153 msecs [39531.328706] PM: resume of drv:uvcvideo dev:1-1.6:1.0 complete after 351.207 msecs [39531.328721] PM: resume of drv: dev:ep_81 complete after 351.213 msecs [39531.466803] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [39531.478725] sdhci-pci 0000:0d:00.0: Will use DMA mode even though HW doesn't fully claim to support it. [39531.478784] sdhci-pci 0000:0d:00.0: setting latency timer to 64 [39531.478860] PM: resume of drv:sdhci-pci dev:0000:0d:00.0 complete after 502.438 msecs [39531.555873] watchdog: only one watchdog can use /dev/watchdog. [39531.555878] watchdog: error registering /dev/watchdog (err=-16). [39531.555880] mei: unable to register watchdog device. [39531.568437] PM: resume of drv:i915 dev:0000:00:02.0 complete after 592.434 msecs [39531.606442] firewire_core: rediscovered device fw0 [39531.623530] ata2.00: ACPI cmd e3/00:10:00:00:00:a0 (IDLE) succeeded [39531.624361] ata2.00: ACPI cmd e3/00:03:00:00:00:a0 (IDLE) succeeded [39531.638704] ata2.00: ACPI cmd e3/00:10:00:00:00:a0 (IDLE) succeeded [39531.639124] ata2.00: ACPI cmd e3/00:03:00:00:00:a0 (IDLE) succeeded [39531.640736] ata2.00: configured for UDMA/100 [39533.373990] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [39533.384192] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [39533.384198] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [39533.384504] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [39533.384509] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [39533.387222] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [39533.387228] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [39533.387495] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [39533.387500] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [39533.388826] ata1.00: configured for UDMA/100 [39533.417002] PM: resume of drv:sd dev:0:0:0:0 complete after 2445.025 msecs [39533.417131] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 2424.572 msecs [39533.417138] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 2445.150 msecs [39533.418059] PM: resume of devices complete after 2446.759 msecs [39533.418156] PM: resume devices took 2.448 seconds [39533.418235] PM: Finishing wakeup. [39533.418236] Restarting tasks ... done. [39533.427335] video LNXVIDEO:00: Restoring backlight state [39533.902236] iwlwifi 0000:03:00.0: L1 Enabled; Disabling L0S [39533.902437] iwlwifi 0000:03:00.0: Radio type=0x0-0x3-0x1 [39534.053669] ADDRCONF(NETDEV_UP): wlan0: link is not ready [39541.797663] wlan0: authenticate with 00:18:39:c1:67:60 (try 1) [39541.801319] wlan0: authenticated [39541.801668] wlan0: associate with 00:18:39:c1:67:60 (try 1) [39541.806728] wlan0: RX AssocResp from 00:18:39:c1:67:60 (capab=0x411 status=0 aid=1) [39541.806734] wlan0: associated [39541.812745] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [39552.039183] wlan0: no IPv6 routers present [39913.648516] e1000e 0000:00:19.0: PME# enabled [39927.298235] usb 1-1.2: USB disconnect, device number 4 [39928.830696] usb 1-1.1: USB disconnect, device number 3 [40455.802300] wlan0: deauthenticating from 00:18:39:c1:67:60 by local choice (reason=3) [40455.857753] cfg80211: All devices are disconnected, going to restore regulatory settings [40455.857760] cfg80211: Restoring regulatory settings [40455.857814] cfg80211: Calling CRDA to update world regulatory domain [40455.893098] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain [40455.893105] cfg80211: World regulatory domain updated: [40455.893108] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) [40455.893114] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) [40455.893119] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) [40455.893125] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) [40455.893130] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) [40455.893135] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) [40456.133108] ADDRCONF(NETDEV_UP): wlan0: link is not ready [40483.779530] e1000e 0000:00:19.0: BAR 0: set to [mem 0xf2600000-0xf261ffff] (PCI address [0xf2600000-0xf261ffff]) [40483.779540] e1000e 0000:00:19.0: BAR 1: set to [mem 0xf2625000-0xf2625fff] (PCI address [0xf2625000-0xf2625fff]) [40483.779549] e1000e 0000:00:19.0: BAR 2: set to [io 0x1820-0x183f] (PCI address [0x1820-0x183f]) [40483.779570] e1000e 0000:00:19.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [40483.779598] e1000e 0000:00:19.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100107) [40483.779660] e1000e 0000:00:19.0: PME# disabled [40490.068789] PM: Syncing filesystems ... done. [40490.082103] PM: Preparing system for mem sleep [40491.869121] Freezing user space processes ... (elapsed 0.01 seconds) done. [40491.883209] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [40491.899169] PM: Entering mem sleep [40491.899297] Suspending console(s) (use no_console_suspend to debug) [40492.066282] PM: suspend of drv:psmouse dev:serio2 complete after 167.213 msecs [40492.066491] sd 0:0:0:0: [sda] Synchronizing SCSI cache [40492.066581] sd 0:0:0:0: [sda] Stopping disk [40492.121735] sdhci-pci 0000:0d:00.0: PCI INT A disabled [40492.126602] ACPI handle has no context! [40492.154545] i915 0000:00:02.0: power state changed by ACPI to D3 [40492.170435] ehci_hcd 0000:00:1d.0: PCI INT D disabled [40492.170446] ehci_hcd 0000:00:1a.0: PCI INT D disabled [40492.185311] e1000e 0000:00:19.0: PME# enabled [40492.185318] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [40492.222816] snd_hda_intel 0000:00:1b.0: PCI INT B disabled [40492.238290] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 117.716 msecs [40492.751341] PM: suspend of drv:sd dev:0:0:0:0 complete after 686.573 msecs [40492.751413] PM: suspend of drv:scsi dev:target0:0:0 complete after 686.606 msecs [40492.751434] PM: suspend of drv:scsi dev:host0 complete after 632.619 msecs [40492.764978] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 645.808 msecs [40492.765003] PM: suspend of drv: dev:pci0000:00 complete after 640.045 msecs [40492.765018] PM: suspend of devices complete after 867.745 msecs [40492.765020] PM: suspend devices took 0.868 seconds [40492.781198] ehci_hcd 0000:00:1d.0: PME# enabled [40492.781204] ehci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI [40492.796910] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [40492.797147] ehci_hcd 0000:00:1a.0: PME# enabled [40492.797151] ehci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI [40492.812854] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D3 [40492.828896] PM: late suspend of devices complete after 64.029 msecs [40492.829116] ACPI: Preparing to enter system sleep state S3 [40493.000427] PM: Saving platform NVS memory [40493.004586] Disabling non-boot CPUs ... [40493.108083] CPU 1 is now offline [40493.211851] CPU 2 is now offline [40493.213083] Broke affinity for irq 1 [40493.315608] CPU 3 is now offline [40493.316061] Extended CMOS year: 2000 [40493.316305] ACPI: Low-level resume complete [40493.316370] PM: Restoring platform NVS memory [40493.317100] Extended CMOS year: 2000 [40493.317170] Enabling non-boot CPUs ... [40493.317316] Booting Node 0 Processor 1 APIC 0x1 [40493.317317] smpboot cpu 1: start_ip = 99000 [40493.328323] Calibrating delay loop (skipped) already calibrated this CPU [40493.348761] NMI watchdog enabled, takes one hw-pmu counter. [40493.412522] CPU1 is up [40493.412693] Booting Node 0 Processor 2 APIC 0x4 [40493.412696] smpboot cpu 2: start_ip = 99000 [40493.423702] Calibrating delay loop (skipped) already calibrated this CPU [40493.444166] NMI watchdog enabled, takes one hw-pmu counter. [40493.508280] CPU2 is up [40493.508435] Booting Node 0 Processor 3 APIC 0x5 [40493.508439] smpboot cpu 3: start_ip = 99000 [40493.519443] Calibrating delay loop (skipped) already calibrated this CPU [40493.540099] NMI watchdog enabled, takes one hw-pmu counter. [40493.604090] CPU3 is up [40493.607178] ACPI: Waking up from system sleep state S3 [40493.971137] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [40493.971221] e1000e 0000:00:19.0: restoring config space at offset 0x1 (was 0x100103, writing 0x100107) [40493.971251] ehci_hcd 0000:00:1a.0: restoring config space at offset 0xf (was 0x400, writing 0x40b) [40493.971268] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2828000) [40493.971276] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [40493.971297] ehci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI [40493.971302] ehci_hcd 0000:00:1a.0: PME# disabled [40493.971306] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0 [40493.971309] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0 [40493.971533] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x100, writing 0x4010b) [40493.971544] pcieport 0000:00:1c.4: restoring config space at offset 0x9 (was 0x10001, writing 0x1fff1) [40493.971548] pcieport 0000:00:1c.4: restoring config space at offset 0x8 (was 0x0, writing 0xf250f250) [40493.971553] pcieport 0000:00:1c.4: restoring config space at offset 0x7 (was 0x20000000, writing 0xf0) [40493.971561] pcieport 0000:00:1c.4: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) [40493.971567] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100000, writing 0x100107) [40493.971601] ehci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x400, writing 0x40b) [40493.971619] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2828400) [40493.971626] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [40493.971644] ehci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI [40493.971648] ehci_hcd 0000:00:1d.0: PME# disabled [40493.971650] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [40493.971654] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [40493.971755] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [40493.971821] intel ips 0000:00:1f.6: restoring config space at offset 0xf (was 0x400, writing 0x40b) [40493.971843] intel ips 0000:00:1f.6: restoring config space at offset 0x1 (was 0x100000, writing 0x100002) [40493.971924] iwlwifi 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [40493.972333] PM: early resume of devices complete after 1.317 msecs [40493.972482] i915 0000:00:02.0: power state changed by ACPI to D0 [40493.972488] i915 0000:00:02.0: power state changed by ACPI to D0 [40493.972501] i915 0000:00:02.0: setting latency timer to 64 [40493.972526] mei 0000:00:16.0: irq 42 for MSI/MSI-X [40493.972552] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [40493.972557] e1000e 0000:00:19.0: PME# disabled [40493.972571] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0 [40493.972577] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0 [40493.972593] ehci_hcd 0000:00:1a.0: PCI INT D -> GSI 23 (level, low) -> IRQ 23 [40493.972605] ehci_hcd 0000:00:1a.0: setting latency timer to 64 [40493.972611] snd_hda_intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [40493.972620] snd_hda_intel 0000:00:1b.0: setting latency timer to 64 [40493.972645] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [40493.972652] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [40493.972664] ehci_hcd 0000:00:1d.0: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [40493.972678] ehci_hcd 0000:00:1d.0: setting latency timer to 64 [40493.972710] pci 0000:00:1e.0: setting latency timer to 64 [40493.972734] e1000e 0000:00:19.0: irq 43 for MSI/MSI-X [40493.972736] ahci 0000:00:1f.2: setting latency timer to 64 [40493.972761] snd_hda_intel 0000:00:1b.0: irq 44 for MSI/MSI-X [40493.972882] sdhci-pci 0000:0d:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [40493.973080] sd 0:0:0:0: [sda] Starting disk [40493.989754] Extended CMOS year: 2000 [40494.026892] firewire_core: skipped bus generations, destroying all nodes [40494.150636] PM: resume of drv: dev:ep_00 complete after 178.007 msecs [40494.150651] PM: resume of drv:hub dev:1-1:1.0 complete after 178.037 msecs [40494.150665] PM: resume of drv: dev:ep_81 complete after 178.049 msecs [40494.222515] usb 1-1.6: reset high-speed USB device number 5 using ehci_hcd [40494.306176] ata5: SATA link down (SStatus 0 SControl 300) [40494.314158] ata6: SATA link down (SStatus 0 SControl 300) [40494.323575] PM: resume of drv:uvcvideo dev:1-1.6:1.1 complete after 349.142 msecs [40494.323583] PM: resume of drv:uvcvideo dev:1-1.6:1.0 complete after 349.228 msecs [40494.323590] PM: resume of drv: dev:ep_00 complete after 349.130 msecs [40494.323602] PM: resume of drv: dev:ep_81 complete after 349.212 msecs [40494.384412] PM: resume of drv:i915 dev:0000:00:02.0 complete after 412.979 msecs [40494.469752] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [40494.473698] sdhci-pci 0000:0d:00.0: Will use DMA mode even though HW doesn't fully claim to support it. [40494.473756] sdhci-pci 0000:0d:00.0: setting latency timer to 64 [40494.473835] PM: resume of drv:sdhci-pci dev:0000:0d:00.0 complete after 502.273 msecs [40494.509739] watchdog: only one watchdog can use /dev/watchdog. [40494.509744] watchdog: error registering /dev/watchdog (err=-16). [40494.509746] mei: unable to register watchdog device. [40494.525601] firewire_core: rediscovered device fw0 [40494.562824] ata2.00: ACPI cmd e3/00:10:00:00:00:a0 (IDLE) succeeded [40494.563660] ata2.00: ACPI cmd e3/00:03:00:00:00:a0 (IDLE) succeeded [40494.577942] ata2.00: ACPI cmd e3/00:10:00:00:00:a0 (IDLE) succeeded [40494.578391] ata2.00: ACPI cmd e3/00:03:00:00:00:a0 (IDLE) succeeded [40494.580010] ata2.00: configured for UDMA/100 [40496.081706] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [40496.086782] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [40496.086788] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [40496.087050] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [40496.087055] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [40496.089746] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [40496.089753] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [40496.090062] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [40496.090067] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [40496.091356] ata1.00: configured for UDMA/100 [40496.127898] PM: resume of drv:sd dev:0:0:0:0 complete after 2160.219 msecs [40496.128026] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 2160.343 msecs [40496.128030] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 2143.614 msecs [40496.129184] PM: resume of devices complete after 2162.184 msecs [40496.129286] PM: resume devices took 2.160 seconds [40496.129380] PM: Finishing wakeup. [40496.129381] Restarting tasks ... done. [40496.143240] video LNXVIDEO:00: Restoring backlight state [40496.214457] [drm:drm_mode_getfb] *ERROR* invalid framebuffer id [40496.214462] [drm:drm_mode_getfb] *ERROR* invalid framebuffer id [54502.861859] init: anacron main process (27152) killed by TERM signal [54503.149093] PM: Syncing filesystems ... done. [54503.162528] PM: Preparing system for mem sleep [54504.710219] Freezing user space processes ... (elapsed 0.01 seconds) done. [54504.724297] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [54504.740318] PM: Entering mem sleep [54504.740454] Suspending console(s) (use no_console_suspend to debug) [54504.908813] PM: suspend of drv:psmouse dev:serio2 complete after 168.592 msecs [54504.909112] sd 0:0:0:0: [sda] Synchronizing SCSI cache [54504.909181] sd 0:0:0:0: [sda] Stopping disk [54504.963781] sdhci-pci 0000:0d:00.0: PCI INT A disabled [54504.967711] ACPI handle has no context! [54504.995625] i915 0000:00:02.0: power state changed by ACPI to D3 [54505.019526] ehci_hcd 0000:00:1a.0: PCI INT D disabled [54505.019530] ehci_hcd 0000:00:1d.0: PCI INT D disabled [54505.025758] e1000e 0000:00:19.0: PME# enabled [54505.025764] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [54505.063939] snd_hda_intel 0000:00:1b.0: PCI INT B disabled [54505.079413] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 116.959 msecs [54505.310866] PM: suspend of drv:sd dev:0:0:0:0 complete after 402.765 msecs [54505.310945] PM: suspend of drv:scsi dev:target0:0:0 complete after 402.723 msecs [54505.310969] PM: suspend of drv:scsi dev:host0 complete after 349.346 msecs [54505.326876] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 365.119 msecs [54505.326948] PM: suspend of drv: dev:pci0000:00 complete after 364.112 msecs [54505.326994] PM: suspend of devices complete after 587.829 msecs [54505.326998] PM: suspend devices took 0.588 seconds [54505.342950] ehci_hcd 0000:00:1d.0: PME# enabled [54505.342956] ehci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI [54505.358712] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [54505.358951] ehci_hcd 0000:00:1a.0: PME# enabled [54505.358956] ehci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI [54505.374672] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D3 [54505.390711] PM: late suspend of devices complete after 63.866 msecs [54505.390929] ACPI: Preparing to enter system sleep state S3 [54505.570224] PM: Saving platform NVS memory [54505.574324] Disabling non-boot CPUs ... [54505.677921] CPU 1 is now offline [54505.781730] CPU 2 is now offline [54505.885387] CPU 3 is now offline [54505.885837] Extended CMOS year: 2000 [54505.886081] ACPI: Low-level resume complete [54505.886145] PM: Restoring platform NVS memory [54505.886885] Extended CMOS year: 2000 [54505.886956] Enabling non-boot CPUs ... [54505.887099] Booting Node 0 Processor 1 APIC 0x1 [54505.887101] smpboot cpu 1: start_ip = 99000 [54505.898106] Calibrating delay loop (skipped) already calibrated this CPU [54505.918504] NMI watchdog enabled, takes one hw-pmu counter. [54505.934479] CPU1 is up [54505.934643] Booting Node 0 Processor 2 APIC 0x4 [54505.934646] smpboot cpu 2: start_ip = 99000 [54505.945651] Calibrating delay loop (skipped) already calibrated this CPU [54505.966078] NMI watchdog enabled, takes one hw-pmu counter. [54505.982339] CPU2 is up [54505.982476] Booting Node 0 Processor 3 APIC 0x5 [54505.982479] smpboot cpu 3: start_ip = 99000 [54505.993484] Calibrating delay loop (skipped) already calibrated this CPU [54506.014065] NMI watchdog enabled, takes one hw-pmu counter. [54506.030326] CPU3 is up [54506.033416] ACPI: Waking up from system sleep state S3 [54506.381347] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [54506.381431] e1000e 0000:00:19.0: restoring config space at offset 0x1 (was 0x100103, writing 0x100107) [54506.381461] ehci_hcd 0000:00:1a.0: restoring config space at offset 0xf (was 0x400, writing 0x40b) [54506.381478] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2828000) [54506.381486] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [54506.381508] ehci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI [54506.381513] ehci_hcd 0000:00:1a.0: PME# disabled [54506.381517] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0 [54506.381520] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0 [54506.381745] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x100, writing 0x4010b) [54506.381756] pcieport 0000:00:1c.4: restoring config space at offset 0x9 (was 0x10001, writing 0x1fff1) [54506.381761] pcieport 0000:00:1c.4: restoring config space at offset 0x8 (was 0x0, writing 0xf250f250) [54506.381765] pcieport 0000:00:1c.4: restoring config space at offset 0x7 (was 0x20000000, writing 0x200000f0) [54506.381774] pcieport 0000:00:1c.4: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) [54506.381780] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100000, writing 0x100107) [54506.381813] ehci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x400, writing 0x40b) [54506.381831] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2828400) [54506.381838] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [54506.381856] ehci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI [54506.381861] ehci_hcd 0000:00:1d.0: PME# disabled [54506.381863] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [54506.381867] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [54506.381968] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [54506.382034] intel ips 0000:00:1f.6: restoring config space at offset 0xf (was 0x400, writing 0x40b) [54506.382055] intel ips 0000:00:1f.6: restoring config space at offset 0x1 (was 0x100000, writing 0x100002) [54506.382137] iwlwifi 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [54506.382549] PM: early resume of devices complete after 1.323 msecs [54506.382694] i915 0000:00:02.0: power state changed by ACPI to D0 [54506.382698] i915 0000:00:02.0: power state changed by ACPI to D0 [54506.382709] i915 0000:00:02.0: setting latency timer to 64 [54506.382715] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [54506.382723] e1000e 0000:00:19.0: PME# disabled [54506.382773] mei 0000:00:16.0: irq 42 for MSI/MSI-X [54506.382781] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0 [54506.382793] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0 [54506.382811] ehci_hcd 0000:00:1a.0: PCI INT D -> GSI 23 (level, low) -> IRQ 23 [54506.382820] ehci_hcd 0000:00:1a.0: setting latency timer to 64 [54506.382859] snd_hda_intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [54506.382867] e1000e 0000:00:19.0: irq 43 for MSI/MSI-X [54506.382875] snd_hda_intel 0000:00:1b.0: setting latency timer to 64 [54506.382921] snd_hda_intel 0000:00:1b.0: irq 44 for MSI/MSI-X [54506.382968] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [54506.382972] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [54506.382977] ehci_hcd 0000:00:1d.0: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [54506.382983] ehci_hcd 0000:00:1d.0: setting latency timer to 64 [54506.383009] pci 0000:00:1e.0: setting latency timer to 64 [54506.383028] ahci 0000:00:1f.2: setting latency timer to 64 [54506.383159] sdhci-pci 0000:0d:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [54506.383437] sd 0:0:0:0: [sda] Starting disk [54506.401174] Extended CMOS year: 2000 [54506.534940] firewire_core: skipped bus generations, destroying all nodes [54506.560816] PM: resume of drv:hub dev:1-1:1.0 complete after 177.863 msecs [54506.560826] PM: resume of drv: dev:ep_00 complete after 177.845 msecs [54506.560844] PM: resume of drv: dev:ep_81 complete after 177.886 msecs [54506.632798] usb 1-1.6: reset high-speed USB device number 5 using ehci_hcd [54506.708411] ata5: SATA link down (SStatus 0 SControl 300) [54506.716404] ata6: SATA link down (SStatus 0 SControl 300) [54506.733750] PM: resume of drv: dev:ep_00 complete after 350.964 msecs [54506.733756] PM: resume of drv:uvcvideo dev:1-1.6:1.1 complete after 350.981 msecs [54506.733765] PM: resume of drv:uvcvideo dev:1-1.6:1.0 complete after 351.025 msecs [54506.733779] PM: resume of drv: dev:ep_81 complete after 351.027 msecs [54506.770632] PM: resume of drv:i915 dev:0000:00:02.0 complete after 388.923 msecs [54506.883910] sdhci-pci 0000:0d:00.0: Will use DMA mode even though HW doesn't fully claim to support it. [54506.883969] sdhci-pci 0000:0d:00.0: setting latency timer to 64 [54506.884048] PM: resume of drv:sdhci-pci dev:0000:0d:00.0 complete after 502.199 msecs [54506.895872] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [54506.898731] ata2.00: ACPI cmd e3/00:10:00:00:00:a0 (IDLE) succeeded [54506.899159] ata2.00: ACPI cmd e3/00:03:00:00:00:a0 (IDLE) succeeded [54506.904351] ata2.00: ACPI cmd e3/00:10:00:00:00:a0 (IDLE) succeeded [54506.905375] ata2.00: ACPI cmd e3/00:03:00:00:00:a0 (IDLE) succeeded [54506.906997] ata2.00: configured for UDMA/100 [54507.031581] firewire_core: rediscovered device fw0 [54507.047094] watchdog: only one watchdog can use /dev/watchdog. [54507.047098] watchdog: error registering /dev/watchdog (err=-16). [54507.047101] mei: unable to register watchdog device. [54508.659496] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [54508.665969] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [54508.665975] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [54508.666243] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [54508.666248] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [54508.668958] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [54508.668965] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [54508.669257] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [54508.669264] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [54508.670581] ata1.00: configured for UDMA/100 [54508.698771] PM: resume of drv:sd dev:0:0:0:0 complete after 2321.138 msecs [54508.698864] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 2321.202 msecs [54508.698872] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 2303.434 msecs [54508.699863] PM: resume of devices complete after 2323.051 msecs [54508.699968] PM: resume devices took 2.324 seconds [54508.700061] PM: Finishing wakeup. [54508.700063] Restarting tasks ... done. [54508.711056] video LNXVIDEO:00: Restoring backlight state [54508.792666] [drm:drm_mode_getfb] *ERROR* invalid framebuffer id [54508.792671] [drm:drm_mode_getfb] *ERROR* invalid framebuffer id [54509.003839] init: anacron main process (27571) killed by TERM signal [54509.077172] e1000e 0000:00:19.0: PME# enabled [54540.666738] iwlwifi 0000:03:00.0: L1 Enabled; Disabling L0S [54540.666937] iwlwifi 0000:03:00.0: Radio type=0x0-0x3-0x1 [54540.809555] ADDRCONF(NETDEV_UP): wlan0: link is not ready [54548.541422] wlan0: authenticate with 00:18:39:c1:67:60 (try 1) [54548.544564] wlan0: authenticated [54548.545471] wlan0: associate with 00:18:39:c1:67:60 (try 1) [54548.550490] wlan0: RX AssocResp from 00:18:39:c1:67:60 (capab=0x411 status=0 aid=1) [54548.550494] wlan0: associated [54548.556374] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [54559.116973] wlan0: no IPv6 routers present [56247.123853] e1000e 0000:00:19.0: BAR 0: set to [mem 0xf2600000-0xf261ffff] (PCI address [0xf2600000-0xf261ffff]) [56247.123868] e1000e 0000:00:19.0: BAR 1: set to [mem 0xf2625000-0xf2625fff] (PCI address [0xf2625000-0xf2625fff]) [56247.123881] e1000e 0000:00:19.0: BAR 2: set to [io 0x1820-0x183f] (PCI address [0x1820-0x183f]) [56247.123912] e1000e 0000:00:19.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [56247.123950] e1000e 0000:00:19.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100107) [56247.124029] e1000e 0000:00:19.0: PME# disabled [59355.986021] type=1400 audit(1338048556.363:152): apparmor="STATUS" operation="profile_load" name="libvirt-653c3e76-9705-334b-7151-ae5dd5248c11" pid=29679 comm="apparmor_parser" [59357.015258] device vnet0 entered promiscuous mode [59357.036426] br-vz0: port 1(vnet0) entering forwarding state [59357.036449] br-vz0: port 1(vnet0) entering forwarding state [59367.580598] vnet0: no IPv6 routers present [59428.348206] tcpdump uses obsolete (PF_INET,SOCK_PACKET) [60468.398467] br-vz0: port 1(vnet0) entering forwarding state [60468.400892] br-vz0: port 1(vnet0) entering disabled state [60468.401107] device vnet0 left promiscuous mode [60468.401110] br-vz0: port 1(vnet0) entering disabled state [60468.402044] br-vz0: mixed no checksumming and other settings. [60469.122856] type=1400 audit(1338049672.289:153): apparmor="STATUS" operation="profile_remove" name="libvirt-653c3e76-9705-334b-7151-ae5dd5248c11" pid=29827 comm="apparmor_parser" [60535.838191] type=1400 audit(1338049739.169:154): apparmor="STATUS" operation="profile_load" name="libvirt-653c3e76-9705-334b-7151-ae5dd5248c11" pid=29919 comm="apparmor_parser" [60536.189399] device vnet0 entered promiscuous mode [60536.214726] br-vz0: port 1(vnet0) entering forwarding state [60536.214752] br-vz0: port 1(vnet0) entering forwarding state [60546.483558] vnet0: no IPv6 routers present [60609.413101] br-vz0: port 1(vnet0) entering forwarding state [60609.415893] br-vz0: port 1(vnet0) entering disabled state [60609.415978] device vnet0 left promiscuous mode [60609.415984] br-vz0: port 1(vnet0) entering disabled state [60609.417274] br-vz0: mixed no checksumming and other settings. [60610.137643] type=1400 audit(1338049813.656:155): apparmor="STATUS" operation="profile_remove" name="libvirt-653c3e76-9705-334b-7151-ae5dd5248c11" pid=29971 comm="apparmor_parser" [60644.244117] type=1400 audit(1338049847.848:156): apparmor="STATUS" operation="profile_load" name="libvirt-653c3e76-9705-334b-7151-ae5dd5248c11" pid=29981 comm="apparmor_parser" [60644.518242] device vnet0 entered promiscuous mode [60644.539491] br-vz0: port 1(vnet0) entering forwarding state [60644.539514] br-vz0: port 1(vnet0) entering forwarding state [60654.836286] vnet0: no IPv6 routers present [60671.247377] br-vz0: port 1(vnet0) entering forwarding state [60671.250060] br-vz0: port 1(vnet0) entering disabled state [60671.250265] device vnet0 left promiscuous mode [60671.250268] br-vz0: port 1(vnet0) entering disabled state [60671.251470] br-vz0: mixed no checksumming and other settings. [60671.975808] type=1400 audit(1338049875.648:157): apparmor="STATUS" operation="profile_remove" name="libvirt-653c3e76-9705-334b-7151-ae5dd5248c11" pid=30029 comm="apparmor_parser" [60691.219839] type=1400 audit(1338049894.940:158): apparmor="STATUS" operation="profile_load" name="libvirt-653c3e76-9705-334b-7151-ae5dd5248c11" pid=30039 comm="apparmor_parser" [60691.544488] device vnet0 entered promiscuous mode [60691.547065] br-vz0: port 1(vnet0) entering forwarding state [60691.547089] br-vz0: port 1(vnet0) entering forwarding state [60701.902487] vnet0: no IPv6 routers present [61381.405668] device-mapper: multipath: version 1.3.0 loaded [61851.313588] EXT4-fs (loop0): mounted filesystem with ordered data mode. Opts: (null) [61861.790586] EXT4-fs (loop1): mounted filesystem with ordered data mode. Opts: (null) [61945.191708] EXT4-fs (dm-12): mounted filesystem with ordered data mode. Opts: (null) [61993.466732] br-vz0: port 1(vnet0) entering forwarding state [61993.471369] br-vz0: port 1(vnet0) entering disabled state [61993.471692] device vnet0 left promiscuous mode [61993.471699] br-vz0: port 1(vnet0) entering disabled state [61993.473058] br-vz0: mixed no checksumming and other settings. [61994.188896] type=1400 audit(1338051201.172:159): apparmor="STATUS" operation="profile_remove" name="libvirt-653c3e76-9705-334b-7151-ae5dd5248c11" pid=32223 comm="apparmor_parser" [61995.900681] type=1400 audit(1338051202.888:160): apparmor="STATUS" operation="profile_load" name="libvirt-653c3e76-9705-334b-7151-ae5dd5248c11" pid=32227 comm="apparmor_parser" [61996.233945] device vnet0 entered promiscuous mode [61996.254960] br-vz0: port 1(vnet0) entering forwarding state [61996.254983] br-vz0: port 1(vnet0) entering forwarding state [62006.899186] vnet0: no IPv6 routers present [62019.697168] br-vz0: port 1(vnet0) entering forwarding state [62019.699539] br-vz0: port 1(vnet0) entering disabled state [62019.699683] device vnet0 left promiscuous mode [62019.699687] br-vz0: port 1(vnet0) entering disabled state [62019.700452] br-vz0: mixed no checksumming and other settings. [62020.423388] type=1400 audit(1338051227.472:161): apparmor="STATUS" operation="profile_remove" name="libvirt-653c3e76-9705-334b-7151-ae5dd5248c11" pid=32275 comm="apparmor_parser" [62150.192700] type=1400 audit(1338051357.567:162): apparmor="STATUS" operation="profile_load" name="libvirt-653c3e76-9705-334b-7151-ae5dd5248c11" pid=32406 comm="apparmor_parser" [62150.480885] device vnet0 entered promiscuous mode [62150.494108] br-vz0: port 1(vnet0) entering forwarding state [62150.494129] br-vz0: port 1(vnet0) entering forwarding state [62160.926573] vnet0: no IPv6 routers present [62179.911431] br-vz0: port 1(vnet0) entering forwarding state [62179.911574] device vnet0 left promiscuous mode [62179.911580] br-vz0: port 1(vnet0) entering disabled state [62179.913254] br-vz0: mixed no checksumming and other settings. [62180.636532] type=1400 audit(1338051388.087:163): apparmor="STATUS" operation="profile_remove" name="libvirt-653c3e76-9705-334b-7151-ae5dd5248c11" pid=32454 comm="apparmor_parser" [62205.835028] type=1400 audit(1338051413.351:164): apparmor="STATUS" operation="profile_load" name="libvirt-653c3e76-9705-334b-7151-ae5dd5248c11" pid=32464 comm="apparmor_parser" [62206.301039] device vnet0 entered promiscuous mode [62206.342040] br-vz0: port 1(vnet0) entering forwarding state [62206.342059] br-vz0: port 1(vnet0) entering forwarding state [62216.395729] vnet0: no IPv6 routers present [62225.781155] br-vz0: port 1(vnet0) entering forwarding state [62225.783927] br-vz0: port 1(vnet0) entering disabled state [62225.784122] device vnet0 left promiscuous mode [62225.784125] br-vz0: port 1(vnet0) entering disabled state [62225.785034] br-vz0: mixed no checksumming and other settings. [62226.510060] type=1400 audit(1338051434.075:165): apparmor="STATUS" operation="profile_remove" name="libvirt-653c3e76-9705-334b-7151-ae5dd5248c11" pid=32513 comm="apparmor_parser" [62288.081210] type=1400 audit(1338051495.803:166): apparmor="STATUS" operation="profile_load" name="libvirt-653c3e76-9705-334b-7151-ae5dd5248c11" pid=32540 comm="apparmor_parser" [62288.359578] device vnet0 entered promiscuous mode [62288.380857] br-vz0: port 1(vnet0) entering forwarding state [62288.380876] br-vz0: port 1(vnet0) entering forwarding state [62299.228314] vnet0: no IPv6 routers present [62409.163656] type=1400 audit(1338051617.190:167): apparmor="STATUS" operation="profile_load" name="libvirt-a27289c9-2c2a-4fd2-7480-1f70b24a966f" pid=32650 comm="apparmor_parser" [62410.071454] type=1400 audit(1338051618.102:168): apparmor="STATUS" operation="profile_remove" name="libvirt-a27289c9-2c2a-4fd2-7480-1f70b24a966f" pid=32655 comm="apparmor_parser" [62622.143057] br-vz0: port 1(vnet0) entering forwarding state [62622.145843] br-vz0: port 1(vnet0) entering disabled state [62622.146096] device vnet0 left promiscuous mode [62622.146103] br-vz0: port 1(vnet0) entering disabled state [62622.147606] br-vz0: mixed no checksumming and other settings. [62622.867822] type=1400 audit(1338051831.430:169): apparmor="STATUS" operation="profile_remove" name="libvirt-653c3e76-9705-334b-7151-ae5dd5248c11" pid=32696 comm="apparmor_parser" [62674.478050] type=1400 audit(1338051883.170:170): apparmor="STATUS" operation="profile_load" name="libvirt-a27289c9-2c2a-4fd2-7480-1f70b24a966f" pid=32751 comm="apparmor_parser" [62674.768017] device vnet0 entered promiscuous mode [62674.793408] br-vz0: port 1(vnet0) entering forwarding state [62674.793424] br-vz0: port 1(vnet0) entering forwarding state [62685.397290] vnet0: no IPv6 routers present [62706.170822] br-vz0: port 1(vnet0) entering forwarding state [62706.171122] device vnet0 left promiscuous mode [62706.171130] br-vz0: port 1(vnet0) entering disabled state [62706.172559] br-vz0: mixed no checksumming and other settings. [62706.649376] type=1400 audit(1338051915.421:171): apparmor="STATUS" operation="profile_remove" name="libvirt-a27289c9-2c2a-4fd2-7480-1f70b24a966f" pid=379 comm="apparmor_parser" [62761.181833] type=1400 audit(1338051970.089:172): apparmor="STATUS" operation="profile_load" name="libvirt-a27289c9-2c2a-4fd2-7480-1f70b24a966f" pid=469 comm="apparmor_parser" [62761.518759] device vnet0 entered promiscuous mode [62761.540066] br-vz0: port 1(vnet0) entering forwarding state [62761.540093] br-vz0: port 1(vnet0) entering forwarding state [62771.709169] vnet0: no IPv6 routers present [64185.746520] kvm: sending ioctl 2285 to a partition! [64185.746572] kvm: sending ioctl 2285 to a partition! [64186.257244] kvm: sending ioctl 2285 to a partition! [64186.257298] kvm: sending ioctl 2285 to a partition! [64186.283963] kvm: sending ioctl 2285 to a partition! [64194.609090] kvm: sending ioctl 2285 to a partition! [64194.609206] kvm: sending ioctl 2285 to a partition! [64202.853663] kvm: sending ioctl 2285 to a partition! [64203.079221] kvm: sending ioctl 2285 to a partition! [64203.079283] kvm: sending ioctl 2285 to a partition! [64203.095130] kvm: sending ioctl 2285 to a partition! [64219.767741] kvm: sending ioctl 2285 to a partition! [64219.767795] kvm: sending ioctl 2285 to a partition! [64229.432720] kvm: sending ioctl 2285 to a partition! [64229.485295] kvm: sending ioctl 2285 to a partition! [64229.486858] kvm: sending ioctl 2285 to a partition! [64229.492924] kvm: sending ioctl 2285 to a partition! [64229.497675] kvm: sending ioctl 2285 to a partition! [64274.204376] kvm: sending ioctl 2285 to a partition! [64274.204429] kvm: sending ioctl 2285 to a partition! [64274.229294] kvm: sending ioctl 2285 to a partition! [64274.229347] kvm: sending ioctl 2285 to a partition! [64274.245071] kvm: sending ioctl 2285 to a partition! [64274.263683] kvm: sending ioctl 2285 to a partition! [65690.053091] kvm: sending ioctl 2285 to a partition! [65690.053157] kvm: sending ioctl 2285 to a partition! [65690.297666] kvm: sending ioctl 2285 to a partition! [65690.297737] kvm: sending ioctl 2285 to a partition! [65715.202909] kvm: sending ioctl 2285 to a partition! [66972.384885] br-vz0: port 1(vnet0) entering forwarding state [66972.386206] br-vz0: port 1(vnet0) entering disabled state [66972.386275] device vnet0 left promiscuous mode [66972.386278] br-vz0: port 1(vnet0) entering disabled state [66972.386910] br-vz0: mixed no checksumming and other settings. [66973.201262] type=1400 audit(1338056192.655:173): apparmor="STATUS" operation="profile_remove" name="libvirt-a27289c9-2c2a-4fd2-7480-1f70b24a966f" pid=6407 comm="apparmor_parser" [66979.219261] type=1400 audit(1338056198.687:174): apparmor="STATUS" operation="profile_load" name="libvirt-a27289c9-2c2a-4fd2-7480-1f70b24a966f" pid=6420 comm="apparmor_parser" [66979.548740] device vnet0 entered promiscuous mode [66979.566041] br-vz0: port 1(vnet0) entering forwarding state [66979.566065] br-vz0: port 1(vnet0) entering forwarding state [66989.691248] vnet0: no IPv6 routers present [67078.083077] kvm[6460]: segfault at 7f53010c7c08 ip 00007f52f838e7da sp 00007fff3f625c60 error 6 in libc-2.15.so[7f52f830e000+1b3000] [67153.949711] br-vz0: port 1(vnet0) entering forwarding state [67153.950511] device vnet0 left promiscuous mode [67153.950516] br-vz0: port 1(vnet0) entering disabled state [67153.951898] br-vz0: mixed no checksumming and other settings. [67154.438859] type=1400 audit(1338056374.352:175): apparmor="STATUS" operation="profile_remove" name="libvirt-a27289c9-2c2a-4fd2-7480-1f70b24a966f" pid=6637 comm="apparmor_parser" [69771.132090] type=1400 audit(1338058997.601:176): apparmor="STATUS" operation="profile_remove" name="/usr/lib/firefox/firefox{,*[^s][^h]}" pid=9448 comm="apparmor_parser" [70236.124365] type=1400 audit(1338059463.756:177): apparmor="STATUS" operation="profile_load" name="/usr/lib/firefox/firefox{,*[^s][^h]}" pid=10471 comm="apparmor_parser" [70236.126307] type=1400 audit(1338059463.760:178): apparmor="STATUS" operation="profile_load" name="/usr/lib/firefox/firefox{,*[^s][^h]}//browser_java" pid=10471 comm="apparmor_parser" [70236.127236] type=1400 audit(1338059463.760:179): apparmor="STATUS" operation="profile_load" name="/usr/lib/firefox/firefox{,*[^s][^h]}//browser_openjdk" pid=10471 comm="apparmor_parser" [70236.128083] type=1400 audit(1338059463.760:180): apparmor="STATUS" operation="profile_load" name="/usr/lib/firefox/firefox{,*[^s][^h]}//sanitized_helper" pid=10471 comm="apparmor_parser"