May 21 14:10:45 r1-base kernel: wlx1cbfced1ed31: Limiting TX power to 17 dBm as advertised by 64:f6:9d:24:fb:0f May 21 14:10:45 r1-base systemd-networkd[719]: wlx1cbfced1ed31: Gained carrier May 21 14:10:45 r1-base NetworkManager[790]: [1716293445.7578] device (wlx1cbfced1ed31): supplicant interface state: associating -> completed May 21 14:10:45 r1-base networkctl[6344]: Interface "wlan0" not found. May 21 14:10:45 r1-base systemd[1]: networkd-dispatcher.service: Got notification message from PID 6344, but reception only permitted for main PID 809 May 21 14:10:45 r1-base networkd-dispatcher[809]: ERROR:Failed to get interface "wlan0" status: Command '['/usr/bin/networkctl', 'status', '--no-pager', '--no-legend', '--', 'wlan0']' returned non-zero exit status 1. May 21 14:10:45 r1-base networkd-dispatcher[6345]: wlan0 No such device May 21 14:10:45 r1-base networkd-dispatcher[809]: ERROR:Error handling notification for interface 'wlan0' entering operational state routable May 21 14:10:45 r1-base networkd-dispatcher[809]: Traceback (most recent call last): May 21 14:10:45 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 334, in _handle_one_state May 21 14:10:45 r1-base networkd-dispatcher[809]: self.run_hooks_for_state(new_iface, state) May 21 14:10:45 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 365, in run_hooks_for_state May 21 14:10:45 r1-base networkd-dispatcher[809]: data = get_interface_data(iface) May 21 14:10:45 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 256, in get_interface_data May 21 14:10:45 r1-base networkd-dispatcher[809]: data['ESSID'] = get_wlan_essid(iface.name) May 21 14:10:45 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 144, in get_wlan_essid May 21 14:10:45 r1-base networkd-dispatcher[809]: return iwconfig_get_ssid(iface_name) May 21 14:10:45 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 160, in iwconfig_get_ssid May 21 14:10:45 r1-base networkd-dispatcher[809]: out = subprocess.check_output([IWCONFIG, '--', iface_name]) May 21 14:10:45 r1-base networkd-dispatcher[809]: File "/usr/lib/python3.10/subprocess.py", line 421, in check_output May 21 14:10:45 r1-base networkd-dispatcher[809]: return run(*popenargs, stdout=PIPE, timeout=timeout, check=True, May 21 14:10:45 r1-base networkd-dispatcher[809]: File "/usr/lib/python3.10/subprocess.py", line 526, in run May 21 14:10:45 r1-base networkd-dispatcher[809]: raise CalledProcessError(retcode, process.args, May 21 14:10:45 r1-base networkd-dispatcher[809]: subprocess.CalledProcessError: Command '['/usr/sbin/iwconfig', '--', 'wlan0']' returned non-zero exit status 237. May 21 14:10:46 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-38 noise=9999 txrate=86700 May 21 14:13:08 r1-base kernel: wlx1cbfced1ed31: disassociated from 64:f6:9d:24:fb:0f (Reason: 1=UNSPECIFIED) May 21 14:13:08 r1-base systemd-networkd[719]: wlx1cbfced1ed31: Lost carrier May 21 14:13:08 r1-base kernel: rtw_8821cu 3-8:1.0: timed out to flush queue 3 May 21 14:13:08 r1-base kernel: rtw_8821cu 3-8:1.0: timed out to flush queue 3 May 21 14:13:08 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-DISCONNECTED bssid=64:f6:9d:24:fb:0f reason=1 May 21 14:13:08 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 21 14:13:08 r1-base NetworkManager[790]: [1716293588.7608] device (wlx1cbfced1ed31): supplicant interface state: completed -> disconnected May 21 14:13:09 r1-base NetworkManager[790]: [1716293589.4374] device (wlx1cbfced1ed31): supplicant interface state: disconnected -> scanning May 21 14:13:10 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN May 21 14:13:10 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN May 21 14:13:10 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN May 21 14:13:10 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN May 21 14:13:13 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: SME: Trying to authenticate with 64:f6:9d:60:fb:5f (SSID='r1_001-wifi' freq=5260 MHz) May 21 14:13:13 r1-base kernel: wlx1cbfced1ed31: authenticate with 64:f6:9d:60:fb:5f May 21 14:13:14 r1-base kernel: wlx1cbfced1ed31: send auth to 64:f6:9d:60:fb:5f (try 1/3) May 21 14:13:14 r1-base kernel: wlx1cbfced1ed31: authenticated May 21 14:13:14 r1-base NetworkManager[790]: [1716293594.3505] device (wlx1cbfced1ed31): supplicant interface state: scanning -> authenticating May 21 14:13:14 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: Trying to associate with 64:f6:9d:60:fb:5f (SSID='r1_001-wifi' freq=5260 MHz) May 21 14:13:14 r1-base kernel: wlx1cbfced1ed31: associate with 64:f6:9d:60:fb:5f (try 1/3) May 21 14:13:14 r1-base kernel: wlx1cbfced1ed31: RX AssocResp from 64:f6:9d:60:fb:5f (capab=0x1011 status=0 aid=1) May 21 14:13:14 r1-base kernel: wlx1cbfced1ed31: associated May 21 14:13:14 r1-base systemd-networkd[719]: wlx1cbfced1ed31: Connected WiFi access point: r1_001-wifi (64:f6:9d:60:fb:5f) May 21 14:13:14 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: Associated with 64:f6:9d:60:fb:5f May 21 14:13:14 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 21 14:13:14 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=IT May 21 14:13:14 r1-base NetworkManager[790]: [1716293594.3812] device (wlx1cbfced1ed31): supplicant interface state: authenticating -> associating May 21 14:13:14 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: WPA: Key negotiation completed with 64:f6:9d:60:fb:5f [PTK=CCMP GTK=CCMP] May 21 14:13:14 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-CONNECTED - Connection to 64:f6:9d:60:fb:5f completed [id=0 id_str=] May 21 14:13:14 r1-base systemd-networkd[719]: wlx1cbfced1ed31: Gained carrier May 21 14:13:14 r1-base NetworkManager[790]: [1716293594.3946] device (wlx1cbfced1ed31): supplicant interface state: associating -> completed May 21 14:13:14 r1-base networkctl[6352]: Interface "wlan0" not found. May 21 14:13:14 r1-base systemd[1]: networkd-dispatcher.service: Got notification message from PID 6352, but reception only permitted for main PID 809 May 21 14:13:14 r1-base networkd-dispatcher[809]: ERROR:Failed to get interface "wlan0" status: Command '['/usr/bin/networkctl', 'status', '--no-pager', '--no-legend', '--', 'wlan0']' returned non-zero exit status 1. May 21 14:13:14 r1-base kernel: wlx1cbfced1ed31: Limiting TX power to 17 dBm as advertised by 64:f6:9d:60:fb:5f May 21 14:13:14 r1-base networkd-dispatcher[6353]: wlan0 No such device May 21 14:13:14 r1-base networkd-dispatcher[809]: ERROR:Error handling notification for interface 'wlan0' entering operational state routable May 21 14:13:14 r1-base networkd-dispatcher[809]: Traceback (most recent call last): May 21 14:13:14 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 334, in _handle_one_state May 21 14:13:14 r1-base networkd-dispatcher[809]: self.run_hooks_for_state(new_iface, state) May 21 14:13:14 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 365, in run_hooks_for_state May 21 14:13:14 r1-base networkd-dispatcher[809]: data = get_interface_data(iface) May 21 14:13:14 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 256, in get_interface_data May 21 14:13:14 r1-base networkd-dispatcher[809]: data['ESSID'] = get_wlan_essid(iface.name) May 21 14:13:14 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 144, in get_wlan_essid May 21 14:13:14 r1-base networkd-dispatcher[809]: return iwconfig_get_ssid(iface_name) May 21 14:13:14 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 160, in iwconfig_get_ssid May 21 14:13:14 r1-base networkd-dispatcher[809]: out = subprocess.check_output([IWCONFIG, '--', iface_name]) May 21 14:13:14 r1-base networkd-dispatcher[809]: File "/usr/lib/python3.10/subprocess.py", line 421, in check_output May 21 14:13:14 r1-base networkd-dispatcher[809]: return run(*popenargs, stdout=PIPE, timeout=timeout, check=True, May 21 14:13:14 r1-base networkd-dispatcher[809]: File "/usr/lib/python3.10/subprocess.py", line 526, in run May 21 14:13:14 r1-base networkd-dispatcher[809]: raise CalledProcessError(retcode, process.args, May 21 14:13:14 r1-base networkd-dispatcher[809]: subprocess.CalledProcessError: Command '['/usr/sbin/iwconfig', '--', 'wlan0']' returned non-zero exit status 237. May 21 14:13:14 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-61 noise=9999 txrate=86700 May 21 14:15:37 r1-base kernel: wlx1cbfced1ed31: disassociated from 64:f6:9d:60:fb:5f (Reason: 1=UNSPECIFIED) May 21 14:15:37 r1-base systemd-networkd[719]: wlx1cbfced1ed31: Lost carrier May 21 14:15:37 r1-base kernel: rtw_8821cu 3-8:1.0: timed out to flush queue 3 May 21 14:15:37 r1-base kernel: rtw_8821cu 3-8:1.0: timed out to flush queue 3 May 21 14:15:37 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-DISCONNECTED bssid=64:f6:9d:60:fb:5f reason=1 May 21 14:15:37 r1-base wpa_supplicant[828]: BSSID 64:f6:9d:60:fb:5f ignore list count incremented to 2, ignoring for 10 seconds May 21 14:15:37 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 21 14:15:37 r1-base NetworkManager[790]: [1716293737.3315] device (wlx1cbfced1ed31): supplicant interface state: completed -> disconnected May 21 14:15:37 r1-base gnome-shell[1109]: An active wireless connection, in infrastructure mode, involves no access point? May 21 14:15:38 r1-base NetworkManager[790]: [1716293738.0187] device (wlx1cbfced1ed31): supplicant interface state: disconnected -> scanning May 21 14:15:44 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN May 21 14:15:44 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN May 21 14:15:45 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN May 21 14:15:45 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN May 21 14:15:48 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: SME: Trying to authenticate with 64:f6:9d:24:fb:0f (SSID='r1_001-wifi' freq=5200 MHz) May 21 14:15:48 r1-base kernel: wlx1cbfced1ed31: authenticate with 64:f6:9d:24:fb:0f May 21 14:15:48 r1-base kernel: wlx1cbfced1ed31: send auth to 64:f6:9d:24:fb:0f (try 1/3) May 21 14:15:48 r1-base kernel: wlx1cbfced1ed31: authenticated May 21 14:15:48 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: Trying to associate with 64:f6:9d:24:fb:0f (SSID='r1_001-wifi' freq=5200 MHz) May 21 14:15:48 r1-base NetworkManager[790]: [1716293748.6681] device (wlx1cbfced1ed31): supplicant interface state: scanning -> authenticating May 21 14:15:48 r1-base kernel: wlx1cbfced1ed31: associate with 64:f6:9d:24:fb:0f (try 1/3) May 21 14:15:48 r1-base NetworkManager[790]: [1716293748.6703] device (wlx1cbfced1ed31): supplicant interface state: authenticating -> associating May 21 14:15:48 r1-base kernel: wlx1cbfced1ed31: RX AssocResp from 64:f6:9d:24:fb:0f (capab=0x1011 status=0 aid=1) May 21 14:15:48 r1-base kernel: wlx1cbfced1ed31: associated May 21 14:15:48 r1-base systemd-networkd[719]: wlx1cbfced1ed31: Connected WiFi access point: r1_001-wifi (64:f6:9d:24:fb:0f) May 21 14:15:48 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: Associated with 64:f6:9d:24:fb:0f May 21 14:15:48 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 21 14:15:48 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=IT May 21 14:15:48 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: WPA: Key negotiation completed with 64:f6:9d:24:fb:0f [PTK=CCMP GTK=CCMP] May 21 14:15:48 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-CONNECTED - Connection to 64:f6:9d:24:fb:0f completed [id=0 id_str=] May 21 14:15:48 r1-base systemd-networkd[719]: wlx1cbfced1ed31: Gained carrier May 21 14:15:48 r1-base NetworkManager[790]: [1716293748.6996] device (wlx1cbfced1ed31): supplicant interface state: associating -> completed May 21 14:15:48 r1-base networkctl[6364]: Interface "wlan0" not found. May 21 14:15:48 r1-base systemd[1]: networkd-dispatcher.service: Got notification message from PID 6364, but reception only permitted for main PID 809 May 21 14:15:48 r1-base networkd-dispatcher[809]: ERROR:Failed to get interface "wlan0" status: Command '['/usr/bin/networkctl', 'status', '--no-pager', '--no-legend', '--', 'wlan0']' returned non-zero exit status 1. May 21 14:15:48 r1-base networkd-dispatcher[6365]: wlan0 No such device May 21 14:15:48 r1-base networkd-dispatcher[809]: ERROR:Error handling notification for interface 'wlan0' entering operational state routable May 21 14:15:48 r1-base networkd-dispatcher[809]: Traceback (most recent call last): May 21 14:15:48 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 334, in _handle_one_state May 21 14:15:48 r1-base networkd-dispatcher[809]: self.run_hooks_for_state(new_iface, state) May 21 14:15:48 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 365, in run_hooks_for_state May 21 14:15:48 r1-base networkd-dispatcher[809]: data = get_interface_data(iface) May 21 14:15:48 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 256, in get_interface_data May 21 14:15:48 r1-base networkd-dispatcher[809]: data['ESSID'] = get_wlan_essid(iface.name) May 21 14:15:48 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 144, in get_wlan_essid May 21 14:15:48 r1-base networkd-dispatcher[809]: return iwconfig_get_ssid(iface_name) May 21 14:15:48 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 160, in iwconfig_get_ssid May 21 14:15:48 r1-base networkd-dispatcher[809]: out = subprocess.check_output([IWCONFIG, '--', iface_name]) May 21 14:15:48 r1-base networkd-dispatcher[809]: File "/usr/lib/python3.10/subprocess.py", line 421, in check_output May 21 14:15:48 r1-base networkd-dispatcher[809]: return run(*popenargs, stdout=PIPE, timeout=timeout, check=True, May 21 14:15:48 r1-base networkd-dispatcher[809]: File "/usr/lib/python3.10/subprocess.py", line 526, in run May 21 14:15:48 r1-base networkd-dispatcher[809]: raise CalledProcessError(retcode, process.args, May 21 14:15:48 r1-base networkd-dispatcher[809]: subprocess.CalledProcessError: Command '['/usr/sbin/iwconfig', '--', 'wlan0']' returned non-zero exit status 237. May 21 14:15:48 r1-base kernel: wlx1cbfced1ed31: Limiting TX power to 17 dBm as advertised by 64:f6:9d:24:fb:0f May 21 14:15:49 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-37 noise=9999 txrate=86700 May 21 14:17:01 r1-base CRON[6402]: pam_unix(cron:session): session opened for user root(uid=0) by (uid=0) May 21 14:17:01 r1-base CRON[6403]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 21 14:17:01 r1-base CRON[6402]: pam_unix(cron:session): session closed for user root May 21 14:18:11 r1-base kernel: wlx1cbfced1ed31: disassociated from 64:f6:9d:24:fb:0f (Reason: 1=UNSPECIFIED) May 21 14:18:11 r1-base systemd-networkd[719]: wlx1cbfced1ed31: Lost carrier May 21 14:18:11 r1-base kernel: rtw_8821cu 3-8:1.0: timed out to flush queue 3 May 21 14:18:11 r1-base kernel: rtw_8821cu 3-8:1.0: timed out to flush queue 3 May 21 14:18:11 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-DISCONNECTED bssid=64:f6:9d:24:fb:0f reason=1 May 21 14:18:11 r1-base wpa_supplicant[828]: BSSID 64:f6:9d:24:fb:0f ignore list count incremented to 2, ignoring for 10 seconds May 21 14:18:11 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 21 14:18:11 r1-base NetworkManager[790]: [1716293891.6529] device (wlx1cbfced1ed31): supplicant interface state: completed -> disconnected May 21 14:18:11 r1-base kernel: rtw_8821cu 3-8:1.0: failed to download firmware May 21 14:18:11 r1-base kernel: rtw_8821cu 3-8:1.0: leave idle state failed May 21 14:18:11 r1-base kernel: rtw_8821cu 3-8:1.0: failed to leave ips state May 21 14:18:11 r1-base kernel: rtw_8821cu 3-8:1.0: failed to leave idle state May 21 14:18:11 r1-base NetworkManager[790]: [1716293891.8280] device (wlx1cbfced1ed31): supplicant interface state: disconnected -> scanning May 21 14:18:18 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN May 21 14:18:18 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN May 21 14:18:18 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN May 21 14:18:19 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN May 21 14:18:21 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: SME: Trying to authenticate with 64:f6:9d:24:fb:0f (SSID='r1_001-wifi' freq=5200 MHz) May 21 14:18:21 r1-base kernel: wlx1cbfced1ed31: authenticate with 64:f6:9d:24:fb:0f May 21 14:18:22 r1-base kernel: wlx1cbfced1ed31: send auth to 64:f6:9d:24:fb:0f (try 1/3) May 21 14:18:22 r1-base kernel: wlx1cbfced1ed31: authenticated May 21 14:18:22 r1-base NetworkManager[790]: [1716293902.4516] device (wlx1cbfced1ed31): supplicant interface state: scanning -> authenticating May 21 14:18:22 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: Trying to associate with 64:f6:9d:24:fb:0f (SSID='r1_001-wifi' freq=5200 MHz) May 21 14:18:22 r1-base kernel: wlx1cbfced1ed31: associate with 64:f6:9d:24:fb:0f (try 1/3) May 21 14:18:22 r1-base kernel: wlx1cbfced1ed31: RX AssocResp from 64:f6:9d:24:fb:0f (capab=0x1011 status=0 aid=1) May 21 14:18:22 r1-base NetworkManager[790]: [1716293902.4636] device (wlx1cbfced1ed31): supplicant interface state: authenticating -> associating May 21 14:18:22 r1-base kernel: wlx1cbfced1ed31: associated May 21 14:18:22 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: Associated with 64:f6:9d:24:fb:0f May 21 14:18:22 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 21 14:18:22 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=IT May 21 14:18:22 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: WPA: Key negotiation completed with 64:f6:9d:24:fb:0f [PTK=CCMP GTK=CCMP] May 21 14:18:22 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-CONNECTED - Connection to 64:f6:9d:24:fb:0f completed [id=0 id_str=] May 21 14:18:22 r1-base systemd-networkd[719]: wlx1cbfced1ed31: Gained carrier May 21 14:18:22 r1-base NetworkManager[790]: [1716293902.4873] device (wlx1cbfced1ed31): supplicant interface state: associating -> completed May 21 14:18:22 r1-base networkctl[6446]: Interface "wlan0" not found. May 21 14:18:22 r1-base systemd[1]: networkd-dispatcher.service: Got notification message from PID 6446, but reception only permitted for main PID 809 May 21 14:18:22 r1-base networkd-dispatcher[809]: ERROR:Failed to get interface "wlan0" status: Command '['/usr/bin/networkctl', 'status', '--no-pager', '--no-legend', '--', 'wlan0']' returned non-zero exit status 1. May 21 14:18:22 r1-base networkd-dispatcher[6447]: wlan0 No such device May 21 14:18:22 r1-base networkd-dispatcher[809]: ERROR:Error handling notification for interface 'wlan0' entering operational state routable May 21 14:18:22 r1-base networkd-dispatcher[809]: Traceback (most recent call last): May 21 14:18:22 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 334, in _handle_one_state May 21 14:18:22 r1-base networkd-dispatcher[809]: self.run_hooks_for_state(new_iface, state) May 21 14:18:22 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 365, in run_hooks_for_state May 21 14:18:22 r1-base networkd-dispatcher[809]: data = get_interface_data(iface) May 21 14:18:22 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 256, in get_interface_data May 21 14:18:22 r1-base networkd-dispatcher[809]: data['ESSID'] = get_wlan_essid(iface.name) May 21 14:18:22 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 144, in get_wlan_essid May 21 14:18:22 r1-base networkd-dispatcher[809]: return iwconfig_get_ssid(iface_name) May 21 14:18:22 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 160, in iwconfig_get_ssid May 21 14:18:22 r1-base networkd-dispatcher[809]: out = subprocess.check_output([IWCONFIG, '--', iface_name]) May 21 14:18:22 r1-base networkd-dispatcher[809]: File "/usr/lib/python3.10/subprocess.py", line 421, in check_output May 21 14:18:22 r1-base networkd-dispatcher[809]: return run(*popenargs, stdout=PIPE, timeout=timeout, check=True, May 21 14:18:22 r1-base networkd-dispatcher[809]: File "/usr/lib/python3.10/subprocess.py", line 526, in run May 21 14:18:22 r1-base networkd-dispatcher[809]: raise CalledProcessError(retcode, process.args, May 21 14:18:22 r1-base networkd-dispatcher[809]: subprocess.CalledProcessError: Command '['/usr/sbin/iwconfig', '--', 'wlan0']' returned non-zero exit status 237. May 21 14:18:22 r1-base kernel: wlx1cbfced1ed31: Limiting TX power to 17 dBm as advertised by 64:f6:9d:24:fb:0f May 21 14:18:22 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-37 noise=9999 txrate=86700 May 21 14:19:27 r1-base sshd[6458]: Accepted password for r1-user from 192.168.101.3 port 38050 ssh2 May 21 14:19:27 r1-base sshd[6458]: pam_unix(sshd:session): session opened for user r1-user(uid=1000) by (uid=0) May 21 14:19:27 r1-base systemd-logind[825]: New session 13 of user r1-user. May 21 14:19:27 r1-base systemd[1]: Started Session 13 of User r1-user. May 21 14:20:36 r1-base sudo[6630]: r1-user : TTY=pts/6 ; PWD=/usr/local/src/robot/tour-guide-robot/docker_stuff/docker_tourCore2 ; USER=root ; COMMAND=/usr/bin/xhost + May 21 14:20:36 r1-base sudo[6630]: pam_unix(sudo:session): session opened for user root(uid=0) by r1-user(uid=1000) May 21 14:20:36 r1-base sudo[6630]: pam_unix(sudo:session): session closed for user root May 21 14:20:36 r1-base sudo[6633]: r1-user : TTY=pts/6 ; PWD=/usr/local/src/robot/tour-guide-robot/docker_stuff/docker_tourCore2 ; USER=root ; COMMAND=/usr/bin/docker run --rm -it --privileged --network host --pid host -e DISPLAY -v /tmp/.X11-unix:/tmp/.X11-unix -e QT_X11_NO_MITSHM=1 elandini84/r1images:tourCore2_ubuntu22.04_iron_devel May 21 14:20:36 r1-base sudo[6633]: pam_unix(sudo:session): session opened for user root(uid=0) by r1-user(uid=1000) May 21 14:20:36 r1-base systemd[1]: var-lib-docker-overlay2-251d4abdcc28ab10b64bbdda20f8e82c99a17289376822bb75e8b9480f817280\x2dinit-merged.mount: Deactivated successfully. May 21 14:20:36 r1-base containerd[913]: time="2024-05-21T14:20:36.642746064+02:00" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 21 14:20:36 r1-base containerd[913]: time="2024-05-21T14:20:36.642817958+02:00" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 21 14:20:36 r1-base containerd[913]: time="2024-05-21T14:20:36.642836369+02:00" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 21 14:20:36 r1-base containerd[913]: time="2024-05-21T14:20:36.642993730+02:00" level=info msg="starting signal loop" namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/ba6febf9d3b3ebc31efce39b56e05b8052737451c7bcb67103c24464a05ed28f pid=6652 runtime=io.containerd.runc.v2 May 21 14:20:36 r1-base systemd[1]: Started libcontainer container ba6febf9d3b3ebc31efce39b56e05b8052737451c7bcb67103c24464a05ed28f. May 21 14:20:52 r1-base systemd[1]: docker-ba6febf9d3b3ebc31efce39b56e05b8052737451c7bcb67103c24464a05ed28f.scope: Deactivated successfully. May 21 14:20:52 r1-base containerd[913]: time="2024-05-21T14:20:52.735241937+02:00" level=info msg="shim disconnected" id=ba6febf9d3b3ebc31efce39b56e05b8052737451c7bcb67103c24464a05ed28f May 21 14:20:52 r1-base containerd[913]: time="2024-05-21T14:20:52.735323926+02:00" level=warning msg="cleaning up after shim disconnected" id=ba6febf9d3b3ebc31efce39b56e05b8052737451c7bcb67103c24464a05ed28f namespace=moby May 21 14:20:52 r1-base containerd[913]: time="2024-05-21T14:20:52.735343571+02:00" level=info msg="cleaning up dead shim" May 21 14:20:52 r1-base dockerd[1056]: time="2024-05-21T14:20:52.735318827+02:00" level=info msg="ignoring event" container=ba6febf9d3b3ebc31efce39b56e05b8052737451c7bcb67103c24464a05ed28f module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" May 21 14:20:52 r1-base containerd[913]: time="2024-05-21T14:20:52.743116707+02:00" level=warning msg="cleanup warnings time=\"2024-05-21T14:20:52+02:00\" level=info msg=\"starting signal loop\" namespace=moby pid=6760 runtime=io.containerd.runc.v2\n" May 21 14:20:52 r1-base systemd[1]: var-lib-docker-overlay2-251d4abdcc28ab10b64bbdda20f8e82c99a17289376822bb75e8b9480f817280-merged.mount: Deactivated successfully. May 21 14:20:52 r1-base sudo[6633]: pam_unix(sudo:session): session closed for user root May 21 14:21:06 r1-base sudo[6808]: r1-user : TTY=pts/7 ; PWD=/usr/local/src/robot/tour-guide-robot/docker_stuff/docker_tourCore2 ; USER=root ; COMMAND=/usr/bin/xhost + May 21 14:21:06 r1-base sudo[6808]: pam_unix(sudo:session): session opened for user root(uid=0) by r1-user(uid=1000) May 21 14:21:06 r1-base sudo[6808]: pam_unix(sudo:session): session closed for user root May 21 14:21:06 r1-base sudo[6811]: r1-user : TTY=pts/7 ; PWD=/usr/local/src/robot/tour-guide-robot/docker_stuff/docker_tourCore2 ; USER=root ; COMMAND=/usr/bin/docker run --rm -it --privileged --network host --pid host -e DISPLAY -v /tmp/.X11-unix:/tmp/.X11-unix -e QT_X11_NO_MITSHM=1 elandini84/r1images:tourCore2_ubuntu22.04_iron_devel May 21 14:21:06 r1-base sudo[6811]: pam_unix(sudo:session): session opened for user root(uid=0) by r1-user(uid=1000) May 21 14:21:06 r1-base systemd[1]: var-lib-docker-overlay2-0f0874028db867d4dc3d7d2cda8859f7f06051577f8f394c5f6f8cd5698eaeb6\x2dinit-merged.mount: Deactivated successfully. May 21 14:21:06 r1-base containerd[913]: time="2024-05-21T14:21:06.763785661+02:00" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 21 14:21:06 r1-base containerd[913]: time="2024-05-21T14:21:06.763843174+02:00" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 21 14:21:06 r1-base containerd[913]: time="2024-05-21T14:21:06.763855780+02:00" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 21 14:21:06 r1-base containerd[913]: time="2024-05-21T14:21:06.764002908+02:00" level=info msg="starting signal loop" namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/b141b558bcf1ade5759103c5c81d074cdd6c6b895215bf6e2d118ae5575fb7ee pid=6829 runtime=io.containerd.runc.v2 May 21 14:21:06 r1-base systemd[1]: Started libcontainer container b141b558bcf1ade5759103c5c81d074cdd6c6b895215bf6e2d118ae5575fb7ee. May 21 14:21:33 r1-base sshd[6514]: Received disconnect from 192.168.101.3 port 38050:11: disconnected by user May 21 14:21:33 r1-base sshd[6514]: Disconnected from user r1-user 192.168.101.3 port 38050 May 21 14:21:33 r1-base sshd[6458]: pam_unix(sshd:session): session closed for user r1-user May 21 14:21:33 r1-base systemd[1]: session-13.scope: Deactivated successfully. May 21 14:21:33 r1-base systemd-logind[825]: Session 13 logged out. Waiting for processes to exit. May 21 14:21:33 r1-base systemd-logind[825]: Removed session 13. May 21 14:21:57 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: WPA: Group rekeying completed with 64:f6:9d:24:fb:0f [GTK=CCMP] May 21 14:22:41 r1-base sshd[6977]: Accepted password for r1-user from 192.168.101.3 port 40010 ssh2 May 21 14:22:41 r1-base sshd[6977]: pam_unix(sshd:session): session opened for user r1-user(uid=1000) by (uid=0) May 21 14:22:41 r1-base systemd-logind[825]: New session 14 of user r1-user. May 21 14:22:42 r1-base systemd[1]: Started Session 14 of User r1-user. May 21 14:23:43 r1-base sshd[7113]: Accepted password for r1-user from 192.168.101.3 port 51384 ssh2 May 21 14:23:43 r1-base sshd[7113]: pam_unix(sshd:session): session opened for user r1-user(uid=1000) by (uid=0) May 21 14:23:43 r1-base systemd-logind[825]: New session 15 of user r1-user. May 21 14:23:43 r1-base systemd[1]: Started Session 15 of User r1-user. May 21 14:24:53 r1-base sshd[7064]: fatal: Timeout before authentication for 192.168.101.3 port 37744 May 21 14:27:33 r1-base kernel: igb 0000:02:00.0 enp2s0: igb: enp2s0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX May 21 14:27:33 r1-base kernel: IPv6: ADDRCONF(NETDEV_CHANGE): enp2s0: link becomes ready May 21 14:27:33 r1-base systemd-networkd[719]: enp2s0: Gained carrier May 21 14:27:33 r1-base avahi-daemon[787]: Joining mDNS multicast group on interface enp2s0.IPv4 with address 10.0.1.104. May 21 14:27:33 r1-base NetworkManager[790]: [1716294453.5741] device (enp2s0): carrier: link connected May 21 14:27:33 r1-base avahi-daemon[787]: New relevant interface enp2s0.IPv4 for mDNS. May 21 14:27:33 r1-base avahi-daemon[787]: Registering new address record for 10.0.1.104 on enp2s0.IPv4. May 21 14:27:34 r1-base avahi-daemon[787]: Joining mDNS multicast group on interface enp2s0.IPv6 with address fe80::218:7dff:fea5:8217. May 21 14:27:34 r1-base avahi-daemon[787]: New relevant interface enp2s0.IPv6 for mDNS. May 21 14:27:34 r1-base systemd-networkd[719]: enp2s0: Gained IPv6LL May 21 14:27:34 r1-base avahi-daemon[787]: Registering new address record for fe80::218:7dff:fea5:8217 on enp2s0.*. May 21 14:27:37 r1-base systemd-networkd[719]: enp2s0: Lost carrier May 21 14:27:37 r1-base kernel: igb 0000:02:00.0 enp2s0: igb: enp2s0 NIC Link is Down May 21 14:27:37 r1-base systemd-networkd[719]: enp2s0: DHCPv6 lease lost May 21 14:27:37 r1-base avahi-daemon[787]: Withdrawing address record for 10.0.1.104 on enp2s0. May 21 14:27:37 r1-base avahi-daemon[787]: Leaving mDNS multicast group on interface enp2s0.IPv4 with address 10.0.1.104. May 21 14:27:37 r1-base avahi-daemon[787]: Interface enp2s0.IPv4 no longer relevant for mDNS. May 21 14:27:38 r1-base kernel: igb 0000:02:00.0 enp2s0: igb: enp2s0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX May 21 14:27:38 r1-base NetworkManager[790]: [1716294458.8971] device (enp2s0): carrier: link connected May 21 14:27:38 r1-base systemd-networkd[719]: enp2s0: Gained carrier May 21 14:27:38 r1-base avahi-daemon[787]: Joining mDNS multicast group on interface enp2s0.IPv4 with address 10.0.1.104. May 21 14:27:38 r1-base avahi-daemon[787]: New relevant interface enp2s0.IPv4 for mDNS. May 21 14:27:38 r1-base avahi-daemon[787]: Registering new address record for 10.0.1.104 on enp2s0.IPv4. May 21 14:30:01 r1-base CRON[7754]: pam_unix(cron:session): session opened for user root(uid=0) by (uid=0) May 21 14:30:01 r1-base CRON[7755]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/null; fi) May 21 14:30:01 r1-base CRON[7754]: pam_unix(cron:session): session closed for user root May 21 14:31:22 r1-base systemd[1]: Started Run anacron jobs. May 21 14:31:22 r1-base anacron[7757]: Anacron 2.3 started on 2024-05-21 May 21 14:31:22 r1-base anacron[7757]: Normal exit (0 jobs run) May 21 14:31:22 r1-base systemd[1]: anacron.service: Deactivated successfully. May 21 14:34:18 r1-base sshd[4701]: pam_unix(sshd:session): session closed for user r1-user May 21 14:34:18 r1-base systemd-logind[825]: Session 10 logged out. Waiting for processes to exit. May 21 14:34:18 r1-base systemd[1]: session-10.scope: Deactivated successfully. May 21 14:34:18 r1-base systemd[1]: session-10.scope: Consumed 1.208s CPU time. May 21 14:34:18 r1-base systemd-logind[825]: Removed session 10. May 21 14:35:25 r1-base systemd-resolved[742]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 8.8.8.8. May 21 14:35:28 r1-base systemd-resolved[742]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 8.8.4.4. May 21 14:35:33 r1-base sshd[3238]: pam_unix(sshd:session): session closed for user r1-user May 21 14:35:33 r1-base systemd[1]: session-7.scope: Deactivated successfully. May 21 14:35:33 r1-base systemd[1]: session-7.scope: Consumed 3.493s CPU time. May 21 14:35:33 r1-base systemd-logind[825]: Session 7 logged out. Waiting for processes to exit. May 21 14:35:33 r1-base systemd-logind[825]: Removed session 7. May 21 14:35:46 r1-base systemd-resolved[742]: Using degraded feature set TCP instead of UDP for DNS server 8.8.4.4. May 21 14:35:52 r1-base systemd-resolved[742]: Using degraded feature set TCP instead of UDP for DNS server 8.8.8.8. May 21 14:36:15 r1-base sshd[4886]: pam_unix(sshd:session): session closed for user r1-user May 21 14:36:15 r1-base systemd-logind[825]: Session 11 logged out. Waiting for processes to exit. May 21 14:38:50 r1-base systemd[1]: session-11.scope: Deactivated successfully. May 21 14:38:50 r1-base systemd-logind[825]: Removed session 11. May 21 14:42:56 r1-base systemd-resolved[742]: Using degraded feature set UDP instead of TCP for DNS server 8.8.4.4. May 21 14:43:05 r1-base systemd-resolved[742]: Using degraded feature set UDP instead of TCP for DNS server 8.8.8.8. May 21 14:47:24 r1-base kernel: show_signal_msg: 55 callbacks suppressed May 21 14:47:24 r1-base kernel: yarprobotinterf[8663]: segfault at 0 ip 00007ff1dc76286c sp 00007ff0bdfc2558 error 4 in tripodMotionControl.so[7ff1dc75f000+13000] likely on CPU 5 (core 1, socket 0) May 21 14:47:24 r1-base kernel: Code: 1e fa 48 8b bf e0 01 00 00 48 8b 07 ff 60 38 66 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 f3 0f 1e fa 48 8b bf 68 02 00 00 48 <8b> 07 ff 60 48 66 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 f3 0f May 21 14:48:30 r1-base systemd-networkd[719]: enp2s0: Lost carrier May 21 14:48:30 r1-base kernel: igb 0000:02:00.0 enp2s0: igb: enp2s0 NIC Link is Down May 21 14:48:30 r1-base avahi-daemon[787]: Withdrawing address record for 10.0.1.104 on enp2s0. May 21 14:48:30 r1-base systemd-networkd[719]: enp2s0: DHCPv6 lease lost May 21 14:48:30 r1-base avahi-daemon[787]: Leaving mDNS multicast group on interface enp2s0.IPv4 with address 10.0.1.104. May 21 14:48:30 r1-base avahi-daemon[787]: Interface enp2s0.IPv4 no longer relevant for mDNS. May 21 14:49:16 r1-base kernel: igb 0000:02:00.0 enp2s0: igb: enp2s0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX May 21 14:49:16 r1-base NetworkManager[790]: [1716295756.1641] device (enp2s0): carrier: link connected May 21 14:49:16 r1-base systemd-networkd[719]: enp2s0: Gained carrier May 21 14:49:16 r1-base avahi-daemon[787]: Joining mDNS multicast group on interface enp2s0.IPv4 with address 10.0.1.104. May 21 14:49:16 r1-base avahi-daemon[787]: New relevant interface enp2s0.IPv4 for mDNS. May 21 14:49:16 r1-base avahi-daemon[787]: Registering new address record for 10.0.1.104 on enp2s0.IPv4. May 21 14:49:19 r1-base kernel: igb 0000:02:00.0 enp2s0: igb: enp2s0 NIC Link is Down May 21 14:49:19 r1-base systemd-networkd[719]: enp2s0: Lost carrier May 21 14:49:19 r1-base systemd-networkd[719]: enp2s0: DHCPv6 lease lost May 21 14:49:19 r1-base avahi-daemon[787]: Withdrawing address record for 10.0.1.104 on enp2s0. May 21 14:49:19 r1-base avahi-daemon[787]: Leaving mDNS multicast group on interface enp2s0.IPv4 with address 10.0.1.104. May 21 14:49:19 r1-base avahi-daemon[787]: Interface enp2s0.IPv4 no longer relevant for mDNS. May 21 14:49:21 r1-base kernel: igb 0000:02:00.0 enp2s0: igb: enp2s0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX May 21 14:49:21 r1-base NetworkManager[790]: [1716295761.4332] device (enp2s0): carrier: link connected May 21 14:49:21 r1-base systemd-networkd[719]: enp2s0: Gained carrier May 21 14:49:21 r1-base avahi-daemon[787]: Joining mDNS multicast group on interface enp2s0.IPv4 with address 10.0.1.104. May 21 14:49:21 r1-base avahi-daemon[787]: New relevant interface enp2s0.IPv4 for mDNS. May 21 14:49:21 r1-base avahi-daemon[787]: Registering new address record for 10.0.1.104 on enp2s0.IPv4. May 21 14:52:29 r1-base systemd[1]: Starting Message of the Day... May 21 14:52:32 r1-base systemd-resolved[742]: Using degraded feature set TCP instead of UDP for DNS server 8.8.4.4. May 21 14:52:41 r1-base systemd-resolved[742]: Using degraded feature set TCP instead of UDP for DNS server 8.8.8.8. May 21 14:52:48 r1-base systemd[1]: motd-news.service: Deactivated successfully. May 21 14:52:48 r1-base systemd[1]: Finished Message of the Day. May 21 15:03:00 r1-base kernel: usb 3-8: USB disconnect, device number 7 May 21 15:03:00 r1-base kernel: wlx1cbfced1ed31: deauthenticating from 64:f6:9d:24:fb:0f by local choice (Reason: 3=DEAUTH_LEAVING) May 21 15:03:00 r1-base kernel: rtw_8821cu 3-8:1.0: timed out to flush queue 0 May 21 15:03:00 r1-base kernel: rtw_8821cu 3-8:1.0: timed out to flush queue 0 May 21 15:03:01 r1-base kernel: rtw_8821cu 3-8:1.0: timed out to flush queue 2 May 21 15:03:01 r1-base kernel: rtw_8821cu 3-8:1.0: timed out to flush queue 0 May 21 15:03:01 r1-base kernel: rtw_8821cu 3-8:1.0: timed out to flush queue 1 May 21 15:03:01 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-DISCONNECTED bssid=64:f6:9d:24:fb:0f reason=3 locally_generated=1 May 21 15:03:01 r1-base kernel: rtw_8821cu 3-8:1.0: timed out to flush queue 1 May 21 15:03:01 r1-base avahi-daemon[787]: Interface wlx1cbfced1ed31.IPv4 no longer relevant for mDNS. May 21 15:03:01 r1-base systemd-networkd[719]: wlx1cbfced1ed31: Link DOWN May 21 15:03:01 r1-base avahi-daemon[787]: Leaving mDNS multicast group on interface wlx1cbfced1ed31.IPv4 with address 192.168.101.10. May 21 15:03:01 r1-base systemd-networkd[719]: wlx1cbfced1ed31: Lost carrier May 21 15:03:01 r1-base avahi-daemon[787]: Withdrawing address record for 192.168.101.10 on wlx1cbfced1ed31. May 21 15:03:01 r1-base networkctl[14183]: Interface "wlan0" not found. May 21 15:03:01 r1-base systemd[1]: networkd-dispatcher.service: Got notification message from PID 14183, but reception only permitted for main PID 809 May 21 15:03:01 r1-base networkd-dispatcher[809]: ERROR:Failed to get interface "wlan0" status: Command '['/usr/bin/networkctl', 'status', '--no-pager', '--no-legend', '--', 'wlan0']' returned non-zero exit status 1. May 21 15:03:01 r1-base NetworkManager[790]: [1716296581.7027] device (wlx1cbfced1ed31): state change: activated -> unmanaged (reason 'removed', sys-iface-state: 'removed') May 21 15:03:01 r1-base NetworkManager[790]: [1716296581.7219] radio killswitch /sys/devices/pci0000:00/0000:00:14.0/usb3/3-8/3-8:1.0/ieee80211/phy0/rfkill1 disappeared May 21 15:03:01 r1-base dbus-daemon[789]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.15' (uid=0 pid=790 comm="/usr/sbin/NetworkManager --no-daemon " label="unconfined") May 21 15:03:01 r1-base networkd-dispatcher[14189]: wlan0 No such device May 21 15:03:01 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-DSCP-POLICY clear_all May 21 15:03:01 r1-base networkd-dispatcher[809]: ERROR:Error handling notification for interface 'wlan0' entering operational state off May 21 15:03:01 r1-base networkd-dispatcher[809]: Traceback (most recent call last): May 21 15:03:01 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 334, in _handle_one_state May 21 15:03:01 r1-base networkd-dispatcher[809]: self.run_hooks_for_state(new_iface, state) May 21 15:03:01 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 365, in run_hooks_for_state May 21 15:03:01 r1-base networkd-dispatcher[809]: data = get_interface_data(iface) May 21 15:03:01 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 256, in get_interface_data May 21 15:03:01 r1-base networkd-dispatcher[809]: data['ESSID'] = get_wlan_essid(iface.name) May 21 15:03:01 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 144, in get_wlan_essid May 21 15:03:01 r1-base networkd-dispatcher[809]: return iwconfig_get_ssid(iface_name) May 21 15:03:01 r1-base networkd-dispatcher[809]: File "/usr/bin/networkd-dispatcher", line 160, in iwconfig_get_ssid May 21 15:03:01 r1-base networkd-dispatcher[809]: out = subprocess.check_output([IWCONFIG, '--', iface_name]) May 21 15:03:01 r1-base networkd-dispatcher[809]: File "/usr/lib/python3.10/subprocess.py", line 421, in check_output May 21 15:03:01 r1-base networkd-dispatcher[809]: return run(*popenargs, stdout=PIPE, timeout=timeout, check=True, May 21 15:03:01 r1-base networkd-dispatcher[809]: File "/usr/lib/python3.10/subprocess.py", line 526, in run May 21 15:03:01 r1-base networkd-dispatcher[809]: raise CalledProcessError(retcode, process.args, May 21 15:03:01 r1-base networkd-dispatcher[809]: subprocess.CalledProcessError: Command '['/usr/sbin/iwconfig', '--', 'wlan0']' returned non-zero exit status 237. May 21 15:03:01 r1-base systemd[1]: Starting Load/Save RF Kill Switch Status... May 21 15:03:01 r1-base systemd[1]: Starting Network Manager Script Dispatcher Service... May 21 15:03:01 r1-base systemd[1]: Started Load/Save RF Kill Switch Status. May 21 15:03:01 r1-base wpa_supplicant[828]: wlx1cbfced1ed31: CTRL-EVENT-DSCP-POLICY clear_all May 21 15:03:01 r1-base wpa_supplicant[828]: nl80211: deinit ifname=wlx1cbfced1ed31 disabled_11b_rates=0 May 21 15:03:01 r1-base dbus-daemon[789]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher' May 21 15:03:01 r1-base systemd[1]: Started Network Manager Script Dispatcher Service. May 21 15:03:01 r1-base kernel: usb 3-8: new high-speed USB device number 8 using xhci_hcd May 21 15:03:02 r1-base kernel: usb 3-8: New USB device found, idVendor=0bda, idProduct=c811, bcdDevice= 2.00 May 21 15:03:02 r1-base kernel: usb 3-8: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 21 15:03:02 r1-base kernel: usb 3-8: Product: 802.11ac NIC May 21 15:03:02 r1-base kernel: usb 3-8: Manufacturer: Realtek May 21 15:03:02 r1-base kernel: usb 3-8: SerialNumber: 123456 May 21 15:03:02 r1-base kernel: rtw_8821cu 3-8:1.0: Firmware version 24.8.0, H2C version 12 May 21 15:03:02 r1-base kernel: BUG: kernel NULL pointer dereference, address: 0000000000000004 May 21 15:03:02 r1-base kernel: #PF: supervisor read access in kernel mode May 21 15:03:02 r1-base kernel: #PF: error_code(0x0000) - not-present page May 21 15:03:02 r1-base kernel: PGD 0 P4D 0 May 21 15:03:02 r1-base kernel: Oops: 0000 [#1] PREEMPT SMP PTI May 21 15:03:02 r1-base kernel: CPU: 0 PID: 6340 Comm: kworker/u16:2 Tainted: G W 6.2.0-1009-lowlatency #9~22.04.1-Ubuntu May 21 15:03:02 r1-base kernel: Hardware name: iEi E436/E436, BIOS E436AR10 05/15/2014 May 21 15:03:02 r1-base kernel: Workqueue: rtw88_usb: rx wq rtw_usb_rx_handler [rtw88_usb] May 21 15:03:02 r1-base kernel: RIP: 0010:rtw_rx_fill_rx_status+0x5b/0x370 [rtw88_core] May 21 15:03:02 r1-base kernel: Code: 00 48 89 45 d0 31 c0 48 83 e7 f8 48 c7 01 00 00 00 00 48 c7 41 28 00 00 00 00 48 29 f9 83 c1 30 c1 e9 03 f3 48 ab 48 8b 42 18 <8b> 48 04 41 0f b7 44 24 1c 66 81 e1 ff 1f 66 25 00 e0 09 c8 66 41 May 21 15:03:02 r1-base kernel: RSP: 0018:ffffa8ea48a1fce8 EFLAGS: 00010206 May 21 15:03:02 r1-base kernel: RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000000 May 21 15:03:02 r1-base kernel: RDX: ffff899f1ef808e0 RSI: ffffa8ea48a1fdf0 RDI: ffffa8ea48a1fdf0 May 21 15:03:02 r1-base kernel: RBP: ffffa8ea48a1fd50 R08: ffff899f1ef82080 R09: ffff899f1ed90038 May 21 15:03:02 r1-base kernel: R10: 0000000000000000 R11: 0000000000000000 R12: ffffa8ea48a1fdc0 May 21 15:03:02 r1-base kernel: R13: ffff899f1ef82080 R14: ffffa8ea48a1fdc0 R15: ffffa8ea48a1fdf0 May 21 15:03:02 r1-base kernel: FS: 0000000000000000(0000) GS:ffff89a016e00000(0000) knlGS:0000000000000000 May 21 15:03:02 r1-base kernel: CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 May 21 15:03:02 r1-base kernel: CR2: 0000000000000004 CR3: 000000001fa10001 CR4: 00000000001706f0 May 21 15:03:02 r1-base kernel: Call Trace: May 21 15:03:02 r1-base kernel: May 21 15:03:02 r1-base kernel: ? update_load_avg+0x82/0x7b0 May 21 15:03:02 r1-base kernel: ? newidle_balance+0x31e/0x490 May 21 15:03:02 r1-base kernel: rtw8821c_query_rx_desc+0x15c/0x310 [rtw88_8821c] May 21 15:03:02 r1-base kernel: rtw_usb_rx_handler+0x106/0x1d0 [rtw88_usb] May 21 15:03:02 r1-base kernel: process_one_work+0x21f/0x440 May 21 15:03:02 r1-base kernel: worker_thread+0x50/0x3f0 May 21 15:03:02 r1-base kernel: ? __pfx_worker_thread+0x10/0x10 May 21 15:03:02 r1-base kernel: kthread+0xee/0x120 May 21 15:03:02 r1-base kernel: ? __pfx_kthread+0x10/0x10 May 21 15:03:02 r1-base kernel: ret_from_fork+0x2c/0x50 May 21 15:03:02 r1-base kernel: May 21 15:03:02 r1-base kernel: Modules linked in: tls ccm rtw88_8821cu rtw88_8821c rtw88_usb rtw88_core mac80211 cfg80211 libarc4 hid_generic usbhid hid xt_conntrack nft_chain_nat xt_MASQUERADE nf_nat nf_conntrack_netlink nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 xfrm_user xfrm_algo xt_addrtype nft_compat nf_tables nfnetlink br_netfilter bridge stp llc rfcomm cmac overlay algif_hash algif_skcipher af_alg bnep binfmt_misc nls_iso8859_1 snd_hda_codec_realtek snd_hda_codec_generic snd_hda_codec_hdmi ledtrig_audio btusb snd_hda_intel snd_intel_dspcfg snd_intel_sdw_acpi btrtl snd_hda_codec intel_rapl_msr btbcm intel_rapl_common btintel snd_hda_core x86_pkg_temp_thermal mei_hdcp btmtk mei_pxp snd_hwdep intel_powerclamp snd_pcm bluetooth snd_seq_midi snd_seq_midi_event coretemp snd_rawmidi rapl cp210x ecdh_generic ecc intel_cstate usbserial snd_seq spi_nor snd_seq_device mtd wmi_bmof snd_timer mei_me at24 snd mei soundcore ie31200_edac mac_hid sch_fq_codel dm_multipath scsi_dh_rdac scsi_dh_emc scsi_dh_alua nfsd May 21 15:03:02 r1-base kernel: auth_rpcgss msr nfs_acl parport_pc lockd ppdev grace ramoops lp pstore_blk parport reed_solomon pstore_zone efi_pstore sunrpc ip_tables x_tables autofs4 btrfs blake2b_generic raid10 raid456 async_raid6_recov async_memcpy async_pq async_xor async_tx xor raid6_pq libcrc32c raid1 raid0 multipath linear i915 drm_buddy ttm drm_display_helper cec spi_intel_platform spi_intel rc_core crct10dif_pclmul drm_kms_helper crc32_pclmul polyval_clmulni polyval_generic ghash_clmulni_intel syscopyarea sha512_ssse3 aesni_intel sysfillrect crypto_simd sysimgblt cryptd igb i2c_i801 drm e1000e dca i2c_smbus lpc_ich pata_acpi i2c_algo_bit xhci_pci xhci_pci_renesas video wmi May 21 15:03:02 r1-base kernel: CR2: 0000000000000004 May 21 15:03:02 r1-base kernel: ---[ end trace 0000000000000000 ]--- May 21 15:03:02 r1-base kernel: pstore: backend (efi_pstore) writing error (-5) May 21 15:03:02 r1-base kernel: RIP: 0010:rtw_rx_fill_rx_status+0x5b/0x370 [rtw88_core] May 21 15:03:02 r1-base kernel: Code: 00 48 89 45 d0 31 c0 48 83 e7 f8 48 c7 01 00 00 00 00 48 c7 41 28 00 00 00 00 48 29 f9 83 c1 30 c1 e9 03 f3 48 ab 48 8b 42 18 <8b> 48 04 41 0f b7 44 24 1c 66 81 e1 ff 1f 66 25 00 e0 09 c8 66 41 May 21 15:03:02 r1-base kernel: RSP: 0018:ffffa8ea48a1fce8 EFLAGS: 00010206 May 21 15:03:02 r1-base kernel: RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000000 May 21 15:03:02 r1-base kernel: RDX: ffff899f1ef808e0 RSI: ffffa8ea48a1fdf0 RDI: ffffa8ea48a1fdf0 May 21 15:03:02 r1-base kernel: RBP: ffffa8ea48a1fd50 R08: ffff899f1ef82080 R09: ffff899f1ed90038 May 21 15:03:02 r1-base kernel: R10: 0000000000000000 R11: 0000000000000000 R12: ffffa8ea48a1fdc0 May 21 15:03:02 r1-base kernel: R13: ffff899f1ef82080 R14: ffffa8ea48a1fdc0 R15: ffffa8ea48a1fdf0 May 21 15:03:02 r1-base kernel: FS: 0000000000000000(0000) GS:ffff89a016e00000(0000) knlGS:0000000000000000 May 21 15:03:02 r1-base kernel: CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 May 21 15:03:02 r1-base kernel: CR2: 0000000000000004 CR3: 000000011d6b4004 CR4: 00000000001706f0 May 21 15:03:02 r1-base kernel: note: kworker/u16:2[6340] exited with irqs disabled May 21 15:03:02 r1-base networkd-dispatcher[809]: WARNING:Unknown index 6 seen, reloading interface list May 21 15:03:02 r1-base NetworkManager[790]: [1716296582.1634] device (wlan0): driver supports Access Point (AP) mode May 21 15:03:02 r1-base NetworkManager[790]: [1716296582.1641] manager: (wlan0): new 802.11 Wi-Fi device (/org/freedesktop/NetworkManager/Devices/6) May 21 15:03:02 r1-base mtp-probe[14234]: checking bus 3, device 8: "/sys/devices/pci0000:00/0000:00:14.0/usb3/3-8" May 21 15:03:02 r1-base mtp-probe[14234]: bus: 3, device: 8 was not an MTP device May 21 15:03:02 r1-base systemd-udevd[14184]: Using default interface naming scheme 'v249'. May 21 15:03:02 r1-base kernel: rtw_8821cu 3-8:1.0 wlx1cbfced1ed31: renamed from wlan0 May 21 15:03:02 r1-base NetworkManager[790]: [1716296582.1779] rfkill2: found Wi-Fi radio killswitch (at /sys/devices/pci0000:00/0000:00:14.0/usb3/3-8/3-8:1.0/ieee80211/phy1/rfkill2) (driver rtw_8821cu) May 21 15:03:02 r1-base systemd-networkd[719]: wlan0: Interface name change detected, renamed to wlx1cbfced1ed31. May 21 15:03:02 r1-base NetworkManager[790]: [1716296582.1867] device (wlan0): interface index 6 renamed iface from 'wlan0' to 'wlx1cbfced1ed31' May 21 15:03:02 r1-base NetworkManager[790]: [1716296582.1937] device (wlx1cbfced1ed31): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external') May 21 15:03:02 r1-base mtp-probe[14243]: checking bus 3, device 8: "/sys/devices/pci0000:00/0000:00:14.0/usb3/3-8" May 21 15:03:02 r1-base mtp-probe[14243]: bus: 3, device: 8 was not an MTP device May 21 15:03:02 r1-base systemd-udevd[14185]: Using default interface naming scheme 'v249'. May 21 15:03:02 r1-base kernel: rtw_8821cu 3-8:1.0: failed to download firmware May 21 15:03:02 r1-base NetworkManager[790]: [1716296582.2705] platform-linux: do-change-link[6]: failure changing link: failure 22 (Invalid argument) May 21 15:03:02 r1-base systemd-networkd[719]: wlx1cbfced1ed31: Link UP May 21 15:03:02 r1-base wpa_supplicant[828]: dbus: fill_dict_with_properties dbus_interface=fi.w1.wpa_supplicant1.Interface.P2PDevice dbus_property=P2PDeviceConfig getter failed May 21 15:03:02 r1-base NetworkManager[790]: [1716296582.8758] device (wlx1cbfced1ed31): supplicant interface state: internal-starting -> disconnected May 21 15:03:02 r1-base NetworkManager[790]: [1716296582.8758] device (wlx1cbfced1ed31): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-state: 'managed') May 21 15:03:04 r1-base ModemManager[907]: [base-manager] couldn't check support for device '/sys/devices/pci0000:00/0000:00:14.0/usb3/3-8': not supported by any plugin May 21 15:03:07 r1-base systemd[1]: systemd-rfkill.service: Deactivated successfully. May 21 15:03:11 r1-base systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully. May 21 15:03:26 r1-base systemd-resolved[742]: Using degraded feature set UDP instead of TCP for DNS server 8.8.4.4. May 21 15:04:23 r1-base sshd[6977]: pam_unix(sshd:session): session closed for user r1-user May 21 15:04:23 r1-base systemd[1]: session-14.scope: Deactivated successfully. May 21 15:04:23 r1-base systemd-logind[825]: Session 14 logged out. Waiting for processes to exit. May 21 15:04:23 r1-base systemd-logind[825]: Removed session 14. May 21 15:04:28 r1-base kernel: rtw_8821cu 3-8:1.0: failed to download firmware May 21 15:04:28 r1-base kernel: rtw_8821cu 3-8:1.0: leave idle state failed May 21 15:04:28 r1-base kernel: rtw_8821cu 3-8:1.0: failed to leave ips state May 21 15:04:28 r1-base kernel: rtw_8821cu 3-8:1.0: failed to leave idle state May 21 15:04:43 r1-base sshd[7113]: pam_unix(sshd:session): session closed for user r1-user May 21 15:04:43 r1-base systemd-logind[825]: Session 15 logged out. Waiting for processes to exit. May 21 15:04:43 r1-base systemd[1]: session-15.scope: Deactivated successfully. May 21 15:04:43 r1-base systemd[1]: session-15.scope: Consumed 1.953s CPU time. May 21 15:04:43 r1-base systemd-logind[825]: Removed session 15. May 21 15:04:54 r1-base sshd[14251]: Accepted password for r1-user from 192.168.100.203 port 42246 ssh2 May 21 15:04:54 r1-base sshd[14251]: pam_unix(sshd:session): session opened for user r1-user(uid=1000) by (uid=0) May 21 15:04:54 r1-base systemd-logind[825]: New session 17 of user r1-user. May 21 15:04:54 r1-base systemd[1]: Started Session 17 of User r1-user. May 21 15:05:05 r1-base sudo[14379]: r1-user : TTY=pts/3 ; PWD=/home/r1-user ; USER=root ; COMMAND=/usr/bin/dmesg May 21 15:05:05 r1-base sudo[14379]: pam_unix(sudo:session): session opened for user root(uid=0) by r1-user(uid=1000) May 21 15:05:05 r1-base sudo[14379]: pam_unix(sudo:session): session closed for user root May 21 15:05:30 r1-base sshd[14348]: Received disconnect from 192.168.100.203 port 42246:11: disconnected by user May 21 15:05:30 r1-base sshd[14348]: Disconnected from user r1-user 192.168.100.203 port 42246 May 21 15:05:30 r1-base sshd[14251]: pam_unix(sshd:session): session closed for user r1-user May 21 15:05:30 r1-base systemd[1]: session-17.scope: Deactivated successfully. May 21 15:05:30 r1-base systemd-logind[825]: Session 17 logged out. Waiting for processes to exit. May 21 15:05:30 r1-base systemd-logind[825]: Removed session 17. May 21 15:06:01 r1-base sshd[14388]: Accepted password for r1-user from 192.168.100.203 port 37734 ssh2 May 21 15:06:01 r1-base sshd[14388]: pam_unix(sshd:session): session opened for user r1-user(uid=1000) by (uid=0) May 21 15:06:01 r1-base systemd-logind[825]: New session 18 of user r1-user. May 21 15:06:01 r1-base systemd[1]: Started Session 18 of User r1-user. May 21 15:06:05 r1-base sudo[14466]: r1-user : TTY=pts/3 ; PWD=/home/r1-user ; USER=root ; COMMAND=/usr/bin/dmesg May 21 15:06:05 r1-base sudo[14466]: pam_unix(sudo:session): session opened for user root(uid=0) by r1-user(uid=1000) May 21 15:06:05 r1-base sudo[14466]: pam_unix(sudo:session): session closed for user root May 21 15:06:14 r1-base kernel: rtw_8821cu 3-8:1.0: failed to download firmware May 21 15:06:14 r1-base kernel: rtw_8821cu 3-8:1.0: leave idle state failed May 21 15:06:14 r1-base kernel: rtw_8821cu 3-8:1.0: failed to leave ips state May 21 15:06:14 r1-base kernel: rtw_8821cu 3-8:1.0: failed to leave idle state May 21 15:06:18 r1-base sshd[14473]: Accepted password for r1-user from 192.168.100.203 port 35184 ssh2 May 21 15:06:18 r1-base sshd[14473]: pam_unix(sshd:session): session opened for user r1-user(uid=1000) by (uid=0) May 21 15:06:18 r1-base systemd-logind[825]: New session 19 of user r1-user. May 21 15:06:18 r1-base systemd[1]: Started Session 19 of User r1-user. May 21 15:10:13 r1-base dbus-daemon[1795]: [session uid=1000 pid=1795] Activating via systemd: service name='org.freedesktop.Tracker3.Miner.Extract' unit='tracker-extract-3.service' requested by ':1.6' (uid=1000 pid=1814 comm="/usr/libexec/tracker-miner-fs-3 " label="unconfined") May 21 15:10:13 r1-base systemd[1766]: Starting Tracker metadata extractor... May 21 15:10:13 r1-base dbus-daemon[1795]: [session uid=1000 pid=1795] Successfully activated service 'org.freedesktop.Tracker3.Miner.Extract' May 21 15:10:13 r1-base systemd[1766]: Started Tracker metadata extractor. May 21 15:10:13 r1-base dbus-daemon[1795]: [session uid=1000 pid=1795] Activating via systemd: service name='org.gtk.vfs.Metadata' unit='gvfs-metadata.service' requested by ':1.17' (uid=1000 pid=14572 comm="/usr/libexec/tracker-extract-3 " label="unconfined") May 21 15:10:13 r1-base systemd[1766]: Starting Virtual filesystem metadata service... May 21 15:10:13 r1-base dbus-daemon[1795]: [session uid=1000 pid=1795] Successfully activated service 'org.gtk.vfs.Metadata' May 21 15:10:13 r1-base systemd[1766]: Started Virtual filesystem metadata service.