// wakeup (NUC with Intel Wireless 7270 abgn + bt

13:45:24 kernel: [139688.632468] PM: Finishing wakeup.
13:45:24 kernel: [139688.632473] Restarting tasks ... done.
13:45:25 NetworkManager[842]: <info> wake requested (sleeping: yes  enabled: yes)
13:45:25 NetworkManager[842]: <info> waking up and re-enabling...
13:45:25 NetworkManager[842]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
13:45:25 NetworkManager[842]: <info> (wlan0): bringing up device.
13:45:25 kernel: [139689.926631] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled
13:45:25 kernel: [139689.926882] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled
13:45:25 NetworkManager[842]: <info> (wlan0): preparing device.
13:45:25 NetworkManager[842]: <info> (wlan0): deactivating device (reason 'managed') [2]
13:45:25 NetworkManager[842]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
13:45:25 NetworkManager[842]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
13:45:25 NetworkManager[842]: <info> NetworkManager state is now CONNECTED_GLOBAL
13:45:25 NetworkManager[842]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
13:45:25 kernel: [139689.939905] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
13:45:25 NetworkManager[842]: <info> (38:16:D1:BE:08:48): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
13:45:25 NetworkManager[842]: <info> (38:16:D1:BE:08:48): deactivating device (reason 'managed') [2]
13:45:25 NetworkManager[842]: <info> NetworkManager state is now DISCONNECTED
13:45:25 NetworkManager[842]: <info> (38:16:D1:BE:08:48): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
13:45:25 NetworkManager[842]: <info> (wlan0) supports 5 scan SSIDs
13:45:25 NetworkManager[842]: <info> (wlan0): supplicant interface state: starting -> ready
13:45:25 NetworkManager[842]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
13:45:25 NetworkManager[842]: <warn> Trying to remove a non-existant call id.
13:45:25 NetworkManager[842]: <info> (wlan0): supplicant interface state: ready -> disconnected
13:45:25 NetworkManager[842]: <info> (wlan0) supports 5 scan SSIDs
13:45:25 wpa_supplicant[970]: wlan0: CTRL-EVENT-SCAN-STARTED 
13:45:29 NetworkManager[842]: <info> (wlan0): supplicant interface state: disconnected -> inactive

// autoconnect starts

13:45:29 NetworkManager[842]: <info> Auto-activating connection 'Oroanjeplage 35 5G'.
13:45:29 NetworkManager[842]: <info> Activation (wlan0) starting connection 'Oroanjeplage 35 5G'
13:45:29 NetworkManager[842]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
13:45:29 NetworkManager[842]: <info> NetworkManager state is now CONNECTING
13:45:29 NetworkManager[842]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
13:45:29 NetworkManager[842]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
13:45:29 NetworkManager[842]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
13:45:29 NetworkManager[842]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
13:45:29 NetworkManager[842]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
13:45:29 NetworkManager[842]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
13:45:29 NetworkManager[842]: <info> Activation (wlan0/wireless): access point 'Oroanjeplage 35 5G' has security, but secrets are required.
13:45:29 NetworkManager[842]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
13:45:29 NetworkManager[842]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
13:45:29 NetworkManager[842]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
13:45:29 NetworkManager[842]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
13:45:29 NetworkManager[842]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
13:45:29 NetworkManager[842]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
13:45:29 NetworkManager[842]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
13:45:29 NetworkManager[842]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
13:45:29 NetworkManager[842]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
13:45:29 NetworkManager[842]: <info> Activation (wlan0/wireless): connection 'Oroanjeplage 35 5G' has security, and secrets exist.  No new secrets needed.
13:45:29 NetworkManager[842]: <info> Config: added 'ssid' value 'Oroanjeplage 35 5G'
13:45:29 NetworkManager[842]: <info> Config: added 'scan_ssid' value '1'
13:45:29 NetworkManager[842]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
13:45:29 NetworkManager[842]: <info> Config: added 'psk' value '<omitted>'
13:45:29 NetworkManager[842]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
13:45:29 NetworkManager[842]: <info> Config: set interface ap_scan to 1
13:45:29 wpa_supplicant[970]: wlan0: CTRL-EVENT-SCAN-STARTED 
13:45:32 wpa_supplicant[970]: wlan0: SME: Trying to authenticate with c0:25:06:cf:bf:ae (SSID='Oroanjeplage 35 5G' freq=5180 MHz)
13:45:32 kernel: [139696.593357] wlan0: authenticate with c0:25:06:cf:bf:ae
13:45:32 kernel: [139696.599103] wlan0: send auth to c0:25:06:cf:bf:ae (try 1/3)
13:45:32 wpa_supplicant[970]: wlan0: Trying to associate with c0:25:06:cf:bf:ae (SSID='Oroanjeplage 35 5G' freq=5180 MHz)
13:45:32 kernel: [139696.601648] wlan0: authenticated
13:45:32 NetworkManager[842]: <info> (wlan0): supplicant interface state: inactive -> authenticating
13:45:32 kernel: [139696.604862] wlan0: associate with c0:25:06:cf:bf:ae (try 1/3)
13:45:32 kernel: [139696.614819] wlan0: RX AssocResp from c0:25:06:cf:bf:ae (capab=0x411 status=0 aid=5)
13:45:32 kernel: [139696.616458] wlan0: associated
13:45:32 kernel: [139696.616562] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
13:45:32 kernel: [139696.616707] cfg80211: Calling CRDA for country: DE
13:45:32 wpa_supplicant[970]: wlan0: Associated with c0:25:06:cf:bf:ae
13:45:32 wpa_supplicant[970]: wlan0: WPA: Key negotiation completed with c0:25:06:cf:bf:ae [PTK=CCMP GTK=CCMP]
13:45:32 wpa_supplicant[970]: wlan0: CTRL-EVENT-CONNECTED - Connection to c0:25:06:cf:bf:ae completed [id=0 id_str=]
13:45:32 wpa_supplicant[970]: wlan0: SME: Trying to authenticate with c0:25:06:cf:bf:ae (SSID='Oroanjeplage 35 5G' freq=5180 MHz)

