// wakeup

13:16:12 kernel: [117455.446259] PM: Finishing wakeup.
13:16:12 NetworkManager[878]: <info> wake requested (sleeping: yes  enabled: yes)
13:16:12 NetworkManager[878]: <info> waking up and re-enabling...
13:16:12 NetworkManager[878]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
13:16:12 NetworkManager[878]: <info> (wlan0): bringing up device.
13:16:12 NetworkManager[878]: <info> (wlan0): preparing device.
13:16:12 NetworkManager[878]: <info> (wlan0): deactivating device (reason 'managed') [2]
13:16:12 NetworkManager[878]: <info> NetworkManager state is now DISCONNECTED
13:16:12 kernel: [117455.883420] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
13:16:12 NetworkManager[878]: <info> (wlan0) supports 4 scan SSIDs
13:16:12 NetworkManager[878]: <info> (wlan0): supplicant interface state: starting -> ready
13:16:12 NetworkManager[878]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
13:16:12 NetworkManager[878]: <warn> Trying to remove a non-existant call id.
13:16:12 NetworkManager[878]: <info> (wlan0): supplicant interface state: ready -> disconnected
13:16:12 NetworkManager[878]: <info> (wlan0) supports 4 scan SSIDs
13:16:12 wpa_supplicant[19459]: wlan0: CTRL-EVENT-SCAN-STARTED 
13:16:16 NetworkManager[878]: <info> (wlan0): supplicant interface state: disconnected -> inactive

// auto reconnect starts

13:16:16 NetworkManager[878]: <info> Auto-activating connection 'Oranjeplantage 35 5G'.
13:16:16 NetworkManager[878]: <info> Activation (wlan0) starting connection 'Oranjeplantage 35 5G'
13:16:16 NetworkManager[878]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
13:16:16 NetworkManager[878]: <info> NetworkManager state is now CONNECTING
13:16:16 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
13:16:16 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
13:16:16 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
13:16:16 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
13:16:16 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
13:16:16 NetworkManager[878]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
13:16:16 NetworkManager[878]: <info> Activation (wlan0/wireless): connection 'Oranjeplantage 35 5G' has security, and secrets exist.  No new secrets needed.
13:16:16 NetworkManager[878]: <info> Config: added 'ssid' value 'Oroanjeplage 35 5G'
13:16:16 NetworkManager[878]: <info> Config: added 'scan_ssid' value '1'
13:16:16 NetworkManager[878]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
13:16:16 NetworkManager[878]: <info> Config: added 'psk' value '<omitted>'
13:16:16 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
13:16:16 NetworkManager[878]: <info> Config: set interface ap_scan to 1
13:16:19 kernel: [117462.694313] wlan0: authenticate with c0:25:06:cf:bf:ae

// out of order message
// scan started causing 3 sec. delay to authenticated?
13:16:16 wpa_supplicant[19459]: wlan0: CTRL-EVENT-SCAN-STARTED
 
