WiFi keeps crashing

For Developers
1

I've been dealing with this for over a year with various trunk revisions of kernel 5.10 and mac80211. WiFi unstable / drops out, does not recover. Current versions:

root@SecureOffice:/# uname -a
Linux SecureOffice 5.10.100 #0 SMP PREEMPT Tue Nov 23 11:58:23 2021 aarch64 GNU/Linux

mac80211-5.15.8-1

Here's a debug log showing loss of two connections:

root@SecureOffice:/# logread -f
Sat Mar  5 08:10:16 2022 daemon.info hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.11: disassociated
Sat Mar  5 08:10:16 2022 daemon.notice hostapd: wlan0: AP-STA-DISCONNECTED b0:fc:36:fa:22:73
Sat Mar  5 08:10:16 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 2 notification
Sat Mar  5 08:10:16 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.1X: unauthorizing port
[ 3879.896726] br-lan: received packet on wlan0 with own address as source address (addr:e0:76:d0:69:35:2d, vlan:0)
Sat Mar  5 08:10:16 2022 daemon.info hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.11: associated
Sat Mar  5 08:10:16 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 1 notification
Sat Mar  5 08:10:16 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: start authentication
Sat Mar  5 08:10:16 2022 kern.warn kernel: [ 3879.896726] br-lan: received packet on wlan0 with own address as source address (addr:e0:76:d0:69:35:2d, vlan:0)
Sat Mar  5 08:10:16 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.1X: unauthorizing port
Sat Mar  5 08:10:16 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:17 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:17 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:18 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:18 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:19 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:19 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:20 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:20 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: PTKSTART: Retry limit 4 reached
Sat Mar  5 08:10:20 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 3 notification
Sat Mar  5 08:10:20 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.1X: unauthorizing port
Sat Mar  5 08:10:20 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 MLME: MLME-DEAUTHENTICATE.indication(b0:fc:36:fa:22:73, 15)
Sat Mar  5 08:10:20 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 MLME: MLME-DELETEKEYS.request(b0:fc:36:fa:22:73)
Sat Mar  5 08:10:20 2022 daemon.info hostapd: wlan0: [ 3883.983279] br-lan: received packet on wlan0 with own address as source address (addr:e0:76:d0:69:35:2d, vlan:0)
STA b0:fc:36:fa:22:73 IEEE 802.11: disassociated
Sat Mar  5 08:10:20 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 2 notification
Sat Mar  5 08:10:20 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.1X: unauthorizing port
Sat Mar  5 08:10:20 2022 daemon.info hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.11: associated
Sat Mar  5 08:10:20 2022 kern.warn kernel: [ 3883.983279] br-lan: received packet on wlan0 with own address as source address (addr:e0:76:d0:69:35:2d, vlan:0)
Sat Mar  5 08:10:20 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 1 notification
Sat Mar  5 08:10:20 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: start authentication
Sat Mar  5 08:10:20 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.1X: unauthorizing port
Sat Mar  5 08:10:20 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:21 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:21 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:22 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:22 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:23 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:23 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:24 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:24 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: PTKSTART: Retry limit 4 reached
Sat Mar  5 08:10:24 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 3 notification
Sat Mar  5 08:10:24 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.1X: unauthorizing port
Sat Mar  5 08:10:24 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 MLME: MLME-DEAUTHENTICATE.indication(b0:fc:36:fa:22:73, 15)
Sat Mar  5 08:10:24 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 MLME: MLME-DELETEKEYS.request(b0:fc:36:fa:22:73)
Sat Mar  5 08:10:24 2022 daemon.info hostapd: wlan0: STA b0:fc:36:fa:[ 3888.075473] br-lan: received packet on wlan0 with own address as source address (addr:e0:76:d0:69:35:2d, vlan:0)
22:73 IEEE 802.11: disassociated
Sat Mar  5 08:10:24 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 2 notification
Sat Mar  5 08:10:24 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.1X: unauthorizing port
Sat Mar  5 08:10:24 2022 daemon.info hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.11: associated
Sat Mar  5 08:10:24 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 1 notification
Sat Mar  5 08:10:24 2022 kern.warn kernel: [ 3888.075473] br-lan: received packet on wlan0 with own address as source address (addr:e0:76:d0:69:35:2d, vlan:0)
Sat Mar  5 08:10:24 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: start authentication
Sat Mar  5 08:10:24 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.1X: unauthorizing port
Sat Mar  5 08:10:24 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:25 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:25 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:26 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:26 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:27 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:27 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:28 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:28 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: PTKSTART: Retry limit 4 reached
Sat Mar  5 08:10:28 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 3 notification
Sat Mar  5 08:10:28 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.1X: unauthorizing port
Sat Mar  5 08:10:28 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 MLME: MLME-DEAUTHENTICATE.indication(b0:fc:36:fa:22:73, 15)
Sat Mar  5 08:10:28 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 MLME: MLME-DELETEKEYS.request(b0:fc:36:fa:22:73)
Sat Mar  5 08:10:28 2022 daemon.info hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.11: disassociated
Sat Mar  5 08:10:28 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 2 notification
Sat Mar  5 08:10:28 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.1X: unauthorizing port
[ 3895.769747] br-lan: received packet on wlan0 with own address as source address (addr:e0:76:d0:69:35:2d, vlan:0)
Sat Mar  5 08:10:32 2022 kern.warn kernel: [ 3895.769747] br-lan: received packet on wlan0 with own address as source address (addr:e0:76:d0:69:35:2d, vlan:0)
Sat Mar  5 08:10:32 2022 daemon.info hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.11: associated
Sat Mar  5 08:10:32 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 1 notification
Sat Mar  5 08:10:32 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: start authentication
Sat Mar  5 08:10:32 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.1X: unauthorizing port
Sat Mar  5 08:10:32 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:33 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:33 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:34 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:34 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
[ 3898.625248] br-lan: received packet on wlan0 with own address as source address (addr:e0:76:d0:69:35:2d, vlan:0)
Sat Mar  5 08:10:35 2022 daemon.info hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.11: associated
Sat Mar  5 08:10:35 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 1 notification
Sat Mar  5 08:10:35 2022 kern.warn kernel: [ 3898.625248] br-lan: received packet on wlan0 with own address as source address (addr:e0:76:d0:69:35:2d, vlan:0)
Sat Mar  5 08:10:35 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 4 notification
Sat Mar  5 08:10:35 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:35 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:35 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
[ 3899.045497] br-lan: received packet on wlan0 with own address as source address (addr:e0:76:d0:69:35:2d, vlan:0)
Sat Mar  5 08:10:35 2022 daemon.info hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.11: associated
Sat Mar  5 08:10:35 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 1 notification
Sat Mar  5 08:10:35 2022 kern.warn kernel: [ 3899.045497] br-lan: received packet on wlan0 with own address as source address (addr:e0:76:d0:69:35:2d, vlan:0)
Sat Mar  5 08:10:35 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 4 notification
Sat Mar  5 08:10:35 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
[ 3899.465306] br-lan: received packet on wlan0 with own address as source address (addr:e0:76:d0:69:35:2d, vlan:0)
Sat Mar  5 08:10:36 2022 kern.warn kernel: [ 3899.465306] br-lan: received packet on wlan0 with own address as source address (addr:e0:76:d0:69:35:2d, vlan:0)
Sat Mar  5 08:10:36 2022 daemon.info hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.11: associated
Sat Mar  5 08:10:36 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 1 notification
Sat Mar  5 08:10:36 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 4 notification
Sat Mar  5 08:10:36 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:36 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:36 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:36 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:36 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:36 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:36 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: sending 1/4 msg of 4-Way Handshake
Sat Mar  5 08:10:37 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: EAPOL-Key timeout
Sat Mar  5 08:10:37 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: PTKSTART: Retry limit 4 reached
Sat Mar  5 08:10:37 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 3 notification
Sat Mar  5 08:10:37 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.1X: unauthorizing port
Sat Mar  5 08:10:37 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 MLME: MLME-DEAUTHENTICATE.indication(b0:fc:36:fa:22:73, 15)
Sat Mar  5 08:10:37 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 MLME: MLME-DELETEKEYS.request(b0:fc:36:fa:22:73)
Sat Mar  5 08:10:37 2022 daemon.info hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.11: disassociated
Sat Mar  5 08:10:37 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 WPA: event 2 notification
Sat Mar  5 08:10:37 2022 daemon.debug hostapd: wlan0: STA b0:fc:36:fa:22:73 IEEE 802.1X: unauthorizing port

