Hello,
I started to have issues with my GL-X3000 router connected to GSM network about 5 months ago.
Symptoms: device "hangs", only power indicator is shown. All Wi-Fi is off. It happens about two times per week. Pretty annoying. Only powering device off and on helps.
I installed external MMC card and redirected syslog there.
This night it happened again. I woke up at 8 a.m. and saw router in this state.
Grepped the log for "Thu Jul 4":
jul-4.zip (5.3 KB)
Thu Jul 4 08:21:01
- start of the last reboot (successfull)
Suspicious log parts are (logged when device "hanged"):
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.745039] 7981@C01L3,wifi_sys_conn_act() 1115: wdev idx = 2
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.751070] 7981@C08L3,hw_ctrl_flow_v2_connt_act() 215: wdev_idx=2
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.873428] 7981@C15L3,WPABuildPairMsg1() 5280: <=== send Msg1 of 4-way
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.880092] 7981@C15L3,PeerPairMsg2Action() 6202: ===>Receive msg 2
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.886873] 7981@C15L3,WPABuildPairMsg3() 5557: <=== send Msg3 of 4-way
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.893556] 7981@C15L3,PeerPairMsg4Action() 6632: ===>Receive msg 4
Thu Jul 4 02:26:53 2024 kern.warn kernel: [ 124.905140] 7981@C15L2,PeerPairMsg4Action() 6994: AP SETKEYS DONE(rax0) - AKMMap=WPA2PSK, PairwiseCipher=AES, GroupCipher=AES, wcid=6 from 2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 kern.warn kernel: [ 124.905140]
Thu Jul 4 02:26:53 2024 daemon.info dnsmasq-dhcp[5014]: DHCPREQUEST(br-lan) 192.168.1.246 2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 daemon.info dnsmasq-dhcp[5014]: DHCPACK(br-lan) 192.168.1.246 2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 daemon.err odhcpd[3892]: Failed to send to 2001:999:250:96f0:1ce2:89fa:8942:676b%modem_0001@rmnet_mhi0 (Bad file descriptor)
Thu Jul 4 02:26:53 2024 daemon.err odhcpd[3892]: Failed to send to 2001:999:250:96f0:d4a3:a76b:6c1d:6028%modem_0001@rmnet_mhi0 (Bad file descriptor)
Thu Jul 4 02:26:25 2024 cron.err crond[4346]: time disparity of 215 minutes detected
Thu Jul 4 02:26:45 2024 cron.err crond[4014]: time disparity of 216 minutes detected
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.697560] 7981@C08L3,ap_peer_auth_req_at_idle_action() 717: AUTH - MBSS(2), Rcv AUTH seq#1, Alg=0, Status=0 from [wcid=1023]2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 kern.debug kernel: [ 124.710500] entrytb_aid_aquire(): found non-occupied aid:9, allocated from:4
Thu Jul 4 02:26:53 2024 kern.warn kernel: [ 124.717566] 7981@C13L2,MacTableInsertEntry() 1537: New Sta:2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.726586] 7981@C08L3,ap_cmm_peer_assoc_req_action() 1647: Recv Assoc from STA - 2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.735931] 7981@C08L3,ap_cmm_peer_assoc_req_action() 2170: ASSOC Send ASSOC response (Status=0)...
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.745039] 7981@C01L3,wifi_sys_conn_act() 1115: wdev idx = 2
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.751070] 7981@C08L3,hw_ctrl_flow_v2_connt_act() 215: wdev_idx=2
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.873428] 7981@C15L3,WPABuildPairMsg1() 5280: <=== send Msg1 of 4-way
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.880092] 7981@C15L3,PeerPairMsg2Action() 6202: ===>Receive msg 2
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.886873] 7981@C15L3,WPABuildPairMsg3() 5557: <=== send Msg3 of 4-way
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.893556] 7981@C15L3,PeerPairMsg4Action() 6632: ===>Receive msg 4
Thu Jul 4 02:26:53 2024 kern.warn kernel: [ 124.905140] 7981@C15L2,PeerPairMsg4Action() 6994: AP SETKEYS DONE(rax0) - AKMMap=WPA2PSK, PairwiseCipher=AES, GroupCipher=AES, wcid=6 from 2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 kern.warn kernel: [ 124.905140]
Thu Jul 4 02:26:53 2024 daemon.info dnsmasq-dhcp[5014]: DHCPREQUEST(br-lan) 192.168.1.246 2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 daemon.info dnsmasq-dhcp[5014]: DHCPACK(br-lan) 192.168.1.246 2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 daemon.err odhcpd[3892]: Failed to send to 2001:999:250:96f0:1ce2:89fa:8942:676b%modem_0001@rmnet_mhi0 (Bad file descriptor)
Thu Jul 4 02:26:53 2024 daemon.err odhcpd[3892]: Failed to send to 2001:999:250:96f0:d4a3:a76b:6c1d:6028%modem_0001@rmnet_mhi0 (Bad file descriptor)
Thu Jul 4 02:26:25 2024 cron.err crond[4346]: time disparity of 215 minutes detected
Thu Jul 4 02:26:45 2024 cron.err crond[4014]: time disparity of 216 minutes detected
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.697560] 7981@C08L3,ap_peer_auth_req_at_idle_action() 717: AUTH - MBSS(2), Rcv AUTH seq#1, Alg=0, Status=0 from [wcid=1023]2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 kern.debug kernel: [ 124.710500] entrytb_aid_aquire(): found non-occupied aid:9, allocated from:4
Thu Jul 4 02:26:53 2024 kern.warn kernel: [ 124.717566] 7981@C13L2,MacTableInsertEntry() 1537: New Sta:2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.726586] 7981@C08L3,ap_cmm_peer_assoc_req_action() 1647: Recv Assoc from STA - 2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.735931] 7981@C08L3,ap_cmm_peer_assoc_req_action() 2170: ASSOC Send ASSOC response (Status=0)...
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.745039] 7981@C01L3,wifi_sys_conn_act() 1115: wdev idx = 2
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.751070] 7981@C08L3,hw_ctrl_flow_v2_connt_act() 215: wdev_idx=2
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.873428] 7981@C15L3,WPABuildPairMsg1() 5280: <=== send Msg1 of 4-way
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.880092] 7981@C15L3,PeerPairMsg2Action() 6202: ===>Receive msg 2
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.886873] 7981@C15L3,WPABuildPairMsg3() 5557: <=== send Msg3 of 4-way
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.893556] 7981@C15L3,PeerPairMsg4Action() 6632: ===>Receive msg 4
Thu Jul 4 02:26:53 2024 kern.warn kernel: [ 124.905140] 7981@C15L2,PeerPairMsg4Action() 6994: AP SETKEYS DONE(rax0) - AKMMap=WPA2PSK, PairwiseCipher=AES, GroupCipher=AES, wcid=6 from 2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 kern.warn kernel: [ 124.905140]
Thu Jul 4 02:26:53 2024 daemon.info dnsmasq-dhcp[5014]: DHCPREQUEST(br-lan) 192.168.1.246 2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 daemon.info dnsmasq-dhcp[5014]: DHCPACK(br-lan) 192.168.1.246 2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 daemon.err odhcpd[3892]: Failed to send to 2001:999:250:96f0:1ce2:89fa:8942:676b%modem_0001@rmnet_mhi0 (Bad file descriptor)
Thu Jul 4 02:26:53 2024 daemon.err odhcpd[3892]: Failed to send to 2001:999:250:96f0:d4a3:a76b:6c1d:6028%modem_0001@rmnet_mhi0 (Bad file descriptor)
Thu Jul 4 02:26:25 2024 cron.err crond[4346]: time disparity of 215 minutes detected
Thu Jul 4 02:26:45 2024 cron.err crond[4014]: time disparity of 216 minutes detected
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.697560] 7981@C08L3,ap_peer_auth_req_at_idle_action() 717: AUTH - MBSS(2), Rcv AUTH seq#1, Alg=0, Status=0 from [wcid=1023]2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 kern.debug kernel: [ 124.710500] entrytb_aid_aquire(): found non-occupied aid:9, allocated from:4
Thu Jul 4 02:26:53 2024 kern.warn kernel: [ 124.717566] 7981@C13L2,MacTableInsertEntry() 1537: New Sta:2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.726586] 7981@C08L3,ap_cmm_peer_assoc_req_action() 1647: Recv Assoc from STA - 2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.735931] 7981@C08L3,ap_cmm_peer_assoc_req_action() 2170: ASSOC Send ASSOC response (Status=0)...
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.745039] 7981@C01L3,wifi_sys_conn_act() 1115: wdev idx = 2
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.751070] 7981@C08L3,hw_ctrl_flow_v2_connt_act() 215: wdev_idx=2
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.873428] 7981@C15L3,WPABuildPairMsg1() 5280: <=== send Msg1 of 4-way
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.880092] 7981@C15L3,PeerPairMsg2Action() 6202: ===>Receive msg 2
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.886873] 7981@C15L3,WPABuildPairMsg3() 5557: <=== send Msg3 of 4-way
Thu Jul 4 02:26:53 2024 kern.notice kernel: [ 124.893556] 7981@C15L3,PeerPairMsg4Action() 6632: ===>Receive msg 4
Thu Jul 4 02:26:53 2024 kern.warn kernel: [ 124.905140] 7981@C15L2,PeerPairMsg4Action() 6994: AP SETKEYS DONE(rax0) - AKMMap=WPA2PSK, PairwiseCipher=AES, GroupCipher=AES, wcid=6 from 2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 kern.warn kernel: [ 124.905140]
Thu Jul 4 02:26:53 2024 daemon.info dnsmasq-dhcp[5014]: DHCPREQUEST(br-lan) 192.168.1.246 2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 daemon.info dnsmasq-dhcp[5014]: DHCPACK(br-lan) 192.168.1.246 2a:02:17:60:72:f1
Thu Jul 4 02:26:53 2024 daemon.err odhcpd[3892]: Failed to send to 2001:999:250:96f0:1ce2:89fa:8942:676b%modem_0001@rmnet_mhi0 (Bad file descriptor)
Thu Jul 4 02:26:53 2024 daemon.err odhcpd[3892]: Failed to send to 2001:999:250:96f0:d4a3:a76b:6c1d:6028%modem_0001@rmnet_mhi0 (Bad file descriptor)
Thu Jul 4 08:20:59 2024 cron.err crond[4446]: time disparity of 569 minutes detected
The issue shall not be related to the hot temperature - it was relaively cold night in my place.
Details:
- Router: Spitz AX (GL-X3000)
- Firmware version: 4.0 / 0409release3 / 2024-06-15 13:30:17(UTC+08:00)
- Router is connected to internet via GSM (one SIM is used), mobile provider: Elisa (Finland). IPv6 is in use
- DNS: received from Elisa
- DHCP is used.
Will be happy to get any help!