// wakeup

13:45:36 kernel: [119092.649836] PM: resume of devices complete after 667.169 msecs
13:45:36 kernel: [119092.650155] PM: Finishing wakeup.
13:45:36 kernel: [119092.650157] Restarting tasks ... done.
13:45:36 kernel: [119092.662514] usb 1-4: USB disconnect, device number 122
13:45:36 NetworkManager[878]: <info> wake requested (sleeping: yes  enabled: yes)
13:45:36 NetworkManager[878]: <info> waking up and re-enabling...
13:45:36 NetworkManager[878]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
13:45:36 NetworkManager[878]: <info> (wlan0): bringing up device.
13:45:36 NetworkManager[878]: <info> (wlan0): preparing device.
13:45:36 NetworkManager[878]: <info> (wlan0): deactivating device (reason 'managed') [2]
13:45:36 NetworkManager[878]: <info> NetworkManager state is now DISCONNECTED
13:45:36 kernel: [119092.992015] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
13:45:36 NetworkManager[878]: <info> (wlan0) supports 4 scan SSIDs
13:45:36 NetworkManager[878]: <info> (wlan0): supplicant interface state: starting -> ready
13:45:36 NetworkManager[878]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
13:45:36 NetworkManager[878]: <warn> Trying to remove a non-existant call id.
13:45:36 NetworkManager[878]: <info> (wlan0): supplicant interface state: ready -> disconnected
13:45:36 NetworkManager[878]: <info> (wlan0) supports 4 scan SSIDs
13:45:36 wpa_supplicant[19459]: wlan0: CTRL-EVENT-SCAN-STARTED 
13:45:40 NetworkManager[878]: <info> (wlan0): supplicant interface state: disconnected -> inactive

// autoconnect starts

13:45:40 NetworkManager[878]: <info> Auto-activating connection 'Oranjeplantage 35 5G'.
13:45:40 NetworkManager[878]: <info> Activation (wlan0) starting connection 'Oranjeplantage 35 5G'
13:45:40 NetworkManager[878]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
13:45:40 NetworkManager[878]: <info> NetworkManager state is now CONNECTING
13:45:40 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
13:45:40 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
13:45:40 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
13:45:40 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
13:45:40 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
13:45:40 NetworkManager[878]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]

// secrets don't exist

13:45:40 NetworkManager[878]: <info> Activation (wlan0/wireless): access point 'Oranjeplantage 35 5G' has security, but secrets are required.
13:45:40 NetworkManager[878]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
13:45:40 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
13:45:40 NetworkManager[878]: get_secret_flags: assertion 'is_secret_prop (setting, secret_name, error)' failed
13:45:40 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
13:45:40 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
13:45:40 NetworkManager[878]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
13:45:40 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
13:45:40 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
13:45:40 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
13:45:40 NetworkManager[878]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
13:45:40 NetworkManager[878]: <info> Activation (wlan0/wireless): connection 'Oranjeplantage 35 5G' has security, and secrets exist.  No new secrets needed.
13:45:40 NetworkManager[878]: <info> Config: added 'ssid' value 'Oroanjeplage 35 5G'
13:45:40 NetworkManager[878]: <info> Config: added 'scan_ssid' value '1'
13:45:40 NetworkManager[878]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
13:45:40 NetworkManager[878]: <info> Config: added 'psk' value '<omitted>'
13:45:40 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
13:45:40 NetworkManager[878]: <info> Config: set interface ap_scan to 1
13:45:43 kernel: [119099.742731] wlan0: authenticate with c0:25:06:cf:bf:ae

// out of order message
// seems to be a scan started before autoconnect?

13:45:39 wpa_supplicant[19459]: wlan0: CTRL-EVENT-SCAN-STARTED 
13:45:43 wpa_supplicant[19459]: wlan0: SME: Trying to authenticate with c0:25:06:cf:bf:ae (SSID='Oroanjeplage 35 5G' freq=5180 MHz)
13:45:43 kernel: [119099.759213] wlan0: send auth to c0:25:06:cf:bf:ae (try 1/3)
13:45:43 wpa_supplicant[19459]: wlan0: Trying to associate with c0:25:06:cf:bf:ae (SSID='Oroanjeplage 35 5G' freq=5180 MHz)
13:45:43 NetworkManager[878]: <info> (wlan0): supplicant interface state: inactive -> authenticating
13:45:43 kernel: [119099.761639] wlan0: authenticated
13:45:43 kernel: [119099.765296] wlan0: associate with c0:25:06:cf:bf:ae (try 1/3)
13:45:43 NetworkManager[878]: <info> (wlan0): supplicant interface state: authenticating -> associating
13:45:43 kernel: [119099.773238] wlan0: RX AssocResp from c0:25:06:cf:bf:ae (capab=0x411 status=0 aid=4)
13:45:43 kernel: [119099.773340] wlan0: associated
13:45:43 kernel: [119099.773433] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
13:45:43 kernel: [119099.773515] cfg80211: Calling CRDA for country: DE
13:45:43 wpa_supplicant[19459]: wlan0: Associated with c0:25:06:cf:bf:ae
13:45:43 wpa_supplicant[19459]: wlan0: WPA: Key negotiation completed with c0:25:06:cf:bf:ae [PTK=CCMP GTK=CCMP]
13:45:43 wpa_supplicant[19459]: wlan0: CTRL-EVENT-CONNECTED - Connection to c0:25:06:cf:bf:ae completed [id=0 id_str=]
13:45:43 wpa_supplicant[19459]: wlan0: SME: Trying to authenticate with c0:25:06:cf:bf:ae (SSID='Oroanjeplage 35 5G' freq=5180 MHz)
13:45:43 kernel: [119099.782334] wlan0: deauthenticating from c0:25:06:cf:bf:ae by local choice (Reason: 2=PREV_AUTH_NOT_VALID)
13:45:43 NetworkManager[878]: <info> (wlan0): supplicant interface state: associating -> 4-way handshake
13:45:43 kernel: [119099.788383] cfg80211: Calling CRDA to update world regulatory domain
13:45:43 kernel: [119099.788494] wlan0: authenticate with c0:25:06:cf:bf:ae

