[SOLVED]Frequent stalls on 21.02-RC3 - possible cause is DHCPv6?

Installing and Using OpenWrt
1

I'm running 21.02-RC3 on TP-Link Archer C2600 for the past 2 days. I'm seeing stalls of 20-30 seconds: on WiFi, there are several (2-3) stalls per hour; and about 1 per hour on a wired connection to my PC. This issue was not happening before the installation of RC3.

What evidence should I look for on the router to back this up? And how to configure the required logging?

The WiFi stalls have been noticed on 3 different devices (2 iPhones and an iPad) and occurs when a new web-page is requested. The issue did not affect an hour long zoom call on an iPad, nor a 9 hour Windows Remote Desktop connection from my PC to my work. This kinda suggests that the issue may be DNS related.

From the (edited) router log for the past 2 hours or so:

Tue Jun 22 14:36:02 2021 daemon.info hostapd: wlan1-1: STA 90:b9:31:df:87:69 IEEE 802.11: authenticated
Tue Jun 22 14:36:02 2021 daemon.info hostapd: wlan1-1: STA 90:b9:31:df:87:69 IEEE 802.11: associated (aid 2)
Tue Jun 22 14:36:02 2021 daemon.notice hostapd: wlan0-1: Prune association for 90:b9:31:df:87:69
Tue Jun 22 14:36:02 2021 daemon.notice hostapd: wlan0-1: AP-STA-DISCONNECTED 90:b9:31:df:87:69
Tue Jun 22 14:36:02 2021 daemon.notice hostapd: wlan1-1: AP-STA-CONNECTED 90:b9:31:df:87:69
Tue Jun 22 14:36:02 2021 daemon.info hostapd: wlan1-1: STA 90:b9:31:df:87:69 WPA: pairwise key handshake completed (RSN)
Tue Jun 22 14:36:02 2021 daemon.info dnsmasq-dhcp[3253]: DHCPREQUEST(br-lan) 192.168.1.23 90:b9:31:df:87:69
Tue Jun 22 14:36:02 2021 daemon.info dnsmasq-dhcp[3253]: DHCPACK(br-lan) 192.168.1.23 90:b9:31:df:87:69 xxxxxx
Tue Jun 22 14:36:08 2021 daemon.info hostapd: wlan0-1: STA 90:b9:31:df:87:69 IEEE 802.11: authenticated
Tue Jun 22 14:36:08 2021 daemon.info hostapd: wlan0-1: STA 90:b9:31:df:87:69 IEEE 802.11: associated (aid 4)
Tue Jun 22 14:36:08 2021 daemon.notice hostapd: wlan1-1: Prune association for 90:b9:31:df:87:69
Tue Jun 22 14:36:08 2021 daemon.notice hostapd: wlan1-1: AP-STA-DISCONNECTED 90:b9:31:df:87:69
Tue Jun 22 14:36:08 2021 daemon.notice hostapd: wlan0-1: AP-STA-CONNECTED 90:b9:31:df:87:69
Tue Jun 22 14:36:08 2021 daemon.info hostapd: wlan0-1: STA 90:b9:31:df:87:69 WPA: pairwise key handshake completed (RSN)
Tue Jun 22 14:36:38 2021 daemon.info hostapd: wlan1-1: STA 90:b9:31:df:87:69 IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Jun 22 14:41:56 2021 daemon.info hostapd: wlan1-2: STA d0:03:df:a2:3d:66 WPA: group key handshake completed (RSN)
Tue Jun 22 14:51:55 2021 daemon.info hostapd: wlan1-2: STA d0:03:df:a2:3d:66 WPA: group key handshake completed (RSN)
Tue Jun 22 14:52:51 2021 daemon.info hostapd: wlan1-1: STA 00:24:e4:72:fe:e8 IEEE 802.11: authenticated
Tue Jun 22 14:52:51 2021 daemon.info hostapd: wlan1-1: STA 00:24:e4:72:fe:e8 IEEE 802.11: associated (aid 2)
Tue Jun 22 14:52:51 2021 daemon.notice hostapd: wlan1-1: AP-STA-CONNECTED 00:24:e4:72:fe:e8
Tue Jun 22 14:52:51 2021 daemon.info hostapd: wlan1-1: STA 00:24:e4:72:fe:e8 WPA: pairwise key handshake completed (RSN)
Tue Jun 22 14:52:51 2021 daemon.info dnsmasq-dhcp[3253]: DHCPDISCOVER(br-lan) 00:24:e4:72:fe:e8
Tue Jun 22 14:52:51 2021 daemon.info dnsmasq-dhcp[3253]: DHCPOFFER(br-lan) 192.168.1.6 00:24:e4:72:fe:e8
Tue Jun 22 14:52:51 2021 daemon.info dnsmasq-dhcp[3253]: DHCPREQUEST(br-lan) 192.168.1.6 00:24:e4:72:fe:e8
Tue Jun 22 14:52:51 2021 daemon.info dnsmasq-dhcp[3253]: DHCPACK(br-lan) 192.168.1.6 00:24:e4:72:fe:e8 xxxxxx
Tue Jun 22 14:52:56 2021 daemon.notice hostapd: wlan1-1: AP-STA-DISCONNECTED 00:24:e4:72:fe:e8
Tue Jun 22 14:52:56 2021 daemon.info hostapd: wlan1-1: STA 00:24:e4:72:fe:e8 IEEE 802.11: disassociated
Tue Jun 22 14:52:57 2021 daemon.info hostapd: wlan1-1: STA 00:24:e4:72:fe:e8 IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Jun 22 15:01:57 2021 daemon.info hostapd: wlan1-2: STA d0:03:df:a2:3d:66 WPA: group key handshake completed (RSN)
Tue Jun 22 15:11:56 2021 daemon.info hostapd: wlan1-2: STA d0:03:df:a2:3d:66 WPA: group key handshake completed (RSN)
Tue Jun 22 15:21:57 2021 daemon.info hostapd: wlan1-2: STA d0:03:df:a2:3d:66 WPA: group key handshake completed (RSN)
Tue Jun 22 15:31:55 2021 daemon.info hostapd: wlan1-2: STA d0:03:df:a2:3d:66 WPA: group key handshake completed (RSN)
Tue Jun 22 15:34:05 2021 daemon.info hostapd: wlan1-1: STA 64:c7:53:44:90:68 IEEE 802.11: authenticated
Tue Jun 22 15:34:05 2021 daemon.info hostapd: wlan1-1: STA 64:c7:53:44:90:68 IEEE 802.11: associated (aid 2)
Tue Jun 22 15:34:05 2021 daemon.notice hostapd: wlan0-1: Prune association for 64:c7:53:44:90:68
Tue Jun 22 15:34:05 2021 daemon.notice hostapd: wlan0-1: AP-STA-DISCONNECTED 64:c7:53:44:90:68
Tue Jun 22 15:34:05 2021 daemon.notice hostapd: wlan1-1: AP-STA-CONNECTED 64:c7:53:44:90:68
Tue Jun 22 15:34:05 2021 daemon.info hostapd: wlan1-1: STA 64:c7:53:44:90:68 WPA: pairwise key handshake completed (RSN)
Tue Jun 22 15:34:35 2021 daemon.info hostapd: wlan0-1: STA 64:c7:53:44:90:68 IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Jun 22 15:41:57 2021 daemon.info hostapd: wlan1-2: STA d0:03:df:a2:3d:66 WPA: group key handshake completed (RSN)
Tue Jun 22 15:51:57 2021 daemon.info hostapd: wlan1-2: STA d0:03:df:a2:3d:66 WPA: group key handshake completed (RSN)
Tue Jun 22 15:54:22 2021 daemon.info hostapd: wlan0-1: STA 64:c7:53:44:90:68 IEEE 802.11: authenticated
Tue Jun 22 15:54:22 2021 daemon.info hostapd: wlan0-1: STA 64:c7:53:44:90:68 IEEE 802.11: associated (aid 6)
Tue Jun 22 15:54:22 2021 daemon.notice hostapd: wlan1-1: Prune association for 64:c7:53:44:90:68
Tue Jun 22 15:54:22 2021 daemon.notice hostapd: wlan1-1: AP-STA-DISCONNECTED 64:c7:53:44:90:68
Tue Jun 22 15:54:22 2021 daemon.notice hostapd: wlan0-1: AP-STA-CONNECTED 64:c7:53:44:90:68
Tue Jun 22 15:54:22 2021 daemon.info hostapd: wlan0-1: STA 64:c7:53:44:90:68 WPA: pairwise key handshake completed (RSN)
Tue Jun 22 15:54:52 2021 daemon.info hostapd: wlan1-1: STA 64:c7:53:44:90:68 IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Jun 22 16:01:55 2021 daemon.info hostapd: wlan1-2: STA d0:03:df:a2:3d:66 WPA: group key handshake completed (RSN)
Tue Jun 22 16:11:55 2021 daemon.info hostapd: wlan1-2: STA d0:03:df:a2:3d:66 WPA: group key handshake completed (RSN)
Tue Jun 22 16:21:57 2021 daemon.info hostapd: wlan1-2: STA d0:03:df:a2:3d:66 WPA: group key handshake completed (RSN)
Tue Jun 22 16:31:57 2021 daemon.info hostapd: wlan1-2: STA d0:03:df:a2:3d:66 WPA: group key handshake completed (RSN)
Tue Jun 22 16:41:42 2021 daemon.info dnsmasq-dhcp[3253]: DHCPREQUEST(br-zGuest) 192.168.254.28 d0:03:df:a2:3d:66
Tue Jun 22 16:41:42 2021 daemon.info dnsmasq-dhcp[3253]: DHCPACK(br-zGuest) 192.168.254.28 d0:03:df:a2:3d:66 xxxxxx
Tue Jun 22 16:41:58 2021 daemon.info hostapd: wlan1-2: STA d0:03:df:a2:3d:66 WPA: group key handshake failed (RSN) after 4 tries
Tue Jun 22 16:41:58 2021 daemon.notice hostapd: wlan1-2: AP-STA-DISCONNECTED d0:03:df:a2:3d:66
Tue Jun 22 16:42:03 2021 daemon.info hostapd: wlan1-2: STA d0:03:df:a2:3d:66 IEEE 802.11: deauthenticated due to local deauth request
Tue Jun 22 16:51:11 2021 daemon.info hostapd: wlan1-1: STA 64:c7:53:44:90:68 IEEE 802.11: authenticated
Tue Jun 22 16:51:11 2021 daemon.info hostapd: wlan1-1: STA 64:c7:53:44:90:68 IEEE 802.11: associated (aid 2)
Tue Jun 22 16:51:11 2021 daemon.notice hostapd: wlan0-1: Prune association for 64:c7:53:44:90:68
Tue Jun 22 16:51:11 2021 daemon.notice hostapd: wlan0-1: AP-STA-DISCONNECTED 64:c7:53:44:90:68
Tue Jun 22 16:51:14 2021 daemon.info hostapd: wlan1-1: STA 64:c7:53:44:90:68 IEEE 802.11: authenticated
Tue Jun 22 16:51:14 2021 daemon.info hostapd: wlan1-1: STA 64:c7:53:44:90:68 IEEE 802.11: associated (aid 2)
Tue Jun 22 16:51:14 2021 daemon.notice hostapd: wlan0-1: Prune association for 64:c7:53:44:90:68
Tue Jun 22 16:51:14 2021 daemon.notice hostapd: wlan1-1: AP-STA-CONNECTED 64:c7:53:44:90:68
Tue Jun 22 16:51:14 2021 daemon.info hostapd: wlan1-1: STA 64:c7:53:44:90:68 WPA: pairwise key handshake completed (RSN)
Tue Jun 22 16:51:14 2021 daemon.info dnsmasq-dhcp[3253]: DHCPREQUEST(br-lan) 192.168.1.21 64:c7:53:44:90:68
Tue Jun 22 16:51:14 2021 daemon.info dnsmasq-dhcp[3253]: DHCPACK(br-lan) 192.168.1.21 64:c7:53:44:90:68 xxxxxx
Tue Jun 22 16:51:44 2021 daemon.info hostapd: wlan0-1: STA 64:c7:53:44:90:68 IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Jun 22 16:57:34 2021 daemon.notice hostapd: wlan1-1: AP-STA-DISCONNECTED 64:c7:53:44:90:68
Tue Jun 22 16:57:34 2021 daemon.info hostapd: wlan1-1: STA 64:c7:53:44:90:68 IEEE 802.11: disassociated
Tue Jun 22 16:57:36 2021 daemon.info hostapd: wlan1-1: STA 64:c7:53:44:90:68 IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Jun 22 16:57:38 2021 daemon.info hostapd: wlan1-1: STA 64:c7:53:44:90:68 IEEE 802.11: authenticated
Tue Jun 22 16:57:38 2021 daemon.info hostapd: wlan1-1: STA 64:c7:53:44:90:68 IEEE 802.11: associated (aid 2)
Tue Jun 22 16:57:38 2021 daemon.notice hostapd: wlan1-1: AP-STA-CONNECTED 64:c7:53:44:90:68
Tue Jun 22 16:57:38 2021 daemon.info hostapd: wlan1-1: STA 64:c7:53:44:90:68 WPA: pairwise key handshake completed (RSN)
Tue Jun 22 16:57:40 2021 daemon.info dnsmasq-dhcp[3253]: DHCPREQUEST(br-lan) 192.168.1.21 64:c7:53:44:90:68
Tue Jun 22 16:57:40 2021 daemon.info dnsmasq-dhcp[3253]: DHCPACK(br-lan) 192.168.1.21 64:c7:53:44:90:68 xxxxxx
1 Like
2

I've noticed that almost always, DHCPv6 appears not to be assigning the specified lease time to static leases. And it often does not assign a lease at all. Once I saw an existing lease with 30 mins remaining just disappear (in Luci).

1 Like
3

Hi!
Are you using the software flow offloading option?
If so, it might be a known bug 3373.

4

The issue I faced was resolved in RC4.

From what I can tell, the specific issue I faced was due to the combination of a bug in odhcpd6 and my ISP providing very short ipv6 address preferred and default lifetimes. Enabling/disabling Software Flow made no difference.

1 Like
5

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