Netgear WNDR4300: atheros AR9580 5 GHz WiFi problem

alfredo · August 27, 2021, 2:44pm

Hi,
I have a netgear WNDR4300 v1 with openWRT 19.07.7 version and with 2.4 GHz wifi connected to a main router and 5 GHz wifi used as access point. Some days ago the atheros AR9580 WiFi card at 5 GHz stopped working properly. In detail, I have to manually disable and enable it in the luci interface at each reboot.
I tried to install the latest version 19.07.8, but nothing changed. Finally I noticed that the current power is different than the maximum power allowed, I tried to modify it, but nothing changed. The current power is above to maximum allowed by the driver.
Any idea?
Thank you

alfredo · August 28, 2021, 3:43pm

Hi,
I checked log files via logread.
Output log example1 after boot: wireless does not work.

Sat Aug 28 14:50:28 2021 daemon.notice hostapd: wlan1: DFS-CAC-COMPLETED success=1 freq=5660 ht_enabled=1 chan_offset=1 chan_width=2 cf1=5670 cf2=0
Sat Aug 28 14:50:28 2021 daemon.err hostapd: Using interface wlan1 with hwaddr and ssid "SSID"
Sat Aug 28 14:50:33 2021 daemon.err hostapd: Failed to set beacon parameters
Sat Aug 28 14:50:33 2021 daemon.err hostapd: Interface initialization failed
Sat Aug 28 14:50:33 2021 daemon.notice hostapd: wlan1: interface state DFS->DISABLED
Sat Aug 28 14:50:33 2021 daemon.notice hostapd: wlan1: AP-DISABLED
Sat Aug 28 14:50:33 2021 daemon.notice hostapd: wlan1: interface state DISABLED->DISABLED
Sat Aug 28 14:50:33 2021 daemon.notice hostapd: wlan1: AP-DISABLED
Sat Aug 28 14:50:33 2021 daemon.notice hostapd: wlan1: CTRL-EVENT-TERMINATING
Sat Aug 28 14:50:33 2021 daemon.err hostapd: hostapd_free_hapd_data: Interface wlan1 wasn't started
Sat Aug 28 14:50:33 2021 daemon.notice hostapd: nl80211: deinit ifname=wlan1 disabled_11b_rates=0
Sat Aug 28 14:50:33 2021 kern.info kernel: [  115.131ff6] device wlan1 left promiscuous mode
Sat Aug 28 14:50:33 2021 kern.info kernel: [  115.13ff70] br-lan: port 2(wlan1) entered disabled state
Sat Aug 28 14:50:33 2021 daemon.notice hostapd: ELOOP: remaining socket: sock=22 eloop_data=0x77effef0 user_data=0 handler=0x413de5
Sat Aug 28 14:50:33 2021 daemon.notice netifd: radio1 (1283): cat: can't open '/var/run/wifi-phy1.pid': No such file or directory
Sat Aug 28 14:50:33 2021 daemon.notice netifd: radio1 (1283): WARNING (wireless_add_process): executable path /usr/sbin/wpad does not match process  path (/proc/exe)
Sat Aug 28 14:50:33 2021 daemon.notice netifd: radio1 (1283): Command failed: Invalid argument
Sat Aug 28 14:50:33 2021 kern.info kernel: [  115.922624] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
Sat Aug 28 14:50:34 2021 kern.info kernel: [  116.613740] br-lan: port 2(wlan1) entered blocking state
Sat Aug 28 14:50:34 2021 kern.info kernel: [  116.619144] br-lan: port 2(wlan1) entered disabled state
Sat Aug 28 14:50:34 2021 kern.info kernel: [  116.624941] device wlan1 entered promiscuous mode

Output log example2 after boot: wireless does not work.