13:16:19 wpa_supplicant[19459]: wlan0: SME: Trying to authenticate with c0:25:06:cf:bf:ae (SSID='Oroanjeplage 35 5G' freq=5180 MHz)
13:16:19 NetworkManager[878]: <info> (wlan0): supplicant interface state: inactive -> authenticating
13:16:19 wpa_supplicant[19459]: wlan0: Trying to associate with c0:25:06:cf:bf:ae (SSID='Oroanjeplage 35 5G' freq=5180 MHz)
13:16:19 kernel: [117462.707014] wlan0: send auth to c0:25:06:cf:bf:ae (try 1/3)
13:16:19 kernel: [117462.710281] wlan0: authenticated
13:16:19 kernel: [117462.712866] wlan0: associate with c0:25:06:cf:bf:ae (try 1/3)
13:16:19 NetworkManager[878]: <info> (wlan0): supplicant interface state: authenticating -> associating
13:16:19 kernel: [117462.720034] wlan0: RX AssocResp from c0:25:06:cf:bf:ae (capab=0x411 status=0 aid=4)
13:16:19 kernel: [117462.720129] wlan0: associated
13:16:19 kernel: [117462.720172] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
13:16:19 kernel: [117462.720277] cfg80211: Calling CRDA for country: DE
13:16:19 wpa_supplicant[19459]: wlan0: Associated with c0:25:06:cf:bf:ae
13:16:19 wpa_supplicant[19459]: wlan0: WPA: Key negotiation completed with c0:25:06:cf:bf:ae [PTK=CCMP GTK=CCMP]
13:16:19 wpa_supplicant[19459]: wlan0: CTRL-EVENT-CONNECTED - Connection to c0:25:06:cf:bf:ae completed [id=0 id_str=]
13:16:19 wpa_supplicant[19459]: wlan0: SME: Trying to authenticate with c0:25:06:cf:bf:ae (SSID='Oroanjeplage 35 5G' freq=5180 MHz)

// already authenticated but now again?

13:16:19 kernel: [117462.727974] wlan0: deauthenticating from c0:25:06:cf:bf:ae by local choice (Reason: 2=PREV_AUTH_NOT_VALID)

// deauthenticate?

