Occasional autonomous wireless disabling

I have BTHH5A as modem + main router + WDS STA on the 2.4 GHz radio, C7 V2 as WDS client on a dedicated SSID, and working as AP serving both 2.4 and 5 GHz clients, plus wired clients. The C7 also served SMB and miniDLNA.

All working well on 19.07.3 until recently when both radios on the C7 started going off by themselves, along with their LEDs. I thought it was a glitch, but even recycling the power on the router didn't always fix the issue (and needless to say, the WiFi hardware button in this device is known to have issues with OpenWrt, so, unsurprisingly, switching it off and on didn't do much good.

Connecting to the router via a cable and opening LuCI, I found the radios disabled; I enabled them and they worked.

So after that issue happened a few times, I decided to upgrade to OpenWrt 19.07.4, and I also took the chance to clean the wireless config file off the settings of a USB WiFi adapter that I had previu9osly instaleld but not plugging it any more.

That seemed to work for a week or so, until it happened again today. I was setting next to the router (literally), so I'm sure that nobody touched the hardware button.

I will include the logs in the replies below.

Does it look like a hardware issue and that the router is simply getting near the end of its life?

I had a similar issue which was likely related to OOM.
Try using this package and check if there's any improvement:
https://openwrt.org/packages/pkgdata/zram-swap

2 Likes

The text below is an extract of the system log