// disconnect? not by me!

13:45:43 wpa_supplicant[19459]: wlan0: CTRL-EVENT-DISCONNECTED bssid=c0:25:06:cf:bf:ae reason=2 locally_generated=1
13:45:43 NetworkManager[878]: <info> (wlan0): supplicant interface state: 4-way handshake -> authenticating
13:45:43 NetworkManager[878]: <warn> Connection disconnected (reason -2)
13:45:43 kernel: [119099.798786] wlan0: send auth to c0:25:06:cf:bf:ae (try 1/3)
13:45:43 kernel: [119099.804412] cfg80211: World regulatory domain updated:
13:45:43 kernel: [119099.804417] cfg80211:  DFS Master region: unset
13:45:43 kernel: [119099.804420] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
13:45:43 kernel: [119099.804423] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
13:45:43 kernel: [119099.804426] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
13:45:43 kernel: [119099.804429] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm), (N/A)
13:45:43 kernel: [119099.804431] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
13:45:43 kernel: [119099.804433] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
13:45:43 wpa_supplicant[19459]: wlan0: CTRL-EVENT-SCAN-STARTED 

// state machine messed up now?

13:45:46 kernel: [119103.285270] wlan0: send auth to c0:25:06:cf:bf:ae (try 2/3)
13:45:46 kernel: [119103.392717] wlan0: send auth to c0:25:06:cf:bf:ae (try 3/3)
13:45:47 kernel: [119103.496740] wlan0: authentication with c0:25:06:cf:bf:ae timed out
13:45:47 NetworkManager[878]: <info> (wlan0): supplicant interface state: authenticating -> disconnected
13:45:47 NetworkManager[878]: <info> (wlan0): supplicant interface state: disconnected -> scanning
13:46:04 NetworkManager[878]: <warn> Activation (wlan0/wireless): association took too long.
13:46:04 NetworkManager[878]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
13:46:04 NetworkManager[878]: <warn> Activation (wlan0/wireless): asking for new secrets
13:46:04 NetworkManager[878]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.
13:46:04 NetworkManager[878]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.
13:46:04 NetworkManager[878]: get_secret_flags: assertion 'is_secret_prop (setting, secret_name, error)' failed
13:46:04 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
13:46:04 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
13:46:04 NetworkManager[878]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
13:46:04 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
13:46:04 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
13:46:04 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
13:46:04 NetworkManager[878]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
13:46:04 NetworkManager[878]: <info> Activation (wlan0/wireless): connection 'Oranjeplantage 35 5G' has security, and secrets exist.  No new secrets needed.
13:46:04 NetworkManager[878]: <info> Config: added 'ssid' value 'Oroanjeplage 35 5G'
13:46:04 NetworkManager[878]: <info> Config: added 'scan_ssid' value '1'
13:46:04 NetworkManager[878]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
13:46:04 NetworkManager[878]: <info> Config: added 'psk' value '<omitted>'
13:46:04 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
13:46:04 NetworkManager[878]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.
13:46:04 NetworkManager[878]: <info> Config: set interface ap_scan to 1
13:46:04 wpa_supplicant[19459]: message repeated 2 times: [ wlan0: CTRL-EVENT-SCAN-STARTED ]
13:46:08 wpa_supplicant[19459]: wlan0: SME: Trying to authenticate with 00:00:00:00:00:00 (SSID='Oroanjeplage 35 5G' freq=5180 MHz)
13:46:08 wpa_supplicant[19459]: wlan0: CTRL-EVENT-SCAN-STARTED 
13:46:08 NetworkManager[878]: <info> (wlan0): supplicant interface state: scanning -> authenticating
13:46:08 NetworkManager[878]: <info> (wlan0): supplicant interface state: authenticating -> disconnected
13:46:08 NetworkManager[878]: <info> (wlan0): supplicant interface state: disconnected -> scanning
13:46:29 NetworkManager[878]: <warn> Activation (wlan0/wireless): association took too long, failing activation.
13:46:29 NetworkManager[878]: <info> (wlan0): device state change: config -> failed (reason 'SSID not found') [50 120 53]
13:46:29 NetworkManager[878]: <info> NetworkManager state is now DISCONNECTED
13:46:29 NetworkManager[878]: <warn> Activation (wlan0) failed for connection 'Oranjeplantage 35 5G'
13:46:29 NetworkManager[878]: <info> (wlan0): device state change: failed -> disconnected (reason 'none') [120 30 0]
13:46:29 NetworkManager[878]: <info> (wlan0): deactivating device (reason 'none') [0]
13:46:29 NetworkManager[878]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.
13:46:29 NetworkManager[878]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.

