Slow wifi, fixed on reconnect

reactormonk · 2023-08-07 12:42:25

I've now had it with two different wifis, where the connection speed dropped after a while to about 3 Mbps, but only the downlink. The uplink was still full 100 Mbps. After a reconnect to the network, I had full speed again.

Logs:

Aug 07 14:06:08 kyrios kernel: wlan0: disassociated from <bssid> (Reason: 252=<unknown>)
Aug 07 14:06:08 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-DISCONNECTED bssid=<bssid> reason=252
Aug 07 14:06:08 kyrios NetworkManager[1661]: <info>  [1691409968.6659] device (wlan0): supplicant interface state: completed -> disconnected
Aug 07 14:06:08 kyrios NetworkManager[1661]: <info>  [1691409968.7557] device (wlan0): supplicant interface state: disconnected -> scanning
Aug 07 14:06:11 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=DE
Aug 07 14:06:11 kyrios wpa_supplicant[1876]: wlan0: SME: Trying to authenticate with <bssid2> (SSID='<ssid>' freq=5300 MHz)
Aug 07 14:06:11 kyrios kernel: wlan0: authenticate with <bssid2>
Aug 07 14:06:11 kyrios kernel: wlan0: send auth to <bssid2> (try 1/3)
Aug 07 14:06:11 kyrios NetworkManager[1661]: <info>  [1691409971.2981] device (wlan0): supplicant interface state: scanning -> authenticating
Aug 07 14:06:11 kyrios kernel: wlan0: authenticated
Aug 07 14:06:11 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=DE
Aug 07 14:06:11 kyrios wpa_supplicant[1876]: wlan0: Trying to associate with <bssid2> (SSID='<ssid>' freq=5300 MHz)
Aug 07 14:06:11 kyrios kernel: wlan0: associate with <bssid2> (try 1/3)
Aug 07 14:06:11 kyrios NetworkManager[1661]: <info>  [1691409971.3167] device (wlan0): supplicant interface state: authenticating -> associating
Aug 07 14:06:11 kyrios kernel: wlan0: RX AssocResp from <bssid2> (capab=0x1111 status=0 aid=4)
Aug 07 14:06:11 kyrios kernel: wlan0: associated
Aug 07 14:06:11 kyrios kernel: wlan0: Limiting TX power to 23 (23 - 0) dBm as advertised by <bssid2>
Aug 07 14:06:11 kyrios wpa_supplicant[1876]: wlan0: Associated with <bssid2>
Aug 07 14:06:11 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Aug 07 14:06:11 kyrios NetworkManager[1661]: <info>  [1691409971.3505] device (wlan0): supplicant interface state: associating -> 4way_handshake
Aug 07 14:06:11 kyrios wpa_supplicant[1876]: wlan0: WPA: Key negotiation completed with <bssid2> [PTK=CCMP GTK=CCMP]
Aug 07 14:06:11 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-CONNECTED - Connection to <bssid2> completed [id=0 id_str=]
Aug 07 14:06:11 kyrios NetworkManager[1661]: <info>  [1691409971.3905] device (wlan0): supplicant interface state: 4way_handshake -> completed
Aug 07 14:06:11 kyrios NetworkManager[1661]: <info>  [1691409971.3907] device (wlan0): ip:dhcp4: restarting
Aug 07 14:06:11 kyrios NetworkManager[1661]: <info>  [1691409971.4047] dhcp4 (wlan0): canceled DHCP transaction
Aug 07 14:06:11 kyrios NetworkManager[1661]: <info>  [1691409971.4047] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Aug 07 14:06:11 kyrios NetworkManager[1661]: <info>  [1691409971.4047] dhcp4 (wlan0): state changed no lease
Aug 07 14:06:11 kyrios NetworkManager[1661]: <info>  [1691409971.4048] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Aug 07 14:06:11 kyrios NetworkManager[1661]: <info>  [1691409971.4193] dhcp4 (wlan0): state changed new lease, address=192.168.32.65
Aug 07 14:06:11 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-53 noise=-111 txrate=344100
Aug 07 14:16:23 kyrios wpa_supplicant[1876]: wlan0: WPA: Group rekeying completed with <bssid2> [GTK=CCMP]
Aug 07 14:16:48 kyrios NetworkManager[1661]: <info>  [1691410608.2799] audit: op="statistics" interface="wlan0" ifindex=4 args="2000" pid=5531 uid=1000 result="success"
Aug 07 14:16:48 kyrios NetworkManager[1661]: <info>  [1691410608.2871] audit: op="statistics" interface="wlan0" ifindex=4 args="500" pid=6893 uid=1000 result="success"
Aug 07 14:16:49 kyrios NetworkManager[1661]: <info>  [1691410609.7349] audit: op="statistics" interface="wlan0" ifindex=4 args="2000" pid=5531 uid=1000 result="success"
Aug 07 14:16:49 kyrios NetworkManager[1661]: <info>  [1691410609.7427] audit: op="statistics" interface="wlan0" ifindex=4 args="500" pid=6893 uid=1000 result="success"
Aug 07 14:17:08 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-BEACON-LOSS
Aug 07 14:17:11 kyrios kernel: wlan0: Connection to AP <bssid2> lost
Aug 07 14:17:11 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-DISCONNECTED bssid=<bssid2> reason=4 locally_generated=1
Aug 07 14:17:11 kyrios NetworkManager[1661]: <info>  [1691410631.6535] device (wlan0): supplicant interface state: completed -> disconnected
Aug 07 14:17:11 kyrios NetworkManager[1661]: <info>  [1691410631.7161] device (wlan0): supplicant interface state: disconnected -> scanning
Aug 07 14:17:12 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=DE
Aug 07 14:17:12 kyrios wpa_supplicant[1876]: wlan0: SME: Trying to authenticate with <bssid3> (SSID='<ssid>' freq=5500 MHz)
Aug 07 14:17:12 kyrios kernel: wlan0: authenticate with <bssid3>
Aug 07 14:17:12 kyrios kernel: wlan0: send auth to <bssid3> (try 1/3)
Aug 07 14:17:12 kyrios NetworkManager[1661]: <info>  [1691410632.4801] device (wlan0): supplicant interface state: scanning -> authenticating
Aug 07 14:17:12 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=DE
Aug 07 14:17:12 kyrios wpa_supplicant[1876]: wlan0: Trying to associate with <bssid3> (SSID='<ssid>' freq=5500 MHz)
Aug 07 14:17:12 kyrios kernel: wlan0: authenticated
Aug 07 14:17:12 kyrios kernel: wlan0: associate with <bssid3> (try 1/3)
Aug 07 14:17:12 kyrios NetworkManager[1661]: <info>  [1691410632.4990] device (wlan0): supplicant interface state: authenticating -> associating
Aug 07 14:17:12 kyrios kernel: wlan0: RX AssocResp from <bssid3> (capab=0x1111 status=0 aid=1)
Aug 07 14:17:12 kyrios wpa_supplicant[1876]: wlan0: Associated with <bssid3>
Aug 07 14:17:12 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Aug 07 14:17:12 kyrios kernel: wlan0: associated
Aug 07 14:17:12 kyrios NetworkManager[1661]: <info>  [1691410632.5284] device (wlan0): supplicant interface state: associating -> 4way_handshake
Aug 07 14:17:12 kyrios wpa_supplicant[1876]: wlan0: WPA: Key negotiation completed with <bssid3> [PTK=CCMP GTK=CCMP]
Aug 07 14:17:12 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-CONNECTED - Connection to <bssid3> completed [id=0 id_str=]
Aug 07 14:17:12 kyrios kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by <bssid3>
Aug 07 14:17:12 kyrios NetworkManager[1661]: <info>  [1691410632.5750] device (wlan0): supplicant interface state: 4way_handshake -> completed
Aug 07 14:17:12 kyrios NetworkManager[1661]: <info>  [1691410632.5763] device (wlan0): ip:dhcp4: restarting
Aug 07 14:17:12 kyrios NetworkManager[1661]: <info>  [1691410632.5947] dhcp4 (wlan0): canceled DHCP transaction
Aug 07 14:17:12 kyrios NetworkManager[1661]: <info>  [1691410632.5947] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Aug 07 14:17:12 kyrios NetworkManager[1661]: <info>  [1691410632.5947] dhcp4 (wlan0): state changed no lease
Aug 07 14:17:12 kyrios NetworkManager[1661]: <info>  [1691410632.5948] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Aug 07 14:17:12 kyrios NetworkManager[1661]: <info>  [1691410632.6141] dhcp4 (wlan0): state changed new lease, address=192.168.32.65
Aug 07 14:17:13 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-SIGNAL-CHANGE above=0 signal=-69 noise=-111 txrate=137600
Aug 07 14:28:24 kyrios NetworkManager[1661]: <info>  [1691411304.2971] audit: op="statistics" interface="wlan0" ifindex=4 args="2000" pid=5531 uid=1000 result="success"
Aug 07 14:30:03 kyrios NetworkManager[1661]: <info>  [1691411403.3988] audit: op="statistics" interface="wlan0" ifindex=4 args="2000" pid=5531 uid=1000 result="success"
Aug 07 14:30:05 kyrios NetworkManager[1661]: <info>  [1691411405.1787] device (wlan0): state change: activated -> deactivating (reason 'user-requested', sys-iface-state: 'managed')
Aug 07 14:30:05 kyrios NetworkManager[1661]: <info>  [1691411405.1792] audit: op="device-disconnect" interface="wlan0" ifindex=4 pid=5531 uid=1000 result="success"
Aug 07 14:30:05 kyrios NetworkManager[1661]: <info>  [1691411405.2095] audit: op="statistics" interface="wlan0" ifindex=4 args="0" pid=5531 uid=1000 result="success"
Aug 07 14:30:05 kyrios NetworkManager[1661]: <info>  [1691411405.2156] audit: op="statistics" interface="wlan0" ifindex=4 args="500" pid=6893 uid=1000 result="success"
Aug 07 14:30:05 kyrios kernel: wlan0: deauthenticating from <bssid3> by local choice (Reason: 3=DEAUTH_LEAVING)
Aug 07 14:30:05 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-DISCONNECTED bssid=<bssid3> reason=3 locally_generated=1
Aug 07 14:30:05 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all
Aug 07 14:30:05 kyrios NetworkManager[1661]: <info>  [1691411405.4820] device (wlan0): supplicant interface state: completed -> disconnected
Aug 07 14:30:05 kyrios NetworkManager[1661]: <info>  [1691411405.4824] device (wlan0): state change: deactivating -> disconnected (reason 'user-requested', sys-iface-state: 'managed')
Aug 07 14:30:05 kyrios NetworkManager[1661]: <info>  [1691411405.4834] dhcp4 (wlan0): canceled DHCP transaction
Aug 07 14:30:05 kyrios NetworkManager[1661]: <info>  [1691411405.4834] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Aug 07 14:30:05 kyrios NetworkManager[1661]: <info>  [1691411405.4834] dhcp4 (wlan0): state changed no lease
Aug 07 14:30:05 kyrios systemd-resolved[1604]: wlan0: Bus client set default route setting: no
Aug 07 14:30:05 kyrios systemd-resolved[1604]: wlan0: Bus client reset DNS server list.
Aug 07 14:30:05 kyrios NetworkManager[1661]: <info>  [1691411405.5792] device (wlan0): set-hw-addr: set MAC address to FA:15:1E:92:76:63 (scanning)
Aug 07 14:30:08 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=DE
Aug 07 14:30:08 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=DE
Aug 07 14:30:08 kyrios NetworkManager[1661]: <info>  [1691411408.9230] device (wlan0): Activation: starting connection '<ssid>' (80ddf085-b2b4-4331-936f-01bd5abb57c4)
Aug 07 14:30:08 kyrios NetworkManager[1661]: <info>  [1691411408.9232] device (wlan0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Aug 07 14:30:08 kyrios NetworkManager[1661]: <info>  [1691411408.9822] device (wlan0): set-hw-addr: reset MAC address to <MAC> (preserve)
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.0486] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.0489] device (wlan0): Activation: (wifi) access point '<ssid>' has security, but secrets are required.
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.0489] device (wlan0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.0499] device (wlan0): supplicant interface state: disconnected -> interface_disabled
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.0702] device (wlan0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.0704] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.0707] device (wlan0): Activation: (wifi) connection '<ssid>' has security, and secrets exist.  No new secrets needed.
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.0748] device (wlan0): supplicant interface state: interface_disabled -> disconnected
Aug 07 14:30:09 kyrios wpa_supplicant[1876]: wlan0: SME: Trying to authenticate with <bssid2> (SSID='<ssid>' freq=5300 MHz)
Aug 07 14:30:09 kyrios kernel: wlan0: authenticate with <bssid2>
Aug 07 14:30:09 kyrios kernel: wlan0: send auth to <bssid2> (try 1/3)
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.1505] device (wlan0): supplicant interface state: disconnected -> authenticating
Aug 07 14:30:09 kyrios wpa_supplicant[1876]: wlan0: Trying to associate with <bssid2> (SSID='<ssid>' freq=5300 MHz)
Aug 07 14:30:09 kyrios kernel: wlan0: authenticated
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.1528] device (wlan0): supplicant interface state: authenticating -> associating
Aug 07 14:30:09 kyrios kernel: wlan0: associate with <bssid2> (try 1/3)
Aug 07 14:30:09 kyrios kernel: wlan0: RX AssocResp from <bssid2> (capab=0x1111 status=0 aid=3)
Aug 07 14:30:09 kyrios wpa_supplicant[1876]: wlan0: Associated with <bssid2>
Aug 07 14:30:09 kyrios kernel: wlan0: associated
Aug 07 14:30:09 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.1888] device (wlan0): supplicant interface state: associating -> 4way_handshake
Aug 07 14:30:09 kyrios wpa_supplicant[1876]: wlan0: WPA: Key negotiation completed with <bssid2> [PTK=CCMP GTK=CCMP]
Aug 07 14:30:09 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-CONNECTED - Connection to <bssid2> completed [id=0 id_str=]
Aug 07 14:30:09 kyrios kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.2279] device (wlan0): supplicant interface state: 4way_handshake -> completed
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.2280] device (wlan0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "<ssid>"
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.2282] device (wlan0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.2288] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.2435] dhcp4 (wlan0): state changed new lease, address=192.168.32.65
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.2439] policy: set '<ssid>' (wlan0) as default for IPv4 routing and DNS
Aug 07 14:30:09 kyrios systemd-resolved[1604]: wlan0: Bus client set default route setting: yes
Aug 07 14:30:09 kyrios systemd-resolved[1604]: wlan0: Bus client set DNS server list to: 194.25.0.52, 194.25.0.60, 194.25.0.68
Aug 07 14:30:09 kyrios kernel: wlan0: Limiting TX power to 23 (23 - 0) dBm as advertised by <bssid2>
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.2705] device (wlan0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.2772] device (wlan0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.2774] device (wlan0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.2781] device (wlan0): Activation: successful, device activated.
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.3002] audit: op="statistics" interface="wlan0" ifindex=4 args="2000" pid=5531 uid=1000 result="success"
Aug 07 14:30:09 kyrios NetworkManager[1661]: <info>  [1691411409.3066] audit: op="statistics" interface="wlan0" ifindex=4 args="500" pid=6893 uid=1000 result="success"
Aug 07 14:30:09 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-54 noise=-111 txrate=413000