Sat Oct 17 17:30:33 2020 daemon.notice netifd: wan (1617): udhcpc: sending discover
Sat Oct 17 17:30:34 2020 kern.info kernel: [   41.313652] br-lan: port 1(eth1.1) entered forwarding state
Sat Oct 17 17:30:34 2020 kern.info kernel: [   41.319313] br-lan: topology change detected, propagating
Sat Oct 17 17:30:34 2020 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/dhcp
Sat Oct 17 17:30:34 2020 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/radvd
Sat Oct 17 17:30:34 2020 daemon.notice netifd: bridge 'br-lan' link is up
Sat Oct 17 17:30:34 2020 daemon.notice netifd: Interface 'lan' has link connectivity
Sat Oct 17 17:30:34 2020 kern.info kernel: [   41.464897] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
Sat Oct 17 17:30:34 2020 user.notice ucitrack: Setting up /etc/config/wireless reload dependency on /etc/config/network
Sat Oct 17 17:30:35 2020 daemon.err hostapd: Configuration file: /var/run/hostapd-phy1.conf
Sat Oct 17 17:30:35 2020 kern.info kernel: [   41.867388] IPv6: ADDRCONF(NETDEV_UP): wlan1-1: link is not ready
Sat Oct 17 17:30:35 2020 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/luci-splash
Sat Oct 17 17:30:35 2020 kern.info kernel: [   41.959826] br-lan: port 2(wlan1-1) entered blocking state
Sat Oct 17 17:30:35 2020 kern.info kernel: [   41.965490] br-lan: port 2(wlan1-1) entered disabled state
Sat Oct 17 17:30:35 2020 kern.info kernel: [   41.971357] device wlan1-1 entered promiscuous mode
Sat Oct 17 17:30:35 2020 kern.info kernel: [   41.976452] br-lan: port 2(wlan1-1) entered blocking state
Sat Oct 17 17:30:35 2020 kern.info kernel: [   41.982016] br-lan: port 2(wlan1-1) entered listening state
Sat Oct 17 17:30:35 2020 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/qos
Sat Oct 17 17:30:35 2020 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/miniupnpd
Sat Oct 17 17:30:35 2020 daemon.err hostapd: Using interface wlan1-1 with hwaddr 1a:d6:c7:6d:33:0e and ssid "C"
Sat Oct 17 17:30:35 2020 daemon.err hostapd: Configuration file: /var/run/hostapd-phy0.conf
Sat Oct 17 17:30:35 2020 daemon.err odhcpd[1324]: Failed to send to ff02::1%lan@br-lan (Address not available)
Sat Oct 17 17:30:35 2020 user.notice ucitrack: Setting up /etc/config/dhcp reload dependency on /etc/config/odhcpd
Sat Oct 17 17:30:36 2020 user.notice ucitrack: Setting up non-init /etc/config/fstab reload handler: /sbin/block mount
Sat Oct 17 17:30:36 2020 user.notice ucitrack: Setting up /etc/config/system reload trigger for non-procd /etc/init.d/led
Sat Oct 17 17:30:37 2020 user.notice ucitrack: Setting up /etc/config/system reload dependency on /etc/config/luci_statistics
Sat Oct 17 17:30:37 2020 user.notice ucitrack: Setting up /etc/config/system reload dependency on /etc/config/dhcp
Sat Oct 17 17:30:37 2020 kern.info kernel: [   44.033655] br-lan: port 2(wlan1-1) entered learning state
Sat Oct 17 17:30:37 2020 kern.warn kernel: [   44.424720] ath10k_pci 0000:00:00.0: 10.1 wmi init: vdevs: 16  peers: 127  tid: 256
Sat Oct 17 17:30:37 2020 kern.info kernel: [   44.442211] ath10k_pci 0000:00:00.0: wmi print 'P 128 V 8 T 410'
Sat Oct 17 17:30:37 2020 kern.info kernel: [   44.448587] ath10k_pci 0000:00:00.0: wmi print 'msdu-desc: 1424  sw-crypt: 0 ct-sta: 0'
Sat Oct 17 17:30:37 2020 kern.info kernel: [   44.456753] ath10k_pci 0000:00:00.0: wmi print 'alloc rem: 20984 iram: 25656'
Sat Oct 17 17:30:37 2020 kern.warn kernel: [   44.542462] ath10k_pci 0000:00:00.0: pdev param 0 not supported by firmware
Sat Oct 17 17:30:37 2020 kern.info kernel: [   44.562648] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Sat Oct 17 17:30:37 2020 kern.info kernel: [   44.569119] br-lan: port 2(wlan1-1) entered disabled state
Sat Oct 17 17:30:37 2020 daemon.notice netifd: wan (1617): udhcpc: sending discover
Sat Oct 17 17:30:37 2020 daemon.notice hostapd: wlan1-1: interface state UNINITIALIZED->ENABLED
Sat Oct 17 17:30:37 2020 daemon.notice hostapd: wlan1-1: AP-ENABLED
Sat Oct 17 17:30:37 2020 kern.info kernel: [   44.601173] br-lan: port 3(wlan0) entered blocking state
Sat Oct 17 17:30:37 2020 kern.info kernel: [   44.606618] br-lan: port 3(wlan0) entered disabled state
Sat Oct 17 17:30:37 2020 kern.info kernel: [   44.612305] device wlan0 entered promiscuous mode
Sat Oct 17 17:30:37 2020 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->COUNTRY_UPDATE
Sat Oct 17 17:30:37 2020 daemon.notice hostapd: wlan0: interface state COUNTRY_UPDATE->HT_SCAN
Sat Oct 17 17:30:38 2020 kern.info kernel: [   44.737748] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
Sat Oct 17 17:30:38 2020 daemon.err hostapd: Using interface wlan0 with hwaddr 18:d6:c7:6d:33:0d and ssid "C"
Sat Oct 17 17:30:38 2020 daemon.notice wpa_supplicant[1965]: Successfully initialized wpa_supplicant
Sat Oct 17 17:30:38 2020 daemon.notice hostapd: handle_probe_req: send failed
Sat Oct 17 17:30:39 2020 kern.info kernel: [   46.008733] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Sat Oct 17 17:30:39 2020 kern.info kernel: [   46.015399] br-lan: port 3(wlan0) entered blocking state
Sat Oct 17 17:30:39 2020 kern.info kernel: [   46.020820] br-lan: port 3(wlan0) entered listening state
Sat Oct 17 17:30:39 2020 daemon.notice hostapd: wlan0: interface state HT_SCAN->ENABLED
Sat Oct 17 17:30:39 2020 daemon.notice hostapd: wlan0: AP-ENABLED
Sat Oct 17 17:30:39 2020 daemon.notice netifd: Network device 'wlan0' link is up
Sat Oct 17 17:30:39 2020 kern.info kernel: [   46.529914] br-lan: port 4(wlan1) entered blocking state
Sat Oct 17 17:30:39 2020 kern.info kernel: [   46.535358] br-lan: port 4(wlan1) entered disabled state
Sat Oct 17 17:30:39 2020 kern.info kernel: [   46.541131] device wlan1 entered promiscuous mode
Sat Oct 17 17:30:40 2020 daemon.info procd: - init complete -
Sat Oct 17 17:30:40 2020 daemon.notice netifd: wan (1617): udhcpc: sending discover
Sat Oct 17 17:30:41 2020 daemon.notice wpa_supplicant[2032]: wlan1: SME: Trying to authenticate with 1a:xx:xx:xx:04 (SSID='W' freq=2462 MHz)
Sat Oct 17 17:30:41 2020 kern.info kernel: [   47.791036] wlan1: authenticate with 1a:xx:xx:xx:04
Sat Oct 17 17:30:41 2020 kern.info kernel: [   47.822756] wlan1: send auth to 1a:xx:xx:xx:04 (try 1/3)
Sat Oct 17 17:30:41 2020 kern.info kernel: [   47.844345] wlan1: authenticated
Sat Oct 17 17:30:41 2020 daemon.notice wpa_supplicant[2032]: wlan1: Trying to associate with 1a:xx:xx:xx:04 (SSID='W' freq=2462 MHz)
Sat Oct 17 17:30:41 2020 kern.info kernel: [   47.863803] wlan1: associate with 1a:xx:xx:xx:04 (try 1/3)
Sat Oct 17 17:30:41 2020 kern.info kernel: [   47.875127] wlan1: RX AssocResp from 1a:xx:xx:xx:04 (capab=0x431 status=0 aid=1)
Sat Oct 17 17:30:41 2020 kern.info kernel: [   47.883222] wlan1: associated
Sat Oct 17 17:30:41 2020 daemon.notice netifd: Network device 'wlan1' link is up
Sat Oct 17 17:30:41 2020 daemon.notice wpa_supplicant[2032]: wlan1: Associated with 1a:xx:xx:xx:04
Sat Oct 17 17:30:41 2020 daemon.notice wpa_supplicant[2032]: wlan1: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Sat Oct 17 17:30:41 2020 daemon.notice wpa_supplicant[2032]: wlan1: WPA: Key negotiation completed with 1a:xx:xx:xx:04 [PTK=CCMP GTK=CCMP]
Sat Oct 17 17:30:41 2020 daemon.notice wpa_supplicant[2032]: wlan1: CTRL-EVENT-CONNECTED - Connection to 1a:xx:xx:xx:04 completed [id=0 id_str=]
Sat Oct 17 17:30:41 2020 kern.info kernel: [   47.899842] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
Sat Oct 17 17:30:41 2020 kern.info kernel: [   47.906599] br-lan: port 4(wlan1) entered blocking state
Sat Oct 17 17:30:41 2020 kern.info kernel: [   47.912021] br-lan: port 4(wlan1) entered listening state
Sat Oct 17 17:30:41 2020 kern.info kernel: [   48.033715] br-lan: port 3(wlan0) entered learning state
Sat Oct 17 17:30:41 2020 kern.info kernel: [   48.258884] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1-1: link becomes ready
Sat Oct 17 17:30:41 2020 kern.info kernel: [   48.265800] br-lan: port 2(wlan1-1) entered blocking state
Sat Oct 17 17:30:41 2020 kern.info kernel: [   48.271369] br-lan: port 2(wlan1-1) entered listening state
Sat Oct 17 17:30:41 2020 daemon.notice netifd: Network device 'wlan1-1' link is up
Sat Oct 17 17:30:43 2020 kern.info kernel: [   49.953636] br-lan: port 4(wlan1) entered learning state
Sat Oct 17 17:30:43 2020 kern.info kernel: [   50.113663] br-lan: port 3(wlan0) entered forwarding state
Sat Oct 17 17:30:43 2020 kern.info kernel: [   50.119247] br-lan: topology change detected, propagating
Sat Oct 17 17:30:43 2020 kern.info kernel: [   50.353679] br-lan: port 2(wlan1-1) entered learning state
Sat Oct 17 17:30:45 2020 kern.info kernel: [   52.033635] br-lan: port 4(wlan1) entered forwarding state
Sat Oct 17 17:30:45 2020 kern.info kernel: [   52.039220] br-lan: topology change detected, propagating
Sat Oct 17 17:30:45 2020 kern.info kernel: [   52.433652] br-lan: port 2(wlan1-1) entered forwarding state
Sat Oct 17 17:30:45 2020 kern.info kernel: [   52.439423] br-lan: topology change detected, propagating
Sat Oct 17 17:30:46 2020 daemon.info dnsmasq[1200]: read /etc/hosts - 4 addresses
Sat Oct 17 17:30:46 2020 daemon.info dnsmasq[1200]: read /tmp/hosts/odhcpd - 0 addresses
Sat Oct 17 17:30:46 2020 daemon.info dnsmasq[1200]: read /tmp/hosts/dhcp.cfg01411c - 0 addresses
Sat Oct 17 17:30:47 2020 daemon.info dnsmasq[1200]: read /etc/hosts - 4 addresses
Sat Oct 17 17:30:47 2020 daemon.info dnsmasq[1200]: read /tmp/hosts/odhcpd - 1 addresses
Sat Oct 17 17:30:47 2020 daemon.info dnsmasq[1200]: read /tmp/hosts/dhcp.cfg01411c - 0 addresses
Sat Oct 17 18:10:55 2020 daemon.info hostapd: wlan0: STA 30:xx:xx:xx:b4 IEEE 802.11: authenticated
Sat Oct 17 18:10:55 2020 daemon.info hostapd: wlan0: STA 30:xx:xx:xx:b4 IEEE 802.11: authenticated
Sat Oct 17 18:10:55 2020 daemon.notice hostapd: wlan0: STA-OPMODE-MAX-BW-CHANGED 30:xx:xx:xx:b4 20
Sat Oct 17 18:10:55 2020 daemon.notice hostapd: wlan0: STA-OPMODE-N_SS-CHANGED 30:xx:xx:xx:b4 1
Sat Oct 17 18:10:55 2020 daemon.info hostapd: wlan0: STA 30:xx:xx:xx:b4 IEEE 802.11: associated (aid 1)
Sat Oct 17 18:10:55 2020 daemon.notice hostapd: wlan0: AP-STA-CONNECTED 30:xx:xx:xx:b4
Sat Oct 17 18:10:55 2020 daemon.info hostapd: wlan0: STA 30:xx:xx:xx:b4 WPA: pairwise key handshake completed (RSN)
Sat Oct 17 18:15:42 2020 daemon.err minidlnad[1969]: Not a JPEG file: starts with 0x89 0x50
Sat Oct 17 18:15:42 2020 daemon.err minidlnad[1969]: Not a JPEG file: starts with 0x89 0x50
Sat Oct 17 18:15:43 2020 daemon.err minidlnad[1969]: Not a JPEG file: starts with 0x89 0x50
Sat Oct 17 18:15:44 2020 daemon.err minidlnad[1969]: Not a JPEG file: starts with 0x89 0x50
Sat Oct 17 18:15:45 2020 daemon.err minidlnad[1969]: Not a JPEG file: starts with 0x89 0x50
Sat Oct 17 18:15:45 2020 daemon.err minidlnad[1969]: Not a JPEG file: starts with 0x89 0x50
Sat Oct 17 18:15:46 2020 daemon.err minidlnad[1969]: Not a JPEG file: starts with 0x89 0x50
Sat Oct 17 18:15:47 2020 daemon.err minidlnad[1969]: Not a JPEG file: starts with 0x89 0x50
Sat Oct 17 18:15:48 2020 daemon.err minidlnad[1969]: Not a JPEG file: starts with 0x89 0x50
Sat Oct 17 18:15:49 2020 daemon.err minidlnad[1969]: Not a JPEG file: starts with 0x89 0x50
Sat Oct 17 18:15:49 2020 daemon.err minidlnad[1969]: Not a JPEG file: starts with 0x89 0x50
Sat Oct 17 18:15:50 2020 daemon.err minidlnad[1969]: Not a JPEG file: starts with 0x89 0x50
Sat Oct 17 18:15:50 2020 daemon.err minidlnad[1969]: Not a JPEG file: starts with 0x89 0x50
Sat Oct 17 18:15:51 2020 daemon.err minidlnad[1969]: Not a JPEG file: starts with 0x89 0x50
Sat Oct 17 18:15:52 2020 daemon.err minidlnad[1969]: Not a JPEG file: starts with 0x89 0x50
Sat Oct 17 18:15:53 2020 daemon.err minidlnad[1969]: Not a JPEG file: starts with 0x89 0x50
Sat Oct 17 18:24:43 2020 daemon.notice hostapd: wlan1-1: interface state ENABLED->DISABLED
Sat Oct 17 18:24:43 2020 daemon.notice hostapd: wlan1-1: AP-DISABLED
Sat Oct 17 18:24:43 2020 daemon.notice hostapd: wlan1-1: CTRL-EVENT-TERMINATING
Sat Oct 17 18:24:43 2020 daemon.notice hostapd: wlan0: interface state ENABLED->DISABLED
Sat Oct 17 18:24:43 2020 daemon.notice hostapd: wlan0: AP-STA-DISCONNECTED 30:xx:xx:xx:b4
Sat Oct 17 18:24:43 2020 daemon.notice hostapd: wlan0: AP-DISABLED
Sat Oct 17 18:24:43 2020 daemon.notice hostapd: wlan0: CTRL-EVENT-TERMINATING
Sat Oct 17 18:24:43 2020 daemon.notice hostapd: nl80211: deinit ifname=wlan1-1 disabled_11b_rates=0
Sat Oct 17 18:24:43 2020 kern.info kernel: [ 2547.267456] device wlan1-1 left promiscuous mode
Sat Oct 17 18:24:43 2020 kern.info kernel: [ 2547.272365] br-lan: port 2(wlan1-1) entered disabled state
Sat Oct 17 18:24:43 2020 daemon.notice hostapd: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
Sat Oct 17 18:24:43 2020 kern.info kernel: [ 2547.280296] device wlan0 left promiscuous mode
Sat Oct 17 18:24:43 2020 kern.info kernel: [ 2547.285102] br-lan: port 3(wlan0) entered disabled state
Sat Oct 17 18:24:43 2020 daemon.notice netifd: Network device 'wlan0' link is down
Sat Oct 17 18:24:43 2020 daemon.notice netifd: Network device 'wlan1-1' link is down
Sat Oct 17 18:24:43 2020 kern.info kernel: [ 2547.434008] wlan1: deauthenticating from 1a:xx:xx:xx:04 by local choice (Reason: 3=DEAUTH_LEAVING)
Sat Oct 17 18:24:43 2020 daemon.notice netifd: Network device 'wlan1' link is down
Sat Oct 17 18:24:43 2020 daemon.notice wpa_supplicant[2032]: wlan1: CTRL-EVENT-DISCONNECTED bssid=1a:xx:xx:xx:04 reason=3 locally_generated=1
Sat Oct 17 18:24:43 2020 daemon.err wpa_supplicant[2032]:  Failed to stop hostapd AP interfaces
Sat Oct 17 18:24:43 2020 kern.info kernel: [ 2547.731068] br-lan: port 4(wlan1) entered disabled state
Sat Oct 17 18:24:43 2020 daemon.err wpa_supplicant[2032]:  Failed to stop hostapd AP interfaces
Sat Oct 17 18:24:43 2020 daemon.notice wpa_supplicant[2032]: nl80211: deinit ifname=wlan1 disabled_11b_rates=0
Sat Oct 17 18:24:43 2020 daemon.notice wpa_supplicant[2032]: wlan1: CTRL-EVENT-TERMINATING
Sat Oct 17 18:24:43 2020 kern.info kernel: [ 2547.790396] br-lan: port 4(wlan1) entered blocking state
Sat Oct 17 18:24:43 2020 kern.info kernel: [ 2547.795831] br-lan: port 4(wlan1) entered listening state
Sat Oct 17 18:24:43 2020 kern.info kernel: [ 2547.838241] br-lan: port 4(wlan1) entered disabled state
Sat Oct 17 18:24:43 2020 kern.info kernel: [ 2547.869665] device wlan1 left promiscuous mode
Sat Oct 17 18:24:43 2020 kern.info kernel: [ 2547.874226] br-lan: port 4(wlan1) entered disabled state
Sat Oct 17 18:26:23 2020 daemon.err uhttpd[1416]: luci: accepted login on / for root from 192.168.0.100
Sat Oct 17 18:26:43 2020 daemon.err hostapd: Configuration file: /var/run/hostapd-phy0.conf
Sat Oct 17 18:26:44 2020 kern.warn kernel: [ 2669.027326] ath10k_pci 0000:00:00.0: 10.1 wmi init: vdevs: 16  peers: 127  tid: 256
Sat Oct 17 18:26:44 2020 kern.info kernel: [ 2669.044794] ath10k_pci 0000:00:00.0: wmi print 'P 128 V 8 T 410'
Sat Oct 17 18:26:44 2020 kern.info kernel: [ 2669.051099] ath10k_pci 0000:00:00.0: wmi print 'msdu-desc: 1424  sw-crypt: 0 ct-sta: 0'
Sat Oct 17 18:26:44 2020 kern.info kernel: [ 2669.059270] ath10k_pci 0000:00:00.0: wmi print 'alloc rem: 20984 iram: 25656'
Sat Oct 17 18:26:45 2020 kern.warn kernel: [ 2669.127743] ath10k_pci 0000:00:00.0: pdev param 0 not supported by firmware
Sat Oct 17 18:26:45 2020 kern.info kernel: [ 2669.143232] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Sat Oct 17 18:26:45 2020 kern.info kernel: [ 2669.153051] br-lan: port 2(wlan0) entered blocking state
Sat Oct 17 18:26:45 2020 kern.info kernel: [ 2669.158450] br-lan: port 2(wlan0) entered disabled state
Sat Oct 17 18:26:45 2020 kern.info kernel: [ 2669.164133] device wlan0 entered promiscuous mode
Sat Oct 17 18:26:45 2020 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->COUNTRY_UPDATE
Sat Oct 17 18:26:45 2020 daemon.notice hostapd: wlan0: interface state COUNTRY_UPDATE->HT_SCAN
Sat Oct 17 18:26:45 2020 daemon.err hostapd: Using interface wlan0 with hwaddr 18:d6:c7:6d:33:0d and ssid "C"
Sat Oct 17 18:26:45 2020 kern.info kernel: [ 2669.718710] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Sat Oct 17 18:26:45 2020 kern.info kernel: [ 2669.725347] br-lan: port 2(wlan0) entered blocking state
Sat Oct 17 18:26:45 2020 kern.info kernel: [ 2669.730741] br-lan: port 2(wlan0) entered listening state
Sat Oct 17 18:26:45 2020 daemon.notice hostapd: wlan0: interface state HT_SCAN->ENABLED
Sat Oct 17 18:26:45 2020 daemon.notice hostapd: wlan0: AP-ENABLED
Sat Oct 17 18:26:45 2020 daemon.notice netifd: Network device 'wlan0' link is up
Sat Oct 17 18:26:47 2020 kern.info kernel: [ 2671.762645] br-lan: port 2(wlan0) entered learning state
Sat Oct 17 18:26:49 2020 kern.info kernel: [ 2673.842630] br-lan: port 2(wlan0) entered forwarding state
Sat Oct 17 18:26:49 2020 kern.info kernel: [ 2673.848217] br-lan: topology change detected, propagating
Sat Oct 17 18:26:55 2020 daemon.err hostapd: Configuration file: /var/run/hostapd-phy1.conf
Sat Oct 17 18:26:55 2020 kern.info kernel: [ 2679.338563] IPv6: ADDRCONF(NETDEV_UP): wlan1-1: link is not ready
Sat Oct 17 18:26:55 2020 kern.info kernel: [ 2679.512434] br-lan: port 3(wlan1-1) entered blocking state
Sat Oct 17 18:26:55 2020 kern.info kernel: [ 2679.518053] br-lan: port 3(wlan1-1) entered disabled state
Sat Oct 17 18:26:55 2020 kern.info kernel: [ 2679.524094] device wlan1-1 entered promiscuous mode
Sat Oct 17 18:26:55 2020 daemon.err hostapd: Using interface wlan1-1 with hwaddr 1a:d6:c7:6d:33:0e and ssid "C"
Sat Oct 17 18:26:55 2020 daemon.notice hostapd: wlan1-1: interface state UNINITIALIZED->ENABLED
Sat Oct 17 18:26:55 2020 daemon.notice hostapd: wlan1-1: AP-ENABLED
Sat Oct 17 18:26:55 2020 daemon.notice hostapd: handle_probe_req: send failed
Sat Oct 17 18:26:55 2020 daemon.notice hostapd: handle_probe_req: send failed
Sat Oct 17 18:26:55 2020 kern.info kernel: [ 2680.004829] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
Sat Oct 17 18:26:55 2020 daemon.notice wpa_supplicant[2603]: Successfully initialized wpa_supplicant
Sat Oct 17 18:26:56 2020 kern.info kernel: [ 2680.536177] br-lan: port 4(wlan1) entered blocking state
Sat Oct 17 18:26:56 2020 kern.info kernel: [ 2680.541575] br-lan: port 4(wlan1) entered disabled state
Sat Oct 17 18:26:56 2020 kern.info kernel: [ 2680.547293] device wlan1 entered promiscuous mode
Sat Oct 17 18:26:57 2020 daemon.notice wpa_supplicant[2604]: wlan1: SME: Trying to authenticate with 1a:xx:xx:xx:04 (SSID='W' freq=2462 MHz)
Sat Oct 17 18:26:57 2020 kern.info kernel: [ 2681.578853] wlan1: authenticate with 1a:xx:xx:xx:04
Sat Oct 17 18:26:57 2020 kern.info kernel: [ 2681.610643] wlan1: send auth to 1a:xx:xx:xx:04 (try 1/3)
Sat Oct 17 18:26:57 2020 kern.info kernel: [ 2681.620047] wlan1: authenticated
Sat Oct 17 18:26:57 2020 daemon.notice wpa_supplicant[2604]: wlan1: Trying to associate with 1a:xx:xx:xx:04 (SSID='W' freq=2462 MHz)
Sat Oct 17 18:26:57 2020 kern.info kernel: [ 2681.632591] wlan1: associate with 1a:xx:xx:xx:04 (try 1/3)
Sat Oct 17 18:26:57 2020 daemon.notice netifd: Network device 'wlan1' link is up
Sat Oct 17 18:26:57 2020 daemon.notice wpa_supplicant[2604]: wlan1: Associated with 1a:xx:xx:xx:04
Sat Oct 17 18:26:57 2020 daemon.notice wpa_supplicant[2604]: wlan1: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Sat Oct 17 18:26:57 2020 kern.info kernel: [ 2681.647391] wlan1: RX AssocResp from 1a:xx:xx:xx:04 (capab=0x431 status=0 aid=1)
Sat Oct 17 18:26:57 2020 kern.info kernel: [ 2681.655481] wlan1: associated
Sat Oct 17 18:26:57 2020 daemon.notice wpa_supplicant[2604]: wlan1: WPA: Key negotiation completed with 1a:xx:xx:xx:04 [PTK=CCMP GTK=CCMP]
Sat Oct 17 18:26:57 2020 daemon.notice wpa_supplicant[2604]: wlan1: CTRL-EVENT-CONNECTED - Connection to 1a:xx:xx:xx:04 completed [id=0 id_str=]
Sat Oct 17 18:26:57 2020 kern.info kernel: [ 2681.798662] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
Sat Oct 17 18:26:57 2020 kern.info kernel: [ 2681.805450] br-lan: port 4(wlan1) entered blocking state
Sat Oct 17 18:26:57 2020 kern.info kernel: [ 2681.810844] br-lan: port 4(wlan1) entered listening state
Sat Oct 17 18:26:58 2020 kern.info kernel: [ 2682.143592] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1-1: link becomes ready
Sat Oct 17 18:26:58 2020 kern.info kernel: [ 2682.150379] br-lan: port 3(wlan1-1) entered blocking state
Sat Oct 17 18:26:58 2020 kern.info kernel: [ 2682.156027] br-lan: port 3(wlan1-1) entered listening state
Sat Oct 17 18:26:58 2020 daemon.notice netifd: Network device 'wlan1-1' link is up
Sat Oct 17 18:26:59 2020 kern.info kernel: [ 2683.842550] br-lan: port 4(wlan1) entered learning state
Sat Oct 17 18:27:00 2020 kern.info kernel: [ 2684.162644] br-lan: port 3(wlan1-1) entered learning state
Sat Oct 17 18:27:01 2020 kern.info kernel: [ 2685.922517] br-lan: port 4(wlan1) entered forwarding state
Sat Oct 17 18:27:01 2020 kern.info kernel: [ 2685.928099] br-lan: topology change detected, propagating
Sat Oct 17 18:27:02 2020 kern.info kernel: [ 2686.242514] br-lan: port 3(wlan1-1) entered forwarding state
Sat Oct 17 18:27:02 2020 kern.info kernel: [ 2686.248294] br-lan: topology change detected, propagating
1 Like