Sat Aug 28 15:09:10 2021 daemon.notice hostapd: wlan1: DFS-CAC-COMPLETED success=1 freq=5660 ht_enabled=1 chan_offset=1 chan_width=2 cf1=5670 cf2=0
Sat Aug 28 15:09:10 2021 daemon.err hostapd: Using interface wlan1 with hwaddr  and ssid "SSID"
Sat Aug 28 15:09:13 2021 daemon.err hostapd: Failed to set beacon parameters
Sat Aug 28 15:09:13 2021 daemon.err hostapd: Interface initialization failed
Sat Aug 28 15:09:13 2021 daemon.notice hostapd: wlan1: interface state DFS->DISABLED
Sat Aug 28 15:09:13 2021 daemon.notice hostapd: wlan1: AP-DISABLED
Sat Aug 28 15:09:13 2021 daemon.notice hostapd: wlan1: interface state DISABLED->DISABLED
Sat Aug 28 15:09:13 2021 daemon.notice hostapd: wlan1: AP-DISABLED
Sat Aug 28 15:09:13 2021 daemon.notice hostapd: wlan1: CTRL-EVENT-TERMINATING
Sat Aug 28 15:09:13 2021 daemon.err hostapd: hostapd_free_hapd_data: Interface wlan1 wasn't started
Sat Aug 28 15:09:13 2021 daemon.notice hostapd: nl80211: deinit ifname=wlan1 disabled_11b_rates=0
Sat Aug 28 15:09:13 2021 kern.info kernel: [  114.047922] device wlan1 left promiscuous mode
Sat Aug 28 15:09:13 2021 kern.info kernel: [  114.052636] br-lan: port 2(wlan1) entered disabled state
Sat Aug 28 15:09:14 2021 daemon.notice hostapd: ELOOP: remaining socket: sock=22 eloop_data=0x77e9eef0 user_data=0 handler=0x413de5
Sat Aug 28 15:09:14 2021 daemon.notice netifd: radio1 (1283): cat: can't open '/var/run/wifi-phy1.pid': No such file or directory
Sat Aug 28 15:09:14 2021 daemon.notice netifd: radio1 (1283): WARNING (wireless_add_process): executable path /usr/sbin/wpad does not match process  path (/proc/exe)
Sat Aug 28 15:09:14 2021 daemon.notice netifd: radio1 (1283): Command failed: Invalid argument
Sat Aug 28 15:09:14 2021 kern.info kernel: [  114.688267] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
Sat Aug 28 15:09:15 2021 kern.info kernel: [  115.420189] br-lan: port 2(wlan1) entered blocking state
Sat Aug 28 15:09:15 2021 kern.info kernel: [  115.4ffff1] br-lan: port 2(wlan1) entered disabled state
Sat Aug 28 15:09:15 2021 kern.info kernel: [  115.431390] device wlan1 entered promiscuous mode

Output log example1 after manually disabling/enabling interface: wireless works.

