You are not logged in.

#1 2024-01-24 22:15:21

crazzyfool
Member
Registered: 2014-04-07
Posts: 33

Wifi randomly disconnects/reconnects

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

#2 2024-01-25 17:04:59

xanderxanderfto
Member
Registered: 2024-01-25
Posts: 4

Re: Wifi randomly disconnects/reconnects

Check this https://bbs.archlinux.org/viewtopic.php?id=213449. You have both networkd and network manager working.

Offline

#3 2024-01-25 18:24:18

crazzyfool
Member
Registered: 2014-04-07
Posts: 33

Re: Wifi randomly disconnects/reconnects

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

#4 2024-01-25 19:12:26

Head_on_a_Stick
Member
From: London
Registered: 2014-02-20
Posts: 7,732
Website

Re: Wifi randomly disconnects/reconnects

crazzyfool wrote:

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

#5 2024-01-25 19:38:32

crazzyfool
Member
Registered: 2014-04-07
Posts: 33

Re: Wifi randomly disconnects/reconnects

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

#6 2024-01-25 21:31:09

seth
Member
Registered: 2012-09-03
Posts: 51,578

Re: Wifi randomly disconnects/reconnects

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

#7 2024-01-25 22:47:35

crazzyfool
Member
Registered: 2014-04-07
Posts: 33

Re: Wifi randomly disconnects/reconnects

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:

https://0x0.st/HG3q.log

Thanks.

Last edited by crazzyfool (2024-01-26 00:12:44)

Offline

#8 2024-01-26 09:02:23

seth
Member
Registered: 2012-09-03
Posts: 51,578

Re: Wifi randomly disconnects/reconnects

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)
seth wrote:

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

#9 2024-01-26 18:17:16

crazzyfool
Member
Registered: 2014-04-07
Posts: 33

Re: Wifi randomly disconnects/reconnects

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

#10 2024-01-26 21:19:01

seth
Member
Registered: 2012-09-03
Posts: 51,578

Re: Wifi randomly disconnects/reconnects

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

Board footer

Powered by FluxBB