Mar 16 03:19:12 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 192.168.0.215 port 67
Mar 16 03:19:12 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 03:19:12 birne dhclient: bound to 192.168.0.161 -- renewal in 1519 seconds.
Mar 16 03:19:12 birne NetworkManager[845]: <info> (wlan0): DHCPv4 state changed bound -> renew
Mar 16 03:19:12 birne NetworkManager[845]: <info>   address 192.168.0.161
Mar 16 03:19:12 birne NetworkManager[845]: <info>   prefix 24 (255.255.255.0)
Mar 16 03:19:12 birne NetworkManager[845]: <info>   gateway 192.168.0.215
Mar 16 03:19:12 birne NetworkManager[845]: <info>   nameserver '77.244.240.244'
Mar 16 03:19:12 birne NetworkManager[845]: <info>   domain name 'example.org'
Mar 16 03:28:01 birne wpa_supplicant[1462]: WPA: Group rekeying completed with 00:1d:7e:fb:d4:5f [GTK=TKIP]
Mar 16 03:44:31 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 192.168.0.215 port 67
Mar 16 03:44:31 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 03:44:31 birne dhclient: bound to 192.168.0.161 -- renewal in 1519 seconds.
Mar 16 04:09:50 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 192.168.0.215 port 67
Mar 16 04:09:50 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 04:09:50 birne dhclient: bound to 192.168.0.161 -- renewal in 1587 seconds.
Mar 16 04:27:57 birne wpa_supplicant[1462]: WPA: Group rekeying completed with 00:1d:7e:fb:d4:5f [GTK=TKIP]
Mar 16 04:31:44 birne NetworkManager[845]: <info> sleep requested (sleeping: no  enabled: yes)
Mar 16 04:31:44 birne NetworkManager[845]: <info> sleeping or disabling...
Mar 16 04:31:44 birne NetworkManager[845]: <info> (eth0): now unmanaged
Mar 16 04:31:44 birne NetworkManager[845]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Mar 16 04:31:44 birne NetworkManager[845]: <info> (eth0): cleaning up...
Mar 16 04:31:44 birne NetworkManager[845]: <info> (eth0): taking down device.
Mar 16 04:31:44 birne NetworkManager[845]: <info> (wlan0): now unmanaged
Mar 16 04:31:44 birne NetworkManager[845]: <info> (wlan0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
Mar 16 04:31:44 birne NetworkManager[845]: <info> (wlan0): deactivating device (reason 'sleeping') [37]
Mar 16 04:31:44 birne NetworkManager[845]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 27546
Mar 16 04:31:44 birne kernel: [71024.834683] wlan0: deauthenticating from 00:1d:7e:fb:d4:5f by local choice (reason=3)
Mar 16 04:31:44 birne wpa_supplicant[1462]: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=3
Mar 16 04:31:44 birne kernel: [71024.927522] cfg80211: All devices are disconnected, going to restore regulatory settings
Mar 16 04:31:44 birne kernel: [71024.927536] cfg80211: Restoring regulatory settings
Mar 16 04:31:44 birne kernel: [71024.927591] cfg80211: Calling CRDA to update world regulatory domain
Mar 16 04:31:44 birne NetworkManager[845]: <info> (wlan0): cleaning up...
Mar 16 04:31:44 birne NetworkManager[845]: <info> (wlan0): taking down device.
Mar 16 04:31:44 birne kernel: [71024.957397] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain 
Mar 16 04:31:44 birne kernel: [71024.957402] cfg80211: World regulatory domain updated:
Mar 16 04:31:44 birne kernel: [71024.957404] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Mar 16 04:31:44 birne kernel: [71024.957406] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 16 04:31:44 birne kernel: [71024.957407] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Mar 16 04:31:44 birne kernel: [71024.957409] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Mar 16 04:31:44 birne kernel: [71024.957411] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 16 04:31:44 birne kernel: [71024.957412] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 16 04:31:45 birne kernel: [71025.679082] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro,commit=0
Mar 16 04:31:45 birne kernel: [71026.296798] usb 1-1.4: USB disconnect, device number 12
Mar 16 04:31:45 birne kernel: [71026.296910] btusb_bulk_complete: hci0 urb ffff880102962240 failed to resubmit (19)
Mar 16 04:31:45 birne kernel: [71026.296941] btusb_intr_complete: hci0 urb ffff880102962180 failed to resubmit (19)
Mar 16 04:31:45 birne kernel: [71026.297034] btusb_bulk_complete: hci0 urb ffff880102962780 failed to resubmit (19)
Mar 16 04:31:45 birne kernel: [71026.297368] btusb_send_frame: hci0 urb ffff8800a7bbb180 submission failed
Mar 16 04:31:46 birne kernel: [71026.698141] PM: Syncing filesystems ... done.
Mar 16 04:31:46 birne kernel: [71026.709870] PM: Preparing system for mem sleep
Mar 16 04:32:30 birne kernel: [71028.045295] Freezing user space processes ... (elapsed 0.01 seconds) done.
Mar 16 04:32:30 birne kernel: [71028.059496] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Mar 16 04:32:30 birne kernel: [71028.075461] PM: Entering mem sleep
Mar 16 04:32:30 birne kernel: [71028.075571] Suspending console(s) (use no_console_suspend to debug)
Mar 16 04:32:30 birne kernel: [71028.240324] PM: suspend of drv:psmouse dev:serio2 complete after 164.878 msecs
Mar 16 04:32:30 birne kernel: [71028.240698] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Mar 16 04:32:30 birne kernel: [71028.240880] sd 0:0:0:0: [sda] Stopping disk
Mar 16 04:32:30 birne kernel: [71028.466424] PM: suspend of drv:tpm_tis dev:00:0b complete after 176.468 msecs
Mar 16 04:32:30 birne kernel: [71028.466557] ehci_hcd 0000:00:1d.0: PCI INT D disabled
Mar 16 04:32:30 birne kernel: [71028.466570] ehci_hcd 0000:00:1a.0: PCI INT D disabled
Mar 16 04:32:30 birne kernel: [71028.466690] ACPI handle has no context!
Mar 16 04:32:30 birne kernel: [71028.498289] i915 0000:00:02.0: power state changed by ACPI to D3
Mar 16 04:32:30 birne kernel: [71028.517791] PM: suspend of drv:sd dev:0:0:0:0 complete after 277.793 msecs
Mar 16 04:32:30 birne kernel: [71028.517801] PM: suspend of drv:scsi dev:target0:0:0 complete after 277.771 msecs
Mar 16 04:32:30 birne kernel: [71028.517809] PM: suspend of drv:scsi dev:host0 complete after 277.746 msecs
Mar 16 04:32:30 birne kernel: [71028.570505] HDA Intel 0000:00:1b.0: PCI INT B disabled
Mar 16 04:32:30 birne kernel: [71028.571770] e1000e 0000:00:19.0: PME# enabled
Mar 16 04:32:30 birne kernel: [71028.571776] e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Mar 16 04:32:30 birne kernel: [71028.586114] PM: suspend of drv:e1000e dev:0000:00:19.0 complete after 119.837 msecs
Mar 16 04:32:30 birne kernel: [71028.586120] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.864 msecs
Mar 16 04:32:30 birne kernel: [71028.586146] PM: suspend of drv: dev:pci0000:00 complete after 119.215 msecs
Mar 16 04:32:30 birne kernel: [71028.586169] PM: suspend of devices complete after 511.633 msecs
Mar 16 04:32:30 birne kernel: [71028.586172] PM: suspend devices took 0.512 seconds
Mar 16 04:32:30 birne kernel: [71028.618015] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D3
Mar 16 04:32:30 birne kernel: [71028.653950] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D3
Mar 16 04:32:30 birne kernel: [71028.669965] PM: late suspend of devices complete after 83.997 msecs
Mar 16 04:32:30 birne kernel: [71028.670168] ACPI: Preparing to enter system sleep state S3
Mar 16 04:32:30 birne kernel: [71028.869423] PM: Saving platform NVS memory
Mar 16 04:32:30 birne kernel: [71028.873955] Disabling non-boot CPUs ...
Mar 16 04:32:30 birne kernel: [71028.977116] CPU 1 is now offline
Mar 16 04:32:30 birne kernel: [71029.080847] CPU 2 is now offline
Mar 16 04:32:30 birne kernel: [71029.082568] CPU 3 is now offline
Mar 16 04:32:30 birne kernel: [71029.083001] Extended CMOS year: 2000
Mar 16 04:32:30 birne kernel: [71029.083220] ACPI: Low-level resume complete
Mar 16 04:32:30 birne kernel: [71029.083275] PM: Restoring platform NVS memory
Mar 16 04:32:30 birne kernel: [71029.083898] Extended CMOS year: 2000
Mar 16 04:32:30 birne kernel: [71029.083944] Enabling non-boot CPUs ...
Mar 16 04:32:30 birne kernel: [71029.084031] Booting Node 0 Processor 1 APIC 0x1
Mar 16 04:32:30 birne kernel: [71029.084032] smpboot cpu 1: start_ip = 99000
Mar 16 04:32:30 birne kernel: [71029.197430] Switched to NOHz mode on CPU #1
Mar 16 04:32:30 birne kernel: [71029.257406] CPU1 is up
Mar 16 04:32:30 birne kernel: [71029.257520] Booting Node 0 Processor 2 APIC 0x4
Mar 16 04:32:30 birne kernel: [71029.257521] smpboot cpu 2: start_ip = 99000
Mar 16 04:32:30 birne kernel: [71029.368952] Switched to NOHz mode on CPU #2
Mar 16 04:32:30 birne kernel: [71029.428968] CPU2 is up
Mar 16 04:32:30 birne kernel: [71029.429065] Booting Node 0 Processor 3 APIC 0x5
Mar 16 04:32:30 birne kernel: [71029.429066] smpboot cpu 3: start_ip = 99000
Mar 16 04:32:30 birne kernel: [71029.540627] Switched to NOHz mode on CPU #3
Mar 16 04:32:30 birne kernel: [71029.600633] CPU3 is up
Mar 16 04:32:30 birne kernel: [71029.602952] ACPI: Waking up from system sleep state S3
Mar 16 04:32:30 birne kernel: [71029.899857] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407)
Mar 16 04:32:30 birne kernel: [71029.900002] ehci_hcd 0000:00:1a.0: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 04:32:30 birne kernel: [71029.900019] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2728000)
Mar 16 04:32:30 birne kernel: [71029.900026] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102)
Mar 16 04:32:30 birne kernel: [71029.919658] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 04:32:30 birne kernel: [71029.919667] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 04:32:30 birne kernel: [71029.919913] ehci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 04:32:30 birne kernel: [71029.919930] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2728400)
Mar 16 04:32:30 birne kernel: [71029.919937] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102)
Mar 16 04:32:30 birne kernel: [71029.919954] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 04:32:30 birne kernel: [71029.919958] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 04:32:30 birne kernel: [71029.920058] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407)
Mar 16 04:32:30 birne kernel: [71029.920123] intel ips 0000:00:1f.6: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 04:32:30 birne kernel: [71029.920144] intel ips 0000:00:1f.6: restoring config space at offset 0x1 (was 0x100000, writing 0x100002)
Mar 16 04:32:30 birne kernel: [71029.920225] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506)
Mar 16 04:32:30 birne kernel: [71029.920410] PM: early resume of devices complete after 20.741 msecs
Mar 16 04:32:30 birne kernel: [71029.920773] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 04:32:30 birne kernel: [71029.920791] pci 0000:00:1e.0: setting latency timer to 64
Mar 16 04:32:30 birne kernel: [71029.920800] i915 0000:00:02.0: power state changed by ACPI to D0
Mar 16 04:32:30 birne kernel: [71029.920806] e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Mar 16 04:32:30 birne kernel: [71029.920812] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 04:32:30 birne kernel: [71029.920816] e1000e 0000:00:19.0: PME# disabled
Mar 16 04:32:30 birne kernel: [71029.920867] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Mar 16 04:32:30 birne kernel: [71029.920872] HDA Intel 0000:00:1b.0: setting latency timer to 64
Mar 16 04:32:30 birne kernel: [71029.920909] ahci 0000:00:1f.2: setting latency timer to 64
Mar 16 04:32:30 birne kernel: [71029.920949] HDA Intel 0000:00:1b.0: irq 41 for MSI/MSI-X
Mar 16 04:32:30 birne kernel: [71029.921017] sd 0:0:0:0: [sda] Starting disk
Mar 16 04:32:30 birne kernel: [71029.921199] e1000e 0000:00:19.0: irq 43 for MSI/MSI-X
Mar 16 04:32:30 birne kernel: [71029.921315] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 04:32:30 birne kernel: [71029.921323] ehci_hcd 0000:00:1a.0: PCI INT D -> GSI 23 (level, low) -> IRQ 23
Mar 16 04:32:30 birne kernel: [71029.921329] ehci_hcd 0000:00:1a.0: setting latency timer to 64
Mar 16 04:32:30 birne kernel: [71029.921336] i915 0000:00:02.0: power state changed by ACPI to D0
Mar 16 04:32:30 birne kernel: [71029.921344] i915 0000:00:02.0: setting latency timer to 64
Mar 16 04:32:30 birne kernel: [71029.923576] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 04:32:30 birne kernel: [71029.923583] ehci_hcd 0000:00:1d.0: PCI INT D -> GSI 19 (level, low) -> IRQ 19
Mar 16 04:32:30 birne kernel: [71029.923589] ehci_hcd 0000:00:1d.0: setting latency timer to 64
Mar 16 04:32:30 birne kernel: [71029.964752] Extended CMOS year: 2000
Mar 16 04:32:30 birne kernel: [71030.099218] PM: resume of drv: dev:ep_00 complete after 178.286 msecs
Mar 16 04:32:30 birne kernel: [71030.099228] PM: resume of drv:hub dev:1-1:1.0 complete after 178.403 msecs
Mar 16 04:32:30 birne kernel: [71030.099284] PM: resume of drv: dev:ep_81 complete after 178.381 msecs
Mar 16 04:32:30 birne kernel: [71030.099615] PM: resume of drv: dev:ep_00 complete after 178.334 msecs
Mar 16 04:32:30 birne kernel: [71030.099621] PM: resume of drv:usbhid dev:1-1.2:1.0 complete after 178.380 msecs
Mar 16 04:32:30 birne kernel: [71030.099631] PM: resume of drv: dev:ep_81 complete after 178.374 msecs
Mar 16 04:32:30 birne kernel: [71030.107201] PM: resume of drv: dev:ep_00 complete after 186.221 msecs
Mar 16 04:32:30 birne kernel: [71030.107230] PM: resume of drv:hub dev:2-1:1.0 complete after 186.300 msecs
Mar 16 04:32:30 birne kernel: [71030.107248] PM: resume of drv: dev:ep_81 complete after 186.297 msecs
Mar 16 04:32:30 birne kernel: [71030.171269] usb 1-1.6: reset high speed USB device number 6 using ehci_hcd
Mar 16 04:32:30 birne kernel: [71030.238872] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Mar 16 04:32:30 birne kernel: [71030.239383] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Mar 16 04:32:30 birne kernel: [71030.239389] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Mar 16 04:32:30 birne kernel: [71030.239396] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Mar 16 04:32:30 birne kernel: [71030.240331] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Mar 16 04:32:30 birne kernel: [71030.240338] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Mar 16 04:32:30 birne kernel: [71030.240343] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Mar 16 04:32:30 birne kernel: [71030.240624] ata1.00: configured for UDMA/133
Mar 16 04:32:30 birne kernel: [71030.246853] ata5: SATA link down (SStatus 0 SControl 300)
Mar 16 04:32:30 birne kernel: [71030.254838] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 16 04:32:30 birne kernel: [71030.255026] PM: resume of drv:sd dev:0:0:0:0 complete after 334.848 msecs
Mar 16 04:32:30 birne kernel: [71030.255041] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 334.833 msecs
Mar 16 04:32:30 birne kernel: [71030.262778] ata6: SATA link down (SStatus 0 SControl 300)
Mar 16 04:32:30 birne kernel: [71030.280281] PM: resume of drv: dev:ep_00 complete after 359.526 msecs
Mar 16 04:32:30 birne kernel: [71030.280298] PM: resume of drv:uvcvideo dev:1-1.6:1.1 complete after 359.567 msecs
Mar 16 04:32:30 birne kernel: [71030.280307] PM: resume of drv:uvcvideo dev:1-1.6:1.0 complete after 359.604 msecs
Mar 16 04:32:30 birne kernel: [71030.280326] PM: resume of drv: dev:ep_81 complete after 359.612 msecs
Mar 16 04:32:30 birne kernel: [71030.316687] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Mar 16 04:32:30 birne kernel: [71030.317510] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Mar 16 04:32:30 birne kernel: [71030.326790] PM: resume of drv:i915 dev:0000:00:02.0 complete after 407.274 msecs
Mar 16 04:32:30 birne kernel: [71030.331887] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Mar 16 04:32:30 birne kernel: [71030.332343] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Mar 16 04:32:30 birne kernel: [71030.334001] ata2.00: configured for UDMA/100
Mar 16 04:32:30 birne kernel: [71030.346652] usb 1-1.3: reset full speed USB device number 4 using ehci_hcd
Mar 16 04:32:30 birne kernel: [71030.439356] PM: resume of drv:usb dev:1-1.3:1.0 complete after 519.170 msecs
Mar 16 04:32:30 birne kernel: [71030.439369] PM: resume of drv: dev:ep_00 complete after 519.108 msecs
Mar 16 04:32:30 birne kernel: [71030.439380] PM: resume of drv: dev:ep_02 complete after 519.161 msecs
Mar 16 04:32:30 birne kernel: [71030.439383] PM: resume of drv: dev:ep_81 complete after 519.177 msecs
Mar 16 04:32:30 birne kernel: [71030.439390] PM: resume of drv: dev:ep_83 complete after 519.149 msecs
Mar 16 04:32:30 birne kernel: [71030.697818] usb 2-1.2: reset low speed USB device number 10 using ehci_hcd
Mar 16 04:32:30 birne kernel: [71030.980154] PM: resume of drv: dev:ep_00 complete after 1061.004 msecs
Mar 16 04:32:30 birne kernel: [71030.980169] PM: resume of drv:usbhid dev:2-1.2:1.0 complete after 1061.056 msecs
Mar 16 04:32:30 birne kernel: [71030.980265] PM: resume of drv: dev:ep_81 complete after 1061.137 msecs
Mar 16 04:32:30 birne kernel: [71030.980268] PM: resume of drv:generic-usb dev:0003:093A:2510.0005 complete after 654.025 msecs
Mar 16 04:32:30 birne kernel: [71030.980273] PM: resume of devices complete after 1062.457 msecs
Mar 16 04:32:30 birne kernel: [71030.980423] PM: resume devices took 1.060 seconds
Mar 16 04:32:30 birne kernel: [71030.980441] PM: Finishing wakeup.
Mar 16 04:32:30 birne kernel: [71030.980442] Restarting tasks ... done.
Mar 16 04:32:30 birne kernel: [71030.992896] usb 1-1.2: USB disconnect, device number 13
Mar 16 04:32:30 birne kernel: [71031.002806] video LNXVIDEO:00: Restoring backlight state
Mar 16 04:32:30 birne kernel: [71031.008754] [drm:drm_mode_getfb] *ERROR* invalid framebuffer id
Mar 16 04:32:31 birne kernel: [71031.405627] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro,commit=0
Mar 16 04:32:31 birne NetworkManager[845]: <info> wake requested (sleeping: yes  enabled: yes)
Mar 16 04:32:31 birne NetworkManager[845]: <info> waking up and re-enabling...
Mar 16 04:32:31 birne NetworkManager[845]: <info> (eth0): now managed
Mar 16 04:32:31 birne NetworkManager[845]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 16 04:32:31 birne NetworkManager[845]: <info> (eth0): bringing up device.
Mar 16 04:32:31 birne kernel: [71031.535791] e1000e 0000:00:19.0: irq 43 for MSI/MSI-X
Mar 16 04:32:31 birne NetworkManager[845]: <info> (eth0): preparing device.
Mar 16 04:32:31 birne NetworkManager[845]: <info> (eth0): deactivating device (reason 'managed') [2]
Mar 16 04:32:31 birne NetworkManager[845]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Mar 16 04:32:31 birne NetworkManager[845]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Mar 16 04:32:31 birne NetworkManager[845]: <info> (wlan0): now managed
Mar 16 04:32:31 birne NetworkManager[845]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 16 04:32:31 birne NetworkManager[845]: <info> (wlan0): bringing up device.
Mar 16 04:32:31 birne kernel: [71031.591486] e1000e 0000:00:19.0: irq 43 for MSI/MSI-X
Mar 16 04:32:31 birne kernel: [71031.591892] ADDRCONF(NETDEV_UP): eth0: link is not ready
Mar 16 04:32:31 birne kernel: [71031.651344] usb 1-1.2: new low speed USB device number 14 using ehci_hcd
Mar 16 04:32:31 birne NetworkManager[845]: <info> (wlan0): preparing device.
Mar 16 04:32:31 birne NetworkManager[845]: <info> (wlan0): deactivating device (reason 'managed') [2]
Mar 16 04:32:31 birne kernel: [71031.735702] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Mar 16 04:32:31 birne kernel: [71031.750223] input: 2.4G Wireless Optical Mouse as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0/input/input17
Mar 16 04:32:31 birne kernel: [71031.750365] generic-usb 0003:1D57:0008.0006: input,hidraw0: USB HID v1.10 Mouse [2.4G Wireless Optical Mouse] on usb-0000:00:1a.0-1.2/input0
Mar 16 04:32:31 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: starting -> ready
Mar 16 04:32:31 birne NetworkManager[845]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Mar 16 04:32:31 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: ready -> inactive
Mar 16 04:32:31 birne kernel: [71031.946622] usb 1-1.4: new full speed USB device number 15 using ehci_hcd
Mar 16 04:32:35 birne NetworkManager[845]: <info> Auto-activating connection 'tschill-net'.
Mar 16 04:32:35 birne NetworkManager[845]: <info> Activation (wlan0) starting connection 'tschill-net'
Mar 16 04:32:35 birne NetworkManager[845]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Mar 16 04:32:35 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Mar 16 04:32:35 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Mar 16 04:32:35 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Mar 16 04:32:35 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Mar 16 04:32:35 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Mar 16 04:32:35 birne NetworkManager[845]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Mar 16 04:32:35 birne NetworkManager[845]: <info> Activation (wlan0/wireless): access point 'tschill-net' has security, but secrets are required.
Mar 16 04:32:35 birne NetworkManager[845]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
Mar 16 04:32:35 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Mar 16 04:32:35 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Mar 16 04:32:35 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Mar 16 04:32:35 birne NetworkManager[845]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Mar 16 04:32:35 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Mar 16 04:32:35 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Mar 16 04:32:35 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Mar 16 04:32:35 birne NetworkManager[845]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Mar 16 04:32:35 birne NetworkManager[845]: <info> Activation (wlan0/wireless): connection 'tschill-net' has security, and secrets exist.  No new secrets needed.
Mar 16 04:32:35 birne NetworkManager[845]: <info> Config: added 'ssid' value 'tschill-net'
Mar 16 04:32:35 birne NetworkManager[845]: <info> Config: added 'scan_ssid' value '1'
Mar 16 04:32:35 birne NetworkManager[845]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Mar 16 04:32:35 birne NetworkManager[845]: <info> Config: added 'auth_alg' value 'OPEN'
Mar 16 04:32:35 birne NetworkManager[845]: <info> Config: added 'psk' value '<omitted>'
Mar 16 04:32:35 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Mar 16 04:32:35 birne NetworkManager[845]: <info> Config: set interface ap_scan to 1
Mar 16 04:32:35 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: inactive -> scanning
Mar 16 04:32:38 birne wpa_supplicant[1462]: Trying to authenticate with 00:1d:7e:fb:d4:5f (SSID='tschill-net' freq=2422 MHz)
Mar 16 04:32:38 birne kernel: [71038.437461] wlan0: authenticate with 00:1d:7e:fb:d4:5f (try 1)
Mar 16 04:32:38 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Mar 16 04:32:38 birne wpa_supplicant[1462]: Trying to associate with 00:1d:7e:fb:d4:5f (SSID='tschill-net' freq=2422 MHz)
Mar 16 04:32:38 birne kernel: [71038.440545] wlan0: authenticated
Mar 16 04:32:38 birne kernel: [71038.441081] wlan0: associate with 00:1d:7e:fb:d4:5f (try 1)
Mar 16 04:32:38 birne kernel: [71038.443390] wlan0: RX AssocResp from 00:1d:7e:fb:d4:5f (capab=0x431 status=0 aid=3)
Mar 16 04:32:38 birne kernel: [71038.443393] wlan0: associated
Mar 16 04:32:38 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: authenticating -> associating
Mar 16 04:32:38 birne wpa_supplicant[1462]: Associated with 00:1d:7e:fb:d4:5f
Mar 16 04:32:38 birne kernel: [71038.452882] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Mar 16 04:32:38 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: associating -> 4-way handshake
Mar 16 04:32:38 birne wpa_supplicant[1462]: WPA: Key negotiation completed with 00:1d:7e:fb:d4:5f [PTK=CCMP GTK=TKIP]
Mar 16 04:32:38 birne wpa_supplicant[1462]: CTRL-EVENT-CONNECTED - Connection to 00:1d:7e:fb:d4:5f completed (auth) [id=0 id_str=]
Mar 16 04:32:38 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: 4-way handshake -> completed
Mar 16 04:32:38 birne NetworkManager[845]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'tschill-net'.
Mar 16 04:32:38 birne NetworkManager[845]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
Mar 16 04:32:38 birne NetworkManager[845]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
Mar 16 04:32:38 birne NetworkManager[845]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
Mar 16 04:32:38 birne NetworkManager[845]: <info> Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Mar 16 04:32:38 birne NetworkManager[845]: <info> dhclient started with pid 29483
Mar 16 04:32:38 birne NetworkManager[845]: <info> Activation (wlan0) Beginning IP6 addrconf.
Mar 16 04:32:38 birne NetworkManager[845]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
Mar 16 04:32:38 birne dhclient: Internet Systems Consortium DHCP Client 4.1.1-P1
Mar 16 04:32:38 birne dhclient: Copyright 2004-2010 Internet Systems Consortium.
Mar 16 04:32:38 birne dhclient: All rights reserved.
Mar 16 04:32:38 birne dhclient: For info, please visit https://www.isc.org/software/dhcp/
Mar 16 04:32:38 birne dhclient: 
Mar 16 04:32:38 birne NetworkManager[845]: <info> (wlan0): DHCPv4 state changed nbi -> preinit
Mar 16 04:32:38 birne dhclient: Listening on LPF/wlan0/00:24:d7:e3:32:78
Mar 16 04:32:38 birne dhclient: Sending on   LPF/wlan0/00:24:d7:e3:32:78
Mar 16 04:32:38 birne dhclient: Sending on   Socket/fallback
Mar 16 04:32:38 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 255.255.255.255 port 67
Mar 16 04:32:38 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 04:32:38 birne dhclient: bound to 192.168.0.161 -- renewal in 1609 seconds.
Mar 16 04:32:38 birne NetworkManager[845]: <info> (wlan0): DHCPv4 state changed preinit -> reboot
Mar 16 04:32:38 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled...
Mar 16 04:32:38 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started...
Mar 16 04:32:38 birne NetworkManager[845]: <info>   address 192.168.0.161
Mar 16 04:32:38 birne NetworkManager[845]: <info>   prefix 24 (255.255.255.0)
Mar 16 04:32:38 birne NetworkManager[845]: <info>   gateway 192.168.0.215
Mar 16 04:32:38 birne NetworkManager[845]: <info>   nameserver '77.244.240.244'
Mar 16 04:32:38 birne NetworkManager[845]: <info>   domain name 'example.org'
Mar 16 04:32:38 birne NetworkManager[845]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
Mar 16 04:32:39 birne NetworkManager[845]: <info> (wlan0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Mar 16 04:32:39 birne NetworkManager[845]: <info> Policy set 'tschill-net' (wlan0) as default for IPv4 routing and DNS.
Mar 16 04:32:39 birne NetworkManager[845]: <info> Activation (wlan0) successful, device activated.
Mar 16 04:32:39 birne NetworkManager[845]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
Mar 16 04:32:39 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete.
Mar 16 04:32:40 birne kernel: [71040.872107] [drm:i915_hangcheck_elapsed] *ERROR* Hangcheck timer elapsed... GPU hung
Mar 16 04:32:40 birne kernel: [71040.872115] [drm:kick_ring] *ERROR* Kicking stuck wait on render ring
Mar 16 04:32:46 birne kernel: [71046.873112] [drm:i915_hangcheck_elapsed] *ERROR* Hangcheck timer elapsed... GPU hung
Mar 16 04:32:46 birne kernel: [71046.873121] [drm] capturing error event; look for more information in /debug/dri/0/i915_error_state
Mar 16 04:32:46 birne kernel: [71046.876865] [drm:i915_wait_request] *ERROR* i915_wait_request returns -11 (awaiting 752 at 743, next 753)
Mar 16 04:32:48 birne kernel: [71048.545028] [drm:i915_hangcheck_elapsed] *ERROR* Hangcheck timer elapsed... GPU hung
Mar 16 04:32:48 birne kernel: [71048.545102] [drm:i915_wait_request] *ERROR* i915_wait_request returns -11 (awaiting 754 at 743, next 784)
Mar 16 04:32:48 birne kernel: [71048.545252] [drm:i915_reset] *ERROR* GPU hanging too fast, declaring wedged!
Mar 16 04:32:48 birne kernel: [71048.545258] [drm:i915_reset] *ERROR* Failed to reset chip.
Mar 16 04:32:48 birne kernel: [71049.279491] wlan0: no IPv6 routers present
Mar 16 04:32:58 birne NetworkManager[845]: <info> (wlan0): IP6 addrconf timed out or failed.
Mar 16 04:32:58 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) scheduled...
Mar 16 04:32:58 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) started...
Mar 16 04:32:58 birne NetworkManager[845]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
Mar 16 04:32:58 birne NetworkManager[845]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
Mar 16 04:32:58 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) complete.
Mar 16 04:33:15 birne NetworkManager[845]: <info> sleep requested (sleeping: no  enabled: yes)
Mar 16 04:33:15 birne NetworkManager[845]: <info> sleeping or disabling...
Mar 16 04:33:15 birne NetworkManager[845]: <info> (eth0): now unmanaged
Mar 16 04:33:15 birne NetworkManager[845]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Mar 16 04:33:15 birne NetworkManager[845]: <info> (eth0): cleaning up...
Mar 16 04:33:15 birne NetworkManager[845]: <info> (eth0): taking down device.
Mar 16 04:33:15 birne NetworkManager[845]: <info> (wlan0): now unmanaged
Mar 16 04:33:15 birne NetworkManager[845]: <info> (wlan0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
Mar 16 04:33:15 birne NetworkManager[845]: <info> (wlan0): deactivating device (reason 'sleeping') [37]
Mar 16 04:33:15 birne NetworkManager[845]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 29483
Mar 16 04:33:15 birne kernel: [71076.036989] wlan0: deauthenticating from 00:1d:7e:fb:d4:5f by local choice (reason=3)
Mar 16 04:33:15 birne NetworkManager[845]: <info> (wlan0): cleaning up...
Mar 16 04:33:15 birne NetworkManager[845]: <info> (wlan0): taking down device.
Mar 16 04:33:15 birne kernel: [71076.062153] cfg80211: All devices are disconnected, going to restore regulatory settings
Mar 16 04:33:15 birne kernel: [71076.062162] cfg80211: Restoring regulatory settings
Mar 16 04:33:15 birne kernel: [71076.062169] cfg80211: Calling CRDA to update world regulatory domain
Mar 16 04:33:15 birne kernel: [71076.133420] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain 
Mar 16 04:33:15 birne kernel: [71076.133427] cfg80211: World regulatory domain updated:
Mar 16 04:33:15 birne kernel: [71076.133430] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Mar 16 04:33:15 birne kernel: [71076.133434] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 16 04:33:15 birne kernel: [71076.133438] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Mar 16 04:33:15 birne kernel: [71076.133442] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Mar 16 04:33:15 birne kernel: [71076.133445] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 16 04:33:15 birne kernel: [71076.133449] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 16 04:33:15 birne wpa_supplicant[1462]: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=3
Mar 16 04:33:16 birne kernel: [71076.846321] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro,commit=0
Mar 16 04:33:17 birne kernel: [71077.419771] usb 1-1.4: USB disconnect, device number 15
Mar 16 04:33:17 birne kernel: [71077.419894] btusb_intr_complete: hci0 urb ffff880033dd4d80 failed to resubmit (19)
Mar 16 04:33:17 birne kernel: [71077.420003] btusb_bulk_complete: hci0 urb ffff880033dd4900 failed to resubmit (19)
Mar 16 04:33:17 birne kernel: [71077.420009] btusb_bulk_complete: hci0 urb ffff880033dd4a80 failed to resubmit (19)
Mar 16 04:33:17 birne kernel: [71077.420048] btusb_send_frame: hci0 urb ffff880112ff2000 submission failed
Mar 16 04:33:17 birne kernel: [71077.876464] PM: Syncing filesystems ... done.
Mar 16 04:33:17 birne kernel: [71077.886302] PM: Preparing system for mem sleep
Mar 16 04:33:36 birne kernel: [71078.385419] Freezing user space processes ... (elapsed 0.01 seconds) done.
Mar 16 04:33:36 birne kernel: [71078.401243] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Mar 16 04:33:36 birne kernel: [71078.417164] PM: Entering mem sleep
Mar 16 04:33:36 birne kernel: [71078.417289] Suspending console(s) (use no_console_suspend to debug)
Mar 16 04:33:36 birne kernel: [71078.574022] PM: suspend of drv:psmouse dev:serio2 complete after 156.772 msecs
Mar 16 04:33:36 birne kernel: [71078.574267] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Mar 16 04:33:36 birne kernel: [71078.574733] sd 0:0:0:0: [sda] Stopping disk
Mar 16 04:33:36 birne kernel: [71078.804344] PM: suspend of drv:tpm_tis dev:00:0b complete after 178.793 msecs
Mar 16 04:33:36 birne kernel: [71078.804635] ehci_hcd 0000:00:1d.0: PCI INT D disabled
Mar 16 04:33:36 birne kernel: [71078.804638] ehci_hcd 0000:00:1a.0: PCI INT D disabled
Mar 16 04:33:36 birne kernel: [71078.804717] ACPI handle has no context!
Mar 16 04:33:36 birne kernel: [71078.812232] [drm:intel_cleanup_ring_buffer] *ERROR* failed to quiesce render ring whilst cleaning up: -11
Mar 16 04:33:36 birne kernel: [71078.844182] i915 0000:00:02.0: power state changed by ACPI to D3
Mar 16 04:33:36 birne kernel: [71078.849396] PM: suspend of drv:sd dev:0:0:0:0 complete after 275.686 msecs
Mar 16 04:33:36 birne kernel: [71078.849402] PM: suspend of drv:scsi dev:target0:0:0 complete after 275.687 msecs
Mar 16 04:33:36 birne kernel: [71078.849407] PM: suspend of drv:scsi dev:host0 complete after 275.680 msecs
Mar 16 04:33:36 birne kernel: [71078.908485] HDA Intel 0000:00:1b.0: PCI INT B disabled
Mar 16 04:33:36 birne kernel: [71078.909910] e1000e 0000:00:19.0: PME# enabled
Mar 16 04:33:36 birne kernel: [71078.909915] e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Mar 16 04:33:36 birne kernel: [71078.924083] PM: suspend of drv:e1000e dev:0000:00:19.0 complete after 119.669 msecs
Mar 16 04:33:36 birne kernel: [71078.924089] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.700 msecs
Mar 16 04:33:36 birne kernel: [71078.924165] PM: suspend of drv: dev:pci0000:00 complete after 118.996 msecs
Mar 16 04:33:36 birne kernel: [71078.924215] PM: suspend of devices complete after 507.711 msecs
Mar 16 04:33:36 birne kernel: [71078.924219] PM: suspend devices took 0.508 seconds
Mar 16 04:33:36 birne kernel: [71078.956008] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D3
Mar 16 04:33:36 birne kernel: [71078.991946] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D3
Mar 16 04:33:36 birne kernel: [71079.007983] PM: late suspend of devices complete after 83.926 msecs
Mar 16 04:33:36 birne kernel: [71079.008175] ACPI: Preparing to enter system sleep state S3
Mar 16 04:33:36 birne kernel: [71079.207547] PM: Saving platform NVS memory
Mar 16 04:33:36 birne kernel: [71079.210855] Disabling non-boot CPUs ...
Mar 16 04:33:36 birne kernel: [71079.315252] CPU 1 is now offline
Mar 16 04:33:36 birne kernel: [71079.419061] CPU 2 is now offline
Mar 16 04:33:36 birne kernel: [71079.420728] CPU 3 is now offline
Mar 16 04:33:36 birne kernel: [71079.421149] Extended CMOS year: 2000
Mar 16 04:33:36 birne kernel: [71079.421365] ACPI: Low-level resume complete
Mar 16 04:33:36 birne kernel: [71079.421419] PM: Restoring platform NVS memory
Mar 16 04:33:36 birne kernel: [71079.422051] Extended CMOS year: 2000
Mar 16 04:33:36 birne kernel: [71079.422096] Enabling non-boot CPUs ...
Mar 16 04:33:36 birne kernel: [71079.422183] Booting Node 0 Processor 1 APIC 0x1
Mar 16 04:33:36 birne kernel: [71079.422184] smpboot cpu 1: start_ip = 99000
Mar 16 04:33:36 birne kernel: [71079.535734] Switched to NOHz mode on CPU #1
Mar 16 04:33:36 birne kernel: [71079.595740] CPU1 is up
Mar 16 04:33:36 birne kernel: [71079.595851] Booting Node 0 Processor 2 APIC 0x4
Mar 16 04:33:36 birne kernel: [71079.595853] smpboot cpu 2: start_ip = 99000
Mar 16 04:33:36 birne kernel: [71079.707334] Switched to NOHz mode on CPU #2
Mar 16 04:33:36 birne kernel: [71079.767392] CPU2 is up
Mar 16 04:33:36 birne kernel: [71079.767488] Booting Node 0 Processor 3 APIC 0x5
Mar 16 04:33:36 birne kernel: [71079.767490] smpboot cpu 3: start_ip = 99000
Mar 16 04:33:36 birne kernel: [71079.879093] Switched to NOHz mode on CPU #3
Mar 16 04:33:36 birne kernel: [71079.939130] CPU3 is up
Mar 16 04:33:36 birne kernel: [71079.941454] ACPI: Waking up from system sleep state S3
Mar 16 04:33:36 birne kernel: [71080.238503] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407)
Mar 16 04:33:36 birne kernel: [71080.238648] ehci_hcd 0000:00:1a.0: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 04:33:36 birne kernel: [71080.238665] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2728000)
Mar 16 04:33:36 birne kernel: [71080.238672] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102)
Mar 16 04:33:36 birne kernel: [71080.258314] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 04:33:36 birne kernel: [71080.258323] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 04:33:36 birne kernel: [71080.258568] ehci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 04:33:36 birne kernel: [71080.258585] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2728400)
Mar 16 04:33:36 birne kernel: [71080.258592] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102)
Mar 16 04:33:36 birne kernel: [71080.258609] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 04:33:36 birne kernel: [71080.258612] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 04:33:36 birne kernel: [71080.258713] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407)
Mar 16 04:33:36 birne kernel: [71080.258777] intel ips 0000:00:1f.6: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 04:33:36 birne kernel: [71080.258798] intel ips 0000:00:1f.6: restoring config space at offset 0x1 (was 0x100000, writing 0x100002)
Mar 16 04:33:36 birne kernel: [71080.258879] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506)
Mar 16 04:33:36 birne kernel: [71080.259064] PM: early resume of devices complete after 20.738 msecs
Mar 16 04:33:36 birne kernel: [71080.259202] i915 0000:00:02.0: power state changed by ACPI to D0
Mar 16 04:33:36 birne kernel: [71080.259206] i915 0000:00:02.0: power state changed by ACPI to D0
Mar 16 04:33:36 birne kernel: [71080.259210] i915 0000:00:02.0: setting latency timer to 64
Mar 16 04:33:36 birne kernel: [71080.259331] e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Mar 16 04:33:36 birne kernel: [71080.259337] e1000e 0000:00:19.0: PME# disabled
Mar 16 04:33:36 birne kernel: [71080.259361] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 04:33:36 birne kernel: [71080.259413] pci 0000:00:1e.0: setting latency timer to 64
Mar 16 04:33:36 birne kernel: [71080.259453] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 04:33:36 birne kernel: [71080.259473] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Mar 16 04:33:36 birne kernel: [71080.259481] HDA Intel 0000:00:1b.0: setting latency timer to 64
Mar 16 04:33:36 birne kernel: [71080.259558] HDA Intel 0000:00:1b.0: irq 41 for MSI/MSI-X
Mar 16 04:33:36 birne kernel: [71080.259560] e1000e 0000:00:19.0: irq 43 for MSI/MSI-X
Mar 16 04:33:36 birne kernel: [71080.259611] ahci 0000:00:1f.2: setting latency timer to 64
Mar 16 04:33:36 birne kernel: [71080.259768] sd 0:0:0:0: [sda] Starting disk
Mar 16 04:33:36 birne kernel: [71080.259794] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 04:33:36 birne kernel: [71080.259799] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 04:33:36 birne kernel: [71080.259801] ehci_hcd 0000:00:1a.0: PCI INT D -> GSI 23 (level, low) -> IRQ 23
Mar 16 04:33:36 birne kernel: [71080.259805] ehci_hcd 0000:00:1d.0: PCI INT D -> GSI 19 (level, low) -> IRQ 19
Mar 16 04:33:36 birne kernel: [71080.259808] ehci_hcd 0000:00:1a.0: setting latency timer to 64
Mar 16 04:33:36 birne kernel: [71080.259814] ehci_hcd 0000:00:1d.0: setting latency timer to 64
Mar 16 04:33:36 birne kernel: [71080.303290] Extended CMOS year: 2000
Mar 16 04:33:36 birne kernel: [71080.437935] PM: resume of drv: dev:ep_00 complete after 178.568 msecs
Mar 16 04:33:36 birne kernel: [71080.437971] PM: resume of drv:hub dev:1-1:1.0 complete after 178.643 msecs
Mar 16 04:33:36 birne kernel: [71080.438026] PM: resume of drv:hub dev:2-1:1.0 complete after 178.657 msecs
Mar 16 04:33:36 birne kernel: [71080.438038] PM: resume of drv: dev:ep_81 complete after 178.711 msecs
Mar 16 04:33:36 birne kernel: [71080.438054] PM: resume of drv: dev:ep_00 complete after 178.681 msecs
Mar 16 04:33:36 birne kernel: [71080.438058] PM: resume of drv: dev:ep_81 complete after 178.686 msecs
Mar 16 04:33:36 birne kernel: [71080.438343] PM: resume of drv:usbhid dev:1-1.2:1.0 complete after 178.787 msecs
Mar 16 04:33:36 birne kernel: [71080.438363] PM: resume of drv: dev:ep_00 complete after 176.478 msecs
Mar 16 04:33:36 birne kernel: [71080.438371] PM: resume of drv: dev:ep_81 complete after 176.482 msecs
Mar 16 04:33:36 birne kernel: [71080.577682] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 16 04:33:36 birne kernel: [71080.585667] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Mar 16 04:33:36 birne kernel: [71080.586124] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Mar 16 04:33:36 birne kernel: [71080.586131] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Mar 16 04:33:36 birne kernel: [71080.586136] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Mar 16 04:33:36 birne kernel: [71080.586991] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Mar 16 04:33:36 birne kernel: [71080.586997] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Mar 16 04:33:36 birne kernel: [71080.587001] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Mar 16 04:33:36 birne kernel: [71080.587288] ata1.00: configured for UDMA/133
Mar 16 04:33:36 birne kernel: [71080.593648] ata6: SATA link down (SStatus 0 SControl 300)
Mar 16 04:33:36 birne kernel: [71080.601593] ata5: SATA link down (SStatus 0 SControl 300)
Mar 16 04:33:36 birne kernel: [71080.601736] PM: resume of drv:sd dev:0:0:0:0 complete after 342.951 msecs
Mar 16 04:33:36 birne kernel: [71080.601749] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 342.836 msecs
Mar 16 04:33:36 birne kernel: [71080.633135] PM: resume of drv:i915 dev:0000:00:02.0 complete after 374.695 msecs
Mar 16 04:33:36 birne kernel: [71080.654421] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Mar 16 04:33:36 birne kernel: [71080.655210] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Mar 16 04:33:36 birne kernel: [71080.669563] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Mar 16 04:33:36 birne kernel: [71080.670044] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Mar 16 04:33:36 birne kernel: [71080.671704] ata2.00: configured for UDMA/100
Mar 16 04:33:36 birne kernel: [71080.697650] usb 2-1.2: reset low speed USB device number 10 using ehci_hcd
Mar 16 04:33:36 birne kernel: [71080.980296] PM: resume of drv: dev:ep_00 complete after 721.834 msecs
Mar 16 04:33:36 birne kernel: [71080.980308] PM: resume of drv:usbhid dev:2-1.2:1.0 complete after 721.895 msecs
Mar 16 04:33:36 birne kernel: [71080.980331] PM: resume of drv: dev:ep_81 complete after 721.876 msecs
Mar 16 04:33:36 birne kernel: [71081.048842] usb 1-1.6: reset high speed USB device number 6 using ehci_hcd
Mar 16 04:33:36 birne kernel: [71081.157894] PM: resume of drv:uvcvideo dev:1-1.6:1.1 complete after 899.912 msecs
Mar 16 04:33:36 birne kernel: [71081.157907] PM: resume of drv: dev:ep_00 complete after 899.914 msecs
Mar 16 04:33:36 birne kernel: [71081.157919] PM: resume of drv:uvcvideo dev:1-1.6:1.0 complete after 899.942 msecs
Mar 16 04:33:36 birne kernel: [71081.157941] PM: resume of drv:video4linux dev:video0 complete after 524.662 msecs
Mar 16 04:33:36 birne kernel: [71081.157987] PM: resume of drv: dev:ep_81 complete after 900.007 msecs
Mar 16 04:33:36 birne kernel: [71081.224512] usb 1-1.3: reset full speed USB device number 4 using ehci_hcd
Mar 16 04:33:36 birne kernel: [71081.316961] PM: resume of drv: dev:ep_00 complete after 1059.311 msecs
Mar 16 04:33:36 birne kernel: [71081.316968] PM: resume of drv:usb dev:1-1.3:1.0 complete after 1059.338 msecs
Mar 16 04:33:36 birne kernel: [71081.316993] PM: resume of drv: dev:ep_02 complete after 1059.359 msecs
Mar 16 04:33:36 birne kernel: [71081.317016] PM: resume of drv: dev:ep_83 complete after 1059.381 msecs
Mar 16 04:33:36 birne kernel: [71081.317027] PM: resume of drv: dev:ep_81 complete after 1059.396 msecs
Mar 16 04:33:36 birne kernel: [71081.317044] PM: resume of devices complete after 1060.037 msecs
Mar 16 04:33:36 birne kernel: [71081.317150] PM: resume devices took 1.060 seconds
Mar 16 04:33:36 birne kernel: [71081.317174] PM: Finishing wakeup.
Mar 16 04:33:36 birne kernel: [71081.317176] Restarting tasks ... done.
Mar 16 04:33:36 birne kernel: [71081.322749] usb 1-1.2: USB disconnect, device number 14
Mar 16 04:33:36 birne kernel: [71081.337944] video LNXVIDEO:00: Restoring backlight state
Mar 16 04:33:36 birne kernel: [71081.385507] [drm:drm_mode_getfb] *ERROR* invalid framebuffer id
Mar 16 04:33:36 birne kernel: [71081.718567] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro,commit=0
Mar 16 04:33:36 birne NetworkManager[845]: <info> wake requested (sleeping: yes  enabled: yes)
Mar 16 04:33:36 birne NetworkManager[845]: <info> waking up and re-enabling...
Mar 16 04:33:36 birne NetworkManager[845]: <info> (eth0): now managed
Mar 16 04:33:36 birne NetworkManager[845]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 16 04:33:36 birne NetworkManager[845]: <info> (eth0): bringing up device.
Mar 16 04:33:36 birne kernel: [71081.852784] e1000e 0000:00:19.0: irq 43 for MSI/MSI-X
Mar 16 04:33:36 birne NetworkManager[845]: <info> (eth0): preparing device.
Mar 16 04:33:36 birne NetworkManager[845]: <info> (eth0): deactivating device (reason 'managed') [2]
Mar 16 04:33:36 birne NetworkManager[845]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Mar 16 04:33:36 birne NetworkManager[845]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Mar 16 04:33:36 birne NetworkManager[845]: <info> (wlan0): now managed
Mar 16 04:33:36 birne NetworkManager[845]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 16 04:33:36 birne NetworkManager[845]: <info> (wlan0): bringing up device.
Mar 16 04:33:36 birne kernel: [71081.906985] e1000e 0000:00:19.0: irq 43 for MSI/MSI-X
Mar 16 04:33:36 birne kernel: [71081.907438] ADDRCONF(NETDEV_UP): eth0: link is not ready
Mar 16 04:33:36 birne kernel: [71081.994952] usb 1-1.2: new low speed USB device number 16 using ehci_hcd
Mar 16 04:33:36 birne NetworkManager[845]: <info> (wlan0): preparing device.
Mar 16 04:33:36 birne NetworkManager[845]: <info> (wlan0): deactivating device (reason 'managed') [2]
Mar 16 04:33:36 birne kernel: [71082.057185] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Mar 16 04:33:36 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: starting -> ready
Mar 16 04:33:36 birne NetworkManager[845]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Mar 16 04:33:36 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: ready -> inactive
Mar 16 04:33:36 birne kernel: [71082.093779] input: 2.4G Wireless Optical Mouse as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0/input/input18
Mar 16 04:33:36 birne kernel: [71082.093983] generic-usb 0003:1D57:0008.0007: input,hidraw0: USB HID v1.10 Mouse [2.4G Wireless Optical Mouse] on usb-0000:00:1a.0-1.2/input0
Mar 16 04:33:37 birne kernel: [71082.294338] usb 1-1.4: new full speed USB device number 17 using ehci_hcd
Mar 16 04:33:40 birne NetworkManager[845]: <info> Auto-activating connection 'tschill-net'.
Mar 16 04:33:40 birne NetworkManager[845]: <info> Activation (wlan0) starting connection 'tschill-net'
Mar 16 04:33:40 birne NetworkManager[845]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Mar 16 04:33:40 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Mar 16 04:33:40 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Mar 16 04:33:40 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Mar 16 04:33:40 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Mar 16 04:33:40 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Mar 16 04:33:40 birne NetworkManager[845]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Mar 16 04:33:40 birne NetworkManager[845]: <info> Activation (wlan0/wireless): access point 'tschill-net' has security, but secrets are required.
Mar 16 04:33:40 birne NetworkManager[845]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
Mar 16 04:33:40 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Mar 16 04:33:40 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Mar 16 04:33:40 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Mar 16 04:33:40 birne NetworkManager[845]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Mar 16 04:33:40 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Mar 16 04:33:40 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Mar 16 04:33:40 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Mar 16 04:33:40 birne NetworkManager[845]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Mar 16 04:33:40 birne NetworkManager[845]: <info> Activation (wlan0/wireless): connection 'tschill-net' has security, and secrets exist.  No new secrets needed.
Mar 16 04:33:40 birne NetworkManager[845]: <info> Config: added 'ssid' value 'tschill-net'
Mar 16 04:33:40 birne NetworkManager[845]: <info> Config: added 'scan_ssid' value '1'
Mar 16 04:33:40 birne NetworkManager[845]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Mar 16 04:33:40 birne NetworkManager[845]: <info> Config: added 'auth_alg' value 'OPEN'
Mar 16 04:33:40 birne NetworkManager[845]: <info> Config: added 'psk' value '<omitted>'
Mar 16 04:33:40 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Mar 16 04:33:40 birne NetworkManager[845]: <info> Config: set interface ap_scan to 1
Mar 16 04:33:40 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: inactive -> scanning
Mar 16 04:33:43 birne wpa_supplicant[1462]: Trying to authenticate with 00:1d:7e:fb:d4:5f (SSID='tschill-net' freq=2422 MHz)
Mar 16 04:33:43 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Mar 16 04:33:43 birne kernel: [71088.693643] wlan0: authenticate with 00:1d:7e:fb:d4:5f (try 1)
Mar 16 04:33:43 birne wpa_supplicant[1462]: Trying to associate with 00:1d:7e:fb:d4:5f (SSID='tschill-net' freq=2422 MHz)
Mar 16 04:33:43 birne kernel: [71088.699025] wlan0: authenticated
Mar 16 04:33:43 birne kernel: [71088.699209] wlan0: associate with 00:1d:7e:fb:d4:5f (try 1)
Mar 16 04:33:43 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: authenticating -> associating
Mar 16 04:33:43 birne kernel: [71088.701707] wlan0: RX AssocResp from 00:1d:7e:fb:d4:5f (capab=0x431 status=0 aid=3)
Mar 16 04:33:43 birne kernel: [71088.701710] wlan0: associated
Mar 16 04:33:43 birne wpa_supplicant[1462]: Associated with 00:1d:7e:fb:d4:5f
Mar 16 04:33:43 birne kernel: [71088.709560] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Mar 16 04:33:43 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: associating -> 4-way handshake
Mar 16 04:33:43 birne wpa_supplicant[1462]: WPA: Key negotiation completed with 00:1d:7e:fb:d4:5f [PTK=CCMP GTK=TKIP]
Mar 16 04:33:43 birne wpa_supplicant[1462]: CTRL-EVENT-CONNECTED - Connection to 00:1d:7e:fb:d4:5f completed (auth) [id=0 id_str=]
Mar 16 04:33:43 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: 4-way handshake -> completed
Mar 16 04:33:43 birne NetworkManager[845]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'tschill-net'.
Mar 16 04:33:43 birne NetworkManager[845]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
Mar 16 04:33:43 birne NetworkManager[845]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
Mar 16 04:33:43 birne NetworkManager[845]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
Mar 16 04:33:43 birne NetworkManager[845]: <info> Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Mar 16 04:33:43 birne NetworkManager[845]: <info> dhclient started with pid 30347
Mar 16 04:33:43 birne NetworkManager[845]: <info> Activation (wlan0) Beginning IP6 addrconf.
Mar 16 04:33:43 birne NetworkManager[845]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
Mar 16 04:33:43 birne dhclient: Internet Systems Consortium DHCP Client 4.1.1-P1
Mar 16 04:33:43 birne dhclient: Copyright 2004-2010 Internet Systems Consortium.
Mar 16 04:33:43 birne dhclient: All rights reserved.
Mar 16 04:33:43 birne dhclient: For info, please visit https://www.isc.org/software/dhcp/
Mar 16 04:33:43 birne dhclient: 
Mar 16 04:33:43 birne NetworkManager[845]: <info> (wlan0): DHCPv4 state changed nbi -> preinit
Mar 16 04:33:43 birne dhclient: Listening on LPF/wlan0/00:24:d7:e3:32:78
Mar 16 04:33:43 birne dhclient: Sending on   LPF/wlan0/00:24:d7:e3:32:78
Mar 16 04:33:43 birne dhclient: Sending on   Socket/fallback
Mar 16 04:33:43 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 255.255.255.255 port 67
Mar 16 04:33:43 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 04:33:43 birne dhclient: bound to 192.168.0.161 -- renewal in 1772 seconds.
Mar 16 04:33:43 birne NetworkManager[845]: <info> (wlan0): DHCPv4 state changed preinit -> reboot
Mar 16 04:33:43 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled...
Mar 16 04:33:43 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started...
Mar 16 04:33:43 birne NetworkManager[845]: <info>   address 192.168.0.161
Mar 16 04:33:43 birne NetworkManager[845]: <info>   prefix 24 (255.255.255.0)
Mar 16 04:33:43 birne NetworkManager[845]: <info>   gateway 192.168.0.215
Mar 16 04:33:43 birne NetworkManager[845]: <info>   nameserver '77.244.240.244'
Mar 16 04:33:43 birne NetworkManager[845]: <info>   domain name 'example.org'
Mar 16 04:33:43 birne NetworkManager[845]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
Mar 16 04:33:44 birne NetworkManager[845]: <info> (wlan0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Mar 16 04:33:44 birne NetworkManager[845]: <info> Policy set 'tschill-net' (wlan0) as default for IPv4 routing and DNS.
Mar 16 04:33:44 birne NetworkManager[845]: <info> Activation (wlan0) successful, device activated.
Mar 16 04:33:44 birne NetworkManager[845]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
Mar 16 04:33:44 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete.
Mar 16 04:33:53 birne kernel: [71099.068539] wlan0: no IPv6 routers present
Mar 16 04:34:03 birne NetworkManager[845]: <info> (wlan0): IP6 addrconf timed out or failed.
Mar 16 04:34:03 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) scheduled...
Mar 16 04:34:03 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) started...
Mar 16 04:34:03 birne NetworkManager[845]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
Mar 16 04:34:03 birne NetworkManager[845]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
Mar 16 04:34:03 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) complete.
Mar 16 04:34:22 birne NetworkManager[845]: <info> sleep requested (sleeping: no  enabled: yes)
Mar 16 04:34:22 birne NetworkManager[845]: <info> sleeping or disabling...
Mar 16 04:34:22 birne NetworkManager[845]: <info> (eth0): now unmanaged
Mar 16 04:34:22 birne NetworkManager[845]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Mar 16 04:34:22 birne NetworkManager[845]: <info> (eth0): cleaning up...
Mar 16 04:34:22 birne NetworkManager[845]: <info> (eth0): taking down device.
Mar 16 04:34:22 birne NetworkManager[845]: <info> (wlan0): now unmanaged
Mar 16 04:34:22 birne NetworkManager[845]: <info> (wlan0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
Mar 16 04:34:22 birne NetworkManager[845]: <info> (wlan0): deactivating device (reason 'sleeping') [37]
Mar 16 04:34:22 birne NetworkManager[845]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 30347
Mar 16 04:34:22 birne kernel: [71128.160834] wlan0: deauthenticating from 00:1d:7e:fb:d4:5f by local choice (reason=3)
Mar 16 04:34:23 birne wpa_supplicant[1462]: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=3
Mar 16 04:34:23 birne NetworkManager[845]: <info> (wlan0): cleaning up...
Mar 16 04:34:23 birne NetworkManager[845]: <info> (wlan0): taking down device.
Mar 16 04:34:23 birne kernel: [71128.322050] cfg80211: All devices are disconnected, going to restore regulatory settings
Mar 16 04:34:23 birne kernel: [71128.322068] cfg80211: Restoring regulatory settings
Mar 16 04:34:23 birne kernel: [71128.322075] cfg80211: Calling CRDA to update world regulatory domain
Mar 16 04:34:23 birne kernel: [71128.335303] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain 
Mar 16 04:34:23 birne kernel: [71128.335308] cfg80211: World regulatory domain updated:
Mar 16 04:34:23 birne kernel: [71128.335310] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Mar 16 04:34:23 birne kernel: [71128.335312] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 16 04:34:23 birne kernel: [71128.335314] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Mar 16 04:34:23 birne kernel: [71128.335316] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Mar 16 04:34:23 birne kernel: [71128.335318] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 16 04:34:23 birne kernel: [71128.335320] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 16 04:34:23 birne kernel: [71128.958835] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro,commit=0
Mar 16 04:34:24 birne kernel: [71129.545652] usb 1-1.4: USB disconnect, device number 17
Mar 16 04:34:24 birne kernel: [71129.545753] btusb_bulk_complete: hci0 urb ffff88012cbb0a80 failed to resubmit (19)
Mar 16 04:34:24 birne kernel: [71129.545800] btusb_intr_complete: hci0 urb ffff88012cbb0c00 failed to resubmit (19)
Mar 16 04:34:24 birne kernel: [71129.545876] btusb_bulk_complete: hci0 urb ffff88012cbb0f00 failed to resubmit (19)
Mar 16 04:34:24 birne kernel: [71129.545922] btusb_send_frame: hci0 urb ffff880053faeb40 submission failed
Mar 16 04:34:24 birne kernel: [71129.964560] PM: Syncing filesystems ... done.
Mar 16 04:34:24 birne kernel: [71129.973753] PM: Preparing system for mem sleep
Mar 16 04:34:32 birne kernel: [71130.489740] Freezing user space processes ... (elapsed 0.01 seconds) done.
Mar 16 04:34:32 birne kernel: [71130.505516] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Mar 16 04:34:32 birne kernel: [71130.521536] PM: Entering mem sleep
Mar 16 04:34:32 birne kernel: [71130.521665] Suspending console(s) (use no_console_suspend to debug)
Mar 16 04:34:32 birne kernel: [71130.682880] PM: suspend of drv:psmouse dev:serio2 complete after 161.259 msecs
Mar 16 04:34:32 birne kernel: [71130.683162] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Mar 16 04:34:32 birne kernel: [71130.683325] sd 0:0:0:0: [sda] Stopping disk
Mar 16 04:34:32 birne kernel: [71130.916668] PM: suspend of drv:tpm_tis dev:00:0b complete after 179.481 msecs
Mar 16 04:34:32 birne kernel: [71130.916837] ehci_hcd 0000:00:1d.0: PCI INT D disabled
Mar 16 04:34:32 birne kernel: [71130.916851] ehci_hcd 0000:00:1a.0: PCI INT D disabled
Mar 16 04:34:32 birne kernel: [71130.917621] ACPI handle has no context!
Mar 16 04:34:32 birne kernel: [71130.948554] i915 0000:00:02.0: power state changed by ACPI to D3
Mar 16 04:34:32 birne kernel: [71130.960942] PM: suspend of drv:sd dev:0:0:0:0 complete after 278.341 msecs
Mar 16 04:34:32 birne kernel: [71130.960951] PM: suspend of drv:scsi dev:target0:0:0 complete after 278.342 msecs
Mar 16 04:34:32 birne kernel: [71130.960968] PM: suspend of drv:scsi dev:host0 complete after 278.343 msecs
Mar 16 04:34:32 birne kernel: [71131.020825] HDA Intel 0000:00:1b.0: PCI INT B disabled
Mar 16 04:34:32 birne kernel: [71131.022045] e1000e 0000:00:19.0: PME# enabled
Mar 16 04:34:32 birne kernel: [71131.022050] e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Mar 16 04:34:32 birne kernel: [71131.036406] PM: suspend of drv:e1000e dev:0000:00:19.0 complete after 119.786 msecs
Mar 16 04:34:32 birne kernel: [71131.036412] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.816 msecs
Mar 16 04:34:32 birne kernel: [71131.036481] PM: suspend of drv: dev:pci0000:00 complete after 119.071 msecs
Mar 16 04:34:32 birne kernel: [71131.036490] PM: suspend of devices complete after 515.623 msecs
Mar 16 04:34:32 birne kernel: [71131.036493] PM: suspend devices took 0.516 seconds
Mar 16 04:34:32 birne kernel: [71131.068333] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D3
Mar 16 04:34:32 birne kernel: [71131.104270] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D3
Mar 16 04:34:32 birne kernel: [71131.120306] PM: late suspend of devices complete after 83.975 msecs
Mar 16 04:34:32 birne kernel: [71131.120497] ACPI: Preparing to enter system sleep state S3
Mar 16 04:34:32 birne kernel: [71131.319870] PM: Saving platform NVS memory
Mar 16 04:34:32 birne kernel: [71131.323248] Disabling non-boot CPUs ...
Mar 16 04:34:32 birne kernel: [71131.427564] CPU 1 is now offline
Mar 16 04:34:32 birne kernel: [71131.531405] CPU 2 is now offline
Mar 16 04:34:32 birne kernel: [71131.635172] CPU 3 is now offline
Mar 16 04:34:32 birne kernel: [71131.635615] Extended CMOS year: 2000
Mar 16 04:34:32 birne kernel: [71131.635834] ACPI: Low-level resume complete
Mar 16 04:34:32 birne kernel: [71131.635888] PM: Restoring platform NVS memory
Mar 16 04:34:32 birne kernel: [71131.636525] Extended CMOS year: 2000
Mar 16 04:34:32 birne kernel: [71131.636570] Enabling non-boot CPUs ...
Mar 16 04:34:32 birne kernel: [71131.636658] Booting Node 0 Processor 1 APIC 0x1
Mar 16 04:34:32 birne kernel: [71131.636659] smpboot cpu 1: start_ip = 99000
Mar 16 04:34:32 birne kernel: [71131.747869] Switched to NOHz mode on CPU #1
Mar 16 04:34:32 birne kernel: [71131.807859] CPU1 is up
Mar 16 04:34:32 birne kernel: [71131.807969] Booting Node 0 Processor 2 APIC 0x4
Mar 16 04:34:32 birne kernel: [71131.807970] smpboot cpu 2: start_ip = 99000
Mar 16 04:34:32 birne kernel: [71131.919465] Switched to NOHz mode on CPU #2
Mar 16 04:34:32 birne kernel: [71131.979523] CPU2 is up
Mar 16 04:34:32 birne kernel: [71131.979619] Booting Node 0 Processor 3 APIC 0x5
Mar 16 04:34:32 birne kernel: [71131.979620] smpboot cpu 3: start_ip = 99000
Mar 16 04:34:32 birne kernel: [71132.091167] Switched to NOHz mode on CPU #3
Mar 16 04:34:32 birne kernel: [71132.151304] CPU3 is up
Mar 16 04:34:32 birne kernel: [71132.153620] ACPI: Waking up from system sleep state S3
Mar 16 04:34:32 birne kernel: [71132.450633] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407)
Mar 16 04:34:32 birne kernel: [71132.450778] ehci_hcd 0000:00:1a.0: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 04:34:32 birne kernel: [71132.450795] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2728000)
Mar 16 04:34:32 birne kernel: [71132.450802] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102)
Mar 16 04:34:32 birne kernel: [71132.470445] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 04:34:32 birne kernel: [71132.470454] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 04:34:32 birne kernel: [71132.470700] ehci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 04:34:32 birne kernel: [71132.470717] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2728400)
Mar 16 04:34:32 birne kernel: [71132.470724] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102)
Mar 16 04:34:32 birne kernel: [71132.470741] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 04:34:32 birne kernel: [71132.470744] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 04:34:32 birne kernel: [71132.470846] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407)
Mar 16 04:34:32 birne kernel: [71132.470911] intel ips 0000:00:1f.6: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 04:34:32 birne kernel: [71132.470931] intel ips 0000:00:1f.6: restoring config space at offset 0x1 (was 0x100000, writing 0x100002)
Mar 16 04:34:32 birne kernel: [71132.471012] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506)
Mar 16 04:34:32 birne kernel: [71132.471197] PM: early resume of devices complete after 20.740 msecs
Mar 16 04:34:32 birne kernel: [71132.471333] i915 0000:00:02.0: power state changed by ACPI to D0
Mar 16 04:34:32 birne kernel: [71132.471337] i915 0000:00:02.0: power state changed by ACPI to D0
Mar 16 04:34:32 birne kernel: [71132.471341] i915 0000:00:02.0: setting latency timer to 64
Mar 16 04:34:32 birne kernel: [71132.471389] e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Mar 16 04:34:32 birne kernel: [71132.471395] e1000e 0000:00:19.0: PME# disabled
Mar 16 04:34:32 birne kernel: [71132.471404] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 04:34:32 birne kernel: [71132.471409] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 04:34:32 birne kernel: [71132.471424] ehci_hcd 0000:00:1a.0: PCI INT D -> GSI 23 (level, low) -> IRQ 23
Mar 16 04:34:32 birne kernel: [71132.471433] ehci_hcd 0000:00:1a.0: setting latency timer to 64
Mar 16 04:34:32 birne kernel: [71132.471499] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Mar 16 04:34:32 birne kernel: [71132.471506] HDA Intel 0000:00:1b.0: setting latency timer to 64
Mar 16 04:34:32 birne kernel: [71132.471549] e1000e 0000:00:19.0: irq 41 for MSI/MSI-X
Mar 16 04:34:32 birne kernel: [71132.471590] HDA Intel 0000:00:1b.0: irq 43 for MSI/MSI-X
Mar 16 04:34:32 birne kernel: [71132.471641] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 04:34:32 birne kernel: [71132.471645] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 04:34:32 birne kernel: [71132.471650] ehci_hcd 0000:00:1d.0: PCI INT D -> GSI 19 (level, low) -> IRQ 19
Mar 16 04:34:32 birne kernel: [71132.471656] ehci_hcd 0000:00:1d.0: setting latency timer to 64
Mar 16 04:34:32 birne kernel: [71132.471709] pci 0000:00:1e.0: setting latency timer to 64
Mar 16 04:34:32 birne kernel: [71132.471733] ahci 0000:00:1f.2: setting latency timer to 64
Mar 16 04:34:32 birne kernel: [71132.471968] sd 0:0:0:0: [sda] Starting disk
Mar 16 04:34:32 birne kernel: [71132.515660] Extended CMOS year: 2000
Mar 16 04:34:32 birne kernel: [71132.650108] PM: resume of drv: dev:ep_00 complete after 178.475 msecs
Mar 16 04:34:32 birne kernel: [71132.650130] PM: resume of drv:hub dev:2-1:1.0 complete after 178.488 msecs
Mar 16 04:34:32 birne kernel: [71132.650135] PM: resume of drv:hub dev:1-1:1.0 complete after 178.519 msecs
Mar 16 04:34:32 birne kernel: [71132.650145] PM: resume of drv: dev:ep_00 complete after 178.490 msecs
Mar 16 04:34:32 birne kernel: [71132.650151] PM: resume of drv: dev:ep_81 complete after 178.506 msecs
Mar 16 04:34:32 birne kernel: [71132.650160] PM: resume of drv: dev:ep_81 complete after 178.540 msecs
Mar 16 04:34:32 birne kernel: [71132.650667] PM: resume of drv: dev:ep_00 complete after 178.930 msecs
Mar 16 04:34:32 birne kernel: [71132.650676] PM: resume of drv:usbhid dev:1-1.2:1.0 complete after 178.945 msecs
Mar 16 04:34:32 birne kernel: [71132.650702] PM: resume of drv: dev:ep_81 complete after 178.966 msecs
Mar 16 04:34:32 birne kernel: [71132.789814] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 16 04:34:32 birne kernel: [71132.797757] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Mar 16 04:34:32 birne kernel: [71132.798315] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Mar 16 04:34:32 birne kernel: [71132.798322] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Mar 16 04:34:32 birne kernel: [71132.798327] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Mar 16 04:34:32 birne kernel: [71132.799372] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Mar 16 04:34:32 birne kernel: [71132.799378] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Mar 16 04:34:32 birne kernel: [71132.799383] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Mar 16 04:34:32 birne kernel: [71132.799671] ata1.00: configured for UDMA/133
Mar 16 04:34:32 birne kernel: [71132.805742] ata5: SATA link down (SStatus 0 SControl 300)
Mar 16 04:34:32 birne kernel: [71132.813741] ata6: SATA link down (SStatus 0 SControl 300)
Mar 16 04:34:32 birne kernel: [71132.813892] PM: resume of drv:sd dev:0:0:0:0 complete after 342.634 msecs
Mar 16 04:34:32 birne kernel: [71132.813900] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 342.621 msecs
Mar 16 04:34:32 birne kernel: [71132.845270] PM: resume of drv:i915 dev:0000:00:02.0 complete after 374.699 msecs
Mar 16 04:34:32 birne kernel: [71132.870201] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Mar 16 04:34:32 birne kernel: [71132.871059] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Mar 16 04:34:32 birne kernel: [71132.885359] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Mar 16 04:34:32 birne kernel: [71132.885812] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Mar 16 04:34:32 birne kernel: [71132.887472] ata2.00: configured for UDMA/100
Mar 16 04:34:32 birne kernel: [71132.909729] usb 2-1.2: reset low speed USB device number 10 using ehci_hcd
Mar 16 04:34:32 birne kernel: [71133.191992] PM: resume of drv:usbhid dev:2-1.2:1.0 complete after 721.361 msecs
Mar 16 04:34:32 birne kernel: [71133.192005] PM: resume of drv: dev:ep_00 complete after 721.366 msecs
Mar 16 04:34:32 birne kernel: [71133.192021] PM: resume of drv: dev:ep_81 complete after 721.389 msecs
Mar 16 04:34:32 birne kernel: [71133.260965] usb 1-1.6: reset high speed USB device number 6 using ehci_hcd
Mar 16 04:34:32 birne kernel: [71133.370121] PM: resume of drv:uvcvideo dev:1-1.6:1.1 complete after 899.864 msecs
Mar 16 04:34:32 birne kernel: [71133.370130] PM: resume of drv: dev:ep_00 complete after 899.862 msecs
Mar 16 04:34:32 birne kernel: [71133.370151] PM: resume of drv:uvcvideo dev:1-1.6:1.0 complete after 899.907 msecs
Mar 16 04:34:32 birne kernel: [71133.370178] PM: resume of drv: dev:ep_81 complete after 899.925 msecs
Mar 16 04:34:32 birne kernel: [71133.370188] PM: resume of drv:video4linux dev:video0 complete after 524.783 msecs
Mar 16 04:34:32 birne kernel: [71133.436638] usb 1-1.3: reset full speed USB device number 4 using ehci_hcd
Mar 16 04:34:32 birne kernel: [71133.529350] PM: resume of drv:usb dev:1-1.3:1.0 complete after 1059.449 msecs
Mar 16 04:34:32 birne kernel: [71133.529359] PM: resume of drv: dev:ep_00 complete after 1059.434 msecs
Mar 16 04:34:32 birne kernel: [71133.529403] PM: resume of drv: dev:ep_81 complete after 1059.500 msecs
Mar 16 04:34:32 birne kernel: [71133.529409] PM: resume of drv: dev:ep_83 complete after 1059.494 msecs
Mar 16 04:34:32 birne kernel: [71133.529426] PM: resume of drv: dev:ep_02 complete after 1059.519 msecs
Mar 16 04:34:32 birne kernel: [71133.529439] PM: resume of devices complete after 1060.300 msecs
Mar 16 04:34:32 birne kernel: [71133.529546] PM: resume devices took 1.060 seconds
Mar 16 04:34:32 birne kernel: [71133.529563] PM: Finishing wakeup.
Mar 16 04:34:32 birne kernel: [71133.529564] Restarting tasks ... done.
Mar 16 04:34:32 birne kernel: [71133.537579] usb 1-1.2: USB disconnect, device number 16
Mar 16 04:34:32 birne kernel: [71133.550223] video LNXVIDEO:00: Restoring backlight state
Mar 16 04:34:32 birne kernel: [71133.559765] [drm:drm_mode_getfb] *ERROR* invalid framebuffer id
Mar 16 04:34:32 birne kernel: [71133.967570] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro,commit=0
Mar 16 04:34:32 birne NetworkManager[845]: <info> wake requested (sleeping: yes  enabled: yes)
Mar 16 04:34:32 birne NetworkManager[845]: <info> waking up and re-enabling...
Mar 16 04:34:32 birne NetworkManager[845]: <info> (eth0): now managed
Mar 16 04:34:32 birne NetworkManager[845]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 16 04:34:32 birne NetworkManager[845]: <info> (eth0): bringing up device.
Mar 16 04:34:32 birne kernel: [71134.095367] e1000e 0000:00:19.0: irq 41 for MSI/MSI-X
Mar 16 04:34:32 birne NetworkManager[845]: <info> (eth0): preparing device.
Mar 16 04:34:32 birne NetworkManager[845]: <info> (eth0): deactivating device (reason 'managed') [2]
Mar 16 04:34:32 birne NetworkManager[845]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Mar 16 04:34:32 birne NetworkManager[845]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Mar 16 04:34:32 birne NetworkManager[845]: <info> (wlan0): now managed
Mar 16 04:34:32 birne NetworkManager[845]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 16 04:34:32 birne NetworkManager[845]: <info> (wlan0): bringing up device.
Mar 16 04:34:33 birne kernel: [71134.151115] e1000e 0000:00:19.0: irq 41 for MSI/MSI-X
Mar 16 04:34:33 birne kernel: [71134.151615] ADDRCONF(NETDEV_UP): eth0: link is not ready
Mar 16 04:34:33 birne kernel: [71134.203078] usb 1-1.2: new low speed USB device number 18 using ehci_hcd
Mar 16 04:34:33 birne kernel: [71134.302274] input: 2.4G Wireless Optical Mouse as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0/input/input19
Mar 16 04:34:33 birne kernel: [71134.302471] generic-usb 0003:1D57:0008.0008: input,hidraw0: USB HID v1.10 Mouse [2.4G Wireless Optical Mouse] on usb-0000:00:1a.0-1.2/input0
Mar 16 04:34:33 birne kernel: [71134.314463] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Mar 16 04:34:33 birne NetworkManager[845]: <info> (wlan0): preparing device.
Mar 16 04:34:33 birne NetworkManager[845]: <info> (wlan0): deactivating device (reason 'managed') [2]
Mar 16 04:34:33 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: starting -> ready
Mar 16 04:34:33 birne NetworkManager[845]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Mar 16 04:34:33 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: ready -> inactive
Mar 16 04:34:33 birne kernel: [71134.506402] usb 1-1.4: new full speed USB device number 19 using ehci_hcd
Mar 16 04:34:37 birne NetworkManager[845]: <info> Auto-activating connection 'tschill-net'.
Mar 16 04:34:37 birne NetworkManager[845]: <info> Activation (wlan0) starting connection 'tschill-net'
Mar 16 04:34:37 birne NetworkManager[845]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Mar 16 04:34:37 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Mar 16 04:34:37 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Mar 16 04:34:37 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Mar 16 04:34:37 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Mar 16 04:34:37 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Mar 16 04:34:37 birne NetworkManager[845]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Mar 16 04:34:37 birne NetworkManager[845]: <info> Activation (wlan0/wireless): access point 'tschill-net' has security, but secrets are required.
Mar 16 04:34:37 birne NetworkManager[845]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
Mar 16 04:34:37 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Mar 16 04:34:37 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Mar 16 04:34:37 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Mar 16 04:34:37 birne NetworkManager[845]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Mar 16 04:34:37 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Mar 16 04:34:37 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Mar 16 04:34:37 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Mar 16 04:34:37 birne NetworkManager[845]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Mar 16 04:34:37 birne NetworkManager[845]: <info> Activation (wlan0/wireless): connection 'tschill-net' has security, and secrets exist.  No new secrets needed.
Mar 16 04:34:37 birne NetworkManager[845]: <info> Config: added 'ssid' value 'tschill-net'
Mar 16 04:34:37 birne NetworkManager[845]: <info> Config: added 'scan_ssid' value '1'
Mar 16 04:34:37 birne NetworkManager[845]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Mar 16 04:34:37 birne NetworkManager[845]: <info> Config: added 'auth_alg' value 'OPEN'
Mar 16 04:34:37 birne NetworkManager[845]: <info> Config: added 'psk' value '<omitted>'
Mar 16 04:34:37 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Mar 16 04:34:37 birne NetworkManager[845]: <info> Config: set interface ap_scan to 1
Mar 16 04:34:37 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: inactive -> scanning
Mar 16 04:34:39 birne wpa_supplicant[1462]: Trying to authenticate with 00:1d:7e:fb:d4:5f (SSID='tschill-net' freq=2422 MHz)
Mar 16 04:34:39 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Mar 16 04:34:39 birne kernel: [71141.082792] wlan0: authenticate with 00:1d:7e:fb:d4:5f (try 1)
Mar 16 04:34:39 birne wpa_supplicant[1462]: Trying to associate with 00:1d:7e:fb:d4:5f (SSID='tschill-net' freq=2422 MHz)
Mar 16 04:34:39 birne kernel: [71141.085683] wlan0: authenticated
Mar 16 04:34:39 birne kernel: [71141.086736] wlan0: associate with 00:1d:7e:fb:d4:5f (try 1)
Mar 16 04:34:39 birne kernel: [71141.089264] wlan0: RX AssocResp from 00:1d:7e:fb:d4:5f (capab=0x431 status=0 aid=3)
Mar 16 04:34:39 birne kernel: [71141.089268] wlan0: associated
Mar 16 04:34:39 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: authenticating -> associating
Mar 16 04:34:39 birne kernel: [71141.095401] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Mar 16 04:34:39 birne wpa_supplicant[1462]: Associated with 00:1d:7e:fb:d4:5f
Mar 16 04:34:39 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: associating -> 4-way handshake
Mar 16 04:34:39 birne wpa_supplicant[1462]: WPA: Key negotiation completed with 00:1d:7e:fb:d4:5f [PTK=CCMP GTK=TKIP]
Mar 16 04:34:39 birne wpa_supplicant[1462]: CTRL-EVENT-CONNECTED - Connection to 00:1d:7e:fb:d4:5f completed (auth) [id=0 id_str=]
Mar 16 04:34:39 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: 4-way handshake -> completed
Mar 16 04:34:39 birne NetworkManager[845]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'tschill-net'.
Mar 16 04:34:39 birne NetworkManager[845]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
Mar 16 04:34:39 birne NetworkManager[845]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
Mar 16 04:34:39 birne NetworkManager[845]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
Mar 16 04:34:39 birne NetworkManager[845]: <info> Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Mar 16 04:34:39 birne dhclient: Internet Systems Consortium DHCP Client 4.1.1-P1
Mar 16 04:34:39 birne dhclient: Copyright 2004-2010 Internet Systems Consortium.
Mar 16 04:34:39 birne dhclient: All rights reserved.
Mar 16 04:34:39 birne dhclient: For info, please visit https://www.isc.org/software/dhcp/
Mar 16 04:34:39 birne dhclient: 
Mar 16 04:34:39 birne NetworkManager[845]: <info> dhclient started with pid 31133
Mar 16 04:34:39 birne NetworkManager[845]: <info> Activation (wlan0) Beginning IP6 addrconf.
Mar 16 04:34:39 birne NetworkManager[845]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
Mar 16 04:34:40 birne NetworkManager[845]: <info> (wlan0): DHCPv4 state changed nbi -> preinit
Mar 16 04:34:40 birne dhclient: Listening on LPF/wlan0/00:24:d7:e3:32:78
Mar 16 04:34:40 birne dhclient: Sending on   LPF/wlan0/00:24:d7:e3:32:78
Mar 16 04:34:40 birne dhclient: Sending on   Socket/fallback
Mar 16 04:34:40 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 255.255.255.255 port 67
Mar 16 04:34:40 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 04:34:40 birne dhclient: bound to 192.168.0.161 -- renewal in 1733 seconds.
Mar 16 04:34:40 birne NetworkManager[845]: <info> (wlan0): DHCPv4 state changed preinit -> reboot
Mar 16 04:34:40 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled...
Mar 16 04:34:40 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started...
Mar 16 04:34:40 birne NetworkManager[845]: <info>   address 192.168.0.161
Mar 16 04:34:40 birne NetworkManager[845]: <info>   prefix 24 (255.255.255.0)
Mar 16 04:34:40 birne NetworkManager[845]: <info>   gateway 192.168.0.215
Mar 16 04:34:40 birne NetworkManager[845]: <info>   nameserver '77.244.240.244'
Mar 16 04:34:40 birne NetworkManager[845]: <info>   domain name 'example.org'
Mar 16 04:34:40 birne NetworkManager[845]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
Mar 16 04:34:41 birne NetworkManager[845]: <info> (wlan0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Mar 16 04:34:41 birne NetworkManager[845]: <info> Policy set 'tschill-net' (wlan0) as default for IPv4 routing and DNS.
Mar 16 04:34:41 birne NetworkManager[845]: <info> Activation (wlan0) successful, device activated.
Mar 16 04:34:41 birne NetworkManager[845]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
Mar 16 04:34:41 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete.
Mar 16 04:34:50 birne kernel: [71151.915396] wlan0: no IPv6 routers present
Mar 16 04:35:00 birne NetworkManager[845]: <info> (wlan0): IP6 addrconf timed out or failed.
Mar 16 04:35:00 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) scheduled...
Mar 16 04:35:00 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) started...
Mar 16 04:35:00 birne NetworkManager[845]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
Mar 16 04:35:00 birne NetworkManager[845]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
Mar 16 04:35:00 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) complete.
Mar 16 04:35:04 birne kernel: [71165.844179] SysRq : SAK
Mar 16 04:35:04 birne kernel: [71165.844260] SAK: killed process 1129 (Xorg): task_session(p)==tty->session
Mar 16 04:35:04 birne kernel: [71165.844327] SAK: killed process 1129 (Xorg): task_session(p)==tty->session
Mar 16 04:35:04 birne kernel: [71165.844345] SAK: killed process 1573 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844490] SAK: killed process 1574 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844495] SAK: killed process 1576 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844499] SAK: killed process 1577 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844503] SAK: killed process 1578 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844509] SAK: killed process 1579 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844513] SAK: killed process 1580 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844517] SAK: killed process 1581 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844521] SAK: killed process 1582 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844525] SAK: killed process 1583 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844529] SAK: killed process 1584 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844532] SAK: killed process 1585 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844536] SAK: killed process 1586 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844540] SAK: killed process 1587 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844544] SAK: killed process 1588 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844548] SAK: killed process 1589 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844551] SAK: killed process 1590 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844555] SAK: killed process 1591 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844559] SAK: killed process 1592 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844563] SAK: killed process 1593 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844567] SAK: killed process 1594 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844570] SAK: killed process 1595 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844574] SAK: killed process 1596 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844578] SAK: killed process 1597 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844586] SAK: killed process 1598 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844590] SAK: killed process 1599 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844594] SAK: killed process 1600 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844597] SAK: killed process 1601 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844601] SAK: killed process 1602 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844605] SAK: killed process 1603 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844609] SAK: killed process 1604 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844613] SAK: killed process 1605 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844617] SAK: killed process 1606 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844620] SAK: killed process 1607 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844624] SAK: killed process 1608 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844628] SAK: killed process 1609 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844632] SAK: killed process 1610 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844636] SAK: killed process 1611 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844640] SAK: killed process 1612 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844644] SAK: killed process 1613 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844648] SAK: killed process 1614 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844651] SAK: killed process 1615 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844655] SAK: killed process 1616 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844659] SAK: killed process 1617 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844663] SAK: killed process 1618 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844667] SAK: killed process 1619 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844670] SAK: killed process 1620 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844674] SAK: killed process 1621 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844678] SAK: killed process 1622 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844682] SAK: killed process 1623 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844686] SAK: killed process 1624 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844693] SAK: killed process 1625 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844697] SAK: killed process 1626 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844701] SAK: killed process 1627 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844705] SAK: killed process 1628 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844709] SAK: killed process 1629 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844713] SAK: killed process 1630 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844716] SAK: killed process 1631 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844720] SAK: killed process 1632 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844724] SAK: killed process 1633 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844728] SAK: killed process 1634 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844732] SAK: killed process 1635 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844736] SAK: killed process 1637 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844740] SAK: killed process 1638 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:04 birne kernel: [71165.844743] SAK: killed process 21715 (console-kit-dae): fd#10 opened to the tty
Mar 16 04:35:14 birne kernel: [71175.238207] audit_printk_skb: 30 callbacks suppressed
Mar 16 04:35:14 birne kernel: [71175.238213] type=1400 audit(1331868914.163:21): apparmor="DENIED" operation="file_perm" info="Failed name lookup" error=-22 parent=2035 profile="/usr/sbin/mysqld-akonadi///usr/sbin/mysqld" name="" pid=31403 comm="mysqld" requested_mask="w" denied_mask="w" fsuid=1000 ouid=1000
Mar 16 04:35:14 birne kernel: [71175.284514] type=1400 audit(1331868914.211:22): apparmor="DENIED" operation="file_perm" info="Failed name lookup" error=-22 parent=2035 profile="/usr/sbin/mysqld-akonadi///usr/sbin/mysqld" name="" pid=31403 comm="mysqld" requested_mask="w" denied_mask="w" fsuid=1000 ouid=1000
Mar 16 04:35:14 birne kernel: [71175.284528] type=1400 audit(1331868914.211:23): apparmor="DENIED" operation="file_perm" info="Failed name lookup" error=-22 parent=2035 profile="/usr/sbin/mysqld-akonadi///usr/sbin/mysqld" name="" pid=31403 comm="mysqld" requested_mask="w" denied_mask="w" fsuid=1000 ouid=1000
Mar 16 04:35:16 birne kernel: [71177.850231] type=1400 audit(1331868916.778:24): apparmor="DENIED" operation="file_perm" info="Failed name lookup" error=-22 parent=2035 profile="/usr/sbin/mysqld-akonadi///usr/sbin/mysqld" name="" pid=31403 comm="mysqld" requested_mask="w" denied_mask="w" fsuid=1000 ouid=1000
Mar 16 04:35:16 birne kernel: [71177.850684] type=1400 audit(1331868916.778:25): apparmor="DENIED" operation="file_perm" info="Failed name lookup" error=-22 parent=2035 profile="/usr/sbin/mysqld-akonadi///usr/sbin/mysqld" name="" pid=31403 comm="mysqld" requested_mask="w" denied_mask="w" fsuid=1000 ouid=1000
Mar 16 04:35:16 birne kernel: [71177.854274] type=1400 audit(1331868916.782:26): apparmor="DENIED" operation="file_perm" info="Failed name lookup" error=-22 parent=2035 profile="/usr/sbin/mysqld-akonadi///usr/sbin/mysqld" name="" pid=31403 comm="mysqld" requested_mask="w" denied_mask="w" fsuid=1000 ouid=1000
Mar 16 04:42:00 birne kernel: [71580.572933] packagekitd[28714]: segfault at 8 ip 00000000004128e6 sp 00007ffff47a18b0 error 4 in packagekitd[400000+51000]
Mar 16 04:45:26 birne kernel: [71785.602699] type=1400 audit(1331869526.029:27): apparmor="DENIED" operation="file_perm" info="Failed name lookup" error=-22 parent=31550 profile="/usr/sbin/mysqld-akonadi///usr/sbin/mysqld" name="" pid=1800 comm="mysqld" requested_mask="w" denied_mask="w" fsuid=1000 ouid=1000
Mar 16 04:45:26 birne kernel: [71785.604545] type=1400 audit(1331869526.029:28): apparmor="DENIED" operation="file_perm" info="Failed name lookup" error=-22 parent=31550 profile="/usr/sbin/mysqld-akonadi///usr/sbin/mysqld" name="" pid=1800 comm="mysqld" requested_mask="w" denied_mask="w" fsuid=1000 ouid=1000
Mar 16 04:45:26 birne kernel: [71785.604564] type=1400 audit(1331869526.029:29): apparmor="DENIED" operation="file_perm" info="Failed name lookup" error=-22 parent=31550 profile="/usr/sbin/mysqld-akonadi///usr/sbin/mysqld" name="" pid=1800 comm="mysqld" requested_mask="w" denied_mask="w" fsuid=1000 ouid=1000
Mar 16 04:45:28 birne kernel: [71788.513961] type=1400 audit(1331869528.945:30): apparmor="DENIED" operation="file_perm" info="Failed name lookup" error=-22 parent=31550 profile="/usr/sbin/mysqld-akonadi///usr/sbin/mysqld" name="" pid=1800 comm="mysqld" requested_mask="w" denied_mask="w" fsuid=1000 ouid=1000
Mar 16 04:45:28 birne kernel: [71788.513972] type=1400 audit(1331869528.945:31): apparmor="DENIED" operation="file_perm" info="Failed name lookup" error=-22 parent=31550 profile="/usr/sbin/mysqld-akonadi///usr/sbin/mysqld" name="" pid=1800 comm="mysqld" requested_mask="w" denied_mask="w" fsuid=1000 ouid=1000
Mar 16 04:45:28 birne kernel: [71788.514312] type=1400 audit(1331869528.945:32): apparmor="DENIED" operation="file_perm" info="Failed name lookup" error=-22 parent=31550 profile="/usr/sbin/mysqld-akonadi///usr/sbin/mysqld" name="" pid=1800 comm="mysqld" requested_mask="w" denied_mask="w" fsuid=1000 ouid=1000
Mar 16 05:03:33 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 192.168.0.215 port 67
Mar 16 05:03:33 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 05:03:33 birne dhclient: bound to 192.168.0.161 -- renewal in 1652 seconds.
Mar 16 05:03:33 birne NetworkManager[845]: <info> (wlan0): DHCPv4 state changed reboot -> renew
Mar 16 05:03:33 birne NetworkManager[845]: <info>   address 192.168.0.161
Mar 16 05:03:33 birne NetworkManager[845]: <info>   prefix 24 (255.255.255.0)
Mar 16 05:03:33 birne NetworkManager[845]: <info>   gateway 192.168.0.215
Mar 16 05:03:33 birne NetworkManager[845]: <info>   nameserver '77.244.240.244'
Mar 16 05:03:33 birne NetworkManager[845]: <info>   domain name 'example.org'
Mar 16 05:05:15 birne NetworkManager[845]: <info> sleep requested (sleeping: no  enabled: yes)
Mar 16 05:05:15 birne NetworkManager[845]: <info> sleeping or disabling...
Mar 16 05:05:15 birne NetworkManager[845]: <info> (eth0): now unmanaged
Mar 16 05:05:15 birne NetworkManager[845]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Mar 16 05:05:15 birne NetworkManager[845]: <info> (eth0): cleaning up...
Mar 16 05:05:15 birne NetworkManager[845]: <info> (eth0): taking down device.
Mar 16 05:05:16 birne NetworkManager[845]: <info> (wlan0): now unmanaged
Mar 16 05:05:16 birne NetworkManager[845]: <info> (wlan0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
Mar 16 05:05:16 birne NetworkManager[845]: <info> (wlan0): deactivating device (reason 'sleeping') [37]
Mar 16 05:05:16 birne NetworkManager[845]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 31133
Mar 16 05:05:16 birne NetworkManager[845]: <info> (wlan0): cleaning up...
Mar 16 05:05:16 birne kernel: [72972.853527] wlan0: deauthenticating from 00:1d:7e:fb:d4:5f by local choice (reason=3)
Mar 16 05:05:16 birne NetworkManager[845]: <info> (wlan0): taking down device.
Mar 16 05:05:16 birne kernel: [72972.957972] cfg80211: All devices are disconnected, going to restore regulatory settings
Mar 16 05:05:16 birne kernel: [72972.957983] cfg80211: Restoring regulatory settings
Mar 16 05:05:16 birne kernel: [72972.957992] cfg80211: Calling CRDA to update world regulatory domain
Mar 16 05:05:16 birne kernel: [72972.979492] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain 
Mar 16 05:05:16 birne kernel: [72972.979498] cfg80211: World regulatory domain updated:
Mar 16 05:05:16 birne kernel: [72972.979501] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Mar 16 05:05:16 birne kernel: [72972.979506] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 16 05:05:16 birne kernel: [72972.979510] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Mar 16 05:05:16 birne kernel: [72972.979514] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Mar 16 05:05:16 birne kernel: [72972.979518] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 16 05:05:16 birne kernel: [72972.979522] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 16 05:05:16 birne wpa_supplicant[1462]: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=3
Mar 16 05:05:17 birne kernel: [72973.973238] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro,commit=0
Mar 16 05:05:18 birne kernel: [72974.895659] usb 1-1.4: USB disconnect, device number 19
Mar 16 05:05:18 birne kernel: [72974.895776] btusb_intr_complete: hci0 urb ffff88012fbb0480 failed to resubmit (19)
Mar 16 05:05:18 birne kernel: [72974.895907] btusb_bulk_complete: hci0 urb ffff88012fbb0300 failed to resubmit (19)
Mar 16 05:05:18 birne kernel: [72974.895914] btusb_bulk_complete: hci0 urb ffff88012fbb0f00 failed to resubmit (19)
Mar 16 05:05:18 birne kernel: [72974.895959] btusb_send_frame: hci0 urb ffff880112f40240 submission failed
Mar 16 05:05:18 birne kernel: [72975.381156] PM: Syncing filesystems ... done.
Mar 16 05:05:18 birne kernel: [72975.426398] PM: Preparing system for mem sleep
Mar 16 05:06:47 birne kernel: [72975.579346] Freezing user space processes ... (elapsed 0.01 seconds) done.
Mar 16 05:06:47 birne kernel: [72975.595143] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Mar 16 05:06:47 birne kernel: [72975.611085] PM: Entering mem sleep
Mar 16 05:06:47 birne kernel: [72975.611229] Suspending console(s) (use no_console_suspend to debug)
Mar 16 05:06:47 birne kernel: [72975.770695] PM: suspend of drv:psmouse dev:serio2 complete after 159.528 msecs
Mar 16 05:06:47 birne kernel: [72975.770957] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Mar 16 05:06:47 birne kernel: [72975.771313] sd 0:0:0:0: [sda] Stopping disk
Mar 16 05:06:47 birne kernel: [72975.998113] PM: suspend of drv:tpm_tis dev:00:0b complete after 176.839 msecs
Mar 16 05:06:47 birne kernel: [72975.998335] ehci_hcd 0000:00:1d.0: PCI INT D disabled
Mar 16 05:06:47 birne kernel: [72975.998352] ehci_hcd 0000:00:1a.0: PCI INT D disabled
Mar 16 05:06:47 birne kernel: [72975.998421] ACPI handle has no context!
Mar 16 05:06:47 birne kernel: [72976.033931] i915 0000:00:02.0: power state changed by ACPI to D3
Mar 16 05:06:47 birne kernel: [72976.050393] PM: suspend of drv:sd dev:0:0:0:0 complete after 280.141 msecs
Mar 16 05:06:47 birne kernel: [72976.050418] PM: suspend of drv:scsi dev:target0:0:0 complete after 280.129 msecs
Mar 16 05:06:47 birne kernel: [72976.050426] PM: suspend of drv:scsi dev:host0 complete after 280.098 msecs
Mar 16 05:06:47 birne kernel: [72976.103438] e1000e 0000:00:19.0: PME# enabled
Mar 16 05:06:47 birne kernel: [72976.103444] e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Mar 16 05:06:47 birne kernel: [72976.117762] PM: suspend of drv:e1000e dev:0000:00:19.0 complete after 119.707 msecs
Mar 16 05:06:47 birne kernel: [72976.205642] HDA Intel 0000:00:1b.0: PCI INT B disabled
Mar 16 05:06:47 birne kernel: [72976.221453] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 223.681 msecs
Mar 16 05:06:47 birne kernel: [72976.221475] PM: suspend of drv: dev:pci0000:00 complete after 223.086 msecs
Mar 16 05:06:47 birne kernel: [72976.221581] PM: suspend of devices complete after 611.616 msecs
Mar 16 05:06:47 birne kernel: [72976.221587] PM: suspend devices took 0.612 seconds
Mar 16 05:06:47 birne kernel: [72976.253414] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D3
Mar 16 05:06:47 birne kernel: [72976.289332] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D3
Mar 16 05:06:47 birne kernel: [72976.305380] PM: late suspend of devices complete after 83.998 msecs
Mar 16 05:06:47 birne kernel: [72976.305583] ACPI: Preparing to enter system sleep state S3
Mar 16 05:06:47 birne kernel: [72976.504849] PM: Saving platform NVS memory
Mar 16 05:06:47 birne kernel: [72976.508101] Disabling non-boot CPUs ...
Mar 16 05:06:47 birne kernel: [72976.612562] CPU 1 is now offline
Mar 16 05:06:47 birne kernel: [72976.716273] CPU 2 is now offline
Mar 16 05:06:47 birne kernel: [72976.820007] CPU 3 is now offline
Mar 16 05:06:47 birne kernel: [72976.820460] Extended CMOS year: 2000
Mar 16 05:06:47 birne kernel: [72976.820678] ACPI: Low-level resume complete
Mar 16 05:06:47 birne kernel: [72976.820731] PM: Restoring platform NVS memory
Mar 16 05:06:47 birne kernel: [72976.821364] Extended CMOS year: 2000
Mar 16 05:06:47 birne kernel: [72976.821410] Enabling non-boot CPUs ...
Mar 16 05:06:47 birne kernel: [72976.821496] Booting Node 0 Processor 1 APIC 0x1
Mar 16 05:06:47 birne kernel: [72976.821497] smpboot cpu 1: start_ip = 99000
Mar 16 05:06:47 birne kernel: [72976.832619] intel ips 0000:00:1f.6: MCP limit exceeded: Avg temp 15173, limit 9000
Mar 16 05:06:47 birne kernel: [72976.832623] intel ips 0000:00:1f.6: MCP limit exceeded: Avg power 44582, limit 35000
Mar 16 05:06:47 birne kernel: [72976.932619] Switched to NOHz mode on CPU #1
Mar 16 05:06:47 birne kernel: [72976.992593] CPU1 is up
Mar 16 05:06:47 birne kernel: [72976.992707] Booting Node 0 Processor 2 APIC 0x4
Mar 16 05:06:47 birne kernel: [72976.992708] smpboot cpu 2: start_ip = 99000
Mar 16 05:06:47 birne kernel: [72977.104139] Switched to NOHz mode on CPU #2
Mar 16 05:06:47 birne kernel: [72977.164155] CPU2 is up
Mar 16 05:06:47 birne kernel: [72977.164249] Booting Node 0 Processor 3 APIC 0x5
Mar 16 05:06:47 birne kernel: [72977.164251] smpboot cpu 3: start_ip = 99000
Mar 16 05:06:47 birne kernel: [72977.275813] Switched to NOHz mode on CPU #3
Mar 16 05:06:47 birne kernel: [72977.335820] CPU3 is up
Mar 16 05:06:47 birne kernel: [72977.338132] ACPI: Waking up from system sleep state S3
Mar 16 05:06:47 birne kernel: [72977.635045] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407)
Mar 16 05:06:47 birne kernel: [72977.635187] ehci_hcd 0000:00:1a.0: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 05:06:47 birne kernel: [72977.635205] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2728000)
Mar 16 05:06:47 birne kernel: [72977.635212] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102)
Mar 16 05:06:47 birne kernel: [72977.654844] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 05:06:47 birne kernel: [72977.654853] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 05:06:47 birne kernel: [72977.655095] ehci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 05:06:47 birne kernel: [72977.655112] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2728400)
Mar 16 05:06:47 birne kernel: [72977.655119] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102)
Mar 16 05:06:47 birne kernel: [72977.655136] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 05:06:47 birne kernel: [72977.655139] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 05:06:47 birne kernel: [72977.655241] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407)
Mar 16 05:06:47 birne kernel: [72977.655303] intel ips 0000:00:1f.6: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 05:06:47 birne kernel: [72977.655324] intel ips 0000:00:1f.6: restoring config space at offset 0x1 (was 0x100000, writing 0x100002)
Mar 16 05:06:47 birne kernel: [72977.655404] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506)
Mar 16 05:06:47 birne kernel: [72977.655590] PM: early resume of devices complete after 20.733 msecs
Mar 16 05:06:47 birne kernel: [72977.655773] i915 0000:00:02.0: power state changed by ACPI to D0
Mar 16 05:06:47 birne kernel: [72977.655941] pci 0000:00:1e.0: setting latency timer to 64
Mar 16 05:06:47 birne kernel: [72977.655966] e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Mar 16 05:06:47 birne kernel: [72977.655972] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 05:06:47 birne kernel: [72977.655977] e1000e 0000:00:19.0: PME# disabled
Mar 16 05:06:47 birne kernel: [72977.656006] i915 0000:00:02.0: power state changed by ACPI to D0
Mar 16 05:06:47 birne kernel: [72977.656019] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 05:06:47 birne kernel: [72977.656021] i915 0000:00:02.0: setting latency timer to 64
Mar 16 05:06:47 birne kernel: [72977.658782] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Mar 16 05:06:47 birne kernel: [72977.658789] HDA Intel 0000:00:1b.0: setting latency timer to 64
Mar 16 05:06:47 birne kernel: [72977.658836] HDA Intel 0000:00:1b.0: irq 41 for MSI/MSI-X
Mar 16 05:06:47 birne kernel: [72977.658908] ahci 0000:00:1f.2: setting latency timer to 64
Mar 16 05:06:47 birne kernel: [72977.659021] sd 0:0:0:0: [sda] Starting disk
Mar 16 05:06:47 birne kernel: [72977.659123] e1000e 0000:00:19.0: irq 43 for MSI/MSI-X
Mar 16 05:06:47 birne kernel: [72977.662146] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 05:06:47 birne kernel: [72977.662152] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 05:06:47 birne kernel: [72977.662155] ehci_hcd 0000:00:1a.0: PCI INT D -> GSI 23 (level, low) -> IRQ 23
Mar 16 05:06:47 birne kernel: [72977.662161] ehci_hcd 0000:00:1d.0: PCI INT D -> GSI 19 (level, low) -> IRQ 19
Mar 16 05:06:47 birne kernel: [72977.662163] ehci_hcd 0000:00:1a.0: setting latency timer to 64
Mar 16 05:06:47 birne kernel: [72977.662169] ehci_hcd 0000:00:1d.0: setting latency timer to 64
Mar 16 05:06:47 birne kernel: [72977.703612] Extended CMOS year: 2000
Mar 16 05:06:47 birne kernel: [72977.838420] PM: resume of drv: dev:ep_00 complete after 182.406 msecs
Mar 16 05:06:47 birne kernel: [72977.838437] PM: resume of drv:hub dev:2-1:1.0 complete after 182.462 msecs
Mar 16 05:06:47 birne kernel: [72977.838555] PM: resume of drv: dev:ep_00 complete after 182.620 msecs
Mar 16 05:06:47 birne kernel: [72977.838569] PM: resume of drv:hub dev:1-1:1.0 complete after 182.668 msecs
Mar 16 05:06:47 birne kernel: [72977.838579] PM: resume of drv: dev:ep_81 complete after 182.588 msecs
Mar 16 05:06:47 birne kernel: [72977.838588] PM: resume of drv: dev:ep_81 complete after 182.672 msecs
Mar 16 05:06:47 birne kernel: [72977.839258] PM: resume of drv: dev:ep_00 complete after 182.826 msecs
Mar 16 05:06:47 birne kernel: [72977.839268] PM: resume of drv:usbhid dev:1-1.2:1.0 complete after 182.877 msecs
Mar 16 05:06:47 birne kernel: [72977.839304] PM: resume of drv: dev:ep_81 complete after 182.873 msecs
Mar 16 05:06:47 birne kernel: [72977.969556] PM: resume of drv:i915 dev:0000:00:02.0 complete after 314.628 msecs
Mar 16 05:06:47 birne kernel: [72977.982012] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 16 05:06:47 birne kernel: [72977.990025] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Mar 16 05:06:47 birne kernel: [72977.990053] ata6: SATA link down (SStatus 0 SControl 300)
Mar 16 05:06:47 birne kernel: [72977.990516] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Mar 16 05:06:47 birne kernel: [72977.990521] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Mar 16 05:06:47 birne kernel: [72977.990526] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Mar 16 05:06:47 birne kernel: [72977.991376] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Mar 16 05:06:47 birne kernel: [72977.991381] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Mar 16 05:06:47 birne kernel: [72977.991385] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Mar 16 05:06:47 birne kernel: [72977.991742] ata1.00: configured for UDMA/133
Mar 16 05:06:47 birne kernel: [72977.997996] ata5: SATA link down (SStatus 0 SControl 300)
Mar 16 05:06:47 birne kernel: [72978.006122] PM: resume of drv:sd dev:0:0:0:0 complete after 350.705 msecs
Mar 16 05:06:47 birne kernel: [72978.006135] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 350.695 msecs
Mar 16 05:06:47 birne kernel: [72978.054061] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Mar 16 05:06:47 birne kernel: [72978.054919] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Mar 16 05:06:47 birne kernel: [72978.069257] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Mar 16 05:06:47 birne kernel: [72978.069687] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Mar 16 05:06:47 birne kernel: [72978.071348] ata2.00: configured for UDMA/100
Mar 16 05:06:47 birne kernel: [72978.097892] usb 2-1.2: reset low speed USB device number 10 using ehci_hcd
Mar 16 05:06:47 birne kernel: [72978.380255] PM: resume of drv: dev:ep_00 complete after 725.256 msecs
Mar 16 05:06:47 birne kernel: [72978.380266] PM: resume of drv:usbhid dev:2-1.2:1.0 complete after 725.307 msecs
Mar 16 05:06:47 birne kernel: [72978.380292] PM: resume of drv: dev:ep_81 complete after 725.311 msecs
Mar 16 05:06:47 birne kernel: [72978.448967] usb 1-1.6: reset high speed USB device number 6 using ehci_hcd
Mar 16 05:06:47 birne kernel: [72978.557598] PM: resume of drv: dev:ep_00 complete after 903.121 msecs
Mar 16 05:06:47 birne kernel: [72978.557606] PM: resume of drv:uvcvideo dev:1-1.6:1.1 complete after 903.149 msecs
Mar 16 05:06:47 birne kernel: [72978.557613] PM: resume of drv:uvcvideo dev:1-1.6:1.0 complete after 903.196 msecs
Mar 16 05:06:47 birne kernel: [72978.557646] PM: resume of drv: dev:ep_81 complete after 903.206 msecs
Mar 16 05:06:47 birne kernel: [72978.557660] PM: resume of drv:video4linux dev:video0 complete after 551.730 msecs
Mar 16 05:06:47 birne kernel: [72978.624551] usb 1-1.3: reset full speed USB device number 4 using ehci_hcd
Mar 16 05:06:47 birne kernel: [72978.717247] PM: resume of drv: dev:ep_00 complete after 1063.266 msecs
Mar 16 05:06:47 birne kernel: [72978.717262] PM: resume of drv:usb dev:1-1.3:1.0 complete after 1063.357 msecs
Mar 16 05:06:47 birne kernel: [72978.717301] PM: resume of drv: dev:ep_02 complete after 1063.358 msecs
Mar 16 05:06:47 birne kernel: [72978.717310] PM: resume of drv: dev:ep_83 complete after 1063.341 msecs
Mar 16 05:06:47 birne kernel: [72978.717319] PM: resume of drv: dev:ep_81 complete after 1063.398 msecs
Mar 16 05:06:47 birne kernel: [72978.717334] PM: resume of devices complete after 1064.343 msecs
Mar 16 05:06:47 birne kernel: [72978.717424] PM: resume devices took 1.064 seconds
Mar 16 05:06:47 birne kernel: [72978.717441] PM: Finishing wakeup.
Mar 16 05:06:47 birne kernel: [72978.717442] Restarting tasks ... 
Mar 16 05:06:47 birne kernel: [72978.724851] usb 1-1.2: USB disconnect, device number 18
Mar 16 05:06:47 birne kernel: [72978.732011] done.
Mar 16 05:06:47 birne kernel: [72978.744490] video LNXVIDEO:00: Restoring backlight state
Mar 16 05:06:47 birne kernel: [72979.156641] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro,commit=0
Mar 16 05:06:47 birne NetworkManager[845]: <info> wake requested (sleeping: yes  enabled: yes)
Mar 16 05:06:47 birne NetworkManager[845]: <info> waking up and re-enabling...
Mar 16 05:06:47 birne NetworkManager[845]: <info> (eth0): now managed
Mar 16 05:06:47 birne NetworkManager[845]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 16 05:06:47 birne NetworkManager[845]: <info> (eth0): bringing up device.
Mar 16 05:06:47 birne kernel: [72979.286243] e1000e 0000:00:19.0: irq 43 for MSI/MSI-X
Mar 16 05:06:47 birne NetworkManager[845]: <info> (eth0): preparing device.
Mar 16 05:06:47 birne NetworkManager[845]: <info> (eth0): deactivating device (reason 'managed') [2]
Mar 16 05:06:47 birne NetworkManager[845]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Mar 16 05:06:47 birne NetworkManager[845]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Mar 16 05:06:47 birne NetworkManager[845]: <info> (wlan0): now managed
Mar 16 05:06:47 birne NetworkManager[845]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 16 05:06:47 birne NetworkManager[845]: <info> (wlan0): bringing up device.
Mar 16 05:06:47 birne kernel: [72979.338644] e1000e 0000:00:19.0: irq 43 for MSI/MSI-X
Mar 16 05:06:47 birne kernel: [72979.339153] ADDRCONF(NETDEV_UP): eth0: link is not ready
Mar 16 05:06:47 birne kernel: [72979.397327] usb 1-1.2: new low speed USB device number 20 using ehci_hcd
Mar 16 05:06:47 birne NetworkManager[845]: <info> (wlan0): preparing device.
Mar 16 05:06:47 birne NetworkManager[845]: <info> (wlan0): deactivating device (reason 'managed') [2]
Mar 16 05:06:47 birne kernel: [72979.493351] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Mar 16 05:06:47 birne kernel: [72979.495032] input: 2.4G Wireless Optical Mouse as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0/input/input20
Mar 16 05:06:47 birne kernel: [72979.495348] generic-usb 0003:1D57:0008.0009: input,hidraw0: USB HID v1.10 Mouse [2.4G Wireless Optical Mouse] on usb-0000:00:1a.0-1.2/input0
Mar 16 05:06:47 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: starting -> ready
Mar 16 05:06:47 birne NetworkManager[845]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Mar 16 05:06:47 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: ready -> inactive
Mar 16 05:06:48 birne kernel: [72979.693859] usb 1-1.4: new full speed USB device number 21 using ehci_hcd
Mar 16 05:06:51 birne NetworkManager[845]: <info> Auto-activating connection 'tschill-net'.
Mar 16 05:06:51 birne NetworkManager[845]: <info> Activation (wlan0) starting connection 'tschill-net'
Mar 16 05:06:51 birne NetworkManager[845]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Mar 16 05:06:51 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Mar 16 05:06:51 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Mar 16 05:06:51 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Mar 16 05:06:51 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Mar 16 05:06:51 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Mar 16 05:06:51 birne NetworkManager[845]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Mar 16 05:06:51 birne NetworkManager[845]: <info> Activation (wlan0/wireless): access point 'tschill-net' has security, but secrets are required.
Mar 16 05:06:51 birne NetworkManager[845]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
Mar 16 05:06:51 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Mar 16 05:06:51 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Mar 16 05:06:51 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Mar 16 05:06:51 birne NetworkManager[845]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Mar 16 05:06:51 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Mar 16 05:06:51 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Mar 16 05:06:51 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Mar 16 05:06:51 birne NetworkManager[845]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Mar 16 05:06:51 birne NetworkManager[845]: <info> Activation (wlan0/wireless): connection 'tschill-net' has security, and secrets exist.  No new secrets needed.
Mar 16 05:06:51 birne NetworkManager[845]: <info> Config: added 'ssid' value 'tschill-net'
Mar 16 05:06:51 birne NetworkManager[845]: <info> Config: added 'scan_ssid' value '1'
Mar 16 05:06:51 birne NetworkManager[845]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Mar 16 05:06:51 birne NetworkManager[845]: <info> Config: added 'auth_alg' value 'OPEN'
Mar 16 05:06:51 birne NetworkManager[845]: <info> Config: added 'psk' value '<omitted>'
Mar 16 05:06:51 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Mar 16 05:06:51 birne NetworkManager[845]: <info> Config: set interface ap_scan to 1
Mar 16 05:06:51 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: inactive -> scanning
Mar 16 05:06:54 birne wpa_supplicant[1462]: Trying to authenticate with 00:1d:7e:fb:d4:5f (SSID='tschill-net' freq=2422 MHz)
Mar 16 05:06:54 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Mar 16 05:06:54 birne wpa_supplicant[1462]: Trying to associate with 00:1d:7e:fb:d4:5f (SSID='tschill-net' freq=2422 MHz)
Mar 16 05:06:54 birne kernel: [72986.169675] wlan0: authenticate with 00:1d:7e:fb:d4:5f (try 1)
Mar 16 05:06:54 birne kernel: [72986.172496] wlan0: authenticated
Mar 16 05:06:54 birne kernel: [72986.172963] wlan0: associate with 00:1d:7e:fb:d4:5f (try 1)
Mar 16 05:06:54 birne kernel: [72986.175402] wlan0: RX AssocResp from 00:1d:7e:fb:d4:5f (capab=0x431 status=0 aid=3)
Mar 16 05:06:54 birne kernel: [72986.175407] wlan0: associated
Mar 16 05:06:54 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: authenticating -> associating
Mar 16 05:06:54 birne wpa_supplicant[1462]: Associated with 00:1d:7e:fb:d4:5f
Mar 16 05:06:54 birne kernel: [72986.187517] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Mar 16 05:06:54 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: associating -> associated
Mar 16 05:06:54 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: associated -> 4-way handshake
Mar 16 05:06:54 birne wpa_supplicant[1462]: WPA: Key negotiation completed with 00:1d:7e:fb:d4:5f [PTK=CCMP GTK=TKIP]
Mar 16 05:06:54 birne wpa_supplicant[1462]: CTRL-EVENT-CONNECTED - Connection to 00:1d:7e:fb:d4:5f completed (auth) [id=0 id_str=]
Mar 16 05:06:54 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: 4-way handshake -> completed
Mar 16 05:06:54 birne NetworkManager[845]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'tschill-net'.
Mar 16 05:06:54 birne NetworkManager[845]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
Mar 16 05:06:54 birne NetworkManager[845]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
Mar 16 05:06:54 birne NetworkManager[845]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
Mar 16 05:06:54 birne NetworkManager[845]: <info> Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Mar 16 05:06:54 birne NetworkManager[845]: <info> dhclient started with pid 4748
Mar 16 05:06:54 birne NetworkManager[845]: <info> Activation (wlan0) Beginning IP6 addrconf.
Mar 16 05:06:54 birne NetworkManager[845]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
Mar 16 05:06:54 birne dhclient: Internet Systems Consortium DHCP Client 4.1.1-P1
Mar 16 05:06:54 birne dhclient: Copyright 2004-2010 Internet Systems Consortium.
Mar 16 05:06:54 birne dhclient: All rights reserved.
Mar 16 05:06:54 birne dhclient: For info, please visit https://www.isc.org/software/dhcp/
Mar 16 05:06:54 birne dhclient: 
Mar 16 05:06:54 birne NetworkManager[845]: <info> (wlan0): DHCPv4 state changed nbi -> preinit
Mar 16 05:06:54 birne dhclient: Listening on LPF/wlan0/00:24:d7:e3:32:78
Mar 16 05:06:54 birne dhclient: Sending on   LPF/wlan0/00:24:d7:e3:32:78
Mar 16 05:06:54 birne dhclient: Sending on   Socket/fallback
Mar 16 05:06:54 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 255.255.255.255 port 67
Mar 16 05:06:54 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 05:06:54 birne dhclient: bound to 192.168.0.161 -- renewal in 1397 seconds.
Mar 16 05:06:54 birne NetworkManager[845]: <info> (wlan0): DHCPv4 state changed preinit -> reboot
Mar 16 05:06:54 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled...
Mar 16 05:06:54 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started...
Mar 16 05:06:54 birne NetworkManager[845]: <info>   address 192.168.0.161
Mar 16 05:06:54 birne NetworkManager[845]: <info>   prefix 24 (255.255.255.0)
Mar 16 05:06:54 birne NetworkManager[845]: <info>   gateway 192.168.0.215
Mar 16 05:06:54 birne NetworkManager[845]: <info>   nameserver '77.244.240.244'
Mar 16 05:06:54 birne NetworkManager[845]: <info>   domain name 'example.org'
Mar 16 05:06:54 birne NetworkManager[845]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
Mar 16 05:06:55 birne NetworkManager[845]: <info> (wlan0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Mar 16 05:06:55 birne NetworkManager[845]: <info> Policy set 'tschill-net' (wlan0) as default for IPv4 routing and DNS.
Mar 16 05:06:55 birne NetworkManager[845]: <info> Activation (wlan0) successful, device activated.
Mar 16 05:06:55 birne NetworkManager[845]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
Mar 16 05:06:55 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete.
Mar 16 05:07:05 birne kernel: [72996.930250] wlan0: no IPv6 routers present
Mar 16 05:07:14 birne NetworkManager[845]: <info> (wlan0): IP6 addrconf timed out or failed.
Mar 16 05:07:14 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) scheduled...
Mar 16 05:07:14 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) started...
Mar 16 05:07:14 birne NetworkManager[845]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
Mar 16 05:07:14 birne NetworkManager[845]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
Mar 16 05:07:14 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) complete.
Mar 16 05:27:52 birne wpa_supplicant[1462]: WPA: Group rekeying completed with 00:1d:7e:fb:d4:5f [GTK=TKIP]
Mar 16 05:30:11 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 192.168.0.215 port 67
Mar 16 05:30:11 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 05:30:11 birne dhclient: bound to 192.168.0.161 -- renewal in 1491 seconds.
Mar 16 05:30:11 birne NetworkManager[845]: <info> (wlan0): DHCPv4 state changed reboot -> renew
Mar 16 05:30:11 birne NetworkManager[845]: <info>   address 192.168.0.161
Mar 16 05:30:11 birne NetworkManager[845]: <info>   prefix 24 (255.255.255.0)
Mar 16 05:30:11 birne NetworkManager[845]: <info>   gateway 192.168.0.215
Mar 16 05:30:11 birne NetworkManager[845]: <info>   nameserver '77.244.240.244'
Mar 16 05:30:11 birne NetworkManager[845]: <info>   domain name 'example.org'
Mar 16 05:55:02 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 192.168.0.215 port 67
Mar 16 05:55:02 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 05:55:02 birne dhclient: bound to 192.168.0.161 -- renewal in 1781 seconds.
Mar 16 06:24:43 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 192.168.0.215 port 67
Mar 16 06:24:43 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 06:24:43 birne dhclient: bound to 192.168.0.161 -- renewal in 1387 seconds.
Mar 16 06:27:55 birne wpa_supplicant[1462]: WPA: Group rekeying completed with 00:1d:7e:fb:d4:5f [GTK=TKIP]
Mar 16 06:47:50 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 192.168.0.215 port 67
Mar 16 06:47:50 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 06:47:50 birne dhclient: bound to 192.168.0.161 -- renewal in 1563 seconds.
Mar 16 07:13:53 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 192.168.0.215 port 67
Mar 16 07:13:53 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 07:13:53 birne dhclient: bound to 192.168.0.161 -- renewal in 1399 seconds.
Mar 16 07:27:58 birne wpa_supplicant[1462]: WPA: Group rekeying completed with 00:1d:7e:fb:d4:5f [GTK=TKIP]
Mar 16 07:37:12 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 192.168.0.215 port 67
Mar 16 07:37:12 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 07:37:12 birne dhclient: bound to 192.168.0.161 -- renewal in 1415 seconds.
Mar 16 08:00:47 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 192.168.0.215 port 67
Mar 16 08:00:47 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 08:00:47 birne dhclient: bound to 192.168.0.161 -- renewal in 1580 seconds.
Mar 16 08:27:07 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 192.168.0.215 port 67
Mar 16 08:27:07 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 08:27:07 birne dhclient: bound to 192.168.0.161 -- renewal in 1540 seconds.
Mar 16 08:27:58 birne wpa_supplicant[1462]: WPA: Group rekeying completed with 00:1d:7e:fb:d4:5f [GTK=TKIP]
Mar 16 08:52:47 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 192.168.0.215 port 67
Mar 16 08:52:47 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 08:52:47 birne dhclient: bound to 192.168.0.161 -- renewal in 1360 seconds.
Mar 16 09:15:27 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 192.168.0.215 port 67
Mar 16 09:15:27 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 09:15:27 birne dhclient: bound to 192.168.0.161 -- renewal in 1727 seconds.
Mar 16 09:27:52 birne wpa_supplicant[1462]: WPA: Group rekeying completed with 00:1d:7e:fb:d4:5f [GTK=TKIP]
Mar 16 09:44:14 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 192.168.0.215 port 67
Mar 16 09:44:14 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 09:44:14 birne dhclient: bound to 192.168.0.161 -- renewal in 1614 seconds.
Mar 16 10:11:08 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 192.168.0.215 port 67
Mar 16 10:11:08 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 10:11:08 birne dhclient: bound to 192.168.0.161 -- renewal in 1391 seconds.
Mar 16 10:27:54 birne wpa_supplicant[1462]: WPA: Group rekeying completed with 00:1d:7e:fb:d4:5f [GTK=TKIP]
Mar 16 10:34:19 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 192.168.0.215 port 67
Mar 16 10:34:19 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 10:34:19 birne dhclient: bound to 192.168.0.161 -- renewal in 1410 seconds.
Mar 16 10:57:49 birne dhclient: DHCPREQUEST of 192.168.0.161 on wlan0 to 192.168.0.215 port 67
Mar 16 10:57:49 birne dhclient: DHCPACK of 192.168.0.161 from 192.168.0.215
Mar 16 10:57:49 birne dhclient: bound to 192.168.0.161 -- renewal in 1377 seconds.
Mar 16 10:59:05 birne wpa_supplicant[1462]: CTRL-EVENT-DISCONNECTED bssid=00:1d:7e:fb:d4:5f reason=3
Mar 16 10:59:05 birne kernel: [94063.991140] iwlagn 0000:03:00.0: RF_KILL bit toggled to disable radio.
Mar 16 10:59:05 birne kernel: [94063.991507] iwlagn 0000:03:00.0: Not sending command - RF KILL
Mar 16 10:59:05 birne kernel: [94063.991513] iwlagn 0000:03:00.0: Error sending REPLY_QOS_PARAM: enqueue_hcmd failed: -5
Mar 16 10:59:05 birne kernel: [94063.991519] iwlagn 0000:03:00.0: Failed to update QoS
Mar 16 10:59:05 birne kernel: [94063.991526] iwlagn 0000:03:00.0: Not sending command - RF KILL
Mar 16 10:59:05 birne kernel: [94063.991530] iwlagn 0000:03:00.0: Error sending REPLY_RXON: enqueue_hcmd failed: -5
Mar 16 10:59:05 birne kernel: [94063.991535] iwlagn 0000:03:00.0: Error clearing ASSOC_MSK on BSS (-5)
Mar 16 10:59:05 birne kernel: [94063.991541] iwlagn 0000:03:00.0: Not sending command - RF KILL
Mar 16 10:59:05 birne kernel: [94063.991544] iwlagn 0000:03:00.0: Error sending REPLY_RXON: enqueue_hcmd failed: -5
Mar 16 10:59:05 birne kernel: [94063.991547] iwlagn 0000:03:00.0: Error clearing ASSOC_MSK on BSS (-5)
Mar 16 10:59:05 birne kernel: [94063.991551] wlan0: deauthenticating from 00:1d:7e:fb:d4:5f by local choice (reason=3)
Mar 16 10:59:05 birne NetworkManager[845]: <info> WiFi now disabled by radio killswitch
Mar 16 10:59:05 birne NetworkManager[845]: <info> (wlan0): device state change: activated -> unavailable (reason 'none') [100 20 0]
Mar 16 10:59:05 birne NetworkManager[845]: <info> (wlan0): deactivating device (reason 'none') [0]
Mar 16 10:59:05 birne NetworkManager[845]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 4748
Mar 16 10:59:05 birne kernel: [94064.013351] iwlagn 0000:03:00.0: Not sending command - RF KILL
Mar 16 10:59:05 birne kernel: [94064.013358] iwlagn 0000:03:00.0: Error sending REPLY_REMOVE_STA: enqueue_hcmd failed: -5
Mar 16 10:59:05 birne kernel: [94064.013364] iwlagn 0000:03:00.0: Error removing station 00:1d:7e:fb:d4:5f
Mar 16 10:59:05 birne kernel: [94064.015513] usb 1-1.4: USB disconnect, device number 21
Mar 16 10:59:05 birne kernel: [94064.015644] btusb_bulk_complete: hci0 urb ffff88003336e840 failed to resubmit (19)
Mar 16 10:59:05 birne kernel: [94064.015670] btusb_intr_complete: hci0 urb ffff8800714b19c0 failed to resubmit (19)
Mar 16 10:59:05 birne kernel: [94064.015767] btusb_bulk_complete: hci0 urb ffff8800714b1cc0 failed to resubmit (19)
Mar 16 10:59:05 birne kernel: [94064.015922] btusb_send_frame: hci0 urb ffff8801010e73c0 submission failed
Mar 16 10:59:05 birne kernel: [94064.018461] iwlagn 0000:03:00.0: Not sending command - RF KILL
Mar 16 10:59:05 birne kernel: [94064.018463] iwlagn 0000:03:00.0: Error sending REPLY_RXON: enqueue_hcmd failed: -5
Mar 16 10:59:05 birne kernel: [94064.018465] iwlagn 0000:03:00.0: Error clearing ASSOC_MSK on BSS (-5)
Mar 16 10:59:05 birne kernel: [94064.018493] iwlagn 0000:03:00.0: Not sending command - RF KILL
Mar 16 10:59:05 birne kernel: [94064.018494] iwlagn 0000:03:00.0: Error sending REPLY_LEDS_CMD: enqueue_hcmd failed: -5
Mar 16 10:59:05 birne kernel: [94064.018497] iwlagn 0000:03:00.0: Not sending command - RF KILL
Mar 16 10:59:05 birne kernel: [94064.018498] iwlagn 0000:03:00.0: Error sending REPLY_RXON: enqueue_hcmd failed: -5
Mar 16 10:59:05 birne kernel: [94064.018500] iwlagn 0000:03:00.0: Error clearing ASSOC_MSK on BSS (-5)
Mar 16 10:59:05 birne kernel: [94064.018531] cfg80211: All devices are disconnected, going to restore regulatory settings
Mar 16 10:59:05 birne kernel: [94064.018535] cfg80211: Restoring regulatory settings
Mar 16 10:59:05 birne kernel: [94064.018588] cfg80211: Calling CRDA to update world regulatory domain
Mar 16 10:59:05 birne kernel: [94064.032990] iwlagn 0000:03:00.0: Not sending command - RF KILL
Mar 16 10:59:05 birne kernel: [94064.032997] iwlagn 0000:03:00.0: Error sending REPLY_RXON: enqueue_hcmd failed: -5
Mar 16 10:59:05 birne kernel: [94064.033003] iwlagn 0000:03:00.0: Error clearing ASSOC_MSK on BSS (-5)
Mar 16 10:59:05 birne kernel: [94064.039281] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain 
Mar 16 10:59:05 birne kernel: [94064.039290] cfg80211: World regulatory domain updated:
Mar 16 10:59:05 birne kernel: [94064.039294] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Mar 16 10:59:05 birne kernel: [94064.039301] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 16 10:59:05 birne kernel: [94064.039307] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Mar 16 10:59:05 birne kernel: [94064.039313] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Mar 16 10:59:05 birne kernel: [94064.039318] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 16 10:59:05 birne kernel: [94064.039324] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 16 10:59:13 birne NetworkManager[845]: <info> sleep requested (sleeping: no  enabled: yes)
Mar 16 10:59:13 birne NetworkManager[845]: <info> sleeping or disabling...
Mar 16 10:59:13 birne NetworkManager[845]: <info> (eth0): now unmanaged
Mar 16 10:59:13 birne NetworkManager[845]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Mar 16 10:59:13 birne NetworkManager[845]: <info> (eth0): cleaning up...
Mar 16 10:59:13 birne NetworkManager[845]: <info> (eth0): taking down device.
Mar 16 10:59:13 birne NetworkManager[845]: <info> (wlan0): now unmanaged
Mar 16 10:59:13 birne NetworkManager[845]: <info> (wlan0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Mar 16 10:59:13 birne NetworkManager[845]: <info> (wlan0): cleaning up...
Mar 16 10:59:14 birne kernel: [94072.514153] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro,commit=0
Mar 16 10:59:15 birne kernel: [94073.233970] PM: Syncing filesystems ... done.
Mar 16 10:59:15 birne kernel: [94073.296637] PM: Preparing system for mem sleep
Mar 16 12:45:37 birne kernel: [94073.521450] Freezing user space processes ... (elapsed 0.01 seconds) done.
Mar 16 12:45:37 birne kernel: [94073.537246] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Mar 16 12:45:37 birne kernel: [94073.553218] PM: Entering mem sleep
Mar 16 12:45:37 birne kernel: [94073.553376] Suspending console(s) (use no_console_suspend to debug)
Mar 16 12:45:37 birne kernel: [94073.713636] PM: suspend of drv:psmouse dev:serio2 complete after 160.400 msecs
Mar 16 12:45:37 birne kernel: [94073.713971] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Mar 16 12:45:37 birne kernel: [94073.714184] sd 0:0:0:0: [sda] Stopping disk
Mar 16 12:45:37 birne kernel: [94073.948193] PM: suspend of drv:tpm_tis dev:00:0b complete after 178.670 msecs
Mar 16 12:45:37 birne kernel: [94073.948345] ehci_hcd 0000:00:1d.0: PCI INT D disabled
Mar 16 12:45:37 birne kernel: [94073.948423] ehci_hcd 0000:00:1a.0: PCI INT D disabled
Mar 16 12:45:37 birne kernel: [94073.948500] ACPI handle has no context!
Mar 16 12:45:37 birne kernel: [94073.980026] i915 0000:00:02.0: power state changed by ACPI to D3
Mar 16 12:45:37 birne kernel: [94073.989554] PM: suspend of drv:sd dev:0:0:0:0 complete after 276.280 msecs
Mar 16 12:45:37 birne kernel: [94073.989567] PM: suspend of drv:scsi dev:target0:0:0 complete after 276.267 msecs
Mar 16 12:45:37 birne kernel: [94073.989574] PM: suspend of drv:scsi dev:host0 complete after 276.240 msecs
Mar 16 12:45:37 birne kernel: [94074.052269] HDA Intel 0000:00:1b.0: PCI INT B disabled
Mar 16 12:45:37 birne kernel: [94074.053348] e1000e 0000:00:19.0: PME# enabled
Mar 16 12:45:37 birne kernel: [94074.053355] e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Mar 16 12:45:37 birne kernel: [94074.067857] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.801 msecs
Mar 16 12:45:37 birne kernel: [94074.067865] PM: suspend of drv:e1000e dev:0000:00:19.0 complete after 119.727 msecs
Mar 16 12:45:37 birne kernel: [94074.067900] PM: suspend of drv: dev:pci0000:00 complete after 119.629 msecs
Mar 16 12:45:37 birne kernel: [94074.067917] PM: suspend of devices complete after 515.599 msecs
Mar 16 12:45:37 birne kernel: [94074.067920] PM: suspend devices took 0.516 seconds
Mar 16 12:45:37 birne kernel: [94074.099784] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D3
Mar 16 12:45:37 birne kernel: [94074.139689] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D3
Mar 16 12:45:37 birne kernel: [94074.155722] PM: late suspend of devices complete after 88.017 msecs
Mar 16 12:45:37 birne kernel: [94074.155925] ACPI: Preparing to enter system sleep state S3
Mar 16 12:45:37 birne kernel: [94074.355180] PM: Saving platform NVS memory
Mar 16 12:45:37 birne kernel: [94074.358395] Disabling non-boot CPUs ...
Mar 16 12:45:37 birne kernel: [94074.462830] CPU 1 is now offline
Mar 16 12:45:37 birne kernel: [94074.566602] CPU 2 is now offline
Mar 16 12:45:37 birne kernel: [94074.670341] CPU 3 is now offline
Mar 16 12:45:37 birne kernel: [94074.670755] Extended CMOS year: 2000
Mar 16 12:45:37 birne kernel: [94074.670974] ACPI: Low-level resume complete
Mar 16 12:45:37 birne kernel: [94074.671028] PM: Restoring platform NVS memory
Mar 16 12:45:37 birne kernel: [94074.671665] Extended CMOS year: 2000
Mar 16 12:45:37 birne kernel: [94074.671711] Enabling non-boot CPUs ...
Mar 16 12:45:37 birne kernel: [94074.671796] Booting Node 0 Processor 1 APIC 0x1
Mar 16 12:45:37 birne kernel: [94074.671797] smpboot cpu 1: start_ip = 99000
Mar 16 12:45:37 birne kernel: [94074.782926] Switched to NOHz mode on CPU #1
Mar 16 12:45:37 birne kernel: [94074.842930] CPU1 is up
Mar 16 12:45:37 birne kernel: [94074.843042] Booting Node 0 Processor 2 APIC 0x4
Mar 16 12:45:37 birne kernel: [94074.843044] smpboot cpu 2: start_ip = 99000
Mar 16 12:45:37 birne kernel: [94074.954477] Switched to NOHz mode on CPU #2
Mar 16 12:45:37 birne kernel: [94075.014492] CPU2 is up
Mar 16 12:45:37 birne kernel: [94075.014596] Booting Node 0 Processor 3 APIC 0x5
Mar 16 12:45:37 birne kernel: [94075.014598] smpboot cpu 3: start_ip = 99000
Mar 16 12:45:37 birne kernel: [94075.126084] Switched to NOHz mode on CPU #3
Mar 16 12:45:37 birne kernel: [94075.186149] CPU3 is up
Mar 16 12:45:37 birne kernel: [94075.188452] ACPI: Waking up from system sleep state S3
Mar 16 12:45:37 birne kernel: [94075.485378] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407)
Mar 16 12:45:37 birne kernel: [94075.485522] ehci_hcd 0000:00:1a.0: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 12:45:37 birne kernel: [94075.485540] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2728000)
Mar 16 12:45:37 birne kernel: [94075.485546] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102)
Mar 16 12:45:37 birne kernel: [94075.505181] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 12:45:37 birne kernel: [94075.505191] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 12:45:37 birne kernel: [94075.505431] ehci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 12:45:37 birne kernel: [94075.505449] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2728400)
Mar 16 12:45:37 birne kernel: [94075.505456] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102)
Mar 16 12:45:37 birne kernel: [94075.505472] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 12:45:37 birne kernel: [94075.505476] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 12:45:37 birne kernel: [94075.505577] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407)
Mar 16 12:45:37 birne kernel: [94075.505642] intel ips 0000:00:1f.6: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 12:45:37 birne kernel: [94075.505663] intel ips 0000:00:1f.6: restoring config space at offset 0x1 (was 0x100000, writing 0x100002)
Mar 16 12:45:37 birne kernel: [94075.505743] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506)
Mar 16 12:45:37 birne kernel: [94075.505930] PM: early resume of devices complete after 20.736 msecs
Mar 16 12:45:37 birne kernel: [94075.506128] i915 0000:00:02.0: power state changed by ACPI to D0
Mar 16 12:45:37 birne kernel: [94075.506132] i915 0000:00:02.0: power state changed by ACPI to D0
Mar 16 12:45:37 birne kernel: [94075.506136] i915 0000:00:02.0: setting latency timer to 64
Mar 16 12:45:37 birne kernel: [94075.506180] e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Mar 16 12:45:37 birne kernel: [94075.506186] e1000e 0000:00:19.0: PME# disabled
Mar 16 12:45:37 birne kernel: [94075.506189] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 12:45:37 birne kernel: [94075.506193] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 12:45:37 birne kernel: [94075.506204] ehci_hcd 0000:00:1a.0: PCI INT D -> GSI 23 (level, low) -> IRQ 23
Mar 16 12:45:37 birne kernel: [94075.506214] ehci_hcd 0000:00:1a.0: setting latency timer to 64
Mar 16 12:45:37 birne kernel: [94075.506261] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Mar 16 12:45:37 birne kernel: [94075.506266] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 12:45:37 birne kernel: [94075.506272] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 12:45:37 birne kernel: [94075.506274] HDA Intel 0000:00:1b.0: setting latency timer to 64
Mar 16 12:45:37 birne kernel: [94075.506282] ehci_hcd 0000:00:1d.0: PCI INT D -> GSI 19 (level, low) -> IRQ 19
Mar 16 12:45:37 birne kernel: [94075.506296] ehci_hcd 0000:00:1d.0: setting latency timer to 64
Mar 16 12:45:37 birne kernel: [94075.506339] e1000e 0000:00:19.0: irq 41 for MSI/MSI-X
Mar 16 12:45:37 birne kernel: [94075.506341] pci 0000:00:1e.0: setting latency timer to 64
Mar 16 12:45:37 birne kernel: [94075.506359] HDA Intel 0000:00:1b.0: irq 43 for MSI/MSI-X
Mar 16 12:45:37 birne kernel: [94075.506362] ahci 0000:00:1f.2: setting latency timer to 64
Mar 16 12:45:37 birne kernel: [94075.506429] iwlagn 0000:03:00.0: RF_KILL bit toggled to disable radio.
Mar 16 12:45:37 birne kernel: [94075.506609] sd 0:0:0:0: [sda] Starting disk
Mar 16 12:45:37 birne kernel: [94075.546175] Extended CMOS year: 2000
Mar 16 12:45:37 birne kernel: [94075.684773] PM: resume of drv:hub dev:1-1:1.0 complete after 178.429 msecs
Mar 16 12:45:37 birne kernel: [94075.684784] PM: resume of drv: dev:ep_00 complete after 178.360 msecs
Mar 16 12:45:37 birne kernel: [94075.684798] PM: resume of drv:hub dev:2-1:1.0 complete after 178.332 msecs
Mar 16 12:45:37 birne kernel: [94075.684806] PM: resume of drv: dev:ep_00 complete after 178.268 msecs
Mar 16 12:45:37 birne kernel: [94075.684830] PM: resume of drv: dev:ep_81 complete after 178.425 msecs
Mar 16 12:45:37 birne kernel: [94075.684851] PM: resume of drv: dev:ep_81 complete after 178.367 msecs
Mar 16 12:45:37 birne kernel: [94075.685275] PM: resume of drv:usbhid dev:1-1.2:1.0 complete after 178.323 msecs
Mar 16 12:45:37 birne kernel: [94075.685281] PM: resume of drv: dev:ep_00 complete after 178.398 msecs
Mar 16 12:45:37 birne kernel: [94075.685287] PM: resume of drv: dev:ep_00 complete after 178.291 msecs
Mar 16 12:45:37 birne kernel: [94075.685291] PM: resume of drv:usbhid dev:2-1.2:1.0 complete after 178.451 msecs
Mar 16 12:45:37 birne kernel: [94075.685310] PM: resume of drv: dev:ep_81 complete after 178.453 msecs
Mar 16 12:45:37 birne kernel: [94075.685360] PM: resume of drv: dev:ep_81 complete after 178.393 msecs
Mar 16 12:45:37 birne kernel: [94075.756660] usb 1-1.3: reset full speed USB device number 4 using ehci_hcd
Mar 16 12:45:37 birne kernel: [94075.820033] PM: resume of drv:i915 dev:0000:00:02.0 complete after 314.703 msecs
Mar 16 12:45:37 birne kernel: [94075.824396] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Mar 16 12:45:37 birne kernel: [94075.824808] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Mar 16 12:45:37 birne kernel: [94075.824815] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Mar 16 12:45:37 birne kernel: [94075.824820] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Mar 16 12:45:37 birne kernel: [94075.825659] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Mar 16 12:45:37 birne kernel: [94075.825665] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Mar 16 12:45:37 birne kernel: [94075.825670] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Mar 16 12:45:37 birne kernel: [94075.825950] ata1.00: configured for UDMA/133
Mar 16 12:45:37 birne kernel: [94075.832341] ata6: SATA link down (SStatus 0 SControl 300)
Mar 16 12:45:37 birne kernel: [94075.840316] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 16 12:45:37 birne kernel: [94075.840504] PM: resume of drv:sd dev:0:0:0:0 complete after 334.734 msecs
Mar 16 12:45:37 birne kernel: [94075.840512] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 334.689 msecs
Mar 16 12:45:37 birne kernel: [94075.849182] PM: resume of drv: dev:ep_00 complete after 342.885 msecs
Mar 16 12:45:37 birne kernel: [94075.849192] PM: resume of drv:usb dev:1-1.3:1.0 complete after 343.019 msecs
Mar 16 12:45:37 birne kernel: [94075.849231] PM: resume of drv: dev:ep_81 complete after 343.040 msecs
Mar 16 12:45:37 birne kernel: [94075.849237] PM: resume of drv: dev:ep_83 complete after 342.995 msecs
Mar 16 12:45:37 birne kernel: [94075.849242] PM: resume of drv: dev:ep_02 complete after 343.034 msecs
Mar 16 12:45:37 birne kernel: [94075.852276] ata5: SATA link down (SStatus 0 SControl 300)
Mar 16 12:45:37 birne kernel: [94075.906855] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Mar 16 12:45:37 birne kernel: [94075.907687] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Mar 16 12:45:37 birne kernel: [94075.920257] usb 1-1.6: reset high speed USB device number 6 using ehci_hcd
Mar 16 12:45:37 birne kernel: [94075.922019] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Mar 16 12:45:37 birne kernel: [94075.922469] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Mar 16 12:45:37 birne kernel: [94075.924070] ata2.00: configured for UDMA/100
Mar 16 12:45:37 birne kernel: [94076.029217] PM: resume of drv: dev:ep_00 complete after 523.281 msecs
Mar 16 12:45:37 birne kernel: [94076.029227] PM: resume of drv:uvcvideo dev:1-1.6:1.1 complete after 523.320 msecs
Mar 16 12:45:37 birne kernel: [94076.029234] PM: resume of drv:uvcvideo dev:1-1.6:1.0 complete after 523.361 msecs
Mar 16 12:45:37 birne kernel: [94076.029256] PM: resume of drv: dev:ep_81 complete after 523.362 msecs
Mar 16 12:45:37 birne kernel: [94076.029267] PM: resume of drv:video4linux dev:video0 complete after 188.081 msecs
Mar 16 12:45:37 birne kernel: [94076.029307] PM: resume of devices complete after 524.580 msecs
Mar 16 12:45:37 birne kernel: [94076.029441] PM: resume devices took 0.524 seconds
Mar 16 12:45:37 birne kernel: [94076.029459] PM: Finishing wakeup.
Mar 16 12:45:37 birne kernel: [94076.029460] Restarting tasks ... done.
Mar 16 12:45:37 birne kernel: [94076.031298] video LNXVIDEO:00: Restoring backlight state
Mar 16 12:45:37 birne kernel: [94076.034800] usb 2-1.2: USB disconnect, device number 10
Mar 16 12:45:37 birne kernel: [94076.081278] usb 1-1.2: USB disconnect, device number 20
Mar 16 12:45:39 birne kernel: [94077.237050] usb 1-1.2: new low speed USB device number 22 using ehci_hcd
Mar 16 12:45:39 birne kernel: [94077.344774] input: 2.4G Wireless Optical Mouse as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0/input/input21
Mar 16 12:45:39 birne kernel: [94077.344916] generic-usb 0003:1D57:0008.000A: input,hidraw0: USB HID v1.10 Mouse [2.4G Wireless Optical Mouse] on usb-0000:00:1a.0-1.2/input0
Mar 16 12:45:40 birne kernel: [94078.524151] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro,commit=0
Mar 16 12:45:40 birne NetworkManager[845]: <info> wake requested (sleeping: yes  enabled: yes)
Mar 16 12:45:40 birne NetworkManager[845]: <info> waking up and re-enabling...
Mar 16 12:45:40 birne NetworkManager[845]: <info> (eth0): now managed
Mar 16 12:45:40 birne NetworkManager[845]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 16 12:45:40 birne NetworkManager[845]: <info> (eth0): bringing up device.
Mar 16 12:45:40 birne kernel: [94078.669232] e1000e 0000:00:19.0: irq 41 for MSI/MSI-X
Mar 16 12:45:40 birne NetworkManager[845]: <info> (eth0): preparing device.
Mar 16 12:45:40 birne NetworkManager[845]: <info> (eth0): deactivating device (reason 'managed') [2]
Mar 16 12:45:40 birne NetworkManager[845]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Mar 16 12:45:40 birne NetworkManager[845]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Mar 16 12:45:40 birne NetworkManager[845]: <info> (wlan0): now managed
Mar 16 12:45:40 birne NetworkManager[845]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 16 12:45:40 birne NetworkManager[845]: <info> (wlan0): bringing up device.
Mar 16 12:45:40 birne NetworkManager[845]: <info> (wlan0): deactivating device (reason 'managed') [2]
Mar 16 12:45:40 birne kernel: [94078.725125] e1000e 0000:00:19.0: irq 41 for MSI/MSI-X
Mar 16 12:45:40 birne kernel: [94078.725554] ADDRCONF(NETDEV_UP): eth0: link is not ready
Mar 16 12:45:43 birne NetworkManager[845]: <info> (eth0): carrier now ON (device state 20)
Mar 16 12:45:43 birne NetworkManager[845]: <info> (eth0): device state change: unavailable -> disconnected (reason 'carrier-changed') [20 30 40]
Mar 16 12:45:43 birne kernel: [94081.739491] e1000e: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: None
Mar 16 12:45:43 birne kernel: [94081.740150] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Mar 16 12:45:43 birne NetworkManager[845]: <info> Auto-activating connection 'Wired connection 1'.
Mar 16 12:45:43 birne NetworkManager[845]: <info> Activation (eth0) starting connection 'Wired connection 1'
Mar 16 12:45:43 birne NetworkManager[845]: <info> (eth0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Mar 16 12:45:43 birne NetworkManager[845]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
Mar 16 12:45:43 birne NetworkManager[845]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) started...
Mar 16 12:45:43 birne NetworkManager[845]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
Mar 16 12:45:43 birne NetworkManager[845]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
Mar 16 12:45:43 birne NetworkManager[845]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) starting...
Mar 16 12:45:43 birne NetworkManager[845]: <info> (eth0): device state change: prepare -> config (reason 'none') [40 50 0]
Mar 16 12:45:43 birne NetworkManager[845]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) successful.
Mar 16 12:45:43 birne NetworkManager[845]: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
Mar 16 12:45:43 birne NetworkManager[845]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) complete.
Mar 16 12:45:43 birne NetworkManager[845]: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
Mar 16 12:45:43 birne NetworkManager[845]: <info> (eth0): device state change: config -> ip-config (reason 'none') [50 70 0]
Mar 16 12:45:43 birne NetworkManager[845]: <info> Activation (eth0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Mar 16 12:45:43 birne NetworkManager[845]: <info> dhclient started with pid 6854
Mar 16 12:45:43 birne NetworkManager[845]: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
Mar 16 12:45:43 birne dhclient: Internet Systems Consortium DHCP Client 4.1.1-P1
Mar 16 12:45:43 birne dhclient: Copyright 2004-2010 Internet Systems Consortium.
Mar 16 12:45:43 birne dhclient: All rights reserved.
Mar 16 12:45:43 birne dhclient: For info, please visit https://www.isc.org/software/dhcp/
Mar 16 12:45:43 birne dhclient: 
Mar 16 12:45:43 birne NetworkManager[845]: <info> (eth0): DHCPv4 state changed nbi -> preinit
Mar 16 12:45:43 birne dhclient: Listening on LPF/eth0/f0:de:f1:25:26:f8
Mar 16 12:45:43 birne dhclient: Sending on   LPF/eth0/f0:de:f1:25:26:f8
Mar 16 12:45:43 birne dhclient: Sending on   Socket/fallback
Mar 16 12:45:43 birne dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 3
Mar 16 12:45:46 birne dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 7
Mar 16 12:45:49 birne dhclient: DHCPOFFER of 78.104.151.116 from 78.104.151.2
Mar 16 12:45:49 birne dhclient: DHCPREQUEST of 78.104.151.116 on eth0 to 255.255.255.255 port 67
Mar 16 12:45:49 birne dhclient: DHCPACK of 78.104.151.116 from 78.104.151.2
Mar 16 12:45:49 birne dhclient: bound to 78.104.151.116 -- renewal in 1533 seconds.
Mar 16 12:45:49 birne NetworkManager[845]: <info> (eth0): DHCPv4 state changed preinit -> bound
Mar 16 12:45:49 birne NetworkManager[845]: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) scheduled...
Mar 16 12:45:49 birne NetworkManager[845]: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) started...
Mar 16 12:45:49 birne NetworkManager[845]: <info>   address 78.104.151.116
Mar 16 12:45:49 birne NetworkManager[845]: <info>   prefix 25 (255.255.255.128)
Mar 16 12:45:49 birne NetworkManager[845]: <info>   gateway 78.104.151.1
Mar 16 12:45:49 birne NetworkManager[845]: <info>   hostname 'birne'
Mar 16 12:45:49 birne NetworkManager[845]: <info>   nameserver '78.104.151.2'
Mar 16 12:45:49 birne NetworkManager[845]: <info>   domain name 'olympdns'
Mar 16 12:45:49 birne NetworkManager[845]: <info>   wins '78.104.151.2'
Mar 16 12:45:49 birne NetworkManager[845]: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) started...
Mar 16 12:45:50 birne NetworkManager[845]: <info> (eth0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Mar 16 12:45:50 birne NetworkManager[845]: <info> Policy set 'Wired connection 1' (eth0) as default for IPv4 routing and DNS.
Mar 16 12:45:50 birne NetworkManager[845]: <info> Activation (eth0) successful, device activated.
Mar 16 12:45:50 birne NetworkManager[845]: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) complete.
Mar 16 12:45:50 birne NetworkManager[845]: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) complete.
Mar 16 12:45:53 birne kernel: [94091.919945] eth0: no IPv6 routers present
Mar 16 13:03:25 birne NetworkManager[845]: <info> sleep requested (sleeping: no  enabled: yes)
Mar 16 13:03:25 birne NetworkManager[845]: <info> sleeping or disabling...
Mar 16 13:03:25 birne NetworkManager[845]: <info> (eth0): now unmanaged
Mar 16 13:03:25 birne NetworkManager[845]: <info> (eth0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
Mar 16 13:03:25 birne NetworkManager[845]: <info> (eth0): deactivating device (reason 'sleeping') [37]
Mar 16 13:03:25 birne NetworkManager[845]: <info> (eth0): canceled DHCP transaction, DHCP client pid 6854
Mar 16 13:03:25 birne NetworkManager[845]: <info> (eth0): cleaning up...
Mar 16 13:03:25 birne NetworkManager[845]: <info> (eth0): taking down device.
Mar 16 13:03:25 birne NetworkManager[845]: <info> (wlan0): now unmanaged
Mar 16 13:03:25 birne NetworkManager[845]: <info> (wlan0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Mar 16 13:03:25 birne NetworkManager[845]: <info> (eth0): carrier now OFF (device state 10)
Mar 16 13:03:26 birne kernel: [95141.769838] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro,commit=0
Mar 16 13:03:26 birne kernel: [95142.448876] PM: Syncing filesystems ... done.
Mar 16 13:03:26 birne kernel: [95142.516717] PM: Preparing system for mem sleep
Mar 16 13:03:42 birne kernel: [95142.836384] Freezing user space processes ... (elapsed 0.01 seconds) done.
Mar 16 13:03:42 birne kernel: [95142.852097] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Mar 16 13:03:42 birne kernel: [95142.868040] PM: Entering mem sleep
Mar 16 13:03:42 birne kernel: [95142.868191] Suspending console(s) (use no_console_suspend to debug)
Mar 16 13:03:42 birne kernel: [95143.029308] PM: suspend of drv:psmouse dev:serio2 complete after 161.254 msecs
Mar 16 13:03:42 birne kernel: [95143.029620] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Mar 16 13:03:42 birne kernel: [95143.029727] sd 0:0:0:0: [sda] Stopping disk
Mar 16 13:03:42 birne kernel: [95143.259040] PM: suspend of drv:tpm_tis dev:00:0b complete after 178.892 msecs
Mar 16 13:03:42 birne kernel: [95143.259208] ehci_hcd 0000:00:1d.0: PCI INT D disabled
Mar 16 13:03:42 birne kernel: [95143.259223] ehci_hcd 0000:00:1a.0: PCI INT D disabled
Mar 16 13:03:42 birne kernel: [95143.259352] ACPI handle has no context!
Mar 16 13:03:42 birne kernel: [95143.290865] i915 0000:00:02.0: power state changed by ACPI to D3
Mar 16 13:03:42 birne kernel: [95143.304726] PM: suspend of drv:sd dev:0:0:0:0 complete after 275.801 msecs
Mar 16 13:03:42 birne kernel: [95143.304740] PM: suspend of drv:scsi dev:target0:0:0 complete after 275.787 msecs
Mar 16 13:03:42 birne kernel: [95143.304748] PM: suspend of drv:scsi dev:host0 complete after 275.737 msecs
Mar 16 13:03:42 birne kernel: [95143.364325] e1000e 0000:00:19.0: PME# enabled
Mar 16 13:03:42 birne kernel: [95143.364332] e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Mar 16 13:03:42 birne kernel: [95143.378729] PM: suspend of drv:e1000e dev:0000:00:19.0 complete after 119.800 msecs
Mar 16 13:03:42 birne kernel: [95143.466617] HDA Intel 0000:00:1b.0: PCI INT B disabled
Mar 16 13:03:42 birne kernel: [95143.482437] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 223.795 msecs
Mar 16 13:03:42 birne kernel: [95143.482490] PM: suspend of drv: dev:pci0000:00 complete after 223.389 msecs
Mar 16 13:03:42 birne kernel: [95143.482591] PM: suspend of devices complete after 615.715 msecs
Mar 16 13:03:42 birne kernel: [95143.482593] PM: suspend devices took 0.616 seconds
Mar 16 13:03:42 birne kernel: [95143.514360] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D3
Mar 16 13:03:42 birne kernel: [95143.550277] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D3
Mar 16 13:03:42 birne kernel: [95143.566310] PM: late suspend of devices complete after 83.922 msecs
Mar 16 13:03:42 birne kernel: [95143.566519] ACPI: Preparing to enter system sleep state S3
Mar 16 13:03:42 birne kernel: [95143.765770] PM: Saving platform NVS memory
Mar 16 13:03:42 birne kernel: [95143.768989] Disabling non-boot CPUs ...
Mar 16 13:03:42 birne kernel: [95143.873489] CPU 1 is now offline
Mar 16 13:03:42 birne kernel: [95143.977235] CPU 2 is now offline
Mar 16 13:03:42 birne kernel: [95143.978374] Broke affinity for irq 12
Mar 16 13:03:42 birne kernel: [95143.978384] Broke affinity for irq 19
Mar 16 13:03:42 birne kernel: [95144.080931] CPU 3 is now offline
Mar 16 13:03:42 birne kernel: [95144.081349] Extended CMOS year: 2000
Mar 16 13:03:42 birne kernel: [95144.081567] ACPI: Low-level resume complete
Mar 16 13:03:42 birne kernel: [95144.081621] PM: Restoring platform NVS memory
Mar 16 13:03:42 birne kernel: [95144.082257] Extended CMOS year: 2000
Mar 16 13:03:42 birne kernel: [95144.082302] Enabling non-boot CPUs ...
Mar 16 13:03:42 birne kernel: [95144.082387] Booting Node 0 Processor 1 APIC 0x1
Mar 16 13:03:42 birne kernel: [95144.082389] smpboot cpu 1: start_ip = 99000
Mar 16 13:03:42 birne kernel: [95144.193542] Switched to NOHz mode on CPU #1
Mar 16 13:03:42 birne kernel: [95144.253511] CPU1 is up
Mar 16 13:03:42 birne kernel: [95144.253624] Booting Node 0 Processor 2 APIC 0x4
Mar 16 13:03:42 birne kernel: [95144.253625] smpboot cpu 2: start_ip = 99000
Mar 16 13:03:42 birne kernel: [95144.365062] Switched to NOHz mode on CPU #2
Mar 16 13:03:42 birne kernel: [95144.425080] CPU2 is up
Mar 16 13:03:42 birne kernel: [95144.425175] Booting Node 0 Processor 3 APIC 0x5
Mar 16 13:03:42 birne kernel: [95144.425176] smpboot cpu 3: start_ip = 99000
Mar 16 13:03:42 birne kernel: [95144.536670] Switched to NOHz mode on CPU #3
Mar 16 13:03:42 birne kernel: [95144.596743] CPU3 is up
Mar 16 13:03:42 birne kernel: [95144.599038] ACPI: Waking up from system sleep state S3
Mar 16 13:03:42 birne kernel: [95144.895967] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407)
Mar 16 13:03:42 birne kernel: [95144.896111] ehci_hcd 0000:00:1a.0: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 13:03:42 birne kernel: [95144.896129] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2728000)
Mar 16 13:03:42 birne kernel: [95144.896136] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102)
Mar 16 13:03:42 birne kernel: [95144.915767] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 13:03:42 birne kernel: [95144.915777] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 13:03:42 birne kernel: [95144.916021] ehci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 13:03:42 birne kernel: [95144.916039] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2728400)
Mar 16 13:03:42 birne kernel: [95144.916046] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102)
Mar 16 13:03:42 birne kernel: [95144.916063] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 13:03:42 birne kernel: [95144.916066] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 13:03:42 birne kernel: [95144.916167] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407)
Mar 16 13:03:42 birne kernel: [95144.916232] intel ips 0000:00:1f.6: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 13:03:42 birne kernel: [95144.916253] intel ips 0000:00:1f.6: restoring config space at offset 0x1 (was 0x100000, writing 0x100002)
Mar 16 13:03:42 birne kernel: [95144.916334] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506)
Mar 16 13:03:42 birne kernel: [95144.916517] PM: early resume of devices complete after 20.737 msecs
Mar 16 13:03:42 birne kernel: [95144.916651] i915 0000:00:02.0: power state changed by ACPI to D0
Mar 16 13:03:42 birne kernel: [95144.916654] i915 0000:00:02.0: power state changed by ACPI to D0
Mar 16 13:03:42 birne kernel: [95144.916658] i915 0000:00:02.0: setting latency timer to 64
Mar 16 13:03:42 birne kernel: [95144.916697] e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Mar 16 13:03:42 birne kernel: [95144.916700] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 13:03:42 birne kernel: [95144.916704] e1000e 0000:00:19.0: PME# disabled
Mar 16 13:03:42 birne kernel: [95144.916706] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 13:03:42 birne kernel: [95144.916717] ehci_hcd 0000:00:1a.0: PCI INT D -> GSI 23 (level, low) -> IRQ 23
Mar 16 13:03:42 birne kernel: [95144.916728] ehci_hcd 0000:00:1a.0: setting latency timer to 64
Mar 16 13:03:42 birne kernel: [95144.916790] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Mar 16 13:03:42 birne kernel: [95144.916807] HDA Intel 0000:00:1b.0: setting latency timer to 64
Mar 16 13:03:42 birne kernel: [95144.916849] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 13:03:42 birne kernel: [95144.916852] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 13:03:42 birne kernel: [95144.916860] ehci_hcd 0000:00:1d.0: PCI INT D -> GSI 19 (level, low) -> IRQ 19
Mar 16 13:03:42 birne kernel: [95144.916875] ehci_hcd 0000:00:1d.0: setting latency timer to 64
Mar 16 13:03:42 birne kernel: [95144.916879] e1000e 0000:00:19.0: irq 41 for MSI/MSI-X
Mar 16 13:03:42 birne kernel: [95144.916900] HDA Intel 0000:00:1b.0: irq 43 for MSI/MSI-X
Mar 16 13:03:42 birne kernel: [95144.916923] pci 0000:00:1e.0: setting latency timer to 64
Mar 16 13:03:42 birne kernel: [95144.916942] ahci 0000:00:1f.2: setting latency timer to 64
Mar 16 13:03:42 birne kernel: [95144.917006] iwlagn 0000:03:00.0: RF_KILL bit toggled to disable radio.
Mar 16 13:03:42 birne kernel: [95144.917108] sd 0:0:0:0: [sda] Starting disk
Mar 16 13:03:42 birne kernel: [95144.961009] Extended CMOS year: 2000
Mar 16 13:03:42 birne kernel: [95145.095396] PM: resume of drv:hub dev:1-1:1.0 complete after 178.666 msecs
Mar 16 13:03:42 birne kernel: [95145.095405] PM: resume of drv: dev:ep_00 complete after 178.621 msecs
Mar 16 13:03:42 birne kernel: [95145.095414] PM: resume of drv: dev:ep_81 complete after 178.663 msecs
Mar 16 13:03:42 birne kernel: [95145.096269] PM: resume of drv: dev:ep_00 complete after 179.098 msecs
Mar 16 13:03:42 birne kernel: [95145.096288] PM: resume of drv:usbhid dev:1-1.2:1.0 complete after 179.156 msecs
Mar 16 13:03:42 birne kernel: [95145.096314] PM: resume of drv: dev:ep_81 complete after 179.164 msecs
Mar 16 13:03:42 birne kernel: [95145.167332] usb 1-1.6: reset high speed USB device number 6 using ehci_hcd
Mar 16 13:03:42 birne kernel: [95145.230671] PM: resume of drv:i915 dev:0000:00:02.0 complete after 314.819 msecs
Mar 16 13:03:42 birne kernel: [95145.234944] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 16 13:03:42 birne kernel: [95145.242918] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Mar 16 13:03:42 birne kernel: [95145.243330] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Mar 16 13:03:42 birne kernel: [95145.243336] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Mar 16 13:03:42 birne kernel: [95145.243341] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Mar 16 13:03:42 birne kernel: [95145.244317] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Mar 16 13:03:42 birne kernel: [95145.244323] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Mar 16 13:03:42 birne kernel: [95145.244328] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Mar 16 13:03:42 birne kernel: [95145.244679] ata1.00: configured for UDMA/133
Mar 16 13:03:42 birne kernel: [95145.250927] ata5: SATA link down (SStatus 0 SControl 300)
Mar 16 13:03:42 birne kernel: [95145.259088] PM: resume of drv:sd dev:0:0:0:0 complete after 342.835 msecs
Mar 16 13:03:42 birne kernel: [95145.259117] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 342.838 msecs
Mar 16 13:03:42 birne kernel: [95145.262872] ata6: SATA link down (SStatus 0 SControl 300)
Mar 16 13:03:42 birne kernel: [95145.276376] PM: resume of drv: dev:ep_00 complete after 359.731 msecs
Mar 16 13:03:42 birne kernel: [95145.276384] PM: resume of drv:uvcvideo dev:1-1.6:1.1 complete after 359.758 msecs
Mar 16 13:03:42 birne kernel: [95145.276392] PM: resume of drv:uvcvideo dev:1-1.6:1.0 complete after 359.805 msecs
Mar 16 13:03:42 birne kernel: [95145.276414] PM: resume of drv: dev:ep_81 complete after 359.805 msecs
Mar 16 13:03:42 birne kernel: [95145.314319] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Mar 16 13:03:42 birne kernel: [95145.315097] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Mar 16 13:03:42 birne kernel: [95145.329507] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Mar 16 13:03:42 birne kernel: [95145.329940] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Mar 16 13:03:42 birne kernel: [95145.331580] ata2.00: configured for UDMA/100
Mar 16 13:03:42 birne kernel: [95145.342887] usb 1-1.3: reset full speed USB device number 4 using ehci_hcd
Mar 16 13:03:42 birne kernel: [95145.435314] PM: resume of drv: dev:ep_00 complete after 519.158 msecs
Mar 16 13:03:42 birne kernel: [95145.435332] PM: resume of drv:usb dev:1-1.3:1.0 complete after 519.260 msecs
Mar 16 13:03:42 birne kernel: [95145.435355] PM: resume of drv: dev:ep_81 complete after 519.251 msecs
Mar 16 13:03:42 birne kernel: [95145.435363] PM: resume of drv: dev:ep_83 complete after 519.217 msecs
Mar 16 13:03:42 birne kernel: [95145.435371] PM: resume of drv: dev:ep_02 complete after 519.237 msecs
Mar 16 13:03:42 birne kernel: [95145.435388] PM: resume of devices complete after 520.110 msecs
Mar 16 13:03:42 birne kernel: [95145.435507] PM: resume devices took 0.520 seconds
Mar 16 13:03:42 birne kernel: [95145.435528] PM: Finishing wakeup.
Mar 16 13:03:42 birne kernel: [95145.435529] Restarting tasks ... done.
Mar 16 13:03:42 birne kernel: [95145.437799] video LNXVIDEO:00: Restoring backlight state
Mar 16 13:03:42 birne kernel: [95145.441120] usb 1-1.2: USB disconnect, device number 22
Mar 16 13:03:43 birne kernel: [95146.109619] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro,commit=0
Mar 16 13:03:43 birne NetworkManager[845]: <info> wake requested (sleeping: yes  enabled: yes)
Mar 16 13:03:43 birne NetworkManager[845]: <info> waking up and re-enabling...
Mar 16 13:03:43 birne NetworkManager[845]: <info> (eth0): now managed
Mar 16 13:03:43 birne NetworkManager[845]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 16 13:03:43 birne NetworkManager[845]: <info> (eth0): bringing up device.
Mar 16 13:03:43 birne kernel: [95146.241662] e1000e 0000:00:19.0: irq 41 for MSI/MSI-X
Mar 16 13:03:43 birne NetworkManager[845]: <info> (eth0): preparing device.
Mar 16 13:03:43 birne NetworkManager[845]: <info> (eth0): deactivating device (reason 'managed') [2]
Mar 16 13:03:43 birne NetworkManager[845]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Mar 16 13:03:43 birne NetworkManager[845]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Mar 16 13:03:43 birne NetworkManager[845]: <info> (wlan0): now managed
Mar 16 13:03:43 birne NetworkManager[845]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 16 13:03:43 birne NetworkManager[845]: <info> (wlan0): bringing up device.
Mar 16 13:03:43 birne NetworkManager[845]: <info> (wlan0): deactivating device (reason 'managed') [2]
Mar 16 13:03:43 birne kernel: [95146.296340] e1000e 0000:00:19.0: irq 41 for MSI/MSI-X
Mar 16 13:03:43 birne kernel: [95146.297287] ADDRCONF(NETDEV_UP): eth0: link is not ready
Mar 16 13:03:44 birne kernel: [95146.647565] usb 1-1.2: new low speed USB device number 23 using ehci_hcd
Mar 16 13:03:44 birne kernel: [95146.746210] input: 2.4G Wireless Optical Mouse as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0/input/input22
Mar 16 13:03:44 birne kernel: [95146.746348] generic-usb 0003:1D57:0008.000B: input,hidraw0: USB HID v1.10 Mouse [2.4G Wireless Optical Mouse] on usb-0000:00:1a.0-1.2/input0
Mar 16 13:03:46 birne NetworkManager[845]: <info> (eth0): carrier now ON (device state 20)
Mar 16 13:03:46 birne NetworkManager[845]: <info> (eth0): device state change: unavailable -> disconnected (reason 'carrier-changed') [20 30 40]
Mar 16 13:03:46 birne NetworkManager[845]: <info> Auto-activating connection 'Wired connection 1'.
Mar 16 13:03:46 birne NetworkManager[845]: <info> Activation (eth0) starting connection 'Wired connection 1'
Mar 16 13:03:46 birne NetworkManager[845]: <info> (eth0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Mar 16 13:03:46 birne kernel: [95148.640419] e1000e: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: None
Mar 16 13:03:46 birne kernel: [95148.641073] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Mar 16 13:03:46 birne NetworkManager[845]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
Mar 16 13:03:46 birne NetworkManager[845]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) started...
Mar 16 13:03:46 birne NetworkManager[845]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
Mar 16 13:03:46 birne NetworkManager[845]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
Mar 16 13:03:46 birne NetworkManager[845]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) starting...
Mar 16 13:03:46 birne NetworkManager[845]: <info> (eth0): device state change: prepare -> config (reason 'none') [40 50 0]
Mar 16 13:03:46 birne NetworkManager[845]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) successful.
Mar 16 13:03:46 birne NetworkManager[845]: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
Mar 16 13:03:46 birne NetworkManager[845]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) complete.
Mar 16 13:03:46 birne NetworkManager[845]: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
Mar 16 13:03:46 birne NetworkManager[845]: <info> (eth0): device state change: config -> ip-config (reason 'none') [50 70 0]
Mar 16 13:03:46 birne NetworkManager[845]: <info> Activation (eth0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Mar 16 13:03:46 birne NetworkManager[845]: <info> dhclient started with pid 7813
Mar 16 13:03:46 birne NetworkManager[845]: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
Mar 16 13:03:46 birne dhclient: Internet Systems Consortium DHCP Client 4.1.1-P1
Mar 16 13:03:46 birne dhclient: Copyright 2004-2010 Internet Systems Consortium.
Mar 16 13:03:46 birne dhclient: All rights reserved.
Mar 16 13:03:46 birne dhclient: For info, please visit https://www.isc.org/software/dhcp/
Mar 16 13:03:46 birne dhclient: 
Mar 16 13:03:46 birne NetworkManager[845]: <info> (eth0): DHCPv4 state changed nbi -> preinit
Mar 16 13:03:46 birne dhclient: Listening on LPF/eth0/f0:de:f1:25:26:f8
Mar 16 13:03:46 birne dhclient: Sending on   LPF/eth0/f0:de:f1:25:26:f8
Mar 16 13:03:46 birne dhclient: Sending on   Socket/fallback
Mar 16 13:03:46 birne dhclient: DHCPREQUEST of 78.104.151.116 on eth0 to 255.255.255.255 port 67
Mar 16 13:03:49 birne dhclient: DHCPREQUEST of 78.104.151.116 on eth0 to 255.255.255.255 port 67
Mar 16 13:03:49 birne dhclient: DHCPACK of 78.104.151.116 from 78.104.151.2
Mar 16 13:03:49 birne dhclient: bound to 78.104.151.116 -- renewal in 1765 seconds.
Mar 16 13:03:49 birne NetworkManager[845]: <info> (eth0): DHCPv4 state changed preinit -> reboot
Mar 16 13:03:49 birne NetworkManager[845]: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) scheduled...
Mar 16 13:03:49 birne NetworkManager[845]: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) started...
Mar 16 13:03:49 birne NetworkManager[845]: <info>   address 78.104.151.116
Mar 16 13:03:49 birne NetworkManager[845]: <info>   prefix 25 (255.255.255.128)
Mar 16 13:03:49 birne NetworkManager[845]: <info>   gateway 78.104.151.1
Mar 16 13:03:49 birne NetworkManager[845]: <info>   hostname 'birne'
Mar 16 13:03:49 birne NetworkManager[845]: <info>   nameserver '78.104.151.2'
Mar 16 13:03:49 birne NetworkManager[845]: <info>   domain name 'olympdns'
Mar 16 13:03:49 birne NetworkManager[845]: <info>   wins '78.104.151.2'
Mar 16 13:03:49 birne NetworkManager[845]: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) started...
Mar 16 13:03:50 birne NetworkManager[845]: <info> (eth0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Mar 16 13:03:50 birne NetworkManager[845]: <info> Policy set 'Wired connection 1' (eth0) as default for IPv4 routing and DNS.
Mar 16 13:03:50 birne NetworkManager[845]: <info> Activation (eth0) successful, device activated.
Mar 16 13:03:50 birne NetworkManager[845]: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) complete.
Mar 16 13:03:50 birne NetworkManager[845]: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) complete.
Mar 16 13:03:56 birne kernel: [95159.247780] eth0: no IPv6 routers present
Mar 16 13:33:14 birne dhclient: DHCPREQUEST of 78.104.151.116 on eth0 to 78.104.151.2 port 67
Mar 16 13:33:14 birne dhclient: DHCPACK of 78.104.151.116 from 78.104.151.2
Mar 16 13:33:14 birne dhclient: bound to 78.104.151.116 -- renewal in 1478 seconds.
Mar 16 13:33:14 birne NetworkManager[845]: <info> (eth0): DHCPv4 state changed reboot -> renew
Mar 16 13:33:14 birne NetworkManager[845]: <info>   address 78.104.151.116
Mar 16 13:33:14 birne NetworkManager[845]: <info>   prefix 25 (255.255.255.128)
Mar 16 13:33:14 birne NetworkManager[845]: <info>   gateway 78.104.151.1
Mar 16 13:33:14 birne NetworkManager[845]: <info>   hostname 'birne'
Mar 16 13:33:14 birne NetworkManager[845]: <info>   nameserver '78.104.151.2'
Mar 16 13:33:14 birne NetworkManager[845]: <info>   domain name 'olympdns'
Mar 16 13:33:14 birne NetworkManager[845]: <info>   wins '78.104.151.2'
Mar 16 13:57:52 birne dhclient: DHCPREQUEST of 78.104.151.116 on eth0 to 78.104.151.2 port 67
Mar 16 13:57:52 birne dhclient: DHCPACK of 78.104.151.116 from 78.104.151.2
Mar 16 13:57:52 birne dhclient: bound to 78.104.151.116 -- renewal in 1469 seconds.
Mar 16 14:17:37 birne NetworkManager[845]: <info> sleep requested (sleeping: no  enabled: yes)
Mar 16 14:17:37 birne NetworkManager[845]: <info> sleeping or disabling...
Mar 16 14:17:37 birne NetworkManager[845]: <info> (eth0): now unmanaged
Mar 16 14:17:37 birne NetworkManager[845]: <info> (eth0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
Mar 16 14:17:37 birne NetworkManager[845]: <info> (eth0): deactivating device (reason 'sleeping') [37]
Mar 16 14:17:37 birne NetworkManager[845]: <info> (eth0): canceled DHCP transaction, DHCP client pid 7813
Mar 16 14:17:37 birne NetworkManager[845]: <info> (eth0): cleaning up...
Mar 16 14:17:37 birne NetworkManager[845]: <info> (eth0): taking down device.
Mar 16 14:17:37 birne NetworkManager[845]: <info> (wlan0): now unmanaged
Mar 16 14:17:37 birne NetworkManager[845]: <info> (wlan0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Mar 16 14:17:37 birne NetworkManager[845]: <info> (eth0): carrier now OFF (device state 10)
Mar 16 14:17:38 birne kernel: [99569.497122] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro,commit=0
Mar 16 14:17:39 birne kernel: [99570.188984] PM: Syncing filesystems ... done.
Mar 16 14:17:39 birne kernel: [99570.257794] PM: Preparing system for mem sleep
Mar 16 14:24:42 birne kernel: [99570.440120] Freezing user space processes ... (elapsed 0.01 seconds) done.
Mar 16 14:24:42 birne kernel: [99570.455852] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Mar 16 14:24:42 birne kernel: [99570.471788] PM: Entering mem sleep
Mar 16 14:24:42 birne kernel: [99570.471917] Suspending console(s) (use no_console_suspend to debug)
Mar 16 14:24:42 birne kernel: [99570.628972] PM: suspend of drv:psmouse dev:serio2 complete after 157.185 msecs
Mar 16 14:24:42 birne kernel: [99570.629224] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Mar 16 14:24:42 birne kernel: [99570.629327] sd 0:0:0:0: [sda] Stopping disk
Mar 16 14:24:42 birne kernel: [99570.858807] PM: suspend of drv:tpm_tis dev:00:0b complete after 179.435 msecs
Mar 16 14:24:42 birne kernel: [99570.858895] ehci_hcd 0000:00:1d.0: PCI INT D disabled
Mar 16 14:24:42 birne kernel: [99570.858964] ehci_hcd 0000:00:1a.0: PCI INT D disabled
Mar 16 14:24:42 birne kernel: [99570.859036] ACPI handle has no context!
Mar 16 14:24:42 birne kernel: [99570.890645] i915 0000:00:02.0: power state changed by ACPI to D3
Mar 16 14:24:42 birne kernel: [99570.905592] PM: suspend of drv:sd dev:0:0:0:0 complete after 277.066 msecs
Mar 16 14:24:42 birne kernel: [99570.905603] PM: suspend of drv:scsi dev:target0:0:0 complete after 277.044 msecs
Mar 16 14:24:42 birne kernel: [99570.905611] PM: suspend of drv:scsi dev:host0 complete after 277.016 msecs
Mar 16 14:24:42 birne kernel: [99570.962887] HDA Intel 0000:00:1b.0: PCI INT B disabled
Mar 16 14:24:42 birne kernel: [99570.964081] e1000e 0000:00:19.0: PME# enabled
Mar 16 14:24:42 birne kernel: [99570.964088] e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Mar 16 14:24:42 birne kernel: [99570.978466] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.859 msecs
Mar 16 14:24:42 birne kernel: [99570.978474] PM: suspend of drv:e1000e dev:0000:00:19.0 complete after 119.810 msecs
Mar 16 14:24:42 birne kernel: [99570.978493] PM: suspend of drv: dev:pci0000:00 complete after 119.360 msecs
Mar 16 14:24:42 birne kernel: [99570.978514] PM: suspend of devices complete after 507.640 msecs
Mar 16 14:24:42 birne kernel: [99570.978518] PM: suspend devices took 0.508 seconds
Mar 16 14:24:42 birne kernel: [99571.010395] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D3
Mar 16 14:24:42 birne kernel: [99571.046309] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D3
Mar 16 14:24:42 birne kernel: [99571.062342] PM: late suspend of devices complete after 84.028 msecs
Mar 16 14:24:42 birne kernel: [99571.062541] ACPI: Preparing to enter system sleep state S3
Mar 16 14:24:42 birne kernel: [99571.261778] PM: Saving platform NVS memory
Mar 16 14:24:42 birne kernel: [99571.265061] Disabling non-boot CPUs ...
Mar 16 14:24:42 birne kernel: [99571.369498] CPU 1 is now offline
Mar 16 14:24:42 birne kernel: [99571.473224] CPU 2 is now offline
Mar 16 14:24:42 birne kernel: [99571.576958] CPU 3 is now offline
Mar 16 14:24:42 birne kernel: [99571.577371] Extended CMOS year: 2000
Mar 16 14:24:42 birne kernel: [99571.577589] ACPI: Low-level resume complete
Mar 16 14:24:42 birne kernel: [99571.577643] PM: Restoring platform NVS memory
Mar 16 14:24:42 birne kernel: [99571.578271] Extended CMOS year: 2000
Mar 16 14:24:42 birne kernel: [99571.578316] Enabling non-boot CPUs ...
Mar 16 14:24:42 birne kernel: [99571.578405] Booting Node 0 Processor 1 APIC 0x1
Mar 16 14:24:42 birne kernel: [99571.578406] smpboot cpu 1: start_ip = 99000
Mar 16 14:24:42 birne kernel: [99571.689576] Switched to NOHz mode on CPU #1
Mar 16 14:24:42 birne kernel: [99571.749537] CPU1 is up
Mar 16 14:24:42 birne kernel: [99571.749648] Booting Node 0 Processor 2 APIC 0x4
Mar 16 14:24:42 birne kernel: [99571.749649] smpboot cpu 2: start_ip = 99000
Mar 16 14:24:42 birne kernel: [99571.861086] Switched to NOHz mode on CPU #2
Mar 16 14:24:42 birne kernel: [99571.921114] CPU2 is up
Mar 16 14:24:42 birne kernel: [99571.921210] Booting Node 0 Processor 3 APIC 0x5
Mar 16 14:24:42 birne kernel: [99571.921211] smpboot cpu 3: start_ip = 99000
Mar 16 14:24:42 birne kernel: [99572.032704] Switched to NOHz mode on CPU #3
Mar 16 14:24:42 birne kernel: [99572.092863] CPU3 is up
Mar 16 14:24:42 birne kernel: [99572.095161] ACPI: Waking up from system sleep state S3
Mar 16 14:24:42 birne kernel: [99572.391987] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407)
Mar 16 14:24:42 birne kernel: [99572.392132] ehci_hcd 0000:00:1a.0: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 14:24:42 birne kernel: [99572.392149] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2728000)
Mar 16 14:24:42 birne kernel: [99572.392156] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102)
Mar 16 14:24:42 birne kernel: [99572.411792] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 14:24:42 birne kernel: [99572.411801] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 14:24:42 birne kernel: [99572.412042] ehci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 14:24:42 birne kernel: [99572.412059] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2728400)
Mar 16 14:24:42 birne kernel: [99572.412066] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102)
Mar 16 14:24:42 birne kernel: [99572.412083] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 14:24:42 birne kernel: [99572.412086] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 14:24:42 birne kernel: [99572.412186] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407)
Mar 16 14:24:42 birne kernel: [99572.412251] intel ips 0000:00:1f.6: restoring config space at offset 0xf (was 0x400, writing 0x40b)
Mar 16 14:24:42 birne kernel: [99572.412271] intel ips 0000:00:1f.6: restoring config space at offset 0x1 (was 0x100000, writing 0x100002)
Mar 16 14:24:42 birne kernel: [99572.412352] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506)
Mar 16 14:24:42 birne kernel: [99572.412536] PM: early resume of devices complete after 20.732 msecs
Mar 16 14:24:42 birne kernel: [99572.412735] e1000e 0000:00:19.0: wake-up capability disabled by ACPI
Mar 16 14:24:42 birne kernel: [99572.412739] i915 0000:00:02.0: power state changed by ACPI to D0
Mar 16 14:24:42 birne kernel: [99572.412742] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 14:24:42 birne kernel: [99572.412745] e1000e 0000:00:19.0: PME# disabled
Mar 16 14:24:42 birne kernel: [99572.412747] i915 0000:00:02.0: power state changed by ACPI to D0
Mar 16 14:24:42 birne kernel: [99572.412751] i915 0000:00:02.0: setting latency timer to 64
Mar 16 14:24:42 birne kernel: [99572.412759] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mar 16 14:24:42 birne kernel: [99572.412768] ehci_hcd 0000:00:1a.0: PCI INT D -> GSI 23 (level, low) -> IRQ 23
Mar 16 14:24:42 birne kernel: [99572.412776] ehci_hcd 0000:00:1a.0: setting latency timer to 64
Mar 16 14:24:42 birne kernel: [99572.412849] iwlagn 0000:03:00.0: RF_KILL bit toggled to disable radio.
Mar 16 14:24:42 birne kernel: [99572.412881] pci 0000:00:1e.0: setting latency timer to 64
Mar 16 14:24:42 birne kernel: [99572.412888] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 14:24:42 birne kernel: [99572.412899] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Mar 16 14:24:42 birne kernel: [99572.412909] HDA Intel 0000:00:1b.0: setting latency timer to 64
Mar 16 14:24:42 birne kernel: [99572.412924] e1000e 0000:00:19.0: irq 41 for MSI/MSI-X
Mar 16 14:24:42 birne kernel: [99572.412980] HDA Intel 0000:00:1b.0: irq 43 for MSI/MSI-X
Mar 16 14:24:42 birne kernel: [99572.413033] ahci 0000:00:1f.2: setting latency timer to 64
Mar 16 14:24:42 birne kernel: [99572.413154] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mar 16 14:24:42 birne kernel: [99572.413160] ehci_hcd 0000:00:1d.0: PCI INT D -> GSI 19 (level, low) -> IRQ 19
Mar 16 14:24:42 birne kernel: [99572.413166] ehci_hcd 0000:00:1d.0: setting latency timer to 64
Mar 16 14:24:42 birne kernel: [99572.413205] sd 0:0:0:0: [sda] Starting disk
Mar 16 14:24:42 birne kernel: [99572.448648] Extended CMOS year: 2000
Mar 16 14:24:42 birne kernel: [99572.591395] PM: resume of drv: dev:ep_00 complete after 175.942 msecs
Mar 16 14:24:42 birne kernel: [99572.591403] PM: resume of drv:hub dev:1-1:1.0 complete after 176.025 msecs
Mar 16 14:24:42 birne kernel: [99572.591464] PM: resume of drv: dev:ep_81 complete after 176.064 msecs
Mar 16 14:24:42 birne kernel: [99572.591691] PM: resume of drv:usbhid dev:1-1.2:1.0 complete after 175.906 msecs
Mar 16 14:24:42 birne kernel: [99572.591697] PM: resume of drv: dev:ep_00 complete after 175.816 msecs
Mar 16 14:24:42 birne kernel: [99572.591703] PM: resume of drv: dev:ep_81 complete after 175.848 msecs
Mar 16 14:24:42 birne kernel: [99572.663330] usb 1-1.6: reset high speed USB device number 6 using ehci_hcd
Mar 16 14:24:42 birne kernel: [99572.718431] PM: resume of drv:i915 dev:0000:00:02.0 complete after 306.481 msecs
Mar 16 14:24:42 birne kernel: [99572.730976] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Mar 16 14:24:42 birne kernel: [99572.731529] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Mar 16 14:24:42 birne kernel: [99572.731537] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Mar 16 14:24:42 birne kernel: [99572.731539] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Mar 16 14:24:42 birne kernel: [99572.732405] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Mar 16 14:24:42 birne kernel: [99572.732411] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Mar 16 14:24:42 birne kernel: [99572.732416] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Mar 16 14:24:42 birne kernel: [99572.732694] ata1.00: configured for UDMA/133
Mar 16 14:24:42 birne kernel: [99572.738981] ata6: SATA link down (SStatus 0 SControl 300)
Mar 16 14:24:42 birne kernel: [99572.746984] ata5: SATA link down (SStatus 0 SControl 300)
Mar 16 14:24:42 birne kernel: [99572.747046] PM: resume of drv:sd dev:0:0:0:0 complete after 334.677 msecs
Mar 16 14:24:42 birne kernel: [99572.747074] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 332.150 msecs
Mar 16 14:24:42 birne kernel: [99572.754961] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 16 14:24:42 birne kernel: [99572.776465] PM: resume of drv: dev:ep_00 complete after 361.189 msecs
Mar 16 14:24:42 birne kernel: [99572.776475] PM: resume of drv:uvcvideo dev:1-1.6:1.1 complete after 361.215 msecs
Mar 16 14:24:42 birne kernel: [99572.776483] PM: resume of drv:uvcvideo dev:1-1.6:1.0 complete after 361.271 msecs
Mar 16 14:24:42 birne kernel: [99572.776533] PM: resume of drv: dev:ep_81 complete after 361.301 msecs
Mar 16 14:24:42 birne kernel: [99572.809651] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Mar 16 14:24:42 birne kernel: [99572.810476] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Mar 16 14:24:42 birne kernel: [99572.824820] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Mar 16 14:24:42 birne kernel: [99572.825280] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Mar 16 14:24:42 birne kernel: [99572.826925] ata2.00: configured for UDMA/100
Mar 16 14:24:42 birne kernel: [99572.842900] usb 1-1.3: reset full speed USB device number 4 using ehci_hcd
Mar 16 14:24:42 birne kernel: [99572.935273] PM: resume of drv: dev:ep_00 complete after 520.487 msecs
Mar 16 14:24:42 birne kernel: [99572.935291] PM: resume of drv:usb dev:1-1.3:1.0 complete after 520.567 msecs
Mar 16 14:24:42 birne kernel: [99572.935308] PM: resume of drv: dev:ep_83 complete after 520.538 msecs
Mar 16 14:24:42 birne kernel: [99572.935334] PM: resume of drv: dev:ep_81 complete after 520.593 msecs
Mar 16 14:24:42 birne kernel: [99572.935341] PM: resume of drv: dev:ep_02 complete after 520.580 msecs
Mar 16 14:24:42 birne kernel: [99572.935348] PM: resume of devices complete after 524.013 msecs
Mar 16 14:24:42 birne kernel: [99572.935467] PM: resume devices took 0.524 seconds
Mar 16 14:24:42 birne kernel: [99572.935487] PM: Finishing wakeup.
Mar 16 14:24:42 birne kernel: [99572.935488] Restarting tasks ... 
Mar 16 14:24:42 birne kernel: [99572.945156] usb 1-1.2: USB disconnect, device number 23
Mar 16 14:24:42 birne kernel: [99572.947735] done.
Mar 16 14:24:42 birne kernel: [99572.950464] video LNXVIDEO:00: Restoring backlight state
Mar 16 14:24:42 birne kernel: [99573.673303] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro,commit=0
Mar 16 14:24:42 birne NetworkManager[845]: <info> wake requested (sleeping: yes  enabled: yes)
Mar 16 14:24:42 birne NetworkManager[845]: <info> waking up and re-enabling...
Mar 16 14:24:42 birne NetworkManager[845]: <info> (eth0): now managed
Mar 16 14:24:42 birne NetworkManager[845]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 16 14:24:42 birne NetworkManager[845]: <info> (eth0): bringing up device.
Mar 16 14:24:42 birne kernel: [99573.811045] e1000e 0000:00:19.0: irq 41 for MSI/MSI-X
Mar 16 14:24:43 birne NetworkManager[845]: <info> (eth0): preparing device.
Mar 16 14:24:43 birne NetworkManager[845]: <info> (eth0): deactivating device (reason 'managed') [2]
Mar 16 14:24:43 birne NetworkManager[845]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Mar 16 14:24:43 birne kernel: [99573.864289] e1000e 0000:00:19.0: irq 41 for MSI/MSI-X
Mar 16 14:24:43 birne kernel: [99573.865957] ADDRCONF(NETDEV_UP): eth0: link is not ready
Mar 16 14:24:43 birne NetworkManager[845]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Mar 16 14:24:43 birne NetworkManager[845]: <info> (wlan0): now managed
Mar 16 14:24:43 birne NetworkManager[845]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 16 14:24:43 birne NetworkManager[845]: <info> (wlan0): bringing up device.
Mar 16 14:24:43 birne NetworkManager[845]: <info> (wlan0): deactivating device (reason 'managed') [2]
Mar 16 14:24:43 birne kernel: [99574.147558] usb 1-1.2: new low speed USB device number 24 using ehci_hcd
Mar 16 14:24:43 birne kernel: [99574.246811] input: 2.4G Wireless Optical Mouse as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0/input/input23
Mar 16 14:24:43 birne kernel: [99574.246934] generic-usb 0003:1D57:0008.000C: input,hidraw0: USB HID v1.10 Mouse [2.4G Wireless Optical Mouse] on usb-0000:00:1a.0-1.2/input0
Mar 16 14:25:55 birne NetworkManager[845]: <info> WiFi now enabled by radio killswitch
Mar 16 14:25:55 birne kernel: [99645.660154] iwlagn 0000:03:00.0: RF_KILL bit toggled to enable radio.
Mar 16 14:25:55 birne NetworkManager[845]: <info> (wlan0): bringing up device.
Mar 16 14:25:55 birne kernel: [99645.802135] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Mar 16 14:25:55 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: starting -> ready
Mar 16 14:25:55 birne NetworkManager[845]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Mar 16 14:25:55 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: ready -> inactive
Mar 16 14:25:55 birne kernel: [99646.154979] usb 1-1.4: new full speed USB device number 25 using ehci_hcd
Mar 16 14:25:56 birne kernel: [99647.173110] cfg80211: Found new beacon on frequency: 2467 MHz (Ch 12) on phy0
Mar 16 14:26:14 birne NetworkManager[845]: <info> Activation (wlan0) starting connection 'rudolfinum'
Mar 16 14:26:14 birne NetworkManager[845]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Mar 16 14:26:14 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Mar 16 14:26:14 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Mar 16 14:26:14 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Mar 16 14:26:14 birne NetworkManager[845]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Mar 16 14:26:14 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Mar 16 14:26:14 birne NetworkManager[845]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Mar 16 14:26:14 birne NetworkManager[845]: <info> (wlan0): preparing device.
Mar 16 14:26:14 birne NetworkManager[845]: <info> Activation (wlan0/wireless): connection 'rudolfinum' has security, and secrets exist.  No new secrets needed.
Mar 16 14:26:14 birne NetworkManager[845]: <info> Config: added 'ssid' value 'rudolfinum'
Mar 16 14:26:14 birne NetworkManager[845]: <info> Config: added 'scan_ssid' value '1'
Mar 16 14:26:14 birne NetworkManager[845]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Mar 16 14:26:14 birne NetworkManager[845]: <info> Config: added 'psk' value '<omitted>'
Mar 16 14:26:14 birne NetworkManager[845]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Mar 16 14:26:14 birne NetworkManager[845]: <info> Config: set interface ap_scan to 1
Mar 16 14:26:14 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: inactive -> scanning
Mar 16 14:26:16 birne wpa_supplicant[1462]: Trying to authenticate with e0:91:f5:c9:f2:d0 (SSID='rudolfinum' freq=2422 MHz)
Mar 16 14:26:16 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Mar 16 14:26:16 birne wpa_supplicant[1462]: Trying to associate with e0:91:f5:c9:f2:d0 (SSID='rudolfinum' freq=2422 MHz)
Mar 16 14:26:16 birne kernel: [99667.297963] wlan0: authenticate with e0:91:f5:c9:f2:d0 (try 1)
Mar 16 14:26:16 birne kernel: [99667.300662] wlan0: authenticated
Mar 16 14:26:16 birne kernel: [99667.301298] wlan0: associate with e0:91:f5:c9:f2:d0 (try 1)
Mar 16 14:26:16 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: authenticating -> associating
Mar 16 14:26:16 birne kernel: [99667.309323] wlan0: RX AssocResp from e0:91:f5:c9:f2:d0 (capab=0x31 status=0 aid=5)
Mar 16 14:26:16 birne kernel: [99667.309329] wlan0: associated
Mar 16 14:26:16 birne kernel: [99667.317181] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Mar 16 14:26:16 birne wpa_supplicant[1462]: Associated with e0:91:f5:c9:f2:d0
Mar 16 14:26:16 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: associating -> associated
Mar 16 14:26:16 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: associated -> 4-way handshake
Mar 16 14:26:16 birne wpa_supplicant[1462]: WPA: Key negotiation completed with e0:91:f5:c9:f2:d0 [PTK=CCMP GTK=CCMP]
Mar 16 14:26:16 birne wpa_supplicant[1462]: CTRL-EVENT-CONNECTED - Connection to e0:91:f5:c9:f2:d0 completed (auth) [id=0 id_str=]
Mar 16 14:26:16 birne NetworkManager[845]: <info> (wlan0): supplicant interface state: 4-way handshake -> completed
Mar 16 14:26:16 birne NetworkManager[845]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'rudolfinum'.
Mar 16 14:26:16 birne NetworkManager[845]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
Mar 16 14:26:16 birne NetworkManager[845]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
Mar 16 14:26:16 birne NetworkManager[845]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
Mar 16 14:26:16 birne NetworkManager[845]: <info> Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Mar 16 14:26:16 birne NetworkManager[845]: <info> dhclient started with pid 9346
Mar 16 14:26:16 birne NetworkManager[845]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
Mar 16 14:26:16 birne dhclient: Internet Systems Consortium DHCP Client 4.1.1-P1
Mar 16 14:26:16 birne dhclient: Copyright 2004-2010 Internet Systems Consortium.
Mar 16 14:26:16 birne dhclient: All rights reserved.
Mar 16 14:26:16 birne dhclient: For info, please visit https://www.isc.org/software/dhcp/
Mar 16 14:26:16 birne dhclient: 
Mar 16 14:26:16 birne NetworkManager[845]: <info> (wlan0): DHCPv4 state changed nbi -> preinit
Mar 16 14:26:16 birne dhclient: Listening on LPF/wlan0/00:24:d7:e3:32:78
Mar 16 14:26:16 birne dhclient: Sending on   LPF/wlan0/00:24:d7:e3:32:78
Mar 16 14:26:16 birne dhclient: Sending on   Socket/fallback
Mar 16 14:26:16 birne dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 3
Mar 16 14:26:16 birne dhclient: DHCPOFFER of 192.168.1.49 from 192.168.1.1
Mar 16 14:26:16 birne dhclient: DHCPREQUEST of 192.168.1.49 on wlan0 to 255.255.255.255 port 67
Mar 16 14:26:17 birne dhclient: DHCPACK of 192.168.1.49 from 192.168.1.1
Mar 16 14:26:17 birne dhclient: bound to 192.168.1.49 -- renewal in 36109 seconds.
Mar 16 14:26:17 birne NetworkManager[845]: <info> (wlan0): DHCPv4 state changed preinit -> bound
Mar 16 14:26:17 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled...
Mar 16 14:26:17 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started...
Mar 16 14:26:17 birne NetworkManager[845]: <info>   address 192.168.1.49
Mar 16 14:26:17 birne NetworkManager[845]: <info>   prefix 24 (255.255.255.0)
Mar 16 14:26:17 birne NetworkManager[845]: <info>   gateway 192.168.1.1
Mar 16 14:26:17 birne NetworkManager[845]: <info>   nameserver '192.168.1.1'
Mar 16 14:26:17 birne NetworkManager[845]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
Mar 16 14:26:18 birne NetworkManager[845]: <info> (wlan0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Mar 16 14:26:18 birne NetworkManager[845]: <info> Policy set 'rudolfinum' (wlan0) as default for IPv4 routing and DNS.
Mar 16 14:26:18 birne NetworkManager[845]: <info> Activation (wlan0) successful, device activated.
Mar 16 14:26:18 birne NetworkManager[845]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
Mar 16 14:26:18 birne NetworkManager[845]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete.
Mar 16 14:26:27 birne kernel: [99677.827217] wlan0: no IPv6 routers present
Mar 16 14:51:11 birne kernel: [101157.944560] usb 1-1.2: USB disconnect, device number 24
Mar 16 14:51:15 birne kernel: [101162.481744] usb 1-1.2: new low speed USB device number 26 using ehci_hcd
Mar 16 14:51:16 birne kernel: [101162.581015] input: 2.4G Wireless Optical Mouse as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0/input/input24
Mar 16 14:51:16 birne kernel: [101162.581309] generic-usb 0003:1D57:0008.000D: input,hidraw0: USB HID v1.10 Mouse [2.4G Wireless Optical Mouse] on usb-0000:00:1a.0-1.2/input0
Mar 16 15:15:57 birne wpa_supplicant[1462]: WPA: Group rekeying completed with e0:91:f5:c9:f2:d0 [GTK=CCMP]
Mar 16 15:44:27 birne kernel: [104346.114270] usb 1-1.2: USB disconnect, device number 26
Mar 16 15:44:32 birne kernel: [104351.419390] usb 1-1.2: new low speed USB device number 27 using ehci_hcd
Mar 16 15:44:32 birne kernel: [104351.518940] input: 2.4G Wireless Optical Mouse as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0/input/input25
Mar 16 15:44:32 birne kernel: [104351.519213] generic-usb 0003:1D57:0008.000E: input,hidraw0: USB HID v1.10 Mouse [2.4G Wireless Optical Mouse] on usb-0000:00:1a.0-1.2/input0
Mar 16 16:15:59 birne wpa_supplicant[1462]: WPA: Group rekeying completed with e0:91:f5:c9:f2:d0 [GTK=CCMP]