Sat Aug 28 14:56:55 2021 kern.info kernel: [  497.661289] device wlan1 left promiscuous mode
Sat Aug 28 14:56:55 2021 kern.info kernel: [  497.66ff44] br-lan: port 2(wlan1) entered disabled state
Sat Aug 28 14:57:08 2021 daemon.err hostapd: Configuration file: /var/run/hostapd-phy1.conf
Sat Aug 28 14:57:08 2021 kern.info kernel: [  510.894436] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
Sat Aug 28 14:57:09 2021 kern.info kernel: [  510.936763] br-lan: port 2(wlan1) entered blocking state
Sat Aug 28 14:57:09 2021 kern.info kernel: [  510.942236] br-lan: port 2(wlan1) entered disabled state
Sat Aug 28 14:57:09 2021 kern.info kernel: [  510.948029] device wlan1 entered promiscuous mode
Sat Aug 28 14:57:09 2021 daemon.notice hostapd: wlan1: interface state UNINITIALIZED->COUNTRY_UPDATE
Sat Aug 28 14:57:09 2021 daemon.notice hostapd: ACS: Automatic channel selection started, this may take a bit
Sat Aug 28 14:57:09 2021 daemon.notice hostapd: wlan1: interface state COUNTRY_UPDATE->ACS
Sat Aug 28 14:57:09 2021 daemon.notice hostapd: wlan1: ACS-STARTED
Sat Aug 28 14:57:23 2021 daemon.notice hostapd: wlan1: ACS-COMPLETED freq=5220 channel=44
Sat Aug 28 14:57:23 2021 daemon.notice hostapd: wlan1: interface state ACS->HT_SCAN
Sat Aug 28 14:57:23 2021 daemon.err hostapd: Using interface wlan1 with hwaddr and ssid "SSID"
Sat Aug 28 14:57:24 2021 kern.info kernel: [  526.189986] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
Sat Aug 28 14:57:24 2021 kern.info kernel: [  526.196736] br-lan: port 2(wlan1) entered blocking state
Sat Aug 28 14:57:24 2021 kern.info kernel: [  526.202194] br-lan: port 2(wlan1) entered forwarding state
Sat Aug 28 14:57:24 2021 daemon.notice hostapd: wlan1: interface state HT_SCAN->ENABLED
Sat Aug 28 14:57:24 2021 daemon.notice hostapd: wlan1: AP-ENABLED
Sat Aug 28 14:57:24 2021 daemon.notice netifd: Network device 'wlan1' link is up
Sat Aug 28 14:57:26 2021 daemon.info hostapd: wlan1: STA 84:cf:bf:89:43:ff IEEE 802.11: authenticated
Sat Aug 28 14:57:26 2021 daemon.info hostapd: wlan1: STA 84:cf:bf:89:43:ff IEEE 802.11: associated (aid 1)
Sat Aug 28 14:57:26 2021 daemon.notice hostapd: wlan1: AP-STA-CONNECTED 84:cf:bf:89:43:ff
Sat Aug 28 14:57:26 2021 daemon.info hostapd: wlan1: STA 84:cf:bf:89:43:ff WPA: pairwise key handshake completed (RSN)
Sat Aug 28 14:57:27 2021 daemon.info hostapd: wlan1: STA 1c:bf:ce:35:ff:1b IEEE 802.11: authenticated
Sat Aug 28 14:57:27 2021 daemon.info hostapd: wlan1: STA 1c:bf:ce:35:ff:1b IEEE 802.11: associated (aid 2)
Sat Aug 28 14:57:27 2021 daemon.notice hostapd: wlan1: AP-STA-CONNECTED 1c:bf:ce:35:ff:1b
Sat Aug 28 14:57:27 2021 daemon.info hostapd: wlan1: STA 1c:bf:ce:35:ff:1b WPA: pairwise key handshake completed (RSN)
Sat Aug 28 14:57:29 2021 daemon.info hostapd: wlan1: STA 34:8a:ae:89:ff:c3 IEEE 802.11: authenticated
Sat Aug 28 14:57:29 2021 daemon.info hostapd: wlan1: STA 34:8a:ae:89:ff:c3 IEEE 802.11: associated (aid 3)
Sat Aug 28 14:57:29 2021 daemon.notice hostapd: wlan1: AP-STA-CONNECTED 34:8a:ae:89:ff:c3
Sat Aug 28 14:57:29 2021 daemon.info hostapd: wlan1: STA 34:8a:ae:89:ff:c3 WPA: pairwise key handshake completed (RSN)
Sat Aug 28 14:57:30 2021 daemon.notice hostapd: wlan1: AP-STA-DISCONNECTED 84:cf:bf:89:43:ff
Sat Aug 28 14:57:30 2021 daemon.info hostapd: wlan1: STA 84:cf:bf:89:43:ff IEEE 802.11: disassociated
Sat Aug 28 14:57:31 2021 daemon.info hostapd: wlan1: STA 84:cf:bf:89:43:ff IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Sat Aug 28 14:57:33 2021 daemon.info hostapd: wlan1: STA 84:cf:bf:89:43:ff IEEE 802.11: authenticated
Sat Aug 28 14:57:33 2021 daemon.info hostapd: wlan1: STA 84:cf:bf:89:43:ff IEEE 802.11: associated (aid 1)
Sat Aug 28 14:57:33 2021 daemon.notice hostapd: wlan1: AP-STA-CONNECTED 84:cf:bf:89:43:ff
Sat Aug 28 14:57:33 2021 daemon.info hostapd: wlan1: STA 84:cf:bf:89:43:ff WPA: pairwise key handshake completed (RSN)