Observations:

  • It appears that "received packet on wlan0 with own address..." requires restarting wireless to recover.
  • Initial "disassociated" (minutes to hours) is what causes failure

Questions:
I can restart WiFi automatically by triggering on "received packet on wlan0 with own address"
Need some insight into what causes initial dissociation (channel 1, is clear). Debug tips?

Thanks;
Bill

2

is stable release ok?

if so, this should be a bug report instead of forum post

3

I am working in target meson (bcm27xx) for Amlogic TV Boxes (unsupported), attempting a cheaper alternative to R-Pi for server apps. Building from source and have had this issue for many openwrt revisions.

Looking for insight how to debug:

  • inital disasociated
  • why not recover / re-connect
4

I would try to build on a release branch anyway, just to see if there is any difference

Wireless support for Broadcom devices is already known to be not great (depending on the chip) and is still a work in progress, some reading...

5

Thanks for suggestion. Managed to get it working. Obsolete WiFi firmware and settings file (had to use one from Android TV box firmware) were the culprits.

Can now ping over WiFi for several days until disconnect and can reconnect after disconnect. Will keep testing.

Linux SecureOffice 5.10.100 #0 SMP PREEMPT Tue Nov 23 11:58:23 2021 aarch64 GNU/Linux
target meson (bcm27xx)
mac80211-5.15.8-1

6

This topic was automatically closed 10 days after the last reply. New replies are no longer allowed.