Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

FS#2210 - EA3500, hostapd: WPA: group key handshake failed (RSN) after 4 tries, wlan1: AP-STA-DISCONNTED #7054

Open
openwrt-bot opened this issue Mar 29, 2019 · 2 comments
Labels

Comments

@openwrt-bot
Copy link

builker:

SNAPSHOT r9614-b614954, mwlwifi version 2018-12-18 c2c8244, hostapd-common - 2018-12-02-c2c6c01b-2

Run an EA3500 as AP, serving around 15 devices in a small open office. The signal level is good, SNR -ge 30. When using the default wpa_group_rekey value 600s, there were a lot of disconnections. More than 50% of App requests ended up with a timeout on some devices, such as an iPad. Trying different wpa_group_rekey in values wireless and leasetime in dhcp relieved the problem somewhat. But still many //random// connections and disconnections. The following log focuses on one device during a period of quiet time, with wpa_group_rekey being 3600s and leasetime 3600s as well. The problem/log is at least 15 times bigger/longer.

Fri Mar 29 05:02:36 2019 daemon.info dnsmasq-dhcp[2946]: DHCPREQUEST(br-lan) 192.168.1.52 11:22:33:44:55:66
Fri Mar 29 05:02:36 2019 daemon.info dnsmasq-dhcp[2946]: DHCPACK(br-lan) 192.168.1.52 11:22:33:44:55:66 iPad
Fri Mar 29 05:03:30 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 WPA: group key handshake failed (RSN) after 4 tries
Fri Mar 29 05:03:30 2019 daemon.notice hostapd: wlan1: AP-STA-DISCONNECTED 11:22:33:44:55:66
Fri Mar 29 05:03:35 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 IEEE 802.11: deauthenticated due to local deauth request
Fri Mar 29 05:08:14 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 IEEE 802.11: authenticated
Fri Mar 29 05:08:14 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 IEEE 802.11: associated (aid 7)
Fri Mar 29 05:08:14 2019 daemon.notice hostapd: wlan1: AP-STA-CONNECTED 11:22:33:44:55:66
Fri Mar 29 05:08:14 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 WPA: pairwise key handshake completed (RSN)
Fri Mar 29 05:08:14 2019 daemon.info dnsmasq-dhcp[2946]: DHCPREQUEST(br-lan) 192.168.1.52 11:22:33:44:55:66
Fri Mar 29 05:08:14 2019 daemon.info dnsmasq-dhcp[2946]: DHCPACK(br-lan) 192.168.1.52 11:22:33:44:55:66 iPad
Fri Mar 29 05:38:14 2019 daemon.info dnsmasq-dhcp[2946]: DHCPREQUEST(br-lan) 192.168.1.52 11:22:33:44:55:66
Fri Mar 29 05:38:14 2019 daemon.info dnsmasq-dhcp[2946]: DHCPACK(br-lan) 192.168.1.52 11:22:33:44:55:66 iPad
Fri Mar 29 06:03:30 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 WPA: group key handshake failed (RSN) after 4 tries
Fri Mar 29 06:03:30 2019 daemon.notice hostapd: wlan1: AP-STA-DISCONNECTED 11:22:33:44:55:66
Fri Mar 29 06:03:35 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 IEEE 802.11: deauthenticated due to local deauth request
Fri Mar 29 06:04:21 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 IEEE 802.11: authenticated
Fri Mar 29 06:04:21 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 IEEE 802.11: associated (aid 2)
Fri Mar 29 06:04:21 2019 daemon.notice hostapd: wlan1: AP-STA-CONNECTED 11:22:33:44:55:66
Fri Mar 29 06:04:21 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 WPA: pairwise key handshake completed (RSN)
Fri Mar 29 06:04:21 2019 daemon.info dnsmasq-dhcp[2946]: DHCPREQUEST(br-lan) 192.168.1.52 11:22:33:44:55:66
Fri Mar 29 06:04:21 2019 daemon.info dnsmasq-dhcp[2946]: DHCPACK(br-lan) 192.168.1.52 11:22:33:44:55:66 iPad
Fri Mar 29 06:34:21 2019 daemon.info dnsmasq-dhcp[2946]: DHCPREQUEST(br-lan) 192.168.1.52 11:22:33:44:55:66
Fri Mar 29 06:34:21 2019 daemon.info dnsmasq-dhcp[2946]: DHCPACK(br-lan) 192.168.1.52 11:22:33:44:55:66 iPad
Fri Mar 29 07:02:21 2019 daemon.info dnsmasq-dhcp[2946]: DHCPREQUEST(br-lan) 192.168.1.52 11:22:33:44:55:66
Fri Mar 29 07:02:21 2019 daemon.info dnsmasq-dhcp[2946]: DHCPACK(br-lan) 192.168.1.52 11:22:33:44:55:66 iPad
Fri Mar 29 07:03:30 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 WPA: group key handshake failed (RSN) after 4 tries
Fri Mar 29 07:03:30 2019 daemon.notice hostapd: wlan1: AP-STA-DISCONNECTED 11:22:33:44:55:66
Fri Mar 29 07:03:35 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 IEEE 802.11: deauthenticated due to local deauth request
Fri Mar 29 07:06:37 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 IEEE 802.11: authenticated
Fri Mar 29 07:06:37 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 IEEE 802.11: associated (aid 7)
Fri Mar 29 07:06:37 2019 daemon.notice hostapd: wlan1: AP-STA-CONNECTED 11:22:33:44:55:66
Fri Mar 29 07:06:37 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 WPA: pairwise key handshake completed (RSN)
Fri Mar 29 07:06:37 2019 daemon.info dnsmasq-dhcp[2946]: DHCPREQUEST(br-lan) 192.168.1.52 11:22:33:44:55:66
Fri Mar 29 07:06:37 2019 daemon.info dnsmasq-dhcp[2946]: DHCPACK(br-lan) 192.168.1.52 11:22:33:44:55:66 iPad
Fri Mar 29 07:36:37 2019 daemon.info dnsmasq-dhcp[2946]: DHCPREQUEST(br-lan) 192.168.1.52 11:22:33:44:55:66
Fri Mar 29 07:36:37 2019 daemon.info dnsmasq-dhcp[2946]: DHCPACK(br-lan) 192.168.1.52 11:22:33:44:55:66 iPad
Fri Mar 29 08:03:30 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 WPA: group key handshake failed (RSN) after 4 tries
Fri Mar 29 08:03:30 2019 daemon.notice hostapd: wlan1: AP-STA-DISCONNECTED 11:22:33:44:55:66
Fri Mar 29 08:03:35 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 IEEE 802.11: deauthenticated due to local deauth request
Fri Mar 29 08:04:16 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 IEEE 802.11: authenticated
Fri Mar 29 08:04:16 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 IEEE 802.11: associated (aid 3)
Fri Mar 29 08:04:16 2019 daemon.notice hostapd: wlan1: AP-STA-CONNECTED 11:22:33:44:55:66
Fri Mar 29 08:04:16 2019 daemon.info hostapd: wlan1: STA 11:22:33:44:55:66 WPA: pairwise key handshake completed (RSN)
Fri Mar 29 08:04:16 2019 daemon.info dnsmasq-dhcp[2946]: DHCPREQUEST(br-lan) 192.168.1.52 11:22:33:44:55:66
Fri Mar 29 08:04:16 2019 daemon.info dnsmasq-dhcp[2946]: DHCPACK(br-lan) 192.168.1.52 11:22:33:44:55:66 iPad

@openwrt-bot
Copy link
Author

builker:

Actually the disconnections and connections were not so random. The "WPA: group key handshake failed (RSN) after 4 tries" message showed up every hour at 0*:03:30. Within 1 or 5 minutes, the client was connected, again. Very predictable.

@openwrt-bot
Copy link
Author

timoleon:

EA3500 wifi clients are disconnected as soon as they generate more than a low traffic. Observed on several OpenWrt versions (17 & 18 at least).
ref: https://dev.archive.openwrt.org/ticket/21284

Seems to be a wifi driver issue.
You may try setting eapol_version=1 to the interface with hostapd_cli

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Projects
None yet
Development

No branches or pull requests

1 participant