Output log example2 after manually disabling/enabling interface: wireless works.

Sat Aug 28 15:14:05 2021 kern.info kernel: [  405.939428] device wlan1 left promiscuous mode
Sat Aug 28 15:14:05 2021 kern.info kernel: [  405.944082] br-lan: port 2(wlan1) entered disabled state
Sat Aug 28 15:14:44 2021 daemon.err hostapd: Configuration file: /var/run/hostapd-phy1.conf
Sat Aug 28 15:14:44 2021 kern.info kernel: [  444.507162] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
Sat Aug 28 15:14:44 2021 kern.info kernel: [  444.543189] br-lan: port 2(wlan1) entered blocking state
Sat Aug 28 15:14:44 2021 kern.info kernel: [  444.549074] br-lan: port 2(wlan1) entered disabled state
Sat Aug 28 15:14:44 2021 kern.info kernel: [  444.555218] device wlan1 entered promiscuous mode
Sat Aug 28 15:14:44 2021 daemon.notice hostapd: wlan1: interface state UNINITIALIZED->COUNTRY_UPDATE
Sat Aug 28 15:14:44 2021 daemon.notice hostapd: ACS: Automatic channel selection started, this may take a bit
Sat Aug 28 15:14:44 2021 daemon.notice hostapd: wlan1: interface state COUNTRY_UPDATE->ACS
Sat Aug 28 15:14:44 2021 daemon.notice hostapd: wlan1: ACS-STARTED
Sat Aug 28 15:14:58 2021 daemon.notice hostapd: wlan1: ACS-COMPLETED freq=5260 channel=52
Sat Aug 28 15:14:58 2021 daemon.notice hostapd: wlan1: interface state ACS->HT_SCAN
Sat Aug 28 15:14:ff 2021 daemon.notice hostapd: wlan1: interface state HT_SCAN->DFS
Sat Aug 28 15:14:ff 2021 daemon.notice hostapd: wlan1: DFS-CAC-START freq=5260 chan=52 sec_chan=1, width=0, seg0=0, seg1=0, cac_time=60s

The culprit should be this:

at Aug 28 14:50:33 2021 daemon.notice hostapd: ELOOP: remaining socket: sock=22 eloop_data=0x77effef0 user_data=0 handler=0x413de5
Sat Aug 28 14:50:33 2021 daemon.notice netifd: radio1 (1283): cat: can't open '/var/run/wifi-phy1.pid': No such file or directory
Sat Aug 28 14:50:33 2021 daemon.notice netifd: radio1 (1283): WARNING (wireless_add_process): executable path /usr/sbin/wpad does not match process  path (/proc/exe)
Sat Aug 28 14:50:33 2021 daemon.notice netifd: radio1 (1283): Command failed: Invalid argument

ppmm · October 1, 2021, 1:05pm

It's a known problem check this out

alfredo · October 1, 2021, 2:43pm

Thank you for the reply. I just upgraded to 21.02 (just 1 week) with a new linux kernel 5.4 and the problem seems solved.

ppmm · October 1, 2021, 3:04pm

Check this recent topic the 5G comes and goes.

alfredo · October 25, 2021, 5:21am

I confirm that after about 1 month, I have no problems. Maybe it's because I reset the device.

tmomas · November 4, 2021, 5:22am

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