// now tries again

13:46:32 NetworkManager[878]: <info> Auto-activating connection 'Oranjeplantage 35 5G'.
13:46:32 NetworkManager[878]: <info> Activation (wlan0) starting connection 'Oranjeplantage 35 5G'
13:46:32 NetworkManager[878]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
13:46:32 NetworkManager[878]: <info> NetworkManager state is now CONNECTING
13:46:32 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
13:46:32 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
13:46:32 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
13:46:32 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
13:46:32 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
13:46:32 NetworkManager[878]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
13:46:32 NetworkManager[878]: <info> Activation (wlan0/wireless): access point 'Oranjeplantage 35 5G' has security, but secrets are required.
13:46:32 NetworkManager[878]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
13:46:32 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
13:46:32 NetworkManager[878]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.
13:46:32 NetworkManager[878]: get_secret_flags: assertion 'is_secret_prop (setting, secret_name, error)' failed
13:46:32 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
13:46:32 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
13:46:32 NetworkManager[878]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
13:46:32 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
13:46:32 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
13:46:32 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
13:46:32 NetworkManager[878]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
13:46:32 NetworkManager[878]: <info> Activation (wlan0/wireless): connection 'Oranjeplantage 35 5G' has security, and secrets exist.  No new secrets needed.
13:46:32 NetworkManager[878]: <info> Config: added 'ssid' value 'Oroanjeplage 35 5G'
13:46:32 NetworkManager[878]: <info> Config: added 'scan_ssid' value '1'
13:46:32 NetworkManager[878]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
13:46:32 NetworkManager[878]: <info> Config: added 'psk' value '<omitted>'
13:46:32 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
13:46:32 NetworkManager[878]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.
13:46:32 NetworkManager[878]: <info> Config: set interface ap_scan to 1
13:46:57 NetworkManager[878]: <warn> Activation (wlan0/wireless): association took too long, failing activation.
13:46:57 NetworkManager[878]: <info> (wlan0): device state change: config -> failed (reason 'SSID not found') [50 120 53]
13:46:57 NetworkManager[878]: <info> NetworkManager state is now DISCONNECTED
13:46:57 NetworkManager[878]: <warn> Activation (wlan0) failed for connection 'Oranjeplantage 35 5G'
13:46:57 NetworkManager[878]: <info> (wlan0): device state change: failed -> disconnected (reason 'none') [120 30 0]
13:46:57 NetworkManager[878]: <info> (wlan0): deactivating device (reason 'none') [0]
13:46:57 NetworkManager[878]: <info> (wlan0): supplicant interface state: scanning -> disconnected
13:46:57 NetworkManager[878]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.

// try again

13:47:00 NetworkManager[878]: <info> Auto-activating connection 'Oranjeplantage 35 5G'.
13:47:00 NetworkManager[878]: <info> Activation (wlan0) starting connection 'Oranjeplantage 35 5G'
13:47:00 NetworkManager[878]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
13:47:00 NetworkManager[878]: <info> NetworkManager state is now CONNECTING
13:47:00 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
13:47:00 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
13:47:00 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
13:47:00 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
13:47:00 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
13:47:00 NetworkManager[878]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
13:47:00 NetworkManager[878]: <info> Activation (wlan0/wireless): access point 'Oranjeplantage 35 5G' has security, but secrets are required.
13:47:00 NetworkManager[878]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
13:47:00 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
13:47:00 NetworkManager[878]: get_secret_flags: assertion 'is_secret_prop (setting, secret_name, error)' failed
13:47:00 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
13:47:00 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
13:47:00 NetworkManager[878]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
13:47:00 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
13:47:00 NetworkManager[878]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
13:47:00 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
13:47:00 NetworkManager[878]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
13:47:00 NetworkManager[878]: <info> Activation (wlan0/wireless): connection 'Oranjeplantage 35 5G' has security, and secrets exist.  No new secrets needed.
13:47:00 NetworkManager[878]: <info> Config: added 'ssid' value 'Oroanjeplage 35 5G'
13:47:00 NetworkManager[878]: <info> Config: added 'scan_ssid' value '1'
13:47:00 NetworkManager[878]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
13:47:00 NetworkManager[878]: <info> Config: added 'psk' value '<omitted>'
13:47:00 NetworkManager[878]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
13:47:00 NetworkManager[878]: <info> Config: set interface ap_scan to 1
13:47:00 NetworkManager[878]: <info> (wlan0): supplicant interface state: disconnected -> scanning