seth · 2023-08-07 14:22:26

14:06:08: you disconnect from bssid[1]
14:06:11: you connect to bssid2
14:17:11: you get disconnected because of inactivity
14:17:12: you connect to bssid3
14:30:05: you disconnect from bssid3
14:30:09: you connect back to bssid2

Aug 07 14:06:11 kyrios kernel: wlan0: Limiting TX power to 23 (23 - 0) dBm as advertised by <bssid2>
Aug 07 14:06:11 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-53 noise=-111 txrate=344100
Aug 07 14:17:12 kyrios kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by <bssid3>
Aug 07 14:17:13 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-SIGNAL-CHANGE above=0 signal=-69 noise=-111 txrate=137600
Aug 07 14:30:09 kyrios kernel: wlan0: Limiting TX power to 23 (23 - 0) dBm as advertised by <bssid2>
Aug 07 14:30:09 kyrios wpa_supplicant[1876]: wlan0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-54 noise=-111 txrate=413000

bssid2 has a much (logarithmic scale) better signal than bssid3

Depending on how useful your obfuscation is, bssid2 and bssid3 have the same ssid (but operate on slightly different channels, yet get you the same IP, so probably same AP)
=> lock the bssid instead of connecting w/ an ssid, might fall into https://wiki.archlinux.org/title/Networ … ets_(WiFi)

reactormonk · 2024-02-07 16:09:48

I've also got an Android STB in the same network, and it gets disconnected also. However, it seems to reconnect to the same bssid without issues, maybe the Linux reconnection code tries to avoid the "bad" bssid?

I'd prefer to avoid locking, as the wifi has various APs over the building, and locking would disable me from walking around the building and keeping connection. Currently trying to bother the admin to disable this weird behavior of the APs.

seth · 2024-02-07 21:54:42

Have you tried to throw yourself a lifeline and just

ping -i 10 google.com # pings google every 10s you could also use a LAN IP

Arch Linux

#1 2023-08-07 12:42:25

Slow wifi, fixed on reconnect

#2 2023-08-07 14:22:26

Re: Slow wifi, fixed on reconnect

#3 2024-02-07 16:09:48

Re: Slow wifi, fixed on reconnect

#4 2024-02-07 21:54:42

Re: Slow wifi, fixed on reconnect

Board footer