Dec 7 02:54:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 02:54:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 02:56:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 02:56:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 02:58:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 02:58:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 03:10:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 03:12:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 03:24:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 03:24:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 03:32:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 03:32:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 03:52:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 03:52:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 04:04:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 04:04:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 04:14:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 04:28:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 04:42:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 04:42:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 04:56:56 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 04:58:06 NetworkManager[1285]: last message repeated 2 times Dec 7 05:01:29 xxx1 NetworkManager[1285]: sleep requested (sleeping: no enabled: yes) Dec 7 05:01:29 xxx1 NetworkManager[1285]: sleeping or disabling... Dec 7 05:01:29 xxx1 NetworkManager[1285]: (eth0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37] Dec 7 05:01:29 xxx1 NetworkManager[1285]: (eth0): cleaning up... Dec 7 05:01:29 xxx1 NetworkManager[1285]: (eth0): taking down device. Dec 7 05:01:30 xxx1 kernel: [155583.959398] tg3 0000:02:00.0: System wakeup enabled by ACPI Dec 7 05:01:30 xxx1 NetworkManager[1285]: (wlan0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37] Dec 7 05:01:30 xxx1 NetworkManager[1285]: (wlan0): deactivating device (reason 'sleeping') [37] Dec 7 05:01:30 xxx1 NetworkManager[1285]: (wlan0): canceled DHCP transaction, DHCP client pid 19736 Dec 7 05:01:30 xxx1 kernel: [155584.174428] wlan0: deauthenticating from 44:94:fc:3d:27:7c by local choice (reason=3) Dec 7 05:01:30 xxx1 kernel: [155584.210589] cfg80211: Calling CRDA to update world regulatory domain Dec 7 05:01:30 xxx1 wpa_supplicant[1477]: wlan0: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=3 Dec 7 05:01:30 xxx1 NetworkManager[1285]: DNS: plugin dnsmasq update failed Dec 7 05:01:30 xxx1 NetworkManager[1285]: Removing DNS information from /sbin/resolvconf Dec 7 05:01:30 xxx1 kernel: [155584.219984] cfg80211: World regulatory domain updated: Dec 7 05:01:30 xxx1 kernel: [155584.219989] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) Dec 7 05:01:30 xxx1 kernel: [155584.219993] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 05:01:30 xxx1 kernel: [155584.219996] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) Dec 7 05:01:30 xxx1 kernel: [155584.219999] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) Dec 7 05:01:30 xxx1 kernel: [155584.220001] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 05:01:30 xxx1 kernel: [155584.220004] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 05:01:30 xxx1 NetworkManager[1285]: (wlan0): cleaning up... Dec 7 05:01:30 xxx1 NetworkManager[1285]: (wlan0): taking down device. Dec 7 05:01:32 xxx1 kernel: [155586.027209] PM: Syncing filesystems ... done. Dec 7 13:10:25 xxx1 kernel: [155587.017208] Freezing user space processes ... (elapsed 0.01 seconds) done. Dec 7 13:10:25 xxx1 kernel: [155587.032907] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. Dec 7 13:10:25 xxx1 kernel: [155587.048922] Suspending console(s) (use no_console_suspend to debug) Dec 7 13:10:25 xxx1 kernel: [155587.049192] sd 0:0:0:0: [sda] Synchronizing SCSI cache Dec 7 13:10:25 xxx1 kernel: [155587.056558] sd 0:0:0:0: [sda] Stopping disk Dec 7 13:10:25 xxx1 kernel: [155587.564340] PM: suspend of devices complete after 515.869 msecs Dec 7 13:10:25 xxx1 kernel: [155587.564489] PM: late suspend of devices complete after 0.147 msecs Dec 7 13:10:25 xxx1 kernel: [155587.580726] ehci-pci 0000:00:1d.0: System wakeup enabled by ACPI Dec 7 13:10:25 xxx1 kernel: [155587.596362] ehci-pci 0000:00:1a.0: System wakeup enabled by ACPI Dec 7 13:10:25 xxx1 kernel: [155587.628197] xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI Dec 7 13:10:25 xxx1 kernel: [155587.644211] PM: noirq suspend of devices complete after 79.809 msecs Dec 7 13:10:25 xxx1 kernel: [155587.644432] ACPI: Preparing to enter system sleep state S3 Dec 7 13:10:25 xxx1 kernel: [155587.648176] PM: Saving platform NVS memory Dec 7 13:10:25 xxx1 kernel: [155587.649715] Disabling non-boot CPUs ... Dec 7 13:10:25 xxx1 kernel: [155587.752004] smpboot: CPU 1 is now offline Dec 7 13:10:25 xxx1 kernel: [155587.855891] smpboot: CPU 2 is now offline Dec 7 13:10:25 xxx1 kernel: [155587.856437] Broke affinity for irq 16 Dec 7 13:10:25 xxx1 kernel: [155587.856446] Broke affinity for irq 23 Dec 7 13:10:25 xxx1 kernel: [155587.959778] smpboot: CPU 3 is now offline Dec 7 13:10:25 xxx1 kernel: [155588.063662] smpboot: CPU 4 is now offline Dec 7 13:10:25 xxx1 kernel: [155588.064105] Broke affinity for irq 17 Dec 7 13:10:25 xxx1 kernel: [155588.167547] smpboot: CPU 5 is now offline Dec 7 13:10:25 xxx1 kernel: [155588.271435] smpboot: CPU 6 is now offline Dec 7 13:10:25 xxx1 kernel: [155588.271960] Broke affinity for irq 42 Dec 7 13:10:25 xxx1 kernel: [155588.375320] smpboot: CPU 7 is now offline Dec 7 13:10:25 xxx1 kernel: [155588.376760] ACPI: Low-level resume complete Dec 7 13:10:25 xxx1 kernel: [155588.376808] PM: Restoring platform NVS memory Dec 7 13:10:25 xxx1 kernel: [155588.377291] Enabling non-boot CPUs ... Dec 7 13:10:25 xxx1 kernel: [155588.377351] smpboot: Booting Node 0 Processor 1 APIC 0x1 Dec 7 13:10:25 xxx1 kernel: [155588.407420] CPU1 is up Dec 7 13:10:25 xxx1 kernel: [155588.407485] smpboot: Booting Node 0 Processor 2 APIC 0x2 Dec 7 13:10:25 xxx1 kernel: [155588.421295] CPU2 is up Dec 7 13:10:25 xxx1 kernel: [155588.421352] smpboot: Booting Node 0 Processor 3 APIC 0x3 Dec 7 13:10:25 xxx1 kernel: [155588.435199] CPU3 is up Dec 7 13:10:25 xxx1 kernel: [155588.435252] smpboot: Booting Node 0 Processor 4 APIC 0x4 Dec 7 13:10:25 xxx1 kernel: [155588.449078] CPU4 is up Dec 7 13:10:25 xxx1 kernel: [155588.449130] smpboot: Booting Node 0 Processor 5 APIC 0x5 Dec 7 13:10:25 xxx1 kernel: [155588.462849] CPU5 is up Dec 7 13:10:25 xxx1 kernel: [155588.462899] smpboot: Booting Node 0 Processor 6 APIC 0x6 Dec 7 13:10:25 xxx1 kernel: [155588.476752] CPU6 is up Dec 7 13:10:25 xxx1 kernel: [155588.476812] smpboot: Booting Node 0 Processor 7 APIC 0x7 Dec 7 13:10:25 xxx1 kernel: [155588.490704] CPU7 is up Dec 7 13:10:25 xxx1 kernel: [155588.498170] ACPI: Waking up from system sleep state S3 Dec 7 13:10:25 xxx1 kernel: [155588.528785] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI Dec 7 13:10:25 xxx1 kernel: [155588.560749] ehci-pci 0000:00:1a.0: System wakeup disabled by ACPI Dec 7 13:10:25 xxx1 kernel: [155588.592711] ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI Dec 7 13:10:25 xxx1 kernel: [155588.608713] pci 0000:01:00.0: power state changed by ACPI to D0 Dec 7 13:10:25 xxx1 kernel: [155588.656710] PM: noirq resume of devices complete after 156.721 msecs Dec 7 13:10:25 xxx1 kernel: [155588.656835] PM: early resume of devices complete after 0.100 msecs Dec 7 13:10:25 xxx1 kernel: [155588.656858] i915 0000:00:02.0: setting latency timer to 64 Dec 7 13:10:25 xxx1 kernel: [155588.656868] xhci_hcd 0000:00:14.0: setting latency timer to 64 Dec 7 13:10:25 xxx1 kernel: [155588.656879] ehci-pci 0000:00:1a.0: setting latency timer to 64 Dec 7 13:10:25 xxx1 kernel: [155588.656910] ehci-pci 0000:00:1d.0: setting latency timer to 64 Dec 7 13:10:25 xxx1 kernel: [155588.656956] tg3 0000:02:00.0: System wakeup disabled by ACPI Dec 7 13:10:25 xxx1 kernel: [155588.657007] mei 0000:00:16.0: irq 41 for MSI/MSI-X Dec 7 13:10:25 xxx1 kernel: [155588.657009] ahci 0000:00:1f.2: setting latency timer to 64 Dec 7 13:10:25 xxx1 kernel: [155588.657029] ath: phy0: ASPM enabled: 0x42 Dec 7 13:10:25 xxx1 kernel: [155588.657033] snd_hda_intel 0000:00:1b.0: irq 44 for MSI/MSI-X Dec 7 13:10:25 xxx1 kernel: [155588.960374] dpm_run_callback(): pnp_bus_resume+0x0/0x80 returns -19 Dec 7 13:10:25 xxx1 kernel: [155588.960375] PM: Device 00:00 failed to resume: error -19 Dec 7 13:10:25 xxx1 kernel: [155588.976239] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Dec 7 13:10:25 xxx1 kernel: [155588.980205] ata3.00: configured for UDMA/133 Dec 7 13:10:25 xxx1 kernel: [155589.020481] usb 3-2: reset low-speed USB device number 33 using xhci_hcd Dec 7 13:10:25 xxx1 kernel: [155589.038677] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8801eabd7380 Dec 7 13:10:25 xxx1 kernel: [155589.038684] usb 3-2: ep 0x81 - rounding interval to 64 microframes, ep desc says 80 microframes Dec 7 13:10:25 xxx1 kernel: [155589.204301] usb 3-3: reset high-speed USB device number 34 using xhci_hcd Dec 7 13:10:25 xxx1 kernel: [155589.220197] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8801eabd7a40 Dec 7 13:10:25 xxx1 kernel: [155589.292186] usb 1-1.1: reset full-speed USB device number 19 using ehci-pci Dec 7 13:10:25 xxx1 kernel: [155589.384176] usb 1-1.1: device firmware changed Dec 7 13:10:25 xxx1 kernel: [155589.455972] usb 1-1.3: reset high-speed USB device number 4 using ehci-pci Dec 7 13:10:25 xxx1 kernel: [155589.635825] usb 3-3.3: reset full-speed USB device number 35 using xhci_hcd Dec 7 13:10:25 xxx1 kernel: [155589.668828] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff88021fc0da80 Dec 7 13:10:25 xxx1 kernel: [155589.668831] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff88021fc0dac0 Dec 7 13:10:25 xxx1 kernel: [155589.669209] usblp 3-3.3:1.0: no reset_resume for driver usblp? Dec 7 13:10:25 xxx1 kernel: [155589.669392] usblp0: removed Dec 7 13:10:25 xxx1 kernel: [155589.743632] usb 3-3.4: reset high-speed USB device number 36 using xhci_hcd Dec 7 13:10:25 xxx1 kernel: [155589.759628] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8801eabd7680 Dec 7 13:10:25 xxx1 kernel: [155590.047510] [drm] Enabling RC6 states: RC6 on, RC6p on, RC6pp off Dec 7 13:10:25 xxx1 kernel: [155590.227171] usb 3-3.4.1: reset low-speed USB device number 37 using xhci_hcd Dec 7 13:10:25 xxx1 kernel: [155590.246493] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880245144c80 Dec 7 13:10:25 xxx1 kernel: [155590.246496] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880245144940 Dec 7 13:10:25 xxx1 kernel: [155590.246510] usb 3-3.4.1: ep 0x81 - rounding interval to 64 microframes, ep desc says 80 microframes Dec 7 13:10:25 xxx1 kernel: [155590.246523] usb 3-3.4.1: ep 0x82 - rounding interval to 64 microframes, ep desc says 80 microframes Dec 7 13:10:25 xxx1 kernel: [155591.054104] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) Dec 7 13:10:25 xxx1 kernel: [155591.057957] ata1.00: configured for UDMA/100 Dec 7 13:10:25 xxx1 kernel: [155591.074100] sd 0:0:0:0: [sda] Starting disk Dec 7 13:10:25 xxx1 kernel: [155591.087858] PM: resume of devices complete after 2433.690 msecs Dec 7 13:10:25 xxx1 kernel: [155591.095027] usblp 3-3.3:1.0: usblp0: USB Bidirectional printer dev 35 if 0 alt 0 proto 2 vid 0x03F0 pid 0x1604 Dec 7 13:10:25 xxx1 kernel: [155591.095284] Restarting tasks ... done. Dec 7 13:10:25 xxx1 kernel: [155591.125956] usb 1-1.1: USB disconnect, device number 19 Dec 7 13:10:25 xxx1 kernel: [155591.173973] video LNXVIDEO:00: Restoring backlight state Dec 7 13:10:25 xxx1 kernel: [155591.173976] video LNXVIDEO:01: Restoring backlight state Dec 7 13:10:25 xxx1 kernel: [155591.197875] usb 1-1.1: new full-speed USB device number 20 using ehci-pci Dec 7 13:10:25 xxx1 kernel: [155591.291763] usb 1-1.1: string descriptor 0 read error: -22 Dec 7 13:10:25 xxx1 kernel: [155591.291769] usb 1-1.1: New USB device found, idVendor=0489, idProduct=e04e Dec 7 13:10:25 xxx1 kernel: [155591.291771] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 7 13:10:25 xxx1 kernel: [155591.334470] usb 1-1.1: USB disconnect, device number 20 Dec 7 13:10:26 xxx1 NetworkManager[1285]: wake requested (sleeping: yes enabled: yes) Dec 7 13:10:26 xxx1 NetworkManager[1285]: waking up and re-enabling... Dec 7 13:10:26 xxx1 NetworkManager[1285]: (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2] Dec 7 13:10:26 xxx1 NetworkManager[1285]: (eth0): bringing up device. Dec 7 13:10:26 xxx1 kernel: [155591.529009] tg3 0000:02:00.0: irq 46 for MSI/MSI-X Dec 7 13:10:26 xxx1 kernel: [155591.529021] tg3 0000:02:00.0: irq 47 for MSI/MSI-X Dec 7 13:10:26 xxx1 kernel: [155591.529028] tg3 0000:02:00.0: irq 48 for MSI/MSI-X Dec 7 13:10:26 xxx1 kernel: [155591.529035] tg3 0000:02:00.0: irq 49 for MSI/MSI-X Dec 7 13:10:26 xxx1 kernel: [155591.529042] tg3 0000:02:00.0: irq 50 for MSI/MSI-X Dec 7 13:10:26 xxx1 kernel: [155591.537537] usb 1-1.1: new full-speed USB device number 21 using ehci-pci Dec 7 13:10:26 xxx1 kernel: [155591.631283] usb 1-1.1: string descriptor 0 read error: -22 Dec 7 13:10:26 xxx1 kernel: [155591.631290] usb 1-1.1: New USB device found, idVendor=0489, idProduct=e04e Dec 7 13:10:26 xxx1 kernel: [155591.631294] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 7 13:10:26 xxx1 NetworkManager[1285]: (eth0): preparing device. Dec 7 13:10:26 xxx1 NetworkManager[1285]: (eth0): deactivating device (reason 'managed') [2] Dec 7 13:10:26 xxx1 kernel: [155592.092341] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready Dec 7 13:10:26 xxx1 NetworkManager[1285]: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Dec 7 13:10:26 xxx1 NetworkManager[1285]: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Dec 7 13:10:26 xxx1 NetworkManager[1285]: (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2] Dec 7 13:10:26 xxx1 NetworkManager[1285]: (wlan0): bringing up device. Dec 7 13:10:26 xxx1 NetworkManager[1285]: (wlan0): preparing device. Dec 7 13:10:26 xxx1 NetworkManager[1285]: (wlan0): deactivating device (reason 'managed') [2] Dec 7 13:10:26 xxx1 kernel: [155592.107512] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready Dec 7 13:10:26 xxx1 NetworkManager[1285]: (wlan0) supports 4 scan SSIDs Dec 7 13:10:26 xxx1 NetworkManager[1285]: Trying to remove a non-existant call id. Dec 7 13:10:26 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: starting -> ready Dec 7 13:10:26 xxx1 NetworkManager[1285]: (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42] Dec 7 13:10:26 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: ready -> inactive Dec 7 13:10:26 xxx1 NetworkManager[1285]: (wlan0) supports 4 scan SSIDs Dec 7 13:10:30 xxx1 NetworkManager[1285]: Auto-activating connection 'Sixty-Seven'. Dec 7 13:10:30 xxx1 NetworkManager[1285]: Activation (wlan0) starting connection 'Sixty-Seven' Dec 7 13:10:30 xxx1 NetworkManager[1285]: (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0] Dec 7 13:10:30 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Dec 7 13:10:30 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Dec 7 13:10:30 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Dec 7 13:10:30 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Dec 7 13:10:30 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Dec 7 13:10:30 xxx1 NetworkManager[1285]: (wlan0): device state change: prepare -> config (reason 'none') [40 50 0] Dec 7 13:10:30 xxx1 NetworkManager[1285]: Activation (wlan0/wireless): access point 'Sixty-Seven' has security, but secrets are required. Dec 7 13:10:30 xxx1 NetworkManager[1285]: (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0] Dec 7 13:10:30 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Dec 7 13:10:30 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Dec 7 13:10:30 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Dec 7 13:10:30 xxx1 NetworkManager[1285]: (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0] Dec 7 13:10:30 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Dec 7 13:10:30 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Dec 7 13:10:30 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Dec 7 13:10:30 xxx1 NetworkManager[1285]: (wlan0): device state change: prepare -> config (reason 'none') [40 50 0] Dec 7 13:10:30 xxx1 NetworkManager[1285]: Activation (wlan0/wireless): connection 'Sixty-Seven' has security, and secrets exist. No new secrets needed. Dec 7 13:10:30 xxx1 NetworkManager[1285]: Config: added 'ssid' value 'Sixty-Seven' Dec 7 13:10:30 xxx1 NetworkManager[1285]: Config: added 'scan_ssid' value '1' Dec 7 13:10:30 xxx1 NetworkManager[1285]: Config: added 'key_mgmt' value 'WPA-PSK' Dec 7 13:10:30 xxx1 NetworkManager[1285]: Config: added 'auth_alg' value 'OPEN' Dec 7 13:10:30 xxx1 NetworkManager[1285]: Config: added 'psk' value '' Dec 7 13:10:30 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Dec 7 13:10:30 xxx1 NetworkManager[1285]: Config: set interface ap_scan to 1 Dec 7 13:10:30 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: inactive -> scanning Dec 7 13:10:34 xxx1 wpa_supplicant[1477]: wlan0: SME: Trying to authenticate with 44:94:fc:3d:27:7c (SSID='Sixty-Seven' freq=2462 MHz) Dec 7 13:10:34 xxx1 kernel: [155599.653977] wlan0: authenticate with 44:94:fc:3d:27:7c Dec 7 13:10:34 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: scanning -> authenticating Dec 7 13:10:34 xxx1 wpa_supplicant[1477]: wlan0: Trying to associate with 44:94:fc:3d:27:7c (SSID='Sixty-Seven' freq=2462 MHz) Dec 7 13:10:34 xxx1 kernel: [155599.660633] wlan0: send auth to 44:94:fc:3d:27:7c (try 1/3) Dec 7 13:10:34 xxx1 kernel: [155599.662732] wlan0: authenticated Dec 7 13:10:34 xxx1 kernel: [155599.664503] wlan0: associate with 44:94:fc:3d:27:7c (try 1/3) Dec 7 13:10:34 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: authenticating -> associating Dec 7 13:10:34 xxx1 wpa_supplicant[1477]: wlan0: Associated with 44:94:fc:3d:27:7c Dec 7 13:10:34 xxx1 kernel: [155599.670787] wlan0: RX AssocResp from 44:94:fc:3d:27:7c (capab=0x411 status=0 aid=1) Dec 7 13:10:34 xxx1 kernel: [155599.670843] wlan0: associated Dec 7 13:10:34 xxx1 kernel: [155599.670856] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Dec 7 13:10:34 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: associating -> associated Dec 7 13:10:34 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: associated -> 4-way handshake Dec 7 13:10:34 xxx1 wpa_supplicant[1477]: wlan0: WPA: Key negotiation completed with 44:94:fc:3d:27:7c [PTK=CCMP GTK=CCMP] Dec 7 13:10:34 xxx1 wpa_supplicant[1477]: wlan0: CTRL-EVENT-CONNECTED - Connection to 44:94:fc:3d:27:7c completed (auth) [id=0 id_str=] Dec 7 13:10:34 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: 4-way handshake -> completed Dec 7 13:10:34 xxx1 NetworkManager[1285]: Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'Sixty-Seven'. Dec 7 13:10:34 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled. Dec 7 13:10:34 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) started... Dec 7 13:10:34 xxx1 NetworkManager[1285]: (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0] Dec 7 13:10:34 xxx1 NetworkManager[1285]: Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds) Dec 7 13:10:34 xxx1 NetworkManager[1285]: dhclient started with pid 25796 Dec 7 13:10:34 xxx1 NetworkManager[1285]: Activation (wlan0) Beginning IP6 addrconf. Dec 7 13:10:34 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete. Dec 7 13:10:34 xxx1 dhclient: Internet Systems Consortium DHCP Client 4.2.4 Dec 7 13:10:34 xxx1 dhclient: Copyright 2004-2012 Internet Systems Consortium. Dec 7 13:10:34 xxx1 dhclient: All rights reserved. Dec 7 13:10:34 xxx1 dhclient: For info, please visit https://www.isc.org/software/dhcp/ Dec 7 13:10:34 xxx1 dhclient: Dec 7 13:10:34 xxx1 NetworkManager[1285]: (wlan0): DHCPv4 state changed nbi -> preinit Dec 7 13:10:34 xxx1 dhclient: Listening on LPF/wlan0/a4:17:31:0d:4a:6f Dec 7 13:10:34 xxx1 dhclient: Sending on LPF/wlan0/a4:17:31:0d:4a:6f Dec 7 13:10:34 xxx1 dhclient: Sending on Socket/fallback Dec 7 13:10:34 xxx1 dhclient: DHCPREQUEST of 192.168.1.2 on wlan0 to 255.255.255.255 port 67 (xid=0x40b04f4c) Dec 7 13:10:34 xxx1 dhclient: DHCPACK of 192.168.1.2 from 192.168.1.1 Dec 7 13:10:34 xxx1 dhclient: bound to 192.168.1.2 -- renewal in 33657 seconds. Dec 7 13:10:34 xxx1 NetworkManager[1285]: (wlan0): DHCPv4 state changed preinit -> reboot Dec 7 13:10:34 xxx1 NetworkManager[1285]: address 192.168.1.2 Dec 7 13:10:34 xxx1 NetworkManager[1285]: prefix 24 (255.255.255.0) Dec 7 13:10:34 xxx1 NetworkManager[1285]: gateway 192.168.1.1 Dec 7 13:10:34 xxx1 NetworkManager[1285]: nameserver '192.168.1.1' Dec 7 13:10:34 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 5 of 5 (IPv4 Configure Commit) scheduled... Dec 7 13:10:34 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 5 of 5 (IPv4 Commit) started... Dec 7 13:10:35 xxx1 NetworkManager[1285]: (wlan0): device state change: ip-config -> secondaries (reason 'none') [70 90 0] Dec 7 13:10:35 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 5 of 5 (IPv4 Commit) complete. Dec 7 13:10:35 xxx1 NetworkManager[1285]: (wlan0): device state change: secondaries -> activated (reason 'none') [90 100 0] Dec 7 13:10:35 xxx1 NetworkManager[1285]: Policy set 'Sixty-Seven' (wlan0) as default for IPv4 routing and DNS. Dec 7 13:10:35 xxx1 NetworkManager[1285]: Writing DNS information to /sbin/resolvconf Dec 7 13:10:35 xxx1 NetworkManager[1285]: Activation (wlan0) successful, device activated. Dec 7 13:10:56 xxx1 NetworkManager[1285]: (wlan0): IP6 addrconf timed out or failed. Dec 7 13:10:56 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled... Dec 7 13:10:56 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) started... Dec 7 13:10:56 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) complete. Dec 7 13:11:45 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 13:12:48 NetworkManager[1285]: last message repeated 2 times Dec 7 13:17:54 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 13:21:54 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 13:21:54 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 13:25:54 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 13:27:00 NetworkManager[1285]: last message repeated 2 times Dec 7 13:33:54 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 13:33:54 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 13:35:54 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 13:39:54 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 13:39:54 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 13:41:54 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 13:57:07 xxx1 NetworkManager[1285]: sleep requested (sleeping: no enabled: yes) Dec 7 13:57:07 xxx1 NetworkManager[1285]: sleeping or disabling... Dec 7 13:57:07 xxx1 NetworkManager[1285]: (eth0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37] Dec 7 13:57:07 xxx1 NetworkManager[1285]: (eth0): cleaning up... Dec 7 13:57:07 xxx1 NetworkManager[1285]: (eth0): taking down device. Dec 7 13:57:08 xxx1 kernel: [158389.335141] tg3 0000:02:00.0: System wakeup enabled by ACPI Dec 7 13:57:08 xxx1 NetworkManager[1285]: (wlan0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37] Dec 7 13:57:08 xxx1 NetworkManager[1285]: (wlan0): deactivating device (reason 'sleeping') [37] Dec 7 13:57:08 xxx1 NetworkManager[1285]: (wlan0): canceled DHCP transaction, DHCP client pid 25796 Dec 7 13:57:08 xxx1 kernel: [158389.549887] wlan0: deauthenticating from 44:94:fc:3d:27:7c by local choice (reason=3) Dec 7 13:57:08 xxx1 kernel: [158389.583977] cfg80211: Calling CRDA to update world regulatory domain Dec 7 13:57:08 xxx1 wpa_supplicant[1477]: wlan0: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=3 Dec 7 13:57:08 xxx1 NetworkManager[1285]: DNS: plugin dnsmasq update failed Dec 7 13:57:08 xxx1 NetworkManager[1285]: Removing DNS information from /sbin/resolvconf Dec 7 13:57:08 xxx1 kernel: [158389.592980] cfg80211: World regulatory domain updated: Dec 7 13:57:08 xxx1 kernel: [158389.592987] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) Dec 7 13:57:08 xxx1 kernel: [158389.592990] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 13:57:08 xxx1 kernel: [158389.592993] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) Dec 7 13:57:08 xxx1 kernel: [158389.592996] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) Dec 7 13:57:08 xxx1 kernel: [158389.592998] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 13:57:08 xxx1 kernel: [158389.593001] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 13:57:08 xxx1 NetworkManager[1285]: (wlan0): cleaning up... Dec 7 13:57:08 xxx1 NetworkManager[1285]: (wlan0): taking down device. Dec 7 13:57:09 xxx1 kernel: [158390.366874] PM: Syncing filesystems ... done. Dec 7 14:56:03 xxx1 kernel: [158391.110826] Freezing user space processes ... (elapsed 0.01 seconds) done. Dec 7 14:56:03 xxx1 kernel: [158391.126530] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. Dec 7 14:56:03 xxx1 kernel: [158391.142551] Suspending console(s) (use no_console_suspend to debug) Dec 7 14:56:03 xxx1 kernel: [158391.142795] sd 0:0:0:0: [sda] Synchronizing SCSI cache Dec 7 14:56:03 xxx1 kernel: [158391.142860] sd 0:0:0:0: [sda] Stopping disk Dec 7 14:56:03 xxx1 kernel: [158391.629995] PM: suspend of devices complete after 487.872 msecs Dec 7 14:56:03 xxx1 kernel: [158391.630139] PM: late suspend of devices complete after 0.142 msecs Dec 7 14:56:03 xxx1 kernel: [158391.646389] ehci-pci 0000:00:1d.0: System wakeup enabled by ACPI Dec 7 14:56:03 xxx1 kernel: [158391.662023] ehci-pci 0000:00:1a.0: System wakeup enabled by ACPI Dec 7 14:56:03 xxx1 kernel: [158391.693857] xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI Dec 7 14:56:03 xxx1 kernel: [158391.709873] PM: noirq suspend of devices complete after 79.820 msecs Dec 7 14:56:03 xxx1 kernel: [158391.710095] ACPI: Preparing to enter system sleep state S3 Dec 7 14:56:03 xxx1 kernel: [158391.713836] PM: Saving platform NVS memory Dec 7 14:56:03 xxx1 kernel: [158391.715396] Disabling non-boot CPUs ... Dec 7 14:56:03 xxx1 kernel: [158391.817664] smpboot: CPU 1 is now offline Dec 7 14:56:03 xxx1 kernel: [158391.921554] smpboot: CPU 2 is now offline Dec 7 14:56:03 xxx1 kernel: [158391.922060] Broke affinity for irq 16 Dec 7 14:56:03 xxx1 kernel: [158391.923071] smpboot: CPU 3 is now offline Dec 7 14:56:03 xxx1 kernel: [158392.025437] smpboot: CPU 4 is now offline Dec 7 14:56:03 xxx1 kernel: [158392.025883] Broke affinity for irq 17 Dec 7 14:56:03 xxx1 kernel: [158392.129324] smpboot: CPU 5 is now offline Dec 7 14:56:03 xxx1 kernel: [158392.233213] smpboot: CPU 6 is now offline Dec 7 14:56:03 xxx1 kernel: [158392.233673] Broke affinity for irq 23 Dec 7 14:56:03 xxx1 kernel: [158392.234679] smpboot: CPU 7 is now offline Dec 7 14:56:03 xxx1 kernel: [158392.236064] ACPI: Low-level resume complete Dec 7 14:56:03 xxx1 kernel: [158392.236112] PM: Restoring platform NVS memory Dec 7 14:56:03 xxx1 kernel: [158392.236598] Enabling non-boot CPUs ... Dec 7 14:56:03 xxx1 kernel: [158392.236658] smpboot: Booting Node 0 Processor 1 APIC 0x1 Dec 7 14:56:03 xxx1 kernel: [158392.266647] CPU1 is up Dec 7 14:56:03 xxx1 kernel: [158392.266698] smpboot: Booting Node 0 Processor 2 APIC 0x2 Dec 7 14:56:03 xxx1 kernel: [158392.280504] CPU2 is up Dec 7 14:56:03 xxx1 kernel: [158392.280559] smpboot: Booting Node 0 Processor 3 APIC 0x3 Dec 7 14:56:03 xxx1 kernel: [158392.294462] CPU3 is up Dec 7 14:56:03 xxx1 kernel: [158392.294517] smpboot: Booting Node 0 Processor 4 APIC 0x4 Dec 7 14:56:03 xxx1 kernel: [158392.308256] CPU4 is up Dec 7 14:56:03 xxx1 kernel: [158392.308310] smpboot: Booting Node 0 Processor 5 APIC 0x5 Dec 7 14:56:03 xxx1 kernel: [158392.322168] CPU5 is up Dec 7 14:56:03 xxx1 kernel: [158392.322213] smpboot: Booting Node 0 Processor 6 APIC 0x6 Dec 7 14:56:03 xxx1 kernel: [158392.336049] CPU6 is up Dec 7 14:56:03 xxx1 kernel: [158392.336106] smpboot: Booting Node 0 Processor 7 APIC 0x7 Dec 7 14:56:03 xxx1 kernel: [158392.349937] CPU7 is up Dec 7 14:56:03 xxx1 kernel: [158392.357419] ACPI: Waking up from system sleep state S3 Dec 7 14:56:03 xxx1 kernel: [158392.390657] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI Dec 7 14:56:03 xxx1 kernel: [158392.422621] ehci-pci 0000:00:1a.0: System wakeup disabled by ACPI Dec 7 14:56:03 xxx1 kernel: [158392.454583] ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI Dec 7 14:56:03 xxx1 kernel: [158392.470585] pci 0000:01:00.0: power state changed by ACPI to D0 Dec 7 14:56:03 xxx1 kernel: [158392.518578] PM: noirq resume of devices complete after 159.336 msecs Dec 7 14:56:03 xxx1 kernel: [158392.518697] PM: early resume of devices complete after 0.095 msecs Dec 7 14:56:03 xxx1 kernel: [158392.518719] i915 0000:00:02.0: setting latency timer to 64 Dec 7 14:56:03 xxx1 kernel: [158392.518731] xhci_hcd 0000:00:14.0: setting latency timer to 64 Dec 7 14:56:03 xxx1 kernel: [158392.518738] ehci-pci 0000:00:1a.0: setting latency timer to 64 Dec 7 14:56:03 xxx1 kernel: [158392.518753] ehci-pci 0000:00:1d.0: setting latency timer to 64 Dec 7 14:56:03 xxx1 kernel: [158392.518780] ahci 0000:00:1f.2: setting latency timer to 64 Dec 7 14:56:03 xxx1 kernel: [158392.518809] tg3 0000:02:00.0: System wakeup disabled by ACPI Dec 7 14:56:03 xxx1 kernel: [158392.518924] ath: phy0: ASPM enabled: 0x42 Dec 7 14:56:03 xxx1 kernel: [158392.518927] mei 0000:00:16.0: irq 41 for MSI/MSI-X Dec 7 14:56:03 xxx1 kernel: [158392.518970] snd_hda_intel 0000:00:1b.0: irq 44 for MSI/MSI-X Dec 7 14:56:03 xxx1 kernel: [158392.734442] usb 3-3: reset high-speed USB device number 34 using xhci_hcd Dec 7 14:56:03 xxx1 kernel: [158392.750452] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8801eabd7a40 Dec 7 14:56:03 xxx1 kernel: [158392.826216] dpm_run_callback(): pnp_bus_resume+0x0/0x80 returns -19 Dec 7 14:56:03 xxx1 kernel: [158392.826217] PM: Device 00:00 failed to resume: error -19 Dec 7 14:56:03 xxx1 kernel: [158392.846180] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Dec 7 14:56:03 xxx1 kernel: [158392.850185] ata3.00: configured for UDMA/133 Dec 7 14:56:03 xxx1 kernel: [158393.010221] usb 3-2: reset low-speed USB device number 33 using xhci_hcd Dec 7 14:56:03 xxx1 kernel: [158393.028545] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8801eabd7380 Dec 7 14:56:03 xxx1 kernel: [158393.028570] usb 3-2: ep 0x81 - rounding interval to 64 microframes, ep desc says 80 microframes Dec 7 14:56:03 xxx1 kernel: [158393.098112] usb 1-1.1: reset full-speed USB device number 21 using ehci-pci Dec 7 14:56:03 xxx1 kernel: [158393.190131] usb 1-1.1: device firmware changed Dec 7 14:56:03 xxx1 kernel: [158393.261747] usb 1-1.3: reset high-speed USB device number 4 using ehci-pci Dec 7 14:56:03 xxx1 kernel: [158393.441726] usb 3-3.3: reset full-speed USB device number 35 using xhci_hcd Dec 7 14:56:03 xxx1 kernel: [158393.475911] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff88021fc0da80 Dec 7 14:56:03 xxx1 kernel: [158393.475914] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff88021fc0dac0 Dec 7 14:56:03 xxx1 kernel: [158393.476318] usblp 3-3.3:1.0: no reset_resume for driver usblp? Dec 7 14:56:03 xxx1 kernel: [158393.476511] usblp0: removed Dec 7 14:56:03 xxx1 kernel: [158393.553577] usb 3-3.4: reset high-speed USB device number 36 using xhci_hcd Dec 7 14:56:03 xxx1 kernel: [158393.569560] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8801eabd7680 Dec 7 14:56:03 xxx1 kernel: [158393.921358] [drm] Enabling RC6 states: RC6 on, RC6p on, RC6pp off Dec 7 14:56:03 xxx1 kernel: [158394.037044] usb 3-3.4.1: reset low-speed USB device number 37 using xhci_hcd Dec 7 14:56:03 xxx1 kernel: [158394.056342] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880245144c80 Dec 7 14:56:03 xxx1 kernel: [158394.056345] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880245144940 Dec 7 14:56:03 xxx1 kernel: [158394.056353] usb 3-3.4.1: ep 0x81 - rounding interval to 64 microframes, ep desc says 80 microframes Dec 7 14:56:03 xxx1 kernel: [158394.056365] usb 3-3.4.1: ep 0x82 - rounding interval to 64 microframes, ep desc says 80 microframes Dec 7 14:56:03 xxx1 kernel: [158394.963924] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) Dec 7 14:56:03 xxx1 kernel: [158394.967670] ata1.00: configured for UDMA/100 Dec 7 14:56:03 xxx1 kernel: [158394.983919] sd 0:0:0:0: [sda] Starting disk Dec 7 14:56:03 xxx1 kernel: [158394.990072] PM: resume of devices complete after 2474.087 msecs Dec 7 14:56:03 xxx1 kernel: [158394.992643] usblp 3-3.3:1.0: usblp0: USB Bidirectional printer dev 35 if 0 alt 0 proto 2 vid 0x03F0 pid 0x1604 Dec 7 14:56:03 xxx1 kernel: [158394.992868] Restarting tasks ... done. Dec 7 14:56:03 xxx1 kernel: [158395.023766] usb 1-1.1: USB disconnect, device number 21 Dec 7 14:56:03 xxx1 kernel: [158395.071808] video LNXVIDEO:00: Restoring backlight state Dec 7 14:56:03 xxx1 kernel: [158395.071812] video LNXVIDEO:01: Restoring backlight state Dec 7 14:56:03 xxx1 kernel: [158395.095702] usb 1-1.1: new full-speed USB device number 22 using ehci-pci Dec 7 14:56:03 xxx1 kernel: [158395.189337] usb 1-1.1: string descriptor 0 read error: -22 Dec 7 14:56:03 xxx1 kernel: [158395.189349] usb 1-1.1: New USB device found, idVendor=0489, idProduct=e04e Dec 7 14:56:03 xxx1 kernel: [158395.189354] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 7 14:56:03 xxx1 kernel: [158395.231290] usb 1-1.1: USB disconnect, device number 22 Dec 7 14:56:04 xxx1 NetworkManager[1285]: wake requested (sleeping: yes enabled: yes) Dec 7 14:56:04 xxx1 NetworkManager[1285]: waking up and re-enabling... Dec 7 14:56:04 xxx1 NetworkManager[1285]: (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2] Dec 7 14:56:04 xxx1 NetworkManager[1285]: (eth0): bringing up device. Dec 7 14:56:04 xxx1 kernel: [158395.358249] tg3 0000:02:00.0: irq 46 for MSI/MSI-X Dec 7 14:56:04 xxx1 kernel: [158395.358268] tg3 0000:02:00.0: irq 47 for MSI/MSI-X Dec 7 14:56:04 xxx1 kernel: [158395.358280] tg3 0000:02:00.0: irq 48 for MSI/MSI-X Dec 7 14:56:04 xxx1 kernel: [158395.358291] tg3 0000:02:00.0: irq 49 for MSI/MSI-X Dec 7 14:56:04 xxx1 kernel: [158395.358302] tg3 0000:02:00.0: irq 50 for MSI/MSI-X Dec 7 14:56:04 xxx1 kernel: [158395.435402] usb 1-1.1: new full-speed USB device number 23 using ehci-pci Dec 7 14:56:04 xxx1 kernel: [158395.528907] usb 1-1.1: string descriptor 0 read error: -22 Dec 7 14:56:04 xxx1 kernel: [158395.528917] usb 1-1.1: New USB device found, idVendor=0489, idProduct=e04e Dec 7 14:56:04 xxx1 kernel: [158395.528921] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 7 14:56:04 xxx1 NetworkManager[1285]: (eth0): preparing device. Dec 7 14:56:04 xxx1 NetworkManager[1285]: (eth0): deactivating device (reason 'managed') [2] Dec 7 14:56:04 xxx1 kernel: [158395.922232] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready Dec 7 14:56:04 xxx1 NetworkManager[1285]: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Dec 7 14:56:04 xxx1 NetworkManager[1285]: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Dec 7 14:56:04 xxx1 NetworkManager[1285]: (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2] Dec 7 14:56:04 xxx1 NetworkManager[1285]: (wlan0): bringing up device. Dec 7 14:56:04 xxx1 NetworkManager[1285]: (wlan0): preparing device. Dec 7 14:56:04 xxx1 NetworkManager[1285]: (wlan0): deactivating device (reason 'managed') [2] Dec 7 14:56:04 xxx1 kernel: [158395.958540] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready Dec 7 14:56:04 xxx1 NetworkManager[1285]: (wlan0) supports 4 scan SSIDs Dec 7 14:56:04 xxx1 NetworkManager[1285]: Trying to remove a non-existant call id. Dec 7 14:56:04 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: starting -> ready Dec 7 14:56:04 xxx1 NetworkManager[1285]: (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42] Dec 7 14:56:04 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: ready -> inactive Dec 7 14:56:04 xxx1 NetworkManager[1285]: (wlan0) supports 4 scan SSIDs Dec 7 14:56:08 xxx1 NetworkManager[1285]: Auto-activating connection 'Sixty-Seven'. Dec 7 14:56:08 xxx1 NetworkManager[1285]: Activation (wlan0) starting connection 'Sixty-Seven' Dec 7 14:56:08 xxx1 NetworkManager[1285]: (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0] Dec 7 14:56:08 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Dec 7 14:56:08 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Dec 7 14:56:08 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Dec 7 14:56:08 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Dec 7 14:56:08 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Dec 7 14:56:08 xxx1 NetworkManager[1285]: (wlan0): device state change: prepare -> config (reason 'none') [40 50 0] Dec 7 14:56:08 xxx1 NetworkManager[1285]: Activation (wlan0/wireless): access point 'Sixty-Seven' has security, but secrets are required. Dec 7 14:56:08 xxx1 NetworkManager[1285]: (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0] Dec 7 14:56:08 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Dec 7 14:56:08 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Dec 7 14:56:08 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Dec 7 14:56:08 xxx1 NetworkManager[1285]: (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0] Dec 7 14:56:08 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Dec 7 14:56:08 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Dec 7 14:56:08 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Dec 7 14:56:08 xxx1 NetworkManager[1285]: (wlan0): device state change: prepare -> config (reason 'none') [40 50 0] Dec 7 14:56:08 xxx1 NetworkManager[1285]: Activation (wlan0/wireless): connection 'Sixty-Seven' has security, and secrets exist. No new secrets needed. Dec 7 14:56:08 xxx1 NetworkManager[1285]: Config: added 'ssid' value 'Sixty-Seven' Dec 7 14:56:08 xxx1 NetworkManager[1285]: Config: added 'scan_ssid' value '1' Dec 7 14:56:08 xxx1 NetworkManager[1285]: Config: added 'key_mgmt' value 'WPA-PSK' Dec 7 14:56:08 xxx1 NetworkManager[1285]: Config: added 'auth_alg' value 'OPEN' Dec 7 14:56:08 xxx1 NetworkManager[1285]: Config: added 'psk' value '' Dec 7 14:56:08 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Dec 7 14:56:08 xxx1 NetworkManager[1285]: Config: set interface ap_scan to 1 Dec 7 14:56:08 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: inactive -> scanning Dec 7 14:56:12 xxx1 wpa_supplicant[1477]: wlan0: SME: Trying to authenticate with 44:94:fc:3d:27:7c (SSID='Sixty-Seven' freq=2462 MHz) Dec 7 14:56:12 xxx1 kernel: [158403.524089] wlan0: authenticate with 44:94:fc:3d:27:7c Dec 7 14:56:12 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: scanning -> authenticating Dec 7 14:56:12 xxx1 wpa_supplicant[1477]: wlan0: Trying to associate with 44:94:fc:3d:27:7c (SSID='Sixty-Seven' freq=2462 MHz) Dec 7 14:56:12 xxx1 kernel: [158403.530528] wlan0: send auth to 44:94:fc:3d:27:7c (try 1/3) Dec 7 14:56:12 xxx1 kernel: [158403.532560] wlan0: authenticated Dec 7 14:56:12 xxx1 kernel: [158403.534373] wlan0: associate with 44:94:fc:3d:27:7c (try 1/3) Dec 7 14:56:12 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: authenticating -> associating Dec 7 14:56:12 xxx1 wpa_supplicant[1477]: wlan0: Associated with 44:94:fc:3d:27:7c Dec 7 14:56:12 xxx1 kernel: [158403.538672] wlan0: RX AssocResp from 44:94:fc:3d:27:7c (capab=0x411 status=0 aid=1) Dec 7 14:56:12 xxx1 kernel: [158403.538716] wlan0: associated Dec 7 14:56:12 xxx1 kernel: [158403.538724] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Dec 7 14:56:12 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: associating -> associated Dec 7 14:56:12 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: associated -> 4-way handshake Dec 7 14:56:12 xxx1 wpa_supplicant[1477]: wlan0: WPA: Key negotiation completed with 44:94:fc:3d:27:7c [PTK=CCMP GTK=CCMP] Dec 7 14:56:12 xxx1 wpa_supplicant[1477]: wlan0: CTRL-EVENT-CONNECTED - Connection to 44:94:fc:3d:27:7c completed (auth) [id=0 id_str=] Dec 7 14:56:12 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: 4-way handshake -> completed Dec 7 14:56:12 xxx1 NetworkManager[1285]: Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'Sixty-Seven'. Dec 7 14:56:12 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled. Dec 7 14:56:12 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) started... Dec 7 14:56:12 xxx1 NetworkManager[1285]: (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0] Dec 7 14:56:12 xxx1 NetworkManager[1285]: Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds) Dec 7 14:56:12 xxx1 NetworkManager[1285]: dhclient started with pid 27230 Dec 7 14:56:12 xxx1 NetworkManager[1285]: Activation (wlan0) Beginning IP6 addrconf. Dec 7 14:56:12 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete. Dec 7 14:56:12 xxx1 dhclient: Internet Systems Consortium DHCP Client 4.2.4 Dec 7 14:56:12 xxx1 dhclient: Copyright 2004-2012 Internet Systems Consortium. Dec 7 14:56:12 xxx1 dhclient: All rights reserved. Dec 7 14:56:12 xxx1 dhclient: For info, please visit https://www.isc.org/software/dhcp/ Dec 7 14:56:12 xxx1 dhclient: Dec 7 14:56:12 xxx1 NetworkManager[1285]: (wlan0): DHCPv4 state changed nbi -> preinit Dec 7 14:56:12 xxx1 dhclient: Listening on LPF/wlan0/a4:17:31:0d:4a:6f Dec 7 14:56:12 xxx1 dhclient: Sending on LPF/wlan0/a4:17:31:0d:4a:6f Dec 7 14:56:12 xxx1 dhclient: Sending on Socket/fallback Dec 7 14:56:12 xxx1 dhclient: DHCPREQUEST of 192.168.1.2 on wlan0 to 255.255.255.255 port 67 (xid=0x29e53d96) Dec 7 14:56:12 xxx1 dhclient: DHCPACK of 192.168.1.2 from 192.168.1.1 Dec 7 14:56:12 xxx1 dhclient: bound to 192.168.1.2 -- renewal in 43010 seconds. Dec 7 14:56:12 xxx1 NetworkManager[1285]: (wlan0): DHCPv4 state changed preinit -> reboot Dec 7 14:56:12 xxx1 NetworkManager[1285]: address 192.168.1.2 Dec 7 14:56:12 xxx1 NetworkManager[1285]: prefix 24 (255.255.255.0) Dec 7 14:56:12 xxx1 NetworkManager[1285]: gateway 192.168.1.1 Dec 7 14:56:12 xxx1 NetworkManager[1285]: nameserver '192.168.1.1' Dec 7 14:56:12 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 5 of 5 (IPv4 Configure Commit) scheduled... Dec 7 14:56:12 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 5 of 5 (IPv4 Commit) started... Dec 7 14:56:13 xxx1 NetworkManager[1285]: (wlan0): device state change: ip-config -> secondaries (reason 'none') [70 90 0] Dec 7 14:56:13 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 5 of 5 (IPv4 Commit) complete. Dec 7 14:56:13 xxx1 NetworkManager[1285]: (wlan0): device state change: secondaries -> activated (reason 'none') [90 100 0] Dec 7 14:56:13 xxx1 NetworkManager[1285]: Policy set 'Sixty-Seven' (wlan0) as default for IPv4 routing and DNS. Dec 7 14:56:13 xxx1 NetworkManager[1285]: Writing DNS information to /sbin/resolvconf Dec 7 14:56:13 xxx1 NetworkManager[1285]: Activation (wlan0) successful, device activated. Dec 7 14:56:34 xxx1 NetworkManager[1285]: (wlan0): IP6 addrconf timed out or failed. Dec 7 14:56:34 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled... Dec 7 14:56:34 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) started... Dec 7 14:56:34 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) complete. Dec 7 14:56:40 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 14:56:40 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 14:59:49 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 15:01:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 15:01:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 15:07:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 15:07:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 15:27:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 15:29:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 15:31:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 15:32:57 NetworkManager[1285]: last message repeated 3 times Dec 7 15:35:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 15:41:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 15:41:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 16:11:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 16:13:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 16:21:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 16:33:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 16:33:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 16:39:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 16:41:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 16:41:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 16:55:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 16:55:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 17:07:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 17:07:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 17:09:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 17:11:00 NetworkManager[1285]: last message repeated 2 times Dec 7 17:17:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 17:17:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 17:19:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 17:19:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 17:23:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 17:23:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 17:35:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 17:35:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 17:41:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 17:41:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 17:55:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 17:55:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 18:03:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 18:15:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 18:15:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 18:19:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 18:27:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 18:27:32 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 18:35:10 xxx1 NetworkManager[1285]: (wlan0): device state change: activated -> disconnected (reason 'user-requested') [100 30 39] Dec 7 18:35:10 xxx1 NetworkManager[1285]: (wlan0): deactivating device (reason 'user-requested') [39] Dec 7 18:35:10 xxx1 NetworkManager[1285]: (wlan0): canceled DHCP transaction, DHCP client pid 27230 Dec 7 18:35:10 xxx1 kernel: [171525.778228] wlan0: deauthenticating from 44:94:fc:3d:27:7c by local choice (reason=3) Dec 7 18:35:10 xxx1 kernel: [171525.803734] cfg80211: Calling CRDA to update world regulatory domain Dec 7 18:35:10 xxx1 wpa_supplicant[1477]: wlan0: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=3 Dec 7 18:35:10 xxx1 NetworkManager[1285]: DNS: plugin dnsmasq update failed Dec 7 18:35:10 xxx1 NetworkManager[1285]: Removing DNS information from /sbin/resolvconf Dec 7 18:35:10 xxx1 kernel: [171525.812684] cfg80211: World regulatory domain updated: Dec 7 18:35:10 xxx1 kernel: [171525.812689] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) Dec 7 18:35:10 xxx1 kernel: [171525.812693] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 18:35:10 xxx1 kernel: [171525.812696] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) Dec 7 18:35:10 xxx1 kernel: [171525.812699] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) Dec 7 18:35:10 xxx1 kernel: [171525.812701] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 18:35:10 xxx1 kernel: [171525.812704] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 18:35:10 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: completed -> disconnected Dec 7 18:35:15 xxx1 NetworkManager[1285]: Activation (wlan0) starting connection 'AndroidAP' Dec 7 18:35:15 xxx1 NetworkManager[1285]: (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0] Dec 7 18:35:15 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Dec 7 18:35:15 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Dec 7 18:35:15 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Dec 7 18:35:15 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Dec 7 18:35:15 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Dec 7 18:35:15 xxx1 NetworkManager[1285]: (wlan0): device state change: prepare -> config (reason 'none') [40 50 0] Dec 7 18:35:15 xxx1 NetworkManager[1285]: Activation (wlan0/wireless): access point 'AndroidAP' has security, but secrets are required. Dec 7 18:35:15 xxx1 NetworkManager[1285]: (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0] Dec 7 18:35:15 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Dec 7 18:35:15 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Dec 7 18:35:15 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Dec 7 18:35:15 xxx1 NetworkManager[1285]: (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0] Dec 7 18:35:15 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Dec 7 18:35:15 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Dec 7 18:35:15 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Dec 7 18:35:15 xxx1 NetworkManager[1285]: (wlan0): device state change: prepare -> config (reason 'none') [40 50 0] Dec 7 18:35:15 xxx1 NetworkManager[1285]: Activation (wlan0/wireless): connection 'AndroidAP' has security, and secrets exist. No new secrets needed. Dec 7 18:35:15 xxx1 NetworkManager[1285]: Config: added 'ssid' value 'AndroidAP' Dec 7 18:35:15 xxx1 NetworkManager[1285]: Config: added 'scan_ssid' value '1' Dec 7 18:35:15 xxx1 NetworkManager[1285]: Config: added 'key_mgmt' value 'WPA-PSK' Dec 7 18:35:15 xxx1 NetworkManager[1285]: Config: added 'psk' value '' Dec 7 18:35:15 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Dec 7 18:35:15 xxx1 NetworkManager[1285]: Config: set interface ap_scan to 1 Dec 7 18:35:15 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: disconnected -> scanning Dec 7 18:35:19 xxx1 wpa_supplicant[1477]: wlan0: SME: Trying to authenticate with 74:a7:22:61:c8:7b (SSID='AndroidAP' freq=2437 MHz) Dec 7 18:35:19 xxx1 kernel: [171534.724895] wlan0: authenticate with 74:a7:22:61:c8:7b Dec 7 18:35:19 xxx1 wpa_supplicant[1477]: wlan0: Trying to associate with 74:a7:22:61:c8:7b (SSID='AndroidAP' freq=2437 MHz) Dec 7 18:35:19 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: scanning -> authenticating Dec 7 18:35:19 xxx1 kernel: [171534.731718] wlan0: send auth to 74:a7:22:61:c8:7b (try 1/3) Dec 7 18:35:19 xxx1 kernel: [171534.734108] wlan0: authenticated Dec 7 18:35:19 xxx1 kernel: [171534.735447] wlan0: associate with 74:a7:22:61:c8:7b (try 1/3) Dec 7 18:35:19 xxx1 wpa_supplicant[1477]: wlan0: Associated with 74:a7:22:61:c8:7b Dec 7 18:35:19 xxx1 kernel: [171534.740271] wlan0: RX AssocResp from 74:a7:22:61:c8:7b (capab=0x431 status=0 aid=1) Dec 7 18:35:19 xxx1 kernel: [171534.740339] wlan0: associated Dec 7 18:35:19 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: authenticating -> associating Dec 7 18:35:19 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: associating -> 4-way handshake Dec 7 18:35:19 xxx1 wpa_supplicant[1477]: wlan0: WPA: Key negotiation completed with 74:a7:22:61:c8:7b [PTK=CCMP GTK=CCMP] Dec 7 18:35:19 xxx1 wpa_supplicant[1477]: wlan0: CTRL-EVENT-CONNECTED - Connection to 74:a7:22:61:c8:7b completed (reauth) [id=0 id_str=] Dec 7 18:35:19 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: 4-way handshake -> completed Dec 7 18:35:19 xxx1 NetworkManager[1285]: Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'AndroidAP'. Dec 7 18:35:19 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled. Dec 7 18:35:19 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) started... Dec 7 18:35:19 xxx1 NetworkManager[1285]: (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0] Dec 7 18:35:19 xxx1 NetworkManager[1285]: Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds) Dec 7 18:35:19 xxx1 NetworkManager[1285]: dhclient started with pid 30520 Dec 7 18:35:19 xxx1 NetworkManager[1285]: Activation (wlan0) Beginning IP6 addrconf. Dec 7 18:35:19 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete. Dec 7 18:35:19 xxx1 dhclient: Internet Systems Consortium DHCP Client 4.2.4 Dec 7 18:35:19 xxx1 dhclient: Copyright 2004-2012 Internet Systems Consortium. Dec 7 18:35:19 xxx1 dhclient: All rights reserved. Dec 7 18:35:19 xxx1 dhclient: For info, please visit https://www.isc.org/software/dhcp/ Dec 7 18:35:19 xxx1 dhclient: Dec 7 18:35:19 xxx1 NetworkManager[1285]: (wlan0): DHCPv4 state changed nbi -> preinit Dec 7 18:35:19 xxx1 dhclient: Listening on LPF/wlan0/a4:17:31:0d:4a:6f Dec 7 18:35:19 xxx1 dhclient: Sending on LPF/wlan0/a4:17:31:0d:4a:6f Dec 7 18:35:19 xxx1 dhclient: Sending on Socket/fallback Dec 7 18:35:19 xxx1 dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 3 (xid=0x20c81b3) Dec 7 18:35:19 xxx1 dhclient: DHCPREQUEST of 192.168.43.78 on wlan0 to 255.255.255.255 port 67 (xid=0x20c81b3) Dec 7 18:35:19 xxx1 dhclient: DHCPOFFER of 192.168.43.78 from 192.168.43.1 Dec 7 18:35:19 xxx1 dhclient: DHCPACK of 192.168.43.78 from 192.168.43.1 Dec 7 18:35:19 xxx1 dhclient: bound to 192.168.43.78 -- renewal in 1446 seconds. Dec 7 18:35:19 xxx1 NetworkManager[1285]: (wlan0): DHCPv4 state changed preinit -> bound Dec 7 18:35:19 xxx1 NetworkManager[1285]: address 192.168.43.78 Dec 7 18:35:19 xxx1 NetworkManager[1285]: prefix 24 (255.255.255.0) Dec 7 18:35:19 xxx1 NetworkManager[1285]: gateway 192.168.43.1 Dec 7 18:35:19 xxx1 NetworkManager[1285]: hostname 'xxx1' Dec 7 18:35:19 xxx1 NetworkManager[1285]: nameserver '192.168.43.1' Dec 7 18:35:19 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 5 of 5 (IPv4 Configure Commit) scheduled... Dec 7 18:35:19 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 5 of 5 (IPv4 Commit) started... Dec 7 18:35:20 xxx1 NetworkManager[1285]: (wlan0): device state change: ip-config -> secondaries (reason 'none') [70 90 0] Dec 7 18:35:20 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 5 of 5 (IPv4 Commit) complete. Dec 7 18:35:20 xxx1 NetworkManager[1285]: (wlan0): device state change: secondaries -> activated (reason 'none') [90 100 0] Dec 7 18:35:20 xxx1 NetworkManager[1285]: Policy set 'AndroidAP' (wlan0) as default for IPv4 routing and DNS. Dec 7 18:35:20 xxx1 NetworkManager[1285]: Writing DNS information to /sbin/resolvconf Dec 7 18:35:21 xxx1 NetworkManager[1285]: Activation (wlan0) successful, device activated. Dec 7 18:35:39 xxx1 NetworkManager[1285]: (wlan0): IP6 addrconf timed out or failed. Dec 7 18:35:39 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled... Dec 7 18:35:39 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) started... Dec 7 18:35:39 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) complete. Dec 7 18:37:33 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 18:37:39 xxx1 NetworkManager[1285]: (wlan0): disconnecting for new activation request. Dec 7 18:37:39 xxx1 NetworkManager[1285]: (wlan0): device state change: activated -> disconnected (reason 'none') [100 30 0] Dec 7 18:37:39 xxx1 NetworkManager[1285]: (wlan0): deactivating device (reason 'none') [0] Dec 7 18:37:39 xxx1 NetworkManager[1285]: (wlan0): canceled DHCP transaction, DHCP client pid 30520 Dec 7 18:37:39 xxx1 kernel: [171674.955298] wlan0: deauthenticating from 74:a7:22:61:c8:7b by local choice (reason=3) Dec 7 18:37:39 xxx1 kernel: [171674.979685] cfg80211: Calling CRDA to update world regulatory domain Dec 7 18:37:39 xxx1 wpa_supplicant[1477]: wlan0: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=3 Dec 7 18:37:39 xxx1 NetworkManager[1285]: DNS: plugin dnsmasq update failed Dec 7 18:37:39 xxx1 NetworkManager[1285]: Removing DNS information from /sbin/resolvconf Dec 7 18:37:39 xxx1 kernel: [171674.988415] cfg80211: World regulatory domain updated: Dec 7 18:37:39 xxx1 kernel: [171674.988421] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) Dec 7 18:37:39 xxx1 kernel: [171674.988425] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 18:37:39 xxx1 kernel: [171674.988428] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) Dec 7 18:37:39 xxx1 kernel: [171674.988430] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) Dec 7 18:37:39 xxx1 kernel: [171674.988433] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 18:37:39 xxx1 kernel: [171674.988435] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 18:37:39 xxx1 NetworkManager[1285]: Activation (wlan0) starting connection 'Sixty-Seven' Dec 7 18:37:39 xxx1 NetworkManager[1285]: (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0] Dec 7 18:37:39 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Dec 7 18:37:39 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: completed -> disconnected Dec 7 18:37:39 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Dec 7 18:37:39 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Dec 7 18:37:39 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Dec 7 18:37:39 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Dec 7 18:37:39 xxx1 NetworkManager[1285]: (wlan0): device state change: prepare -> config (reason 'none') [40 50 0] Dec 7 18:37:39 xxx1 NetworkManager[1285]: Activation (wlan0/wireless): access point 'Sixty-Seven' has security, but secrets are required. Dec 7 18:37:39 xxx1 NetworkManager[1285]: (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0] Dec 7 18:37:39 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Dec 7 18:37:39 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Dec 7 18:37:39 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Dec 7 18:37:39 xxx1 NetworkManager[1285]: (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0] Dec 7 18:37:39 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Dec 7 18:37:39 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Dec 7 18:37:39 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Dec 7 18:37:39 xxx1 NetworkManager[1285]: (wlan0): device state change: prepare -> config (reason 'none') [40 50 0] Dec 7 18:37:39 xxx1 NetworkManager[1285]: Activation (wlan0/wireless): connection 'Sixty-Seven' has security, and secrets exist. No new secrets needed. Dec 7 18:37:39 xxx1 NetworkManager[1285]: Config: added 'ssid' value 'Sixty-Seven' Dec 7 18:37:39 xxx1 NetworkManager[1285]: Config: added 'scan_ssid' value '1' Dec 7 18:37:39 xxx1 NetworkManager[1285]: Config: added 'key_mgmt' value 'WPA-PSK' Dec 7 18:37:39 xxx1 NetworkManager[1285]: Config: added 'auth_alg' value 'OPEN' Dec 7 18:37:39 xxx1 NetworkManager[1285]: Config: added 'psk' value '' Dec 7 18:37:39 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Dec 7 18:37:39 xxx1 NetworkManager[1285]: Config: set interface ap_scan to 1 Dec 7 18:37:40 xxx1 wpa_supplicant[1477]: wlan0: Failed to initiate AP scan Dec 7 18:37:41 xxx1 wpa_supplicant[1477]: wlan0: Failed to initiate AP scan Dec 7 18:37:42 xxx1 wpa_supplicant[1477]: wlan0: Failed to initiate AP scan Dec 7 18:37:43 xxx1 wpa_supplicant[1477]: wlan0: Failed to initiate AP scan Dec 7 18:37:43 xxx1 wpa_supplicant[1477]: wlan0: SME: Trying to authenticate with 44:94:fc:3d:27:7c (SSID='Sixty-Seven' freq=2412 MHz) Dec 7 18:37:43 xxx1 kernel: [171678.820457] wlan0: authenticate with 44:94:fc:3d:27:7c Dec 7 18:37:43 xxx1 kernel: [171678.827328] wlan0: send auth to 44:94:fc:3d:27:7c (try 1/3) Dec 7 18:37:43 xxx1 wpa_supplicant[1477]: wlan0: Trying to associate with 44:94:fc:3d:27:7c (SSID='Sixty-Seven' freq=2412 MHz) Dec 7 18:37:43 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: disconnected -> authenticating Dec 7 18:37:43 xxx1 kernel: [171678.831495] wlan0: authenticated Dec 7 18:37:43 xxx1 kernel: [171678.834902] wlan0: associate with 44:94:fc:3d:27:7c (try 1/3) Dec 7 18:37:43 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: authenticating -> associating Dec 7 18:37:43 xxx1 wpa_supplicant[1477]: wlan0: Associated with 44:94:fc:3d:27:7c Dec 7 18:37:43 xxx1 kernel: [171678.839991] wlan0: RX AssocResp from 44:94:fc:3d:27:7c (capab=0x411 status=0 aid=1) Dec 7 18:37:43 xxx1 kernel: [171678.840069] wlan0: associated Dec 7 18:37:43 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: associating -> associated Dec 7 18:37:44 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: associated -> 4-way handshake Dec 7 18:37:44 xxx1 wpa_supplicant[1477]: wlan0: WPA: Key negotiation completed with 44:94:fc:3d:27:7c [PTK=CCMP GTK=CCMP] Dec 7 18:37:44 xxx1 wpa_supplicant[1477]: wlan0: CTRL-EVENT-CONNECTED - Connection to 44:94:fc:3d:27:7c completed (reauth) [id=0 id_str=] Dec 7 18:37:44 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: 4-way handshake -> completed Dec 7 18:37:44 xxx1 NetworkManager[1285]: Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'Sixty-Seven'. Dec 7 18:37:44 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled. Dec 7 18:37:44 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) started... Dec 7 18:37:44 xxx1 NetworkManager[1285]: (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0] Dec 7 18:37:44 xxx1 NetworkManager[1285]: Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds) Dec 7 18:37:44 xxx1 NetworkManager[1285]: dhclient started with pid 30650 Dec 7 18:37:44 xxx1 NetworkManager[1285]: Activation (wlan0) Beginning IP6 addrconf. Dec 7 18:37:44 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete. Dec 7 18:37:44 xxx1 dhclient: Internet Systems Consortium DHCP Client 4.2.4 Dec 7 18:37:44 xxx1 dhclient: Copyright 2004-2012 Internet Systems Consortium. Dec 7 18:37:44 xxx1 dhclient: All rights reserved. Dec 7 18:37:44 xxx1 dhclient: For info, please visit https://www.isc.org/software/dhcp/ Dec 7 18:37:44 xxx1 dhclient: Dec 7 18:37:44 xxx1 NetworkManager[1285]: (wlan0): DHCPv4 state changed nbi -> preinit Dec 7 18:37:44 xxx1 dhclient: Listening on LPF/wlan0/a4:17:31:0d:4a:6f Dec 7 18:37:44 xxx1 dhclient: Sending on LPF/wlan0/a4:17:31:0d:4a:6f Dec 7 18:37:44 xxx1 dhclient: Sending on Socket/fallback Dec 7 18:37:44 xxx1 dhclient: DHCPREQUEST of 192.168.1.2 on wlan0 to 255.255.255.255 port 67 (xid=0x905f763) Dec 7 18:37:44 xxx1 dhclient: DHCPNAK from 192.168.1.1 (xid=0x905f763) Dec 7 18:37:44 xxx1 NetworkManager[1285]: (wlan0): DHCPv4 state changed preinit -> expire Dec 7 18:37:44 xxx1 dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 3 (xid=0x345fec22) Dec 7 18:37:44 xxx1 NetworkManager[1285]: (wlan0): DHCPv4 state changed expire -> preinit Dec 7 18:37:44 xxx1 dhclient: DHCPREQUEST of 192.168.1.2 on wlan0 to 255.255.255.255 port 67 (xid=0x345fec22) Dec 7 18:37:44 xxx1 dhclient: DHCPOFFER of 192.168.1.2 from 192.168.1.1 Dec 7 18:37:44 xxx1 dhclient: DHCPACK of 192.168.1.2 from 192.168.1.1 Dec 7 18:37:44 xxx1 dhclient: bound to 192.168.1.2 -- renewal in 37511 seconds. Dec 7 18:37:44 xxx1 NetworkManager[1285]: (wlan0): DHCPv4 state changed preinit -> bound Dec 7 18:37:44 xxx1 NetworkManager[1285]: address 192.168.1.2 Dec 7 18:37:44 xxx1 NetworkManager[1285]: prefix 24 (255.255.255.0) Dec 7 18:37:44 xxx1 NetworkManager[1285]: gateway 192.168.1.1 Dec 7 18:37:44 xxx1 NetworkManager[1285]: nameserver '192.168.1.1' Dec 7 18:37:44 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 5 of 5 (IPv4 Configure Commit) scheduled... Dec 7 18:37:44 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 5 of 5 (IPv4 Commit) started... Dec 7 18:37:45 xxx1 NetworkManager[1285]: (wlan0): device state change: ip-config -> secondaries (reason 'none') [70 90 0] Dec 7 18:37:45 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 5 of 5 (IPv4 Commit) complete. Dec 7 18:37:45 xxx1 NetworkManager[1285]: (wlan0): device state change: secondaries -> activated (reason 'none') [90 100 0] Dec 7 18:37:45 xxx1 NetworkManager[1285]: Policy set 'Sixty-Seven' (wlan0) as default for IPv4 routing and DNS. Dec 7 18:37:45 xxx1 NetworkManager[1285]: Writing DNS information to /sbin/resolvconf Dec 7 18:37:45 xxx1 NetworkManager[1285]: Activation (wlan0) successful, device activated. Dec 7 18:38:04 xxx1 NetworkManager[1285]: (wlan0): IP6 addrconf timed out or failed. Dec 7 18:38:04 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled... Dec 7 18:38:04 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) started... Dec 7 18:38:04 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) complete. Dec 7 18:38:17 xxx1 NetworkManager[1285]: nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted Dec 7 18:48:55 xxx1 NetworkManager[1285]: (wlan0): disconnecting for new activation request. Dec 7 18:48:55 xxx1 NetworkManager[1285]: (wlan0): device state change: activated -> disconnected (reason 'none') [100 30 0] Dec 7 18:48:55 xxx1 NetworkManager[1285]: (wlan0): deactivating device (reason 'none') [0] Dec 7 18:48:56 xxx1 NetworkManager[1285]: (wlan0): canceled DHCP transaction, DHCP client pid 30650 Dec 7 18:48:56 xxx1 kernel: [172350.574209] wlan0: deauthenticating from 44:94:fc:3d:27:7c by local choice (reason=3) Dec 7 18:48:56 xxx1 NetworkManager[1285]: DNS: plugin dnsmasq update failed Dec 7 18:48:56 xxx1 NetworkManager[1285]: Removing DNS information from /sbin/resolvconf Dec 7 18:48:56 xxx1 wpa_supplicant[1477]: wlan0: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=3 Dec 7 18:48:56 xxx1 kernel: [172350.620684] cfg80211: Calling CRDA to update world regulatory domain Dec 7 18:48:56 xxx1 kernel: [172350.628806] cfg80211: World regulatory domain updated: Dec 7 18:48:56 xxx1 kernel: [172350.628811] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) Dec 7 18:48:56 xxx1 kernel: [172350.628814] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 18:48:56 xxx1 kernel: [172350.628817] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) Dec 7 18:48:56 xxx1 kernel: [172350.628820] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) Dec 7 18:48:56 xxx1 kernel: [172350.628822] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 18:48:56 xxx1 kernel: [172350.628825] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 18:48:56 xxx1 NetworkManager[1285]: Activation (wlan0) starting connection 'AndroidAP' Dec 7 18:48:56 xxx1 NetworkManager[1285]: (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0] Dec 7 18:48:56 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Dec 7 18:48:56 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: completed -> disconnected Dec 7 18:48:56 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Dec 7 18:48:56 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Dec 7 18:48:56 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Dec 7 18:48:56 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Dec 7 18:48:56 xxx1 NetworkManager[1285]: (wlan0): device state change: prepare -> config (reason 'none') [40 50 0] Dec 7 18:48:56 xxx1 NetworkManager[1285]: Activation (wlan0/wireless): access point 'AndroidAP' has security, but secrets are required. Dec 7 18:48:56 xxx1 NetworkManager[1285]: (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0] Dec 7 18:48:56 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Dec 7 18:48:56 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Dec 7 18:48:56 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Dec 7 18:48:56 xxx1 NetworkManager[1285]: (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0] Dec 7 18:48:56 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Dec 7 18:48:56 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Dec 7 18:48:56 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Dec 7 18:48:56 xxx1 NetworkManager[1285]: (wlan0): device state change: prepare -> config (reason 'none') [40 50 0] Dec 7 18:48:56 xxx1 NetworkManager[1285]: Activation (wlan0/wireless): connection 'AndroidAP' has security, and secrets exist. No new secrets needed. Dec 7 18:48:56 xxx1 NetworkManager[1285]: Config: added 'ssid' value 'AndroidAP' Dec 7 18:48:56 xxx1 NetworkManager[1285]: Config: added 'scan_ssid' value '1' Dec 7 18:48:56 xxx1 NetworkManager[1285]: Config: added 'key_mgmt' value 'WPA-PSK' Dec 7 18:48:56 xxx1 NetworkManager[1285]: Config: added 'psk' value '' Dec 7 18:48:56 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Dec 7 18:48:56 xxx1 NetworkManager[1285]: Config: set interface ap_scan to 1 Dec 7 18:48:57 xxx1 wpa_supplicant[1477]: wlan0: Failed to initiate AP scan Dec 7 18:48:58 xxx1 wpa_supplicant[1477]: wlan0: Failed to initiate AP scan Dec 7 18:48:59 xxx1 wpa_supplicant[1477]: wlan0: Failed to initiate AP scan Dec 7 18:49:00 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: disconnected -> scanning Dec 7 18:49:21 xxx1 NetworkManager[1285]: Activation (wlan0/wireless): association took too long, failing activation. Dec 7 18:49:21 xxx1 NetworkManager[1285]: (wlan0): device state change: config -> failed (reason 'SSID not found') [50 120 53] Dec 7 18:49:21 xxx1 NetworkManager[1285]: Activation (wlan0) failed for connection 'AndroidAP' Dec 7 18:49:21 xxx1 NetworkManager[1285]: (wlan0): device state change: failed -> disconnected (reason 'none') [120 30 0] Dec 7 18:49:21 xxx1 NetworkManager[1285]: (wlan0): deactivating device (reason 'none') [0] Dec 7 18:49:21 xxx1 NetworkManager[1285]: Couldn't disconnect supplicant interface: This interface is not connected. Dec 7 18:49:21 xxx1 NetworkManager[1285]: Couldn't disconnect supplicant interface: This interface is not connected. Dec 7 18:49:21 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: scanning -> inactive Dec 7 18:49:24 xxx1 NetworkManager[1285]: Auto-activating connection 'AndroidAP'. Dec 7 18:49:24 xxx1 NetworkManager[1285]: Activation (wlan0) starting connection 'AndroidAP' Dec 7 18:49:24 xxx1 NetworkManager[1285]: (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0] Dec 7 18:49:24 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Dec 7 18:49:24 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Dec 7 18:49:24 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Dec 7 18:49:24 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Dec 7 18:49:24 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Dec 7 18:49:24 xxx1 NetworkManager[1285]: (wlan0): device state change: prepare -> config (reason 'none') [40 50 0] Dec 7 18:49:24 xxx1 NetworkManager[1285]: Activation (wlan0/wireless): access point 'AndroidAP' has security, but secrets are required. Dec 7 18:49:24 xxx1 NetworkManager[1285]: (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0] Dec 7 18:49:24 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Dec 7 18:49:24 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Dec 7 18:49:24 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Dec 7 18:49:24 xxx1 NetworkManager[1285]: (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0] Dec 7 18:49:24 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Dec 7 18:49:24 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Dec 7 18:49:24 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Dec 7 18:49:24 xxx1 NetworkManager[1285]: (wlan0): device state change: prepare -> config (reason 'none') [40 50 0] Dec 7 18:49:24 xxx1 NetworkManager[1285]: Activation (wlan0/wireless): connection 'AndroidAP' has security, and secrets exist. No new secrets needed. Dec 7 18:49:24 xxx1 NetworkManager[1285]: Config: added 'ssid' value 'AndroidAP' Dec 7 18:49:24 xxx1 NetworkManager[1285]: Config: added 'scan_ssid' value '1' Dec 7 18:49:24 xxx1 NetworkManager[1285]: Config: added 'key_mgmt' value 'WPA-PSK' Dec 7 18:49:24 xxx1 NetworkManager[1285]: Config: added 'psk' value '' Dec 7 18:49:24 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Dec 7 18:49:24 xxx1 NetworkManager[1285]: Config: set interface ap_scan to 1 Dec 7 18:49:24 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: inactive -> scanning Dec 7 18:49:42 xxx1 NetworkManager[1285]: (wlan0): disconnecting for new activation request. Dec 7 18:49:42 xxx1 NetworkManager[1285]: (wlan0): device state change: config -> disconnected (reason 'none') [50 30 0] Dec 7 18:49:42 xxx1 NetworkManager[1285]: (wlan0): deactivating device (reason 'none') [0] Dec 7 18:49:42 xxx1 NetworkManager[1285]: Activation (wlan0) starting connection 'AndroidAP' Dec 7 18:49:42 xxx1 NetworkManager[1285]: (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0] Dec 7 18:49:42 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Dec 7 18:49:42 xxx1 NetworkManager[1285]: Couldn't disconnect supplicant interface: This interface is not connected. Dec 7 18:49:42 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Dec 7 18:49:42 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Dec 7 18:49:42 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Dec 7 18:49:42 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Dec 7 18:49:42 xxx1 NetworkManager[1285]: (wlan0): device state change: prepare -> config (reason 'none') [40 50 0] Dec 7 18:49:42 xxx1 NetworkManager[1285]: Activation (wlan0/wireless): connection 'AndroidAP' has security, and secrets exist. No new secrets needed. Dec 7 18:49:42 xxx1 NetworkManager[1285]: Config: added 'ssid' value 'AndroidAP' Dec 7 18:49:42 xxx1 NetworkManager[1285]: Config: added 'scan_ssid' value '1' Dec 7 18:49:42 xxx1 NetworkManager[1285]: Config: added 'key_mgmt' value 'WPA-PSK' Dec 7 18:49:42 xxx1 NetworkManager[1285]: Config: added 'psk' value '' Dec 7 18:49:42 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Dec 7 18:49:42 xxx1 NetworkManager[1285]: Couldn't disconnect supplicant interface: This interface is not connected. Dec 7 18:49:42 xxx1 NetworkManager[1285]: Config: set interface ap_scan to 1 Dec 7 18:49:43 xxx1 wpa_supplicant[1477]: wlan0: Failed to initiate AP scan Dec 7 18:49:49 wpa_supplicant[1477]: last message repeated 2 times Dec 7 18:49:49 xxx1 wpa_supplicant[1477]: wlan0: SME: Trying to authenticate with 74:a7:22:61:c8:7b (SSID='AndroidAP' freq=2412 MHz) Dec 7 18:49:49 xxx1 kernel: [172404.096774] wlan0: authenticate with 74:a7:22:61:c8:7b Dec 7 18:49:49 xxx1 kernel: [172404.103745] wlan0: send auth to 74:a7:22:61:c8:7b (try 1/3) Dec 7 18:49:49 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: scanning -> authenticating Dec 7 18:49:49 xxx1 wpa_supplicant[1477]: wlan0: Trying to associate with 74:a7:22:61:c8:7b (SSID='AndroidAP' freq=2412 MHz) Dec 7 18:49:49 xxx1 kernel: [172404.106110] wlan0: authenticated Dec 7 18:49:49 xxx1 kernel: [172404.107936] wlan0: associate with 74:a7:22:61:c8:7b (try 1/3) Dec 7 18:49:49 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: authenticating -> associating Dec 7 18:49:49 xxx1 wpa_supplicant[1477]: wlan0: Associated with 74:a7:22:61:c8:7b Dec 7 18:49:49 xxx1 kernel: [172404.114662] wlan0: RX AssocResp from 74:a7:22:61:c8:7b (capab=0x431 status=0 aid=1) Dec 7 18:49:49 xxx1 kernel: [172404.114711] wlan0: associated Dec 7 18:49:49 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: associating -> 4-way handshake Dec 7 18:49:49 xxx1 wpa_supplicant[1477]: wlan0: WPA: Key negotiation completed with 74:a7:22:61:c8:7b [PTK=CCMP GTK=CCMP] Dec 7 18:49:49 xxx1 wpa_supplicant[1477]: wlan0: CTRL-EVENT-CONNECTED - Connection to 74:a7:22:61:c8:7b completed (reauth) [id=0 id_str=] Dec 7 18:49:49 xxx1 NetworkManager[1285]: (wlan0): supplicant interface state: 4-way handshake -> completed Dec 7 18:49:49 xxx1 NetworkManager[1285]: Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'AndroidAP'. Dec 7 18:49:49 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled. Dec 7 18:49:49 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) started... Dec 7 18:49:49 xxx1 NetworkManager[1285]: (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0] Dec 7 18:49:49 xxx1 NetworkManager[1285]: Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds) Dec 7 18:49:49 xxx1 NetworkManager[1285]: dhclient started with pid 30805 Dec 7 18:49:49 xxx1 NetworkManager[1285]: Activation (wlan0) Beginning IP6 addrconf. Dec 7 18:49:49 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete. Dec 7 18:49:49 xxx1 dhclient: Internet Systems Consortium DHCP Client 4.2.4 Dec 7 18:49:49 xxx1 dhclient: Copyright 2004-2012 Internet Systems Consortium. Dec 7 18:49:49 xxx1 dhclient: All rights reserved. Dec 7 18:49:49 xxx1 dhclient: For info, please visit https://www.isc.org/software/dhcp/ Dec 7 18:49:49 xxx1 dhclient: Dec 7 18:49:49 xxx1 NetworkManager[1285]: (wlan0): DHCPv4 state changed nbi -> preinit Dec 7 18:49:49 xxx1 dhclient: Listening on LPF/wlan0/a4:17:31:0d:4a:6f Dec 7 18:49:49 xxx1 dhclient: Sending on LPF/wlan0/a4:17:31:0d:4a:6f Dec 7 18:49:49 xxx1 dhclient: Sending on Socket/fallback Dec 7 18:49:49 xxx1 dhclient: DHCPREQUEST of 192.168.43.78 on wlan0 to 255.255.255.255 port 67 (xid=0x38de3113) Dec 7 18:49:49 xxx1 dhclient: DHCPACK of 192.168.43.78 from 192.168.43.1 Dec 7 18:49:49 xxx1 dhclient: bound to 192.168.43.78 -- renewal in 1739 seconds. Dec 7 18:49:49 xxx1 NetworkManager[1285]: (wlan0): DHCPv4 state changed preinit -> reboot Dec 7 18:49:49 xxx1 NetworkManager[1285]: address 192.168.43.78 Dec 7 18:49:49 xxx1 NetworkManager[1285]: prefix 24 (255.255.255.0) Dec 7 18:49:49 xxx1 NetworkManager[1285]: gateway 192.168.43.1 Dec 7 18:49:49 xxx1 NetworkManager[1285]: hostname 'xxx1' Dec 7 18:49:49 xxx1 NetworkManager[1285]: nameserver '192.168.43.1' Dec 7 18:49:49 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 5 of 5 (IPv4 Configure Commit) scheduled... Dec 7 18:49:49 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 5 of 5 (IPv4 Commit) started... Dec 7 18:49:50 xxx1 NetworkManager[1285]: (wlan0): device state change: ip-config -> secondaries (reason 'none') [70 90 0] Dec 7 18:49:50 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 5 of 5 (IPv4 Commit) complete. Dec 7 18:49:50 xxx1 NetworkManager[1285]: (wlan0): device state change: secondaries -> activated (reason 'none') [90 100 0] Dec 7 18:49:50 xxx1 NetworkManager[1285]: Policy set 'AndroidAP' (wlan0) as default for IPv4 routing and DNS. Dec 7 18:49:50 xxx1 NetworkManager[1285]: Writing DNS information to /sbin/resolvconf Dec 7 18:49:51 xxx1 NetworkManager[1285]: (wlan0): roamed from BSSID 74:A7:22:61:C8:7B (AndroidAP) to 74:A7:22:61:C8:7B (AndroidAP) Dec 7 18:49:51 xxx1 NetworkManager[1285]: Activation (wlan0) successful, device activated. Dec 7 18:50:10 xxx1 NetworkManager[1285]: (wlan0): IP6 addrconf timed out or failed. Dec 7 18:50:10 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled... Dec 7 18:50:10 xxx1 NetworkManager[1285]: Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) started... Dec 7 18:50:12 xxx1 NetworkManager[30907]: NetworkManager (version 0.9.8.0) is starting... Dec 7 18:50:12 xxx1 NetworkManager[30907]: Read config file /etc/NetworkManager/NetworkManager.conf Dec 7 18:50:12 xxx1 NetworkManager[30907]: WEXT support is enabled Dec 7 18:50:12 xxx1 NetworkManager[30907]: VPN: loaded org.freedesktop.NetworkManager.pptp Dec 7 18:50:12 xxx1 NetworkManager[30907]: DNS: loaded plugin dnsmasq Dec 7 18:50:12 xxx1 NetworkManager[30907]: SCPlugin-Ifupdown: init! Dec 7 18:50:12 xxx1 NetworkManager[30907]: SCPlugin-Ifupdown: update_system_hostname Dec 7 18:50:12 xxx1 NetworkManager[30907]: SCPluginIfupdown: management mode: unmanaged Dec 7 18:50:12 xxx1 NetworkManager[30907]: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1c.0/0000:02:00.0/net/eth0, iface: eth0) Dec 7 18:50:12 xxx1 NetworkManager[30907]: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1c.0/0000:02:00.0/net/eth0, iface: eth0): no ifupdown configuration found. Dec 7 18:50:12 xxx1 NetworkManager[30907]: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/net/wlan0, iface: wlan0) Dec 7 18:50:12 xxx1 NetworkManager[30907]: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/net/wlan0, iface: wlan0): no ifupdown configuration found. Dec 7 18:50:12 xxx1 NetworkManager[30907]: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/lo, iface: lo) Dec 7 18:50:12 xxx1 NetworkManager[30907]: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/lo, iface: lo): no ifupdown configuration found. Dec 7 18:50:12 xxx1 NetworkManager[30907]: SCPlugin-Ifupdown: end _init. Dec 7 18:50:12 xxx1 NetworkManager[30907]: Loaded plugin ifupdown: (C) 2008 Canonical Ltd. To report bugs please use the NetworkManager mailing list. Dec 7 18:50:12 xxx1 NetworkManager[30907]: Loaded plugin keyfile: (c) 2007 - 2010 Red Hat, Inc. To report bugs please use the NetworkManager mailing list. Dec 7 18:50:12 xxx1 NetworkManager[30907]: Ifupdown: get unmanaged devices count: 0 Dec 7 18:50:12 xxx1 NetworkManager[30907]: SCPlugin-Ifupdown: (16948400) ... get_connections. Dec 7 18:50:12 xxx1 NetworkManager[30907]: SCPlugin-Ifupdown: (16948400) ... get_connections (managed=false): return empty list. Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: parsing AndroidAP ... Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: read connection 'AndroidAP' Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: parsing JAZZTEL_4794 ... Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: read connection 'JAZZTEL_4794' Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: parsing WLAN_97FF ... Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: read connection 'WLAN_97FF' Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: parsing vodafoneBA80 ... Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: read connection 'vodafoneBA80' Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: parsing Sixty-Seven ... Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: read connection 'Sixty-Seven' Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: parsing fff ... Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: read connection 'fff' Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: parsing WLAN_9C4D ... Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: read connection 'WLAN_9C4D' Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: parsing PLANETCOFFEE ... Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: read connection 'PLANETCOFFEE' Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: parsing central ... Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: read connection 'central' Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: parsing WLAN_1B57 ... Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: read connection 'WLAN_1B57' Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: parsing WLAN_7EF7 ... Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: read connection 'WLAN_7EF7' Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: parsing LALDILA ... Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: read connection 'LALDILA' Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: parsing JAZZTEL_62C0 ... Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: read connection 'JAZZTEL_62C0' Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: parsing WLAN_51ED ... Dec 7 18:50:12 xxx1 NetworkManager[30907]: keyfile: read connection 'WLAN_51ED' Dec 7 18:50:12 xxx1 NetworkManager[30907]: Ifupdown: get unmanaged devices count: 0 Dec 7 18:50:12 xxx1 NetworkManager[30907]: modem-manager is now available Dec 7 18:50:12 xxx1 NetworkManager[30907]: monitoring kernel firmware directory '/lib/firmware'. Dec 7 18:50:12 xxx1 NetworkManager[30907]: rfkill0: found WiFi radio killswitch (at /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/ieee80211/phy0/rfkill0) (driver ath9k) Dec 7 18:50:12 xxx1 NetworkManager[30907]: rfkill1: found WiFi radio killswitch (at /sys/devices/platform/acer-wmi/rfkill/rfkill1) (platform driver acer-wmi) Dec 7 18:50:12 xxx1 NetworkManager[30907]: WiFi hardware radio set enabled Dec 7 18:50:12 xxx1 NetworkManager[30907]: WiFi enabled by radio killswitch; enabled by state file Dec 7 18:50:12 xxx1 NetworkManager[30907]: WWAN enabled by radio killswitch; enabled by state file Dec 7 18:50:12 xxx1 NetworkManager[30907]: WiMAX enabled by radio killswitch; enabled by state file Dec 7 18:50:12 xxx1 NetworkManager[30907]: Networking is enabled by state file Dec 7 18:50:12 xxx1 NetworkManager[30907]: failed to allocate link cache: (-10) Operation not supported Dec 7 18:50:12 xxx1 NetworkManager[30907]: (eth0): carrier is OFF Dec 7 18:50:12 xxx1 NetworkManager[30907]: (eth0): new Ethernet device (driver: 'tg3' ifindex: 2) Dec 7 18:50:12 xxx1 NetworkManager[30907]: (eth0): exported as /org/freedesktop/NetworkManager/Devices/0 Dec 7 18:50:12 xxx1 NetworkManager[30907]: (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2] Dec 7 18:50:12 xxx1 NetworkManager[30907]: (eth0): preparing device. Dec 7 18:50:12 xxx1 NetworkManager[30907]: (eth0): deactivating device (reason 'managed') [2] Dec 7 18:50:12 xxx1 NetworkManager[30907]: Added default wired connection 'Wired connection 1' for /sys/devices/pci0000:00/0000:00:1c.0/0000:02:00.0/net/eth0 Dec 7 18:50:12 xxx1 NetworkManager[30907]: (wlan0): using nl80211 for WiFi device control Dec 7 18:50:12 xxx1 NetworkManager[30907]: (wlan0): driver supports Access Point (AP) mode Dec 7 18:50:12 xxx1 NetworkManager[30907]: (wlan0): new 802.11 WiFi device (driver: 'ath9k' ifindex: 3) Dec 7 18:50:12 xxx1 NetworkManager[30907]: (wlan0): exported as /org/freedesktop/NetworkManager/Devices/1 Dec 7 18:50:12 xxx1 NetworkManager[30907]: (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2] Dec 7 18:50:12 xxx1 NetworkManager[30907]: (wlan0): preparing device. Dec 7 18:50:12 xxx1 NetworkManager[30907]: (wlan0): deactivating device (reason 'managed') [2] Dec 7 18:50:12 xxx1 NetworkManager[30907]: /sys/devices/virtual/net/lo: couldn't determine device driver; ignoring... Dec 7 18:50:12 xxx1 NetworkManager[30907]: /sys/devices/virtual/net/lo: couldn't determine device driver; ignoring... Dec 7 18:50:12 xxx1 NetworkManager[30907]: (wlan0) supports 4 scan SSIDs Dec 7 18:50:12 xxx1 NetworkManager[30907]: Trying to remove a non-existant call id. Dec 7 18:50:12 xxx1 NetworkManager[30907]: (wlan0): supplicant interface state: starting -> ready Dec 7 18:50:12 xxx1 NetworkManager[30907]: (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42] Dec 7 18:50:12 xxx1 NetworkManager[30907]: (wlan0): supplicant interface state: ready -> completed Dec 7 18:50:12 xxx1 NetworkManager[30907]: (wlan0) supports 4 scan SSIDs Dec 7 18:50:12 xxx1 NetworkManager[30907]: Auto-activating connection 'Sixty-Seven'. Dec 7 18:50:12 xxx1 NetworkManager[30907]: Activation (wlan0) starting connection 'Sixty-Seven' Dec 7 18:50:12 xxx1 NetworkManager[30907]: (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0] Dec 7 18:50:12 xxx1 NetworkManager[30907]: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Dec 7 18:50:12 xxx1 NetworkManager[30907]: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Dec 7 18:50:12 xxx1 NetworkManager[30907]: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Dec 7 18:50:12 xxx1 NetworkManager[30907]: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Dec 7 18:50:12 xxx1 NetworkManager[30907]: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Dec 7 18:50:12 xxx1 NetworkManager[30907]: (wlan0): device state change: prepare -> config (reason 'none') [40 50 0] Dec 7 18:50:12 xxx1 NetworkManager[30907]: Activation (wlan0/wireless): connection 'Sixty-Seven' has security, and secrets exist. No new secrets needed. Dec 7 18:50:12 xxx1 NetworkManager[30907]: Config: added 'ssid' value 'Sixty-Seven' Dec 7 18:50:12 xxx1 NetworkManager[30907]: Config: added 'scan_ssid' value '1' Dec 7 18:50:12 xxx1 NetworkManager[30907]: Config: added 'key_mgmt' value 'WPA-PSK' Dec 7 18:50:12 xxx1 NetworkManager[30907]: Config: added 'auth_alg' value 'OPEN' Dec 7 18:50:12 xxx1 NetworkManager[30907]: Config: added 'psk' value '' Dec 7 18:50:12 xxx1 NetworkManager[30907]: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Dec 7 18:50:12 xxx1 kernel: [172426.849469] wlan0: deauthenticating from 74:a7:22:61:c8:7b by local choice (reason=3) Dec 7 18:50:12 xxx1 wpa_supplicant[1477]: wlan0: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=3 Dec 7 18:50:12 xxx1 NetworkManager[30907]: (wlan0): supplicant interface state: completed -> disconnected Dec 7 18:50:12 xxx1 NetworkManager[30907]: Config: set interface ap_scan to 1 Dec 7 18:50:12 xxx1 kernel: [172426.870234] cfg80211: Calling CRDA to update world regulatory domain Dec 7 18:50:12 xxx1 kernel: [172426.874513] cfg80211: World regulatory domain updated: Dec 7 18:50:12 xxx1 kernel: [172426.874518] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) Dec 7 18:50:12 xxx1 kernel: [172426.874521] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 18:50:12 xxx1 kernel: [172426.874523] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) Dec 7 18:50:12 xxx1 kernel: [172426.874526] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) Dec 7 18:50:12 xxx1 kernel: [172426.874528] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 18:50:12 xxx1 kernel: [172426.874530] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 18:50:12 xxx1 wpa_supplicant[1477]: wlan0: Failed to initiate AP scan Dec 7 18:50:13 xxx1 wpa_supplicant[1477]: wlan0: Failed to initiate AP scan Dec 7 18:50:14 xxx1 wpa_supplicant[1477]: wlan0: Failed to initiate AP scan Dec 7 18:50:15 xxx1 wpa_supplicant[1477]: wlan0: SME: Trying to authenticate with 44:94:fc:3d:27:7c (SSID='Sixty-Seven' freq=2462 MHz) Dec 7 18:50:15 xxx1 kernel: [172429.920789] wlan0: authenticate with 44:94:fc:3d:27:7c Dec 7 18:50:15 xxx1 wpa_supplicant[1477]: wlan0: Trying to associate with 44:94:fc:3d:27:7c (SSID='Sixty-Seven' freq=2462 MHz) Dec 7 18:50:15 xxx1 kernel: [172429.927694] wlan0: send auth to 44:94:fc:3d:27:7c (try 1/3) Dec 7 18:50:15 xxx1 kernel: [172429.929704] wlan0: authenticated Dec 7 18:50:15 xxx1 kernel: [172429.931388] wlan0: associate with 44:94:fc:3d:27:7c (try 1/3) Dec 7 18:50:15 xxx1 wpa_supplicant[1477]: wlan0: Associated with 44:94:fc:3d:27:7c Dec 7 18:50:15 xxx1 kernel: [172429.935677] wlan0: RX AssocResp from 44:94:fc:3d:27:7c (capab=0x411 status=0 aid=1) Dec 7 18:50:15 xxx1 kernel: [172429.935743] wlan0: associated Dec 7 18:50:15 xxx1 NetworkManager[30907]: (wlan0): supplicant interface state: disconnected -> authenticating Dec 7 18:50:15 xxx1 NetworkManager[30907]: (wlan0): supplicant interface state: authenticating -> associating Dec 7 18:50:15 xxx1 NetworkManager[30907]: (wlan0): supplicant interface state: associating -> associated Dec 7 18:50:15 xxx1 NetworkManager[30907]: (wlan0): supplicant interface state: associated -> 4-way handshake Dec 7 18:50:15 xxx1 wpa_supplicant[1477]: wlan0: WPA: Key negotiation completed with 44:94:fc:3d:27:7c [PTK=CCMP GTK=CCMP] Dec 7 18:50:15 xxx1 wpa_supplicant[1477]: wlan0: CTRL-EVENT-CONNECTED - Connection to 44:94:fc:3d:27:7c completed (reauth) [id=1 id_str=] Dec 7 18:50:15 xxx1 NetworkManager[30907]: (wlan0): supplicant interface state: 4-way handshake -> completed Dec 7 18:50:15 xxx1 NetworkManager[30907]: Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'Sixty-Seven'. Dec 7 18:50:15 xxx1 NetworkManager[30907]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled. Dec 7 18:50:15 xxx1 NetworkManager[30907]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) started... Dec 7 18:50:15 xxx1 NetworkManager[30907]: (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0] Dec 7 18:50:15 xxx1 NetworkManager[30907]: Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds) Dec 7 18:50:15 xxx1 NetworkManager[30907]: dhclient started with pid 30917 Dec 7 18:50:15 xxx1 NetworkManager[30907]: Activation (wlan0) Beginning IP6 addrconf. Dec 7 18:50:15 xxx1 NetworkManager[30907]: Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete. Dec 7 18:50:15 xxx1 dhclient: Internet Systems Consortium DHCP Client 4.2.4 Dec 7 18:50:15 xxx1 dhclient: Copyright 2004-2012 Internet Systems Consortium. Dec 7 18:50:15 xxx1 dhclient: All rights reserved. Dec 7 18:50:15 xxx1 dhclient: For info, please visit https://www.isc.org/software/dhcp/ Dec 7 18:50:15 xxx1 dhclient: Dec 7 18:50:15 xxx1 NetworkManager[30907]: (wlan0): DHCPv4 state changed nbi -> preinit Dec 7 18:50:15 xxx1 dhclient: Listening on LPF/wlan0/a4:17:31:0d:4a:6f Dec 7 18:50:15 xxx1 dhclient: Sending on LPF/wlan0/a4:17:31:0d:4a:6f Dec 7 18:50:15 xxx1 dhclient: Sending on Socket/fallback Dec 7 18:50:15 xxx1 dhclient: DHCPREQUEST of 192.168.1.2 on wlan0 to 255.255.255.255 port 67 (xid=0x32f491f7) Dec 7 18:50:16 xxx1 wpa_supplicant[1477]: wlan0: WPA: Key negotiation completed with 44:94:fc:3d:27:7c [PTK=CCMP GTK=CCMP] Dec 7 18:50:17 xxx1 wpa_supplicant[1477]: wlan0: WPA: Key negotiation completed with 44:94:fc:3d:27:7c [PTK=CCMP GTK=CCMP] Dec 7 18:50:18 xxx1 dhclient: DHCPREQUEST of 192.168.1.2 on wlan0 to 255.255.255.255 port 67 (xid=0x32f491f7) Dec 7 18:50:18 xxx1 wpa_supplicant[1477]: wlan0: WPA: Key negotiation completed with 44:94:fc:3d:27:7c [PTK=CCMP GTK=CCMP] Dec 7 18:50:23 wpa_supplicant[1477]: last message repeated 4 times Dec 7 18:50:23 xxx1 dhclient: DHCPREQUEST of 192.168.1.2 on wlan0 to 255.255.255.255 port 67 (xid=0x32f491f7) Dec 7 18:50:23 xxx1 kernel: [172437.866952] wlan0: deauthenticated from 44:94:fc:3d:27:7c (Reason: 15) Dec 7 18:50:23 xxx1 wpa_supplicant[1477]: wlan0: CTRL-EVENT-DISCONNECTED bssid=44:94:fc:3d:27:7c reason=15 Dec 7 18:50:23 xxx1 kernel: [172437.901777] cfg80211: Calling CRDA to update world regulatory domain Dec 7 18:50:23 xxx1 NetworkManager[30907]: (wlan0): supplicant interface state: completed -> disconnected Dec 7 18:50:23 xxx1 kernel: [172437.910132] cfg80211: World regulatory domain updated: Dec 7 18:50:23 xxx1 kernel: [172437.910138] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) Dec 7 18:50:23 xxx1 kernel: [172437.910141] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 18:50:23 xxx1 kernel: [172437.910144] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) Dec 7 18:50:23 xxx1 kernel: [172437.910147] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) Dec 7 18:50:23 xxx1 kernel: [172437.910149] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 18:50:23 xxx1 kernel: [172437.910152] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Dec 7 18:50:23 xxx1 NetworkManager[30907]: (wlan0): supplicant interface state: disconnected -> scanning Dec 7 18:50:27 xxx1 wpa_supplicant[1477]: wlan0: SME: Trying to authenticate with 44:94:fc:3d:27:7c (SSID='Sixty-Seven' freq=2462 MHz) Dec 7 18:50:27 xxx1 kernel: [172441.838028] wlan0: authenticate with 44:94:fc:3d:27:7c Dec 7 18:50:27 xxx1 NetworkManager[30907]: (wlan0): supplicant interface state: scanning -> authenticating Dec 7 18:50:27 xxx1 kernel: [172441.844370] wlan0: send auth to 44:94:fc:3d:27:7c (try 1/3) Dec 7 18:50:27 xxx1 wpa_supplicant[1477]: wlan0: Trying to associate with 44:94:fc:3d:27:7c (SSID='Sixty-Seven' freq=2462 MHz) Dec 7 18:50:27 xxx1 kernel: [172441.849294] wlan0: authenticated Dec 7 18:50:27 xxx1 kernel: [172441.852263] wlan0: associate with 44:94:fc:3d:27:7c (try 1/3) Dec 7 18:50:27 xxx1 NetworkManager[30907]: (wlan0): supplicant interface state: authenticating -> associating Dec 7 18:50:27 xxx1 wpa_supplicant[1477]: wlan0: Associated with 44:94:fc:3d:27:7c Dec 7 18:50:27 xxx1 kernel: [172441.868968] wlan0: RX AssocResp from 44:94:fc:3d:27:7c (capab=0x411 status=0 aid=1) Dec 7 18:50:27 xxx1 kernel: [172441.869010] wlan0: associated Dec 7 18:50:27 xxx1 NetworkManager[30907]: (wlan0): supplicant interface state: associating -> associated Dec 7 18:50:27 xxx1 NetworkManager[30907]: (wlan0): supplicant interface state: associated -> 4-way handshake Dec 7 18:50:27 xxx1 wpa_supplicant[1477]: wlan0: WPA: Key negotiation completed with 44:94:fc:3d:27:7c [PTK=CCMP GTK=CCMP] Dec 7 18:50:27 xxx1 wpa_supplicant[1477]: wlan0: CTRL-EVENT-CONNECTED - Connection to 44:94:fc:3d:27:7c completed (reauth) [id=1 id_str=] Dec 7 18:50:27 xxx1 NetworkManager[30907]: (wlan0): supplicant interface state: 4-way handshake -> completed Dec 7 18:50:36 xxx1 dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 3 (xid=0x38910353) Dec 7 18:50:36 xxx1 NetworkManager[30907]: (wlan0): IP6 addrconf timed out or failed. Dec 7 18:50:36 xxx1 NetworkManager[30907]: Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled... Dec 7 18:50:36 xxx1 NetworkManager[30907]: Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) started... Dec 7 18:50:36 xxx1 NetworkManager[30907]: Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) complete. Dec 7 18:50:36 xxx1 dhclient: DHCPREQUEST of 192.168.1.2 on wlan0 to 255.255.255.255 port 67 (xid=0x38910353) Dec 7 18:50:36 xxx1 dhclient: DHCPOFFER of 192.168.1.2 from 192.168.1.1 Dec 7 18:50:36 xxx1 dhclient: DHCPACK of 192.168.1.2 from 192.168.1.1 Dec 7 18:50:36 xxx1 dhclient: bound to 192.168.1.2 -- renewal in 37875 seconds. Dec 7 18:50:36 xxx1 NetworkManager[30907]: (wlan0): DHCPv4 state changed preinit -> bound Dec 7 18:50:36 xxx1 NetworkManager[30907]: address 192.168.1.2 Dec 7 18:50:36 xxx1 NetworkManager[30907]: prefix 24 (255.255.255.0) Dec 7 18:50:36 xxx1 NetworkManager[30907]: gateway 192.168.1.1 Dec 7 18:50:36 xxx1 NetworkManager[30907]: nameserver '192.168.1.1' Dec 7 18:50:36 xxx1 NetworkManager[30907]: Activation (wlan0) Stage 5 of 5 (IPv4 Configure Commit) scheduled... Dec 7 18:50:36 xxx1 NetworkManager[30907]: Activation (wlan0) Stage 5 of 5 (IPv4 Commit) started... Dec 7 18:50:37 xxx1 NetworkManager[30907]: (wlan0): device state change: ip-config -> secondaries (reason 'none') [70 90 0] Dec 7 18:50:37 xxx1 NetworkManager[30907]: Activation (wlan0) Stage 5 of 5 (IPv4 Commit) complete. Dec 7 18:50:37 xxx1 NetworkManager[30907]: (wlan0): device state change: secondaries -> activated (reason 'none') [90 100 0] Dec 7 18:50:37 xxx1 NetworkManager[30907]: Policy set 'Sixty-Seven' (wlan0) as default for IPv4 routing and DNS. Dec 7 18:50:37 xxx1 NetworkManager[30907]: Writing DNS information to /sbin/resolvconf Dec 7 18:50:37 xxx1 NetworkManager[30907]: Activation (wlan0) successful, device activated. Dec 7 18:50:41 xxx1 NetworkManager[30907]: Dispatcher script timed out: Script '/etc/NetworkManager/dispatcher.d/01ifupdown' timed out.