// strange, this deauthenticates too...

13:45:32 kernel: [139696.636321] wlan0: deauthenticating from c0:25:06:cf:bf:ae by local choice (Reason: 2=PREV_AUTH_NOT_VALID)
13:45:32 NetworkManager[842]: <info> (wlan0): supplicant interface state: authenticating -> associating
13:45:32 NetworkManager[842]: <info> (wlan0): supplicant interface state: associating -> 4-way handshake
13:45:32 NetworkManager[842]: <info> (wlan0): supplicant interface state: 4-way handshake -> completed
13:45:32 NetworkManager[842]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'Oroanjeplage 35 5G'.

// but connected anyway (3 sec.)

13:45:32 NetworkManager[842]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
13:45:32 NetworkManager[842]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
13:45:32 NetworkManager[842]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
13:45:32 kernel: [139696.654837] cfg80211: Calling CRDA to update world regulatory domain
13:45:32 kernel: [139696.655477] wlan0: authenticate with c0:25:06:cf:bf:ae
13:45:32 wpa_supplicant[970]: wlan0: CTRL-EVENT-DISCONNECTED bssid=c0:25:06:cf:bf:ae reason=2 locally_generated=1
13:45:32 kernel: [139696.658480] wlan0: send auth to c0:25:06:cf:bf:ae (try 1/3)
13:45:32 wpa_supplicant[970]: wlan0: Trying to associate with c0:25:06:cf:bf:ae (SSID='Oroanjeplage 35 5G' freq=5180 MHz)
13:45:32 kernel: [139696.663254] wlan0: authenticated
13:45:32 kernel: [139696.664864] wlan0: associate with c0:25:06:cf:bf:ae (try 1/3)
13:45:32 wpa_supplicant[970]: wlan0: Associated with c0:25:06:cf:bf:ae
13:45:32 kernel: [139696.673217] wlan0: RX AssocResp from c0:25:06:cf:bf:ae (capab=0x411 status=0 aid=5)
13:45:32 kernel: [139696.674494] wlan0: associated

// looks like messages are out of order

