Malfunctioning AX86U Pro? WiFi Radio Fail, Trouble Ejecting/Mounting Entware USB

[Aziron5]

Hello

It started randomly some weeks ago, having trouble ejecting the USB connected storage device, eventually disconnecting, but I've seen some messages in the log I haven't seen before, on older AX and AC routers too.

All of my routers including this one have AsusWRT Merlin's firmware:
The FW is 3004.388.6_2 with AMTM, Driversion, UiDivStats, and nothing special

Nov 21 21:56:25 kernel: CFG80211-ERROR) dhd_config_dongle :
Nov 21 21:56:25 kernel: Dongle is already up
Nov 21 21:56:25 kernel: CFG80211-ERROR) wl_allocate_wowl_config :

SYSLOG TOO LONG FOR FORUM POST - SEE ATTACHEMENT
I haven't seen this before:


It all started when I wanted to enable WiFi 2.4Ghz radio ... wouldn't work no matter what kind of configuration I chose, I played with the AX/AC, N, Legacy, MIMO, Beamforming, modes, combinations, 1 hour ... no phones, laptops, USB Wifi Dongles would detect it.

The WiFi LEDs seem to be functioning normally.

More problems started appearing, WEB GUI was showing WiFi icon as enabled even tho it was disabled when I was testing, never happened before. 5GHz radio doesn't work either.

Then I wanted to eject the mounted USB with Entware/Diversion etc.. it did that, but after those failures.
Then Reboot via Web GUI. After reboot, USB wouldn't mount anymore, SFTP Filezilla connection won't work, SSH Putty worked but even AMTM doesn't work:

 amtm 4.4 FW               by thelonelycoder
 ASUS RT-AX86U_Pro HW: aarch64 Kernel: 4.19.183
 FW: 3004.388.6_2 IP address: 192.168.1.1
_wlcsm_create_nl_socket:268: pid:3957 binding netlink socket error!!!

I didn't try messing with it any further and came here to report on it. Also uiDivStats keeps spamming about no Entware, perhaps the frequency of those messages could be lowered.

 

[bennor]

Have you checked the USB storage device that was attached to the router for errors?
What kind of storage device is it?
The 3004.388.6_2 is from 26-Feb-2024 so its rather old. Have you considered updating it to the latest Asus-Merlin?
Did you by chance have any of the AiCloud 2.0 features (Cloud Disk, Smart Access, AiCloud Sync) enabled?
Have you considered performing a hard factory reset and reconfiguring from scratch?

 

[Aziron5]

Storage Device: UGREEN CM257 USB3.0 to SATA Adapter with SSD Crucial BX500 240GB

I don't have a dedicated linux PC available at the moment to inspect it, I'd need to boot into it but I'm doing some important work on Windows right now and wouldn't like to abruptly end the session.
This is a router that really needs good uptime and kinda "LTS" fashion, regular major upgrades aren't welcome, such decisions aren't up to me because it's supplying multiple apartments and people rely on it, they can't even watch TV without it because of set-top-box being IPTV for advanced features like live TV rewind and VODs.

Landlord already told me that if this one barely works, "let it work" and that I should first fully configure a fresh new Router before taking this one offline for RMA, if it is an actual HW issue.
Also he told me "try to force the dumb seller to send you a free new one before you return this .." ... I don't know how would that work out, ... I don't think there's an exception for "emergency business needs" in our warranty laws here, hehe.

It's just a bit over midnight around here but I can still see people using the network so I can't do more short-term testing at this moment, maybe in an hour or so.

No Ai features.

UPDATE 1:

So far, I disconnected the USB disk, while logged in via Putty, tried reentering amtm, it worked, amtm just said it lost reference to swap file.



UPDATE 2:

Syslog powering up router after 30+ minutes of no power, WAN disconnected, LAN PC1 connected.
See Attachement - Code snip of Errors only:

Nov 24 06:45:00 kernel: bcm_i2c: Error in loading module
Nov 24 06:45:00 kernel: platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
Nov 24 06:45:00 kernel: CFG80211-ERROR) wl_setup_wiphy :
Nov 24 06:45:00 kernel: Registering Vendor80211
Nov 24 06:45:00 kernel: CFG80211-ERROR) wl_cfg80211_apply_eventbuffer :
Nov 24 06:45:00 kernel: Get event_msgs error (-19)
Nov 24 06:45:00 kernel: CFG80211-ERROR) wl_cfg80211_set_cac_state_change_eventbits :
Nov 24 06:45:00 kernel: failed to set WLC_E_CAC_STATE_CHANGE bit
Nov 24 06:45:00 kernel: ^[[0;33;41m[ERROR pktrunner] runnerUcast_inet6addr_event,148: Could not rdpa_system_ipv6_host_address_table_find ret=-5^[[0m
Nov 24 06:45:00 kernel: CFG80211-ERROR) wl_allocate_wowl_config :
Nov 24 06:45:00 kernel: Wowl not supported (-23)
Nov 24 06:45:01 kernel: CFG80211-ERROR) wl_allocate_wowl_config :
Nov 24 06:45:01 kernel: Wowl not supported (-23)
Nov 24 06:45:03 networkmap: Error locking /var/lock//usr/networkmap/nmp_conv_type.js.lock: 2 No such file or directory
Nov 24 06:45:03 networkmap: Error unlocking -1: 9 Bad file descriptor
Nov 24 06:45:03 networkmap: Error locking /var/lock//usr/networkmap/nmp_vendor_type.js.lock: 2 No such file or directory
Nov 24 06:45:03 networkmap: Error unlocking -1: 9 Bad file descriptor
Nov 24 06:45:03 networkmap: Error locking /var/lock//usr/networkmap/nmp_bwdpi_type.js.lock: 2 No such file or directory
Nov 24 06:45:03 networkmap: Error unlocking -1: 9 Bad file descriptor

Syslog snip after Connecting WAN:

Nov 24 06:45:12 BONDING: option disabled
Nov 24 06:45:14 roamast: ROAMING Start...
..
Nov 24 06:57:40 kernel: eth0 (Int switch port: 0) (Logical Port: 0) (phyId: 1) Link Up at 1000 mbps full duplex
Nov 24 06:57:48 lldpd[2396]: removal request for address of WAN-IP-REDACTED%17, but no knowledge of it
Nov 24 06:57:49 dnsmasq[2286]: read /etc/hosts - 26 names
Nov 24 06:57:49 dnsmasq[2286]: using nameserver 1.1.1.1#53
Nov 24 06:57:49 dnsmasq[2286]: using nameserver 1.0.0.1#53
Nov 24 06:57:49 dnsmasq[2286]: using only locally-known addresses for _dns.resolver.arpa
Nov 24 06:57:49 dnsmasq[2286]: using only locally-known addresses for use-application-dns.net
Nov 24 06:57:49 dnsmasq[2286]: using nameserver 1.1.1.1#53
Nov 24 06:57:49 dnsmasq[2286]: using nameserver 1.0.0.1#53
Nov 24 06:57:49 dnsmasq[2286]: using only locally-known addresses for _dns.resolver.arpa
Nov 24 06:57:49 dnsmasq[2286]: using only locally-known addresses for use-application-dns.net
Nov 24 06:57:49 wan: finish adding multi routes
Nov 24 06:57:49 rc_service: udhcpc_wan 6604:notify_rc stop_ntpd
Nov 24 06:57:49 custom_script: Running /jffs/scripts/service-event (args: stop ntpd)
Nov 24 06:57:49 rc_service: udhcpc_wan 6604:notify_rc start_ntpd
Nov 24 06:57:49 rc_service: waitting "stop_ntpd" via udhcpc_wan ...
Nov 24 06:57:50 custom_script: Running /jffs/scripts/service-event (args: start ntpd)
Nov 24 06:57:50 ntpd: Started ntpd
Nov 24 06:57:50 WAN(0)_Connection: WAN was restored.
Nov 24 06:57:50 dnsmasq[2286]: read /etc/hosts - 26 names
Nov 24 06:57:50 dnsmasq[2286]: using nameserver 1.1.1.1#53
Nov 24 06:57:50 dnsmasq[2286]: using nameserver 1.0.0.1#53
Nov 24 06:57:50 dnsmasq[2286]: using only locally-known addresses for _dns.resolver.arpa
Nov 24 06:57:50 dnsmasq[2286]: using only locally-known addresses for use-application-dns.net
Nov 24 06:57:50 dnsmasq[2286]: using nameserver 1.1.1.1#53
Nov 24 06:57:50 dnsmasq[2286]: using nameserver 1.0.0.1#53
Nov 24 06:57:50 dnsmasq[2286]: using only locally-known addresses for _dns.resolver.arpa
Nov 24 06:57:50 dnsmasq[2286]: using only locally-known addresses for use-application-dns.net
Nov 24 11:14:15 ntpd: Initial clock set
Nov 24 11:14:15 rc_service: ntpd_synced 6729:notify_rc restart_diskmon
Nov 24 11:14:15 custom_script: Running /jffs/scripts/service-event (args: restart diskmon)
Nov 24 11:14:15 disk_monitor: Finish
Nov 24 11:14:15 disk_monitor: be idle
Nov 24 11:14:19 rc_service: udhcpc_wan 6604:notify_rc stop_samba
Nov 24 11:14:19 custom_script: Running /jffs/scripts/service-event (args: stop samba)
Nov 24 11:14:19 miniupnpd[6776]: HTTP listening on port 36889
Nov 24 11:14:19 miniupnpd[6776]: Listening for NAT-PMP/PCP traffic on port 5351
Nov 24 11:14:19 Samba_Server: smb daemon is stopped
Nov 24 11:14:20 rc_service: udhcpc_wan 6604:notify_rc start_samba
Nov 24 11:14:20 custom_script: Running /jffs/scripts/service-event (args: start samba)
Nov 24 11:14:20 dnsmasq[2286]: exiting on receipt of SIGTERM
Nov 24 11:14:20 custom_config: Appending content of /jffs/configs/dnsmasq.conf.add.
Nov 24 11:14:20 custom_script: Running /jffs/scripts/dnsmasq.postconf (args: /etc/dnsmasq.conf)
Nov 24 11:14:20 dnsmasq[6787]: started, version 2.90 cachesize 1500
Nov 24 11:14:20 dnsmasq[6787]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-UBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset no-nftset no-auth cryptohash DNSSEC no-ID loop-detect no-inotify no-dumpfile
Nov 24 11:14:20 dnsmasq[6787]: warning: interface br2 does not currently exist
Nov 24 11:14:20 dnsmasq[6787]: warning: interface br1 does not currently exist
Nov 24 11:14:20 dnsmasq[6787]: warning: interface pptp* does not currently exist
Nov 24 11:14:20 dnsmasq[6787]: asynchronous logging enabled, queue limit is 5 messages
Nov 24 11:14:20 dnsmasq-dhcp[6787]: DHCP, IP range 192.168.102.2 -- 192.168.102.254, lease time 1d
Nov 24 11:14:20 dnsmasq-dhcp[6787]: DHCP, IP range 192.168.101.2 -- 192.168.101.254, lease time 1d
Nov 24 11:14:20 dnsmasq-dhcp[6787]: DHCP, IP range 192.168.1.10 -- 192.168.1.99, lease time 7d
Nov 24 11:14:20 dnsmasq[6787]: using only locally-known addresses for _dns.resolver.arpa
Nov 24 11:14:20 dnsmasq[6787]: using only locally-known addresses for use-application-dns.net
Nov 24 11:14:20 dnsmasq[6787]: read /etc/hosts - 26 names
Nov 24 11:14:20 dnsmasq[6787]: using nameserver 1.1.1.1#53
Nov 24 11:14:20 dnsmasq[6787]: using nameserver 1.0.0.1#53
Nov 24 11:14:20 dnsmasq[6787]: using only locally-known addresses for _dns.resolver.arpa
Nov 24 11:14:20 dnsmasq[6787]: using only locally-known addresses for use-application-dns.net
Nov 24 11:14:20 dnsmasq[6787]: using nameserver 1.1.1.1#53
Nov 24 11:14:20 dnsmasq[6787]: using nameserver 1.0.0.1#53
Nov 24 11:14:20 dnsmasq[6787]: using only locally-known addresses for _dns.resolver.arpa
Nov 24 11:14:20 dnsmasq[6787]: using only locally-known addresses for use-application-dns.net
Nov 24 11:14:21 dhcp_client: bound 86.58.76.135/255.255.252.0 via 86.58.76.1 for 86400 seconds.
Nov 24 11:14:23 crond[2312]: time disparity of 257 minutes detected
Nov 24 11:15:56 watchdog: New firmware version .._ is available.

Sidenote: I didn't knew dnsmasq and it's host filtering keeps functioning without entware and diversion ... there's no disk present. How long has this been the case, since the move to NXDOMAIN type of filtering?

 

[Aziron5]

UPDATE 3:

Syslog snip when enabling 2.4GHz WiFi Radio:

Nov 24 11:36:15 rc_service: cfg_server 2403:notify_rc restart_wireless
Nov 24 11:36:15 custom_script: Running /jffs/scripts/service-event (args: restart wireless)
Nov 24 11:36:15 networkmap: Error unlocking 8: 9 Bad file descriptor
Nov 24 11:36:15 networkmap: Error unlocking 0: 9 Bad file descriptor
Nov 24 11:36:15 kernel: device eth6 left promiscuous mode
Nov 24 11:36:15 kernel: br0: port 6(eth6) entered disabled state
Nov 24 11:36:15 kernel: device eth7 left promiscuous mode
Nov 24 11:36:15 kernel: br0: port 7(eth7) entered disabled state
Nov 24 11:36:15 get_ext_phy_id: 0/1/0/0
Nov 24 11:36:15 kernel: CFG80211-ERROR) wl_allocate_wowl_config :
Nov 24 11:36:15 kernel: Wowl not supported (-23)
Nov 24 11:36:15 kernel: wl0: wlc_ulmu_sw_trig_enable: HE UL-OFDMA not enabled
Nov 24 11:36:15 kernel: CSIMON:  CSIMON[1.1.0] Initialization
Nov 24 11:36:15 kernel: CSIMON: M2M usr already registered ...
Nov 24 11:36:15 kernel: br0: port 6(eth6) entered blocking state
Nov 24 11:36:15 kernel: br0: port 6(eth6) entered disabled state
Nov 24 11:36:15 kernel: device eth6 entered promiscuous mode
Nov 24 11:36:15 kernel: br0: port 6(eth6) entered blocking state
Nov 24 11:36:15 kernel: br0: port 6(eth6) entered forwarding state
Nov 24 11:36:15 kernel: CFG80211-ERROR) dhd_config_dongle :
Nov 24 11:36:15 kernel: Dongle is already up
Nov 24 11:36:15 kernel: CFG80211-ERROR) wl_allocate_wowl_config :
Nov 24 11:36:15 kernel: Wowl not supported (-23)
Nov 24 11:36:15 kernel: br0: port 7(eth7) entered blocking state
Nov 24 11:36:15 kernel: br0: port 7(eth7) entered disabled state
Nov 24 11:36:15 kernel: device eth7 entered promiscuous mode
Nov 24 11:36:15 kernel: br0: port 7(eth7) entered blocking state
Nov 24 11:36:15 kernel: br0: port 7(eth7) entered forwarding state
Nov 24 11:36:16 kernel: CFG80211-ERROR) wl_dfs_cac_notify_status :
Nov 24 11:36:16 kernel: In wl_dfs_cac_notify_status chanspec 0x1806 DFS state 0
Nov 24 11:36:16 kernel: wl0: wlc_ulmu_sw_trig_enable: HE UL-OFDMA not enabled
Nov 24 11:36:16 kernel: CSIMON:  CSIMON[1.1.0] Initialization
Nov 24 11:36:16 kernel: CSIMON: M2M usr already registered ...
Nov 24 11:36:16 kernel: wl0: wlc_ulmu_sw_trig_enable: HE UL-OFDMA not enabled
Nov 24 11:36:16 kernel: CSIMON:  CSIMON[1.1.0] Initialization
Nov 24 11:36:16 kernel: CSIMON: M2M usr already registered ...
Nov 24 11:36:16 kernel: CFG80211-ERROR) wl_dfs_cac_notify_status :
Nov 24 11:36:16 kernel: In wl_dfs_cac_notify_status chanspec 0x1806 DFS state 0
Nov 24 11:36:16 hostapd: eth6: IEEE 802.11 driver had channel switch: freq=2427, ht=1, vht_ch=0x0, he_ch=0x0, offset=1, width=2 (40 MHz), cf1=2437, cf2=0
Nov 24 11:36:20 wlceventd: main(1239): wlceventd Start...
Nov 24 11:36:20 acsd: eth6: Selecting 2g band ACS policy
Nov 24 11:36:20 cfg_server: cm_updateChanspec call wl_chanspec_changed_action
Nov 24 11:36:20 cfg_server:  event: wl_chanspec_changed_action_a101 of eid(0) of cfgs(2403)
Nov 24 11:36:21 networkmap: Error locking /var/lock//usr/networkmap/nmp_conv_type.js.lock: 2 No such file or directory
Nov 24 11:36:21 networkmap: Error unlocking -1: 9 Bad file descriptor
Nov 24 11:36:21 networkmap: Error locking /var/lock//usr/networkmap/nmp_vendor_type.js.lock: 2 No such file or directory
Nov 24 11:36:21 networkmap: Error unlocking -1: 9 Bad file descriptor
Nov 24 11:36:21 networkmap: Error locking /var/lock//usr/networkmap/nmp_bwdpi_type.js.lock: 2 No such file or directory
Nov 24 11:36:21 networkmap: Error unlocking -1: 9 Bad file descriptor
Nov 24 11:36:25 acsd: eth6: COEX: downgraded chanspec 0x1806 to 0x1004: channel 9 used by exiting BSSs
Nov 24 11:36:25 acsd: eth6: selected channel spec: 0x1004 (4)
Nov 24 11:36:25 acsd: eth6: Adjusted channel spec: 0x1004 (4)
Nov 24 11:36:25 acsd: eth6: selected channel spec: 0x1004 (4)
Nov 24 11:36:26 hostapd: eth6: IEEE 802.11 driver had channel switch: freq=2427, ht=1, vht_ch=0x0, he_ch=0x0, offset=0, width=1 (20 MHz), cf1=2427, cf2=0
Nov 24 11:36:30 roamast: ROAMING Start...
Nov 24 11:36:40 rc_service: cfg_server 2403:notify_rc update_nbr
Nov 24 11:36:40 custom_script: Running /jffs/scripts/service-event (args:  update_nbr)

WiFi seems to be working now, I'm not seeing anything unusual. Also divstats is quiet, never said a thing.

Before I rebooted, dnsmasq was outputting it's filtering messages to the system log, it's not doing that right now, but was that normal or what?
Dnsmasq isn't filtering hosts anymore, whatever was going on was probably going on from caches and tmp which got cleared but doesn't that somehow make it possible to run without entware/diversion technically?

Also is added external swap really that necessary ?