And from kernel log:

[   40.520334] ath: regdomain 0x8114 dynamically updated by user
[   41.313652] br-lan: port 1(eth1.1) entered forwarding state
[   41.319313] br-lan: topology change detected, propagating
[   41.464897] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[   41.867388] IPv6: ADDRCONF(NETDEV_UP): wlan1-1: link is not ready
[   41.959826] br-lan: port 2(wlan1-1) entered blocking state
[   41.965490] br-lan: port 2(wlan1-1) entered disabled state
[   41.971357] device wlan1-1 entered promiscuous mode
[   41.976452] br-lan: port 2(wlan1-1) entered blocking state
[   41.982016] br-lan: port 2(wlan1-1) entered listening state
[   44.033655] br-lan: port 2(wlan1-1) entered learning state
[   44.424720] ath10k_pci 0000:00:00.0: 10.1 wmi init: vdevs: 16  peers: 127  tid: 256
[   44.442211] ath10k_pci 0000:00:00.0: wmi print 'P 128 V 8 T 410'
[   44.448587] ath10k_pci 0000:00:00.0: wmi print 'msdu-desc: 1424  sw-crypt: 0 ct-sta: 0'
[   44.456753] ath10k_pci 0000:00:00.0: wmi print 'alloc rem: 20984 iram: 25656'
[   44.542462] ath10k_pci 0000:00:00.0: pdev param 0 not supported by firmware
[   44.562648] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   44.569119] br-lan: port 2(wlan1-1) entered disabled state
[   44.601173] br-lan: port 3(wlan0) entered blocking state
[   44.606618] br-lan: port 3(wlan0) entered disabled state
[   44.612305] device wlan0 entered promiscuous mode
[   44.737748] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
[   46.008733] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   46.015399] br-lan: port 3(wlan0) entered blocking state
[   46.020820] br-lan: port 3(wlan0) entered listening state
[   46.529914] br-lan: port 4(wlan1) entered blocking state
[   46.535358] br-lan: port 4(wlan1) entered disabled state
[   46.541131] device wlan1 entered promiscuous mode
[   47.791036] wlan1: authenticate with 1a:1e:78:2b:7a:04
[   47.822756] wlan1: send auth to 1a:1e:78:2b:7a:04 (try 1/3)
[   47.844345] wlan1: authenticated
[   47.863803] wlan1: associate with 1a:1e:78:2b:7a:04 (try 1/3)
[   47.875127] wlan1: RX AssocResp from 1a:1e:78:2b:7a:04 (capab=0x431 status=0 aid=1)
[   47.883222] wlan1: associated
[   47.899842] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[   47.906599] br-lan: port 4(wlan1) entered blocking state
[   47.912021] br-lan: port 4(wlan1) entered listening state
[   48.033715] br-lan: port 3(wlan0) entered learning state
[   48.258884] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1-1: link becomes ready
[   48.265800] br-lan: port 2(wlan1-1) entered blocking state
[   48.271369] br-lan: port 2(wlan1-1) entered listening state
[   49.953636] br-lan: port 4(wlan1) entered learning state
[   50.113663] br-lan: port 3(wlan0) entered forwarding state
[   50.119247] br-lan: topology change detected, propagating
[   50.353679] br-lan: port 2(wlan1-1) entered learning state
[   52.033635] br-lan: port 4(wlan1) entered forwarding state
[   52.039220] br-lan: topology change detected, propagating
[   52.433652] br-lan: port 2(wlan1-1) entered forwarding state
[   52.439423] br-lan: topology change detected, propagating
[ 2547.267456] device wlan1-1 left promiscuous mode
[ 2547.272365] br-lan: port 2(wlan1-1) entered disabled state
[ 2547.280296] device wlan0 left promiscuous mode
[ 2547.285102] br-lan: port 3(wlan0) entered disabled state
[ 2547.434008] wlan1: deauthenticating from 1a:1e:78:2b:7a:04 by local choice (Reason: 3=DEAUTH_LEAVING)
[ 2547.731068] br-lan: port 4(wlan1) entered disabled state
[ 2547.790396] br-lan: port 4(wlan1) entered blocking state
[ 2547.795831] br-lan: port 4(wlan1) entered listening state
[ 2547.838241] br-lan: port 4(wlan1) entered disabled state
[ 2547.869665] device wlan1 left promiscuous mode
[ 2547.874226] br-lan: port 4(wlan1) entered disabled state
[ 2669.027326] ath10k_pci 0000:00:00.0: 10.1 wmi init: vdevs: 16  peers: 127  tid: 256
[ 2669.044794] ath10k_pci 0000:00:00.0: wmi print 'P 128 V 8 T 410'
[ 2669.051099] ath10k_pci 0000:00:00.0: wmi print 'msdu-desc: 1424  sw-crypt: 0 ct-sta: 0'
[ 2669.059270] ath10k_pci 0000:00:00.0: wmi print 'alloc rem: 20984 iram: 25656'
[ 2669.127743] ath10k_pci 0000:00:00.0: pdev param 0 not supported by firmware
[ 2669.143232] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 2669.153051] br-lan: port 2(wlan0) entered blocking state
[ 2669.158450] br-lan: port 2(wlan0) entered disabled state
[ 2669.164133] device wlan0 entered promiscuous mode
[ 2669.718710] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 2669.725347] br-lan: port 2(wlan0) entered blocking state
[ 2669.730741] br-lan: port 2(wlan0) entered listening state
[ 2671.762645] br-lan: port 2(wlan0) entered learning state
[ 2673.842630] br-lan: port 2(wlan0) entered forwarding state
[ 2673.848217] br-lan: topology change detected, propagating
[ 2679.338563] IPv6: ADDRCONF(NETDEV_UP): wlan1-1: link is not ready
[ 2679.512434] br-lan: port 3(wlan1-1) entered blocking state
[ 2679.518053] br-lan: port 3(wlan1-1) entered disabled state
[ 2679.524094] device wlan1-1 entered promiscuous mode
[ 2680.004829] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
[ 2680.536177] br-lan: port 4(wlan1) entered blocking state
[ 2680.541575] br-lan: port 4(wlan1) entered disabled state
[ 2680.547293] device wlan1 entered promiscuous mode
[ 2681.578853] wlan1: authenticate with 1a:1e:78:2b:7a:04
[ 2681.610643] wlan1: send auth to 1a:1e:78:2b:7a:04 (try 1/3)
[ 2681.620047] wlan1: authenticated
[ 2681.632591] wlan1: associate with 1a:1e:78:2b:7a:04 (try 1/3)
[ 2681.647391] wlan1: RX AssocResp from 1a:1e:78:2b:7a:04 (capab=0x431 status=0 aid=1)
[ 2681.655481] wlan1: associated
[ 2681.798662] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[ 2681.805450] br-lan: port 4(wlan1) entered blocking state
[ 2681.810844] br-lan: port 4(wlan1) entered listening state
[ 2682.143592] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1-1: link becomes ready
[ 2682.150379] br-lan: port 3(wlan1-1) entered blocking state
[ 2682.156027] br-lan: port 3(wlan1-1) entered listening state
[ 2683.842550] br-lan: port 4(wlan1) entered learning state
[ 2684.162644] br-lan: port 3(wlan1-1) entered learning state
[ 2685.922517] br-lan: port 4(wlan1) entered forwarding state
[ 2685.928099] br-lan: topology change detected, propagating
[ 2686.242514] br-lan: port 3(wlan1-1) entered forwarding state
[ 2686.248294] br-lan: topology change detected, propagating

Thanks. I don't expect that. I'm not currently running much. I rarely use the SMB share (even though it's permanently mounted on my Windows PC), and if the miniLDNA is used then it's used by one device only.

Anyway thanks for the tip. I have just installed htop and will keep an eye on the memory and see.

2 Likes

Hi, I had that problem running 18.06 on a TL-WDR3600, the radio would get deactivated out of nowhere and had to be enabled again, switching off and on didn't help. There was some message about a button being pressed on the 18.06 logs.

Solved it by deactivating the script that handles the 'rkfill button'.

Remove execute permissions :

chmod -x /etc/rc.button/rfkill

Today I have updated to 19.07 and let it run without disabling the rfkill script. it was running fine for a few hours and then both radios started getting disabled by themselves.

I have disabled that script again and it seems to be running without that hiccup.

I have no idea why in the world would that ghost button get pressed though.

1 Like

Thanks.

It has been working fine for the last 3 days, but if it happens again I will try your solution.