13:45:32 kernel: [139696.674948] cfg80211: Calling CRDA to update world regulatory domain
13:45:32 NetworkManager[842]: <info> Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds)
13:45:32 wpa_supplicant[970]: wlan0: WPA: Key negotiation completed with c0:25:06:cf:bf:ae [PTK=CCMP GTK=CCMP]
13:45:32 wpa_supplicant[970]: wlan0: CTRL-EVENT-CONNECTED - Connection to c0:25:06:cf:bf:ae completed [id=0 id_str=]
13:45:32 NetworkManager[842]: <info> dhclient started with pid 5525
13:45:32 NetworkManager[842]: <info> Activation (wlan0) Beginning IP6 addrconf.
13:45:32 NetworkManager[842]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
13:45:32 NetworkManager[842]: <info> (wlan0): supplicant interface state: completed -> authenticating
13:45:32 NetworkManager[842]: <warn> Connection disconnected (reason -2)
13:45:32 NetworkManager[842]: <info> (wlan0): supplicant interface state: authenticating -> associating
13:45:32 NetworkManager[842]: <info> (wlan0): supplicant interface state: associating -> 4-way handshake
13:45:32 NetworkManager[842]: <info> (wlan0): supplicant interface state: 4-way handshake -> completed
13:45:32 dhclient: Internet Systems Consortium DHCP Client 4.2.4
13:45:32 dhclient: Copyright 2004-2012 Internet Systems Consortium.
13:45:32 dhclient: All rights reserved.
13:45:32 dhclient: For info, please visit https://www.isc.org/software/dhcp/
13:45:32 dhclient: 
13:45:32 kernel: [139696.766985] wlan0: Limiting TX power to 23 (23 - 0) dBm as advertised by c0:25:06:cf:bf:ae
13:45:32 kernel: [139696.767012] cfg80211: Calling CRDA to update world regulatory domain
13:45:32 NetworkManager[842]: <info> (wlan0): DHCPv4 state changed nbi -> preinit
13:45:32 dhclient: Listening on LPF/wlan0/48:51:b7:16:4b:d0
13:45:32 dhclient: Sending on   LPF/wlan0/48:51:b7:16:4b:d0
13:45:32 dhclient: Sending on   Socket/fallback
13:45:32 dhclient: DHCPREQUEST of 192.168.178.64 on wlan0 to 255.255.255.255 port 67 (xid=0x18262ff6)
13:45:32 dhclient: DHCPACK of 192.168.178.64 from 192.168.178.1
13:45:32 dhclient: bound to 192.168.178.64 -- renewal in 373931 seconds.
13:45:32 kernel: [139696.820110] cfg80211: World regulatory domain updated:
13:45:32 kernel: [139696.820121] cfg80211:  DFS Master region: unset
13:45:32 kernel: [139696.820125] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
13:45:32 kernel: [139696.820131] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
13:45:32 kernel: [139696.820136] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
13:45:32 kernel: [139696.820141] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm), (N/A)
13:45:32 kernel: [139696.820146] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
13:45:32 kernel: [139696.820151] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
13:45:32 kernel: [139696.820730] cfg80211: Calling CRDA for country: DE
13:45:32 NetworkManager[842]: <info> (wlan0): DHCPv4 state changed preinit -> reboot
13:45:32 NetworkManager[842]: <info>   address 192.168.178.64
13:45:32 NetworkManager[842]: <info>   prefix 24 (255.255.255.0)
13:45:32 NetworkManager[842]: <info>   gateway 192.168.178.1
13:45:32 NetworkManager[842]: <info>   nameserver '192.168.178.1'
13:45:32 NetworkManager[842]: <info>   domain name 'fritz.box'
13:45:32 NetworkManager[842]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
13:45:32 NetworkManager[842]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) started...
13:45:32 avahi-daemon[580]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.64.
13:45:32 avahi-daemon[580]: New relevant interface wlan0.IPv4 for mDNS.
13:45:32 avahi-daemon[580]: Registering new address record for 192.168.178.64 on wlan0.IPv4.
13:45:32 kernel: [139696.881882] cfg80211: Regulatory domain changed to country: DE
13:45:32 kernel: [139696.881893] cfg80211:  DFS Master region: unset
13:45:32 kernel: [139696.881896] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
13:45:32 kernel: [139696.881903] cfg80211:   (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
13:45:32 kernel: [139696.881908] cfg80211:   (5150000 KHz - 5250000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
13:45:32 kernel: [139696.881913] cfg80211:   (5250000 KHz - 5350000 KHz @ 40000 KHz), (N/A, 2000 mBm), (0 s)
13:45:32 kernel: [139696.881918] cfg80211:   (5470000 KHz - 5725000 KHz @ 40000 KHz), (N/A, 2698 mBm), (0 s)
13:45:32 kernel: [139696.881923] cfg80211:   (57240000 KHz - 65880000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A)
13:45:33 NetworkManager[842]: <info> (wlan0): device state change: ip-config -> secondaries (reason 'none') [70 90 0]
13:45:33 NetworkManager[842]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) complete.
13:45:33 NetworkManager[842]: <info> (wlan0): device state change: secondaries -> activated (reason 'none') [90 100 0]
13:45:33 NetworkManager[842]: <info> NetworkManager state is now CONNECTED_GLOBAL
13:45:33 NetworkManager[842]: <info> Policy set 'Oroanjeplage 35 5G' (wlan0) as default for IPv4 routing and DNS.
13:45:33 dnsmasq[1532]: setting upstream servers from DBus
13:45:33 dnsmasq[1532]: using nameserver 192.168.178.1#53
13:45:33 NetworkManager[842]: <info> Writing DNS information to /sbin/resolvconf
13:45:33 avahi-daemon[580]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::4a51:b7ff:fe16:4bd0.
13:45:33 avahi-daemon[580]: New relevant interface wlan0.IPv6 for mDNS.
13:45:33 avahi-daemon[580]: Registering new address record for fe80::4a51:b7ff:fe16:4bd0 on wlan0.*.
13:45:33 NetworkManager[842]: <info> Activation (wlan0) successful, device activated.
13:45:33 dbus[534]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
13:45:33 dbus[534]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
13:45:34 NetworkManager[842]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) starting DHCPv6 as requested by IPv6 router...
13:45:34 NetworkManager[842]: <info> Activation (wlan0) Beginning DHCPv6 transaction (timeout in 45 seconds)
13:45:34 avahi-daemon[580]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::4a51:b7ff:fe16:4bd0.
13:45:34 avahi-daemon[580]: Joining mDNS multicast group on interface wlan0.IPv6 with address fd00::4a51:b7ff:fe16:4bd0.
13:45:34 avahi-daemon[580]: Registering new address record for fd00::4a51:b7ff:fe16:4bd0 on wlan0.*.
13:45:34 avahi-daemon[580]: Withdrawing address record for fe80::4a51:b7ff:fe16:4bd0 on wlan0.
13:45:34 avahi-daemon[580]: Registering new address record for 2002:c3f1:8c8d:1:4a51:b7ff:fe16:4bd0 on wlan0.*.
13:45:35 NetworkManager[842]: <info> dhclient started with pid 5625
13:45:35 dhclient: Internet Systems Consortium DHCP Client 4.2.4
13:45:35 dhclient: Copyright 2004-2012 Internet Systems Consortium.
13:45:35 dhclient: All rights reserved.
13:45:35 dhclient: For info, please visit https://www.isc.org/software/dhcp/
13:45:35 dhclient: 
13:45:35 dhclient: Bound to *:546
13:45:35 dhclient: Listening on Socket/wlan0
13:45:35 dhclient: Sending on   Socket/wlan0
13:45:35 avahi-daemon[580]: Registering new address record for fd00::dd8c:faba:ff71:147d on wlan0.*.
13:45:35 dhclient: XMT: Info-Request on wlan0, interval 1010ms.
13:45:35 dhclient: RCV: Reply message on wlan0 from fe80::c225:6ff:fecf:bfac.
13:45:35 NetworkManager[842]: <info> (wlan0): DHCPv6 client pid 5625 exited with status 0
13:45:35 NetworkManager[842]: <info> (wlan0): DHCPv6 state changed end -> renew6
13:45:35 NetworkManager[842]: <info>   nameserver 'fd00::c225:6ff:fecf:bfac'
13:45:35 NetworkManager[842]: <info> Activation (wlan0) Stage 5 of 5 (IPv6 Commit) scheduled...
13:45:35 NetworkManager[842]: <info> Activation (wlan0) Stage 5 of 5 (IPv6 Commit) started...
13:45:35 avahi-daemon[580]: Registering new address record for 2002:c3f1:8c8d:1:dd8c:faba:ff71:147d on wlan0.*.
13:45:36 NetworkManager[842]: <info> Policy set 'Oroanjeplage 35 5G' (wlan0) as default for IPv6 routing and DNS.
13:45:36 dnsmasq[1532]: setting upstream servers from DBus
13:45:36 dnsmasq[1532]: using nameserver fd00::c225:6ff:fecf:bfac#53
13:45:36 dnsmasq[1532]: using nameserver 192.168.178.1#53
13:45:36 NetworkManager[842]: <info> Writing DNS information to /sbin/resolvconf
13:45:36 NetworkManager[842]: <info> Activation (wlan0) Stage 5 of 5 (IPv6 Commit) complete.

// configured (4 sec.)

13:45:39 ntpdate[5620]: step time server 91.189.94.4 offset -0.877961 sec
13:45:48 wpa_supplicant[970]: wlan0: CTRL-EVENT-SCAN-STARTED 
13:48:57 wpa_supplicant[970]: message repeated 3 times: [ wlan0: CTRL-EVENT-SCAN-STARTED ]
13:49:54 wpa_supplicant[970]: wlan0: WPA: Group rekeying completed with c0:25:06:cf:bf:ae [GTK=CCMP]
13:50:40 wpa_supplicant[970]: wlan0: CTRL-EVENT-SCAN-STARTED 
13:58:40 wpa_supplicant[970]: message repeated 4 times: [ wlan0: CTRL-EVENT-SCAN-STARTED ]
13:59:54 wpa_supplicant[970]: wlan0: WPA: Group rekeying completed with c0:25:06:cf:bf:ae [GTK=CCMP]
14:00:40 wpa_supplicant[970]: wlan0: CTRL-EVENT-SCAN-STARTED 
14:07:35 dbus[534]: [system] Activating service name='org.freedesktop.systemd1' (using servicehelper)
14:07:35 dbus[534]: [system] Successfully activated service 'org.freedesktop.systemd1'
