You are not logged in.
Pages: 1
Hi,
I previously raised an issue which may or may not be related to this:
https://bbs.archlinux.org/viewtopic.php?id=292150
I raised that post to try and rule out the hardware being the issue. However as yet I haven't got a response and I thought it probably was an issue with the wifi card, I bought a USB wifi stick - a TP-Link Wi-Fi 6 AX1800 (Archer TX20UH). I installed the driver from here:
https://aur.archlinux.org/packages/rtl8852au-dkms-git
And everything seemed to just work for a short period until the connection dropped out. The difference with the previous post is that now it does reconnect again after some time.
It seemed to happen most often when I moved location around the house (2 APs) with my laptop so I added the following file:
tuf ~ # cat /etc/systemd/network/25-wireless.network
[Match]
Name=wlp0s20f0u1
[Network]
DHCP=ipv4
IgnoreCarrierLoss=6s
I think this did have the effect of reducing the number of disconnects I see but it's still happening. I'm not sure if my other post is related or not and I'm really only concerned about get my new wifi stick to work - I only mentioned it in case it helps someone else better understand what the issue might be here.
This is what I see in journalctl when it disconnects:
tuf ~ :( # journalctl -f
Jan 24 22:02:04 tuf systemd-timesyncd[530]: Network configuration changed, trying to establish connection.
Jan 24 22:02:04 tuf NetworkManager[538]: <info> [1706133724.9985] device (wlp0s20f0u1): supplicant interface state: 4way_handshake -> completed
Jan 24 22:02:04 tuf NetworkManager[538]: <info> [1706133724.9986] device (wlp0s20f0u1): ip:dhcp4: restarting
Jan 24 22:02:04 tuf NetworkManager[538]: <info> [1706133724.9987] dhcp4 (wlp0s20f0u1): canceled DHCP transaction
Jan 24 22:02:04 tuf NetworkManager[538]: <info> [1706133724.9988] dhcp4 (wlp0s20f0u1): activation: beginning transaction (timeout in 45 seconds)
Jan 24 22:02:04 tuf NetworkManager[538]: <info> [1706133724.9988] dhcp4 (wlp0s20f0u1): state changed no lease
Jan 24 22:02:04 tuf NetworkManager[538]: <info> [1706133724.9989] dhcp4 (wlp0s20f0u1): activation: beginning transaction (timeout in 45 seconds)
Jan 24 22:02:04 tuf NetworkManager[538]: <info> [1706133724.9993] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: 4way_handshake -> completed
Jan 24 22:02:15 tuf systemd-timesyncd[530]: Timed out waiting for reply from 192.168.254.1:123 (192.168.254.1).
Jan 24 22:02:20 tuf NetworkManager[538]: <info> [1706133740.0528] dhcp4 (wlp0s20f0u1): state changed no lease
And the re-connect happens here:
Jan 24 22:02:50 tuf avahi-daemon[541]: Withdrawing address record for 192.168.254.60 on wlp0s20f0u1.
Jan 24 22:02:50 tuf avahi-daemon[541]: Leaving mDNS multicast group on interface wlp0s20f0u1.IPv4 with address 192.168.254.60.
Jan 24 22:02:50 tuf avahi-daemon[541]: Joining mDNS multicast group on interface wlp0s20f0u1.IPv4 with address 192.168.254.16.
Jan 24 22:02:50 tuf NetworkManager[538]: <info> [1706133770.0874] device (wlp0s20f0u1): state change: activated -> failed (reason 'ip-config-unavailable', sys-iface-state: 'managed')
Jan 24 22:02:50 tuf NetworkManager[538]: <info> [1706133770.0886] manager: NetworkManager state is now DISCONNECTED
Jan 24 22:02:50 tuf wpa_supplicant[611]: wlp0s20f0u1: CTRL-EVENT-DISCONNECTED bssid=ac:8b:a9:28:cd:b9 reason=0 locally_generated=1
Jan 24 22:02:50 tuf wpa_supplicant[611]: BSSID ac:8b:a9:28:cd:b9 ignore list count incremented to 2, ignoring for 10 seconds
Jan 24 22:02:50 tuf systemd-networkd[490]: wlp0s20f0u1: Link DOWN
Jan 24 22:02:50 tuf avahi-daemon[541]: Interface wlp0s20f0u1.IPv6 no longer relevant for mDNS.
Jan 24 22:02:50 tuf systemd-networkd[490]: wlp0s20f0u1: Lost carrier
Jan 24 22:02:50 tuf avahi-daemon[541]: Leaving mDNS multicast group on interface wlp0s20f0u1.IPv6 with address fe80::655d:3d1f:efa7:ec83.
Jan 24 22:02:50 tuf NetworkManager[538]: <info> [1706133770.2544] device (wlp0s20f0u1): set-hw-addr: set MAC address to 8A:5C:59:16:BA:DB (scanning)
Jan 24 22:02:50 tuf avahi-daemon[541]: Interface wlp0s20f0u1.IPv4 no longer relevant for mDNS.
Jan 24 22:02:50 tuf systemd-networkd[490]: wlp0s20f0u1: DHCP lease lost
Jan 24 22:02:50 tuf avahi-daemon[541]: Leaving mDNS multicast group on interface wlp0s20f0u1.IPv4 with address 192.168.254.16.
Jan 24 22:02:50 tuf avahi-daemon[541]: Withdrawing address record for fe80::655d:3d1f:efa7:ec83 on wlp0s20f0u1.
Jan 24 22:02:50 tuf avahi-daemon[541]: Withdrawing address record for 192.168.254.16 on wlp0s20f0u1.
Jan 24 22:02:50 tuf avahi-daemon[541]: Joining mDNS multicast group on interface wlp0s20f0u1.IPv4 with address 192.168.254.16.
Jan 24 22:02:50 tuf avahi-daemon[541]: New relevant interface wlp0s20f0u1.IPv4 for mDNS.
Jan 24 22:02:50 tuf avahi-daemon[541]: Registering new address record for 192.168.254.16 on wlp0s20f0u1.IPv4.
Jan 24 22:02:50 tuf avahi-daemon[541]: Withdrawing address record for 192.168.254.16 on wlp0s20f0u1.
Jan 24 22:02:50 tuf avahi-daemon[541]: Leaving mDNS multicast group on interface wlp0s20f0u1.IPv4 with address 192.168.254.16.
Jan 24 22:02:50 tuf avahi-daemon[541]: Interface wlp0s20f0u1.IPv4 no longer relevant for mDNS.
Jan 24 22:02:50 tuf avahi-daemon[541]: Joining mDNS multicast group on interface wlp0s20f0u1.IPv6 with address fe80::655d:3d1f:efa7:ec83.
Jan 24 22:02:50 tuf avahi-daemon[541]: New relevant interface wlp0s20f0u1.IPv6 for mDNS.
Jan 24 22:02:50 tuf avahi-daemon[541]: Registering new address record for fe80::655d:3d1f:efa7:ec83 on wlp0s20f0u1.*.
Jan 24 22:02:50 tuf NetworkManager[538]: <warn> [1706133770.3847] device (wlp0s20f0u1): Activation: failed for connection 'DigiRiverMobile 1'
Jan 24 22:02:50 tuf systemd-networkd[490]: wlp0s20f0u1: Link UP
Jan 24 22:02:50 tuf NetworkManager[538]: <info> [1706133770.3859] device (wlp0s20f0u1): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
Jan 24 22:02:50 tuf avahi-daemon[541]: Withdrawing address record for fe80::655d:3d1f:efa7:ec83 on wlp0s20f0u1.
Jan 24 22:02:50 tuf avahi-daemon[541]: Leaving mDNS multicast group on interface wlp0s20f0u1.IPv6 with address fe80::655d:3d1f:efa7:ec83.
Jan 24 22:02:50 tuf avahi-daemon[541]: Interface wlp0s20f0u1.IPv6 no longer relevant for mDNS.
Jan 24 22:02:50 tuf NetworkManager[538]: <info> [1706133770.4162] dhcp4 (wlp0s20f0u1): canceled DHCP transaction
Jan 24 22:02:50 tuf NetworkManager[538]: <info> [1706133770.4162] dhcp4 (wlp0s20f0u1): activation: beginning transaction (timeout in 45 seconds)
Jan 24 22:02:50 tuf NetworkManager[538]: <info> [1706133770.4163] dhcp4 (wlp0s20f0u1): state changed no lease
Jan 24 22:02:50 tuf systemd[1]: Starting Network Manager Script Dispatcher Service...
Jan 24 22:02:50 tuf wpa_supplicant[611]: wlp0s20f0u1: Reject scan trigger since one is already pending
Jan 24 22:02:50 tuf NetworkManager[538]: <info> [1706133770.4306] device (wlp0s20f0u1): supplicant interface state: completed -> scanning
Jan 24 22:02:50 tuf NetworkManager[538]: <info> [1706133770.4307] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: completed -> scanning
Jan 24 22:02:50 tuf wpa_supplicant[611]: wlp0s20f0u1: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Jan 24 22:02:50 tuf systemd[1]: Started Network Manager Script Dispatcher Service.
Jan 24 22:02:50 tuf NetworkManager[538]: <info> [1706133770.8611] device (wlp0s20f0u1): supplicant interface state: scanning -> disconnected
Jan 24 22:02:50 tuf NetworkManager[538]: <info> [1706133770.8612] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: scanning -> disconnected
Jan 24 22:02:50 tuf NetworkManager[538]: <info> [1706133770.9316] policy: auto-activating connection 'DigiRiverMobile 1' (0fd68cad-35e3-4cbb-a984-8490979dd4ce)
Jan 24 22:02:50 tuf NetworkManager[538]: <info> [1706133770.9322] device (wlp0s20f0u1): Activation: starting connection 'DigiRiverMobile 1' (0fd68cad-35e3-4cbb-a984-8490979dd4ce)
Jan 24 22:02:50 tuf NetworkManager[538]: <info> [1706133770.9323] device (wlp0s20f0u1): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Jan 24 22:02:50 tuf NetworkManager[538]: <info> [1706133770.9325] manager: NetworkManager state is now CONNECTING
Jan 24 22:02:50 tuf systemd-networkd[490]: wlp0s20f0u1: Link DOWN
Jan 24 22:02:50 tuf NetworkManager[538]: <info> [1706133770.9332] device (wlp0s20f0u1): set-hw-addr: reset MAC address to A8:42:A1:81:1D:74 (preserve)
Jan 24 22:02:51 tuf systemd-networkd[490]: wlp0s20f0u1: Link UP
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.0629] device (wlp0s20f0u1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.0636] device (wlp0s20f0u1): Activation: (wifi) access point 'DigiRiverMobile 1' has security, but secrets are required.
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.0636] device (wlp0s20f0u1): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.0648] device (wlp0s20f0u1): supplicant interface state: disconnected -> interface_disabled
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.0649] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: disconnected -> interface_disabled
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.0678] device (wlp0s20f0u1): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.0689] device (wlp0s20f0u1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.0696] device (wlp0s20f0u1): Activation: (wifi) connection 'DigiRiverMobile 1' has security, and secrets exist. No new secrets needed.
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.0696] Config: added 'ssid' value 'DigiRiverMobile'
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.0696] Config: added 'scan_ssid' value '1'
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.0696] Config: added 'bgscan' value 'simple:30:-65:300'
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.0696] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256 FT-PSK'
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.0697] Config: added 'auth_alg' value 'OPEN'
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.0697] Config: added 'psk' value '<hidden>'
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.1003] device (wlp0s20f0u1): supplicant interface state: interface_disabled -> inactive
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.1003] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: interface_disabled -> inactive
Jan 24 22:02:51 tuf wpa_supplicant[611]: wlp0s20f0u1: Trying to associate with ac:8b:a9:28:cd:b9 (SSID='DigiRiverMobile' freq=2437 MHz)
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.1126] device (wlp0s20f0u1): supplicant interface state: inactive -> associating
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.1127] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: inactive -> associating
Jan 24 22:02:51 tuf rtkit-daemon[740]: Supervising 5 threads of 3 processes of 1 users.
Jan 24 22:02:51 tuf rtkit-daemon[740]: Supervising 5 threads of 3 processes of 1 users.
Jan 24 22:02:51 tuf systemd-networkd[490]: wlp0s20f0u1: Connected WiFi access point: DigiRiverMobile (ac:8b:a9:28:cd:b9)
Jan 24 22:02:51 tuf wpa_supplicant[611]: wlp0s20f0u1: Associated with ac:8b:a9:28:cd:b9
Jan 24 22:02:51 tuf wpa_supplicant[611]: wlp0s20f0u1: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.5630] device (wlp0s20f0u1): supplicant interface state: associating -> associated
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.5631] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: associating -> associated
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.5764] device (wlp0s20f0u1): supplicant interface state: associated -> 4way_handshake
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.5765] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: associated -> 4way_handshake
Jan 24 22:02:51 tuf wpa_supplicant[611]: wlp0s20f0u1: WPA: Key negotiation completed with ac:8b:a9:28:cd:b9 [PTK=CCMP GTK=CCMP]
Jan 24 22:02:51 tuf wpa_supplicant[611]: wlp0s20f0u1: CTRL-EVENT-CONNECTED - Connection to ac:8b:a9:28:cd:b9 completed [id=0 id_str=]
Jan 24 22:02:51 tuf wpa_supplicant[611]: bgscan simple: Failed to enable signal strength monitoring
Jan 24 22:02:51 tuf systemd-networkd[490]: wlp0s20f0u1: Gained carrier
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.5841] device (wlp0s20f0u1): supplicant interface state: 4way_handshake -> completed
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.5842] device (wlp0s20f0u1): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "DigiRiverMobile"
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.5842] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: 4way_handshake -> completed
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.5844] device (wlp0s20f0u1): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.5853] dhcp4 (wlp0s20f0u1): activation: beginning transaction (timeout in 45 seconds)
Jan 24 22:02:51 tuf avahi-daemon[541]: Joining mDNS multicast group on interface wlp0s20f0u1.IPv6 with address fe80::655d:3d1f:efa7:ec83.
Jan 24 22:02:51 tuf avahi-daemon[541]: New relevant interface wlp0s20f0u1.IPv6 for mDNS.
Jan 24 22:02:51 tuf avahi-daemon[541]: Registering new address record for fe80::655d:3d1f:efa7:ec83 on wlp0s20f0u1.*.
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.6686] dhcp4 (wlp0s20f0u1): state changed new lease, address=192.168.254.60
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.6689] policy: set 'DigiRiverMobile 1' (wlp0s20f0u1) as default for IPv4 routing and DNS
Jan 24 22:02:51 tuf avahi-daemon[541]: Joining mDNS multicast group on interface wlp0s20f0u1.IPv4 with address 192.168.254.60.
Jan 24 22:02:51 tuf avahi-daemon[541]: New relevant interface wlp0s20f0u1.IPv4 for mDNS.
Jan 24 22:02:51 tuf avahi-daemon[541]: Registering new address record for 192.168.254.60 on wlp0s20f0u1.IPv4.
Jan 24 22:02:51 tuf systemd-timesyncd[530]: Network configuration changed, trying to establish connection.
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.6747] device (wlp0s20f0u1): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.6784] device (wlp0s20f0u1): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.6785] device (wlp0s20f0u1): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.6789] manager: NetworkManager state is now CONNECTED_SITE
Jan 24 22:02:51 tuf NetworkManager[538]: <info> [1706133771.6793] device (wlp0s20f0u1): Activation: successful, device activated.
Jan 24 22:02:53 tuf systemd-networkd[490]: wlp0s20f0u1: Gained IPv6LL
Any ideas?
Thanks.
Offline
Check this https://bbs.archlinux.org/viewtopic.php?id=213449. You have both networkd and network manager working.
Offline
Thanks for your reply. I disabled `systemd-networkd.service` and `systemd-networkd.socket` and rebooted. I think that helped but it does still keep happening.
This is what the logs look like on a disconnect now:
tuf ~ :( # journalctl -f
Jan 25 18:15:34 tuf wpa_supplicant[611]: wlp0s20f0u1: CTRL-EVENT-CONNECTED - Connection to ac:8b:a9:28:cd:b9 completed [id=0 id_str=]
Jan 25 18:15:34 tuf wpa_supplicant[611]: bgscan simple: Failed to enable signal strength monitoring
Jan 25 18:15:34 tuf NetworkManager[537]: <info> [1706206534.8490] device (wlp0s20f0u1): supplicant interface state: 4way_handshake -> completed
Jan 25 18:15:34 tuf NetworkManager[537]: <info> [1706206534.8490] device (wlp0s20f0u1): ip:dhcp4: restarting
Jan 25 18:15:34 tuf NetworkManager[537]: <info> [1706206534.8491] dhcp4 (wlp0s20f0u1): canceled DHCP transaction
Jan 25 18:15:34 tuf NetworkManager[537]: <info> [1706206534.8491] dhcp4 (wlp0s20f0u1): activation: beginning transaction (timeout in 45 seconds)
Jan 25 18:15:34 tuf NetworkManager[537]: <info> [1706206534.8491] dhcp4 (wlp0s20f0u1): state changed no lease
Jan 25 18:15:34 tuf NetworkManager[537]: <info> [1706206534.8491] dhcp4 (wlp0s20f0u1): activation: beginning transaction (timeout in 45 seconds)
Jan 25 18:15:34 tuf NetworkManager[537]: <info> [1706206534.8492] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: 4way_handshake -> completed
Jan 25 18:15:50 tuf NetworkManager[537]: <info> [1706206550.0475] dhcp4 (wlp0s20f0u1): state changed no lease
And the reconnect:
Jan 25 18:16:20 tuf NetworkManager[537]: <info> [1706206580.0721] device (wlp0s20f0u1): state change: activated -> failed (reason 'ip-config-unavailable', sys-iface-state: 'managed')
Jan 25 18:16:20 tuf NetworkManager[537]: <info> [1706206580.0731] manager: NetworkManager state is now DISCONNECTED
Jan 25 18:16:20 tuf wpa_supplicant[611]: wlp0s20f0u1: CTRL-EVENT-DISCONNECTED bssid=ac:8b:a9:28:cd:b9 reason=0 locally_generated=1
Jan 25 18:16:20 tuf wpa_supplicant[611]: BSSID ac:8b:a9:28:cd:b9 ignore list count incremented to 2, ignoring for 10 seconds
Jan 25 18:16:20 tuf NetworkManager[537]: <info> [1706206580.2358] device (wlp0s20f0u1): set-hw-addr: set MAC address to C6:36:D6:6E:5F:F3 (scanning)
Jan 25 18:16:20 tuf NetworkManager[537]: <warn> [1706206580.3646] device (wlp0s20f0u1): Activation: failed for connection 'DigiRiverMobile 1'
Jan 25 18:16:20 tuf NetworkManager[537]: <info> [1706206580.3651] device (wlp0s20f0u1): supplicant interface state: completed -> interface_disabled
Jan 25 18:16:20 tuf NetworkManager[537]: <info> [1706206580.3651] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: completed -> interface_disabled
Jan 25 18:16:20 tuf NetworkManager[537]: <info> [1706206580.3658] device (wlp0s20f0u1): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
Jan 25 18:16:20 tuf NetworkManager[537]: <info> [1706206580.3919] dhcp4 (wlp0s20f0u1): canceled DHCP transaction
Jan 25 18:16:20 tuf NetworkManager[537]: <info> [1706206580.3920] dhcp4 (wlp0s20f0u1): activation: beginning transaction (timeout in 45 seconds)
Jan 25 18:16:20 tuf NetworkManager[537]: <info> [1706206580.3920] dhcp4 (wlp0s20f0u1): state changed no lease
Jan 25 18:16:20 tuf systemd[1]: Starting Network Manager Script Dispatcher Service...
Jan 25 18:16:20 tuf systemd[1]: Started Network Manager Script Dispatcher Service.
Jan 25 18:16:20 tuf NetworkManager[537]: <info> [1706206580.4024] device (wlp0s20f0u1): supplicant interface state: interface_disabled -> scanning
Jan 25 18:16:20 tuf NetworkManager[537]: <info> [1706206580.4024] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: interface_disabled -> scanning
Jan 25 18:16:20 tuf NetworkManager[537]: <info> [1706206580.7852] device (wlp0s20f0u1): supplicant interface state: scanning -> disconnected
Jan 25 18:16:20 tuf NetworkManager[537]: <info> [1706206580.7852] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: scanning -> disconnected
Jan 25 18:16:21 tuf rtkit-daemon[758]: Supervising 6 threads of 4 processes of 1 users.
Jan 25 18:16:21 tuf rtkit-daemon[758]: Supervising 6 threads of 4 processes of 1 users.
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.1501] device (wlp0s20f0u1): supplicant interface state: disconnected -> inactive
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.1502] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: disconnected -> inactive
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.1546] policy: auto-activating connection 'DigiRiverMobile 1' (0fd68cad-35e3-4cbb-a984-8490979dd4ce)
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.1549] device (wlp0s20f0u1): Activation: starting connection 'DigiRiverMobile 1' (0fd68cad-35e3-4cbb-a984-8490979dd4ce)
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.1550] device (wlp0s20f0u1): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.1553] manager: NetworkManager state is now CONNECTING
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.1560] device (wlp0s20f0u1): set-hw-addr: reset MAC address to A8:42:A1:81:1D:74 (preserve)
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.2865] device (wlp0s20f0u1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.2869] device (wlp0s20f0u1): Activation: (wifi) access point 'DigiRiverMobile 1' has security, but secrets are required.
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.2870] device (wlp0s20f0u1): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.2877] device (wlp0s20f0u1): supplicant interface state: inactive -> interface_disabled
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.2877] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: inactive -> interface_disabled
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.2890] device (wlp0s20f0u1): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.2895] device (wlp0s20f0u1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.2899] device (wlp0s20f0u1): Activation: (wifi) connection 'DigiRiverMobile 1' has security, and secrets exist. No new secrets needed.
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.2900] Config: added 'ssid' value 'DigiRiverMobile'
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.2900] Config: added 'scan_ssid' value '1'
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.2900] Config: added 'bgscan' value 'simple:30:-65:300'
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.2901] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256 FT-PSK'
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.2901] Config: added 'auth_alg' value 'OPEN'
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.2901] Config: added 'psk' value '<hidden>'
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.3297] device (wlp0s20f0u1): supplicant interface state: interface_disabled -> inactive
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.3297] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: interface_disabled -> inactive
Jan 25 18:16:25 tuf wpa_supplicant[611]: wlp0s20f0u1: Trying to associate with ac:8b:a9:28:cd:b9 (SSID='DigiRiverMobile' freq=2437 MHz)
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.3501] device (wlp0s20f0u1): supplicant interface state: inactive -> associating
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.3501] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: inactive -> associating
Jan 25 18:16:25 tuf wpa_supplicant[611]: wlp0s20f0u1: Associated with ac:8b:a9:28:cd:b9
Jan 25 18:16:25 tuf wpa_supplicant[611]: wlp0s20f0u1: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.7178] device (wlp0s20f0u1): supplicant interface state: associating -> associated
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.7179] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: associating -> associated
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.7354] device (wlp0s20f0u1): supplicant interface state: associated -> 4way_handshake
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.7354] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: associated -> 4way_handshake
Jan 25 18:16:25 tuf wpa_supplicant[611]: wlp0s20f0u1: WPA: Key negotiation completed with ac:8b:a9:28:cd:b9 [PTK=CCMP GTK=CCMP]
Jan 25 18:16:25 tuf wpa_supplicant[611]: wlp0s20f0u1: CTRL-EVENT-CONNECTED - Connection to ac:8b:a9:28:cd:b9 completed [id=0 id_str=]
Jan 25 18:16:25 tuf wpa_supplicant[611]: bgscan simple: Failed to enable signal strength monitoring
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.7427] device (wlp0s20f0u1): supplicant interface state: 4way_handshake -> completed
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.7427] device (wlp0s20f0u1): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "DigiRiverMobile"
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.7427] device (p2p-dev-wlp0s20f0u1): supplicant management interface state: 4way_handshake -> completed
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.7428] device (wlp0s20f0u1): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.7432] dhcp4 (wlp0s20f0u1): activation: beginning transaction (timeout in 45 seconds)
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.8254] dhcp4 (wlp0s20f0u1): state changed new lease, address=192.168.254.60
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.8261] policy: set 'DigiRiverMobile 1' (wlp0s20f0u1) as default for IPv4 routing and DNS
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.8339] device (wlp0s20f0u1): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.8372] device (wlp0s20f0u1): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.8373] device (wlp0s20f0u1): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.8378] manager: NetworkManager state is now CONNECTED_SITE
Jan 25 18:16:25 tuf NetworkManager[537]: <info> [1706206585.8382] device (wlp0s20f0u1): Activation: successful, device activated.
Jan 25 18:16:30 tuf systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully.
Jan 25 18:16:30 tuf systemd[1]: Starting Network Manager Script Dispatcher Service...
Jan 25 18:16:30 tuf systemd[1]: Started Network Manager Script Dispatcher Service.
Jan 25 18:16:31 tuf NetworkManager[537]: <info> [1706206591.0268] manager: NetworkManager state is now CONNECTED_GLOBAL
Could there be anything else interfering?
$ sudo systemctl list-units --type service --no-pager
UNIT LOAD ACTIVE SUB DESCRIPTION
alsa-restore.service loaded active exited Save/Restore Sound Card State
asusd.service loaded active running ASUS Notebook Control
dbus-broker.service loaded active running D-Bus System Message Bus
getty@tty1.service loaded active running Getty on tty1
kmod-static-nodes.service loaded active exited Create List of Static Device Nodes
● mdmonitor.service loaded failed failed MD array monitor
NetworkManager.service loaded active running Network Manager
numlock.service loaded active exited numlock
numLockOnTty.service loaded active exited numlock
polkit.service loaded active running Authorization Manager
rtkit-daemon.service loaded active running RealtimeKit Scheduling Policy Ser…
sshd.service loaded active running OpenSSH Daemon
systemd-backlight@backlight:intel… loaded active exited Load/Save Screen Backlight Bright…
systemd-backlight@leds:asus::kbd_… loaded active exited Load/Save Screen Backlight Bright…
systemd-cryptsetup@swap.service loaded active exited Cryptography Setup for swap
systemd-fsck-root.service loaded active exited File System Check on Root Device
systemd-journal-flush.service loaded active exited Flush Journal to Persistent Stora…
systemd-journald.service loaded active running Journal Service
systemd-logind.service loaded active running User Login Management
systemd-modules-load.service loaded active exited Load Kernel Modules
systemd-random-seed.service loaded active exited Load/Save OS Random Seed
systemd-remount-fs.service loaded active exited Remount Root and Kernel File Syst…
systemd-sysctl.service loaded active exited Apply Kernel Variables
systemd-timesyncd.service loaded active running Network Time Synchronization
systemd-tmpfiles-setup-dev-early.… loaded active exited Create Static Device Nodes in /de…
systemd-tmpfiles-setup-dev.service loaded active exited Create Static Device Nodes in /dev
systemd-tmpfiles-setup.service loaded active exited Create Volatile Files and Directo…
systemd-udev-trigger.service loaded active exited Coldplug All udev Devices
systemd-udevd.service loaded active running Rule-based Manager for Device Eve…
systemd-update-utmp.service loaded active exited Record System Boot/Shutdown in UT…
systemd-user-sessions.service loaded active exited Permit User Sessions
systemd-vconsole-setup.service loaded active exited Virtual Console Setup
user-runtime-dir@1000.service loaded active exited User Runtime Directory /run/user/…
user@1000.service loaded active running User Manager for UID 1000
wpa_supplicant.service loaded active running WPA supplicant
Could it be an issue with `wpa_supplicant`?
Offline
Could it be an issue with `wpa_supplicant`?
Only if the service has been enabled. NetworkManager should load wpa_supplicant rather than it being enabled.
Check
ls /etc/systemd/system/multi-user.target.wants
Is it listed there? If so then disable the service and reboot to test.
Offline
No not listed there
ladmin@tuf ~ $ ls /etc/systemd/system/multi-user.target.wants
NetworkManager.service numlock.service sshd.service
numLockOnTty.service remote-fs.target
Offline
There's a 30s gap between the posted segments and the first part doesn't show a disconnect at all - can we please get a coherent journal covering the incident?
Off the bat, lock the BSSID and disable MAC randomization.
Offline
Thanks for your help. I created the following file and then rebooted.
ladmin@tuf ~ $ cat /etc/NetworkManager/conf.d/30-mac-randomization.conf
[device-mac-randomization]
wifi.scan-rand-mac-address=no
The below is the full log capturing the first occurrence of the incident after that reboot:
Thanks.
Last edited by crazzyfool (2024-01-26 00:12:44)
Offline
Jan 26 00:06:54 tuf wpa_supplicant[646]: wlp0s20f0u1: Trying to associate with b2:8b:a9:28:cd:ba (SSID='DigiRiverMobile' freq=5220 MHz)
Jan 26 00:07:30 tuf wpa_supplicant[646]: wlp0s20f0u1: Trying to associate with ac:8b:a9:28:cd:b9 (SSID='DigiRiverMobile' freq=2437 MHz)
lock the BSSID
https://wiki.archlinux.org/title/Networ … ets_(WiFi)
From the brief pattern, you're jumping between the 2.4GHz and 5GHz bands of the same AP.
Lock the 2.4GHz BSSID (ac:8b:a9:28:cd:b9)
Offline
Thanks! I did see your suggestion and followed the link but the documentation said to lock the BSSID if roaming is not important to you. I have two access points so roaming is important to me. I did just try your suggestion though and I think that was the final piece of the puzzle! As long as I'm connected the that AP I don't think I'm seeing any disconnects now. So that's kinda good news! Is there a better solution by any chance? Like can I keep roaming but somehow indicate I only want to connect to only the (preferably) 5Ghz band and not even try the 2.4GHz?
Offline
Roaming is done by the backend, https://wiki.archlinux.org/title/wpa_supplicant#Roaming
But afaict, NM simply hardcodes the config: https://gitlab.freedesktop.org/NetworkM … heads#L627 otherwise you could fine-tune the signal strength limits.
Your suggestion was https://gitlab.freedesktop.org/NetworkM … issues/930
Offline
Pages: 1