Hey OpenWRT lovers,
Recently I installed OpenWRT 22.03.5 r20134-5f15225c1e on MikroTik rb962 (hAP ac) to work in station (STA) mode to connect to the WiFi of the site where the OpenWRT is. The router is portable and we move it while we are working in the site but when we switch between APs, we get a DISASSOC_DUE_TO_INACTIVITY error and OpenWRT doesn't try to reassociate or authenticate again and it just goes offline and we have to manually restart the WIFI interface.
I should also mention that we have the same scenario in other site that works smoothly with roaming but this one goes offline.
Following you can find a sample of log messaged that it produces
Wed Feb 28 07:02:11 2024 kern.info kernel: [81252.773847] wlan0: deauthenticated from xx:xx:xx:xx:43:4c (Reason: 4=DISASSOC_DUE_TO_INACTIVITY)
Wed Feb 28 07:02:11 2024 kern.info kernel: [81252.783466] ath10k_pci 0000:00:00.0: mac flush vdev 0 drop 0 queues 0x1 ar->paused: 0x0 arvif->paused: 0x0
Wed Feb 28 07:02:11 2024 daemon.notice netifd: Network device 'wlan0' link is down
Wed Feb 28 07:02:11 2024 daemon.notice netifd: Interface 'wwan' has link connectivity loss
Wed Feb 28 07:02:11 2024 daemon.notice wpa_supplicant[1587]: wlan0: CTRL-EVENT-DISCONNECTED bssid=xx:xx:xx:xx:43:4c reason=4
Wed Feb 28 07:02:11 2024 kern.info kernel: [81252.829156] ath10k_pci 0000:00:00.0: mac flush null vif, drop 0 queues 0xffff
Wed Feb 28 07:02:11 2024 daemon.notice wpa_supplicant[1587]: wlan0: SME: Trying to authenticate with xx:xx:xx:xx:43:4c (SSID='SITE_WALN' freq=5180 MHz)
Wed Feb 28 07:02:11 2024 daemon.notice wpa_supplicant[1587]: BSSID xx:xx:xx:xx:43:4c ignore list count incremented to 2, ignoring for 10 seconds
Wed Feb 28 07:03:16 2024 daemon.notice wpa_supplicant[1587]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all
Wed Feb 28 07:03:16 2024 daemon.notice wpa_supplicant[1587]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all
Wed Feb 28 07:03:16 2024 daemon.notice wpa_supplicant[1587]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
Wed Feb 28 07:03:16 2024 daemon.err wpa_supplicant[1587]: rmdir[ctrl_interface=/var/run/wpa_supplicant]: Permission denied
Wed Feb 28 07:03:16 2024 daemon.notice netifd: Interface 'VPN' has lost the connection
Wed Feb 28 07:03:16 2024 daemon.warn dnsmasq[1]: no servers found in /tmp/resolv.conf.d/resolv.conf.auto, will retry
Wed Feb 28 07:03:16 2024 daemon.notice netifd: Interface 'wwan' is now down
Wed Feb 28 07:03:16 2024 daemon.notice netifd: Interface 'wwan' is disabled
Wed Feb 28 07:03:16 2024 daemon.notice netifd: Network device 'VPN' link is down
Wed Feb 28 07:03:16 2024 daemon.notice netifd: Wireless device 'radio0' is now down
Wed Feb 28 07:03:16 2024 daemon.notice netifd: Interface 'VPN' is now down
Wed Feb 28 07:03:16 2024 daemon.notice netifd: Interface 'VPN' is setting up now
Wed Feb 28 07:03:17 2024 daemon.info prometheus-node-exporter-lua: defering start until listen interface VPN becomes ready
## MANUAL WIFI RESTART PERFORMED HERE
Wed Feb 28 07:03:38 2024 kern.warn kernel: [81339.597857] ath10k_pci 0000:00:00.0: 10.1 wmi init: vdevs: 16 peers: 127 tid: 256
Wed Feb 28 07:03:38 2024 kern.info kernel: [81339.614539] ath10k_pci 0000:00:00.0: wmi print 'P 128 V 8 T 410'
Wed Feb 28 07:03:38 2024 kern.info kernel: [81339.620906] ath10k_pci 0000:00:00.0: wmi print 'msdu-desc: 1424 sw-crypt: 0 ct-sta: 0'
Wed Feb 28 07:03:38 2024 kern.info kernel: [81339.629046] ath10k_pci 0000:00:00.0: wmi print 'alloc rem: 24984 iram: 38672'
Wed Feb 28 07:03:38 2024 kern.warn kernel: [81339.696048] ath10k_pci 0000:00:00.0: pdev param 0 not supported by firmware
Wed Feb 28 07:03:38 2024 kern.info kernel: [81339.713446] ath10k_pci 0000:00:00.0: rts threshold -1
Wed Feb 28 07:03:38 2024 daemon.notice netifd: radio0 (16001): sh: out of range
Wed Feb 28 07:03:38 2024 daemon.notice netifd: Wireless device 'radio0' is now up
Wed Feb 28 07:03:38 2024 daemon.notice netifd: Interface 'wwan' is enabled
Wed Feb 28 07:03:38 2024 daemon.notice netifd: Interface 'wwan' is setting up now
Wed Feb 28 07:03:38 2024 daemon.notice netifd: Interface 'wwan' is now up
Wed Feb 28 07:03:38 2024 daemon.info dnsmasq[1]: reading /tmp/resolv.conf.d/resolv.conf.auto
Wed Feb 28 07:03:38 2024 daemon.info dnsmasq[1]: using nameserver 1.1.1.1#53
Wed Feb 28 07:03:38 2024 daemon.info dnsmasq[1]: using nameserver 8.8.8.8#53
Wed Feb 28 07:03:38 2024 daemon.info dnsmasq[1]: using only locally-known addresses for test
Wed Feb 28 07:03:38 2024 daemon.info dnsmasq[1]: using only locally-known addresses for onion
Wed Feb 28 07:03:38 2024 daemon.info dnsmasq[1]: using only locally-known addresses for localhost
Wed Feb 28 07:03:38 2024 daemon.info dnsmasq[1]: using only locally-known addresses for local
Wed Feb 28 07:03:38 2024 daemon.info dnsmasq[1]: using only locally-known addresses for invalid
Wed Feb 28 07:03:38 2024 daemon.info dnsmasq[1]: using only locally-known addresses for bind
Wed Feb 28 07:03:38 2024 daemon.info dnsmasq[1]: using only locally-known addresses for lan
Wed Feb 28 07:03:38 2024 user.notice firewall: Reloading firewall due to ifup of wwan (wlan0)
Wed Feb 28 07:03:43 2024 daemon.notice wpa_supplicant[1587]: wlan0: SME: Trying to authenticate with yy:yy:yy:yy:8c:ec (SSID='SITE_WALN' freq=5180 MHz)
Wed Feb 28 07:03:43 2024 kern.info kernel: [81344.953482] wlan0: authenticate with yy:yy:yy:yy:8c:ec
Wed Feb 28 07:03:43 2024 daemon.notice wpa_supplicant[1587]: wlan0: Trying to associate with yy:yy:yy:yy:8c:ec (SSID='SITE_WALN' freq=5180 MHz)
Wed Feb 28 07:03:43 2024 kern.info kernel: [81344.965932] wlan0: send auth to yy:yy:yy:yy:8c:ec (try 1/3)
Wed Feb 28 07:03:43 2024 kern.info kernel: [81344.972762] wlan0: authenticated
Wed Feb 28 07:03:43 2024 kern.info kernel: [81344.983582] wlan0: associate with yy:yy:yy:yy:8c:ec (try 1/3)
Wed Feb 28 07:03:43 2024 kern.info kernel: [81344.993217] wlan0: RX AssocResp from yy:yy:yy:yy:8c:ec (capab=0x1011 status=0 aid=1)
Wed Feb 28 07:03:43 2024 kern.warn kernel: [81345.002373] ath10k_pci 0000:00:00.0: pdev param 0 not supported by firmware
Wed Feb 28 07:03:43 2024 kern.warn kernel: [81345.009651] ath10k_pci 0000:00:00.0: failed to enable peer stats info: -122
Wed Feb 28 07:03:43 2024 kern.info kernel: [81345.016835] wlan0: associated
Wed Feb 28 07:03:43 2024 daemon.notice wpa_supplicant[1587]: wlan0: Associated with yy:yy:yy:yy:8c:ec
Wed Feb 28 07:03:43 2024 daemon.notice wpa_supplicant[1587]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Wed Feb 28 07:03:43 2024 daemon.notice netifd: Network device 'wlan0' link is up
Wed Feb 28 07:03:43 2024 daemon.notice netifd: Interface 'wwan' has link connectivity
Wed Feb 28 07:03:43 2024 daemon.notice wpa_supplicant[1587]: wlan0: WPA: Key negotiation completed with yy:yy:yy:yy:8c:ec [PTK=CCMP GTK=CCMP]
Wed Feb 28 07:03:43 2024 daemon.notice wpa_supplicant[1587]: wlan0: CTRL-EVENT-CONNECTED - Connection to yy:yy:yy:yy:8c:ec completed [id=0 id_str=]
Wed Feb 28 07:03:43 2024 kern.info kernel: [81345.038623] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Wed Feb 28 07:03:43 2024 kern.debug kernel: [81345.077205] wlan0: Limiting TX power to 23 (23 - 0) dBm as advertised by yy:yy:yy:yy:8c:ec
Wed Feb 28 07:03:43 2024 daemon.notice netifd: Interface 'VPN' is now up
Unfortunately, I didn't find a similar Topic since all the other issues related to disassociation issue are discussed for AP mode of the WiFI.