13:16:19 NetworkManager[878]: <info> (wlan0): supplicant interface state: associating -> 4-way handshake
13:16:19 kernel: [117462.735617] cfg80211: Calling CRDA to update world regulatory domain
13:16:19 kernel: [117462.735916] wlan0: authenticate with c0:25:06:cf:bf:ae
13:16:19 kernel: [117462.748414] wlan0: send auth to c0:25:06:cf:bf:ae (try 1/3)
13:16:19 NetworkManager[878]: <info> (wlan0): supplicant interface state: 4-way handshake -> authenticating
13:16:19 wpa_supplicant[19459]: wlan0: CTRL-EVENT-DISCONNECTED bssid=c0:25:06:cf:bf:ae reason=2 locally_generated=1
13:16:19 NetworkManager[878]: <warn> Connection disconnected (reason -2)
13:16:19 wpa_supplicant[19459]: wlan0: Trying to associate with c0:25:06:cf:bf:ae (SSID='Oroanjeplage 35 5G' freq=5180 MHz)
13:16:19 kernel: [117462.754683] wlan0: authenticated
13:16:19 kernel: [117462.756869] wlan0: associate with c0:25:06:cf:bf:ae (try 1/3)
13:16:19 NetworkManager[878]: <info> (wlan0): supplicant interface state: authenticating -> associating
13:16:19 kernel: [117462.757892] cfg80211: World regulatory domain updated:
13:16:19 kernel: [117462.757897] cfg80211:  DFS Master region: unset
13:16:19 kernel: [117462.757899] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
13:16:19 kernel: [117462.757903] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
13:16:19 kernel: [117462.757906] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
13:16:19 kernel: [117462.757908] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm), (N/A)
13:16:19 kernel: [117462.757911] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
13:16:19 kernel: [117462.757913] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
13:16:19 kernel: [117462.765561] wlan0: RX AssocResp from c0:25:06:cf:bf:ae (capab=0x411 status=0 aid=4)
13:16:19 kernel: [117462.765638] wlan0: associated
13:16:19 kernel: [117462.765690] cfg80211: Calling CRDA for country: DE
13:16:19 kernel: [117462.768613] ath: EEPROM regdomain: 0x8114
13:16:19 kernel: [117462.768618] ath: EEPROM indicates we should expect a country code
13:16:19 kernel: [117462.768620] ath: doing EEPROM country->regdmn map search
13:16:19 kernel: [117462.768622] ath: country maps to regdmn code: 0x37
13:16:19 kernel: [117462.768624] ath: Country alpha2 being used: DE
13:16:19 kernel: [117462.768626] ath: Regpair used: 0x37
13:16:19 kernel: [117462.768628] ath: regdomain 0x8114 dynamically updated by country IE
13:16:19 kernel: [117462.768651] cfg80211: Regulatory domain changed to country: DE
13:16:19 kernel: [117462.768653] cfg80211:  DFS Master region: unset
13:16:19 kernel: [117462.768654] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
13:16:19 kernel: [117462.768658] cfg80211:   (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
13:16:19 kernel: [117462.768660] cfg80211:   (5150000 KHz - 5250000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
13:16:19 kernel: [117462.768662] cfg80211:   (5250000 KHz - 5350000 KHz @ 40000 KHz), (N/A, 2000 mBm), (0 s)
13:16:19 kernel: [117462.768664] cfg80211:   (5470000 KHz - 5725000 KHz @ 40000 KHz), (N/A, 2698 mBm), (0 s)
13:16:19 kernel: [117462.768667] cfg80211:   (57240000 KHz - 65880000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A)
13:16:19 wpa_supplicant[19459]: wlan0: Associated with c0:25:06:cf:bf:ae
13:16:19 wpa_supplicant[19459]: wlan0: WPA: Key negotiation completed with c0:25:06:cf:bf:ae [PTK=CCMP GTK=CCMP]
13:16:19 wpa_supplicant[19459]: wlan0: CTRL-EVENT-CONNECTED - Connection to c0:25:06:cf:bf:ae completed [id=0 id_str=]
13:16:19 NetworkManager[878]: <info> (wlan0): supplicant interface state: associating -> completed
13:16:19 NetworkManager[878]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'Oroanjeplage 35 5G'.

// connected (3 sec.)

13:16:19 NetworkManager[878]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
13:16:19 NetworkManager[878]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
13:16:19 NetworkManager[878]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
13:16:19 NetworkManager[878]: <info> Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds)
13:16:19 NetworkManager[878]: <info> dhclient started with pid 23927
13:16:19 NetworkManager[878]: <info> Activation (wlan0) Beginning IP6 addrconf.
13:16:19 NetworkManager[878]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
13:16:19 dhclient: Internet Systems Consortium DHCP Client 4.2.4
13:16:19 dhclient: Copyright 2004-2012 Internet Systems Consortium.
13:16:19 dhclient: All rights reserved.
13:16:19 dhclient: For info, please visit https://www.isc.org/software/dhcp/
13:16:19 dhclient: 
13:16:19 NetworkManager[878]: <info> (wlan0): DHCPv4 state changed nbi -> preinit
13:16:19 dhclient: Listening on LPF/wlan0/9c:ad:97:80:44:25
13:16:19 dhclient: Sending on   LPF/wlan0/9c:ad:97:80:44:25
13:16:19 dhclient: Sending on   Socket/fallback
13:16:19 dhclient: DHCPREQUEST of 192.168.178.55 on wlan0 to 255.255.255.255 port 67 (xid=0x4c441797)
13:16:19 dhclient: DHCPACK of 192.168.178.55 from 192.168.178.1
13:16:19 dhclient: bound to 192.168.178.55 -- renewal in 324639 seconds.
13:16:19 NetworkManager[878]: <info> (wlan0): DHCPv4 state changed preinit -> reboot
13:16:19 NetworkManager[878]: <info>   address 192.168.178.55
13:16:19 NetworkManager[878]: <info>   prefix 24 (255.255.255.0)
13:16:19 NetworkManager[878]: <info>   gateway 192.168.178.1
13:16:19 NetworkManager[878]: <info>   nameserver '192.168.178.1'
13:16:19 NetworkManager[878]: <info>   domain name 'fritz.box'
13:16:19 NetworkManager[878]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
13:16:19 NetworkManager[878]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) started...
13:16:19 kernel: [117462.834780] wlan0: Limiting TX power to 23 (23 - 0) dBm as advertised by c0:25:06:cf:bf:ae
13:16:20 NetworkManager[878]: <info> (wlan0): device state change: ip-config -> secondaries (reason 'none') [70 90 0]
13:16:20 NetworkManager[878]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) complete.
13:16:20 NetworkManager[878]: <info> (wlan0): device state change: secondaries -> activated (reason 'none') [90 100 0]
13:16:20 NetworkManager[878]: <info> NetworkManager state is now CONNECTED_GLOBAL
13:16:20 NetworkManager[878]: <info> Policy set 'Oranjeplantage 35 5G' (wlan0) as default for IPv4 routing and DNS.
13:16:20 NetworkManager[878]: <info> Writing DNS information to /sbin/resolvconf
13:16:20 dnsmasq[1899]: setting upstream servers from DBus
13:16:20 dnsmasq[1899]: using nameserver 192.168.178.1#53
13:16:20 NetworkManager[878]: <info> Activation (wlan0) successful, device activated.
13:16:23 NetworkManager[878]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) starting DHCPv6 as requested by IPv6 router...
13:16:23 NetworkManager[878]: <info> Activation (wlan0) Beginning DHCPv6 transaction (timeout in 45 seconds)
13:16:23 NetworkManager[878]: <info> dhclient started with pid 24026
13:16:23 dhclient: Internet Systems Consortium DHCP Client 4.2.4
13:16:23 dhclient: Copyright 2004-2012 Internet Systems Consortium.
13:16:23 dhclient: All rights reserved.
13:16:23 dhclient: For info, please visit https://www.isc.org/software/dhcp/
13:16:23 dhclient: 
13:16:23 dhclient: Bound to *:546
13:16:23 dhclient: Listening on Socket/wlan0
13:16:23 dhclient: Sending on   Socket/wlan0
13:16:23 dhclient: XMT: Info-Request on wlan0, interval 960ms.
13:16:23 dhclient: RCV: Reply message on wlan0 from fe80::c225:6ff:fecf:bfac.
13:16:23 NetworkManager[878]: <info> (wlan0): DHCPv6 state changed nbi -> renew6
13:16:23 NetworkManager[878]: <info>   nameserver 'fd00::c225:6ff:fecf:bfac'
13:16:23 NetworkManager[878]: <info> Activation (wlan0) Stage 5 of 5 (IPv6 Commit) scheduled...
13:16:23 NetworkManager[878]: <info> Activation (wlan0) Stage 5 of 5 (IPv6 Commit) started...
13:16:23 NetworkManager[878]: <info> Policy set 'Oranjeplantage 35 5G' (wlan0) as default for IPv6 routing and DNS.
13:16:23 NetworkManager[878]: <info> Writing DNS information to /sbin/resolvconf
13:16:23 dnsmasq[1899]: setting upstream servers from DBus
13:16:23 dnsmasq[1899]: using nameserver fd00::c225:6ff:fecf:bfac#53
13:16:23 dnsmasq[1899]: using nameserver 192.168.178.1#53
13:16:23 NetworkManager[878]: <info> Activation (wlan0) Stage 5 of 5 (IPv6 Commit) complete.

// configured (4 sec.)

13:16:23 NetworkManager[878]: <info> (wlan0): DHCPv6 client pid 24026 exited with status 0
13:16:28 ntpdate[24017]: adjust time server 91.189.89.199 offset -0.360275 sec
13:16:35 wpa_supplicant[19459]: wlan0: CTRL-EVENT-SCAN-STARTED 
13:19:53 NetworkManager[878]: <warn> nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted
13:19:44 wpa_supplicant[19459]: message repeated 3 times: [ wlan0: CTRL-EVENT-SCAN-STARTED ]
13:19:54 wpa_supplicant[19459]: wlan0: WPA: Group rekeying completed with c0:25:06:cf:bf:ae [GTK=CCMP]
13:21:27 wpa_supplicant[19459]: wlan0: CTRL-EVENT-SCAN-STARTED 
