Having periodic connection stability issues with wireless connection.
I’m monitoring this on several linux devices:
Thinkpad with Intel AX211 (1)
Thinkpad with Realtek 8852AE (2)
RaspberryPi 4B (BCM2711?) (3)
Recently, I noticed that when I’m on a video call, the transmission corrupts a little bit once in a while. I started to monitor the connection by pinging the router from all those devices.
On average devices have ping like 1-2ms(1), 0.5-1ms(2), 2-3ms(3). [It’s another question why 8852AE gets better ping than AX211]
Once in a while, exactly at the time when I see some lags during videocall, ping rise to 10-15ms(1), 300-500ms(2), 10-15ms(3). This continues for 5-6 seconds, and then get back to normal.
Seems like approximately at the same time there is a bunch of errors in the kernel logs:
Wed May 17 21:35:57 2023 kern.err kernel: [ 8199.818286] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 21:35:57 2023 kern.err kernel: [ 8199.826625] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 21:35:57 2023 kern.err kernel: [ 8199.834965] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 21:36:05 2023 kern.warn kernel: [ 8207.752290] 7981@C01L2,wifi_sys_disconn_act() 1002: wdev_idx=2
Wed May 17 21:36:05 2023 kern.notice kernel: [ 8207.760060] 7981@C08L3,hw_ctrl_flow_v2_disconnt_act() 172: wdev_idx=2
Wed May 17 21:36:05 2023 kern.warn kernel: [ 8207.767506] 7981@C13L2,MacTableDeleteEntry() 1921: Del Sta:xx:xx:xx:xx:xx:xx
Wed May 17 21:36:05 2023 kern.notice kernel: [ 8207.774754] 7981@C08L3,ap_peer_deauth_action() 430: AUTH - receive DE-AUTH(seq-6) from xx:xx:xx:xx:xx:xx, reason=1
Wed May 17 21:36:43 2023 kern.warn kernel: [ 8245.376375] Unknown case of dp, iif=80 --> rax0
Wed May 17 21:36:43 2023 kern.warn kernel: [ 8245.654646] Unknown case of dp, iif=80 --> rax0
Wed May 17 21:36:55 2023 kern.err kernel: [ 8257.553393] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 21:36:55 2023 kern.err kernel: [ 8257.561750] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 21:37:06 2023 kern.warn kernel: [ 8268.945568] Unknown case of dp, iif=80 --> rax0
Wed May 17 21:37:17 2023 kern.err kernel: [ 8279.653983] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 21:38:06 2023 kern.debug kernel: [ 8328.781272] entrytb_aid_aquire(): found non-occupied aid:13, allocated from:4
Wed May 17 21:38:06 2023 kern.warn kernel: [ 8328.788421] 7981@C13L2,MacTableInsertEntry() 1559: New Sta:xx:xx:xx:xx:xx:xx
Wed May 17 21:38:06 2023 kern.notice kernel: [ 8328.798403] 7981@C08L3,ap_cmm_peer_assoc_req_action() 1652: Recv Assoc from STA - xx:xx:xx:xx:xx:xx
Wed May 17 21:38:06 2023 kern.notice kernel: [ 8328.807692] 7981@C08L3,ap_cmm_peer_assoc_req_action() 2175: ASSOC Send ASSOC response (Status=0)...
Wed May 17 21:38:06 2023 kern.notice kernel: [ 8328.816810] 7981@C01L3,wifi_sys_conn_act() 1115: wdev idx = 2
Wed May 17 21:38:06 2023 kern.notice kernel: [ 8328.822848] 7981@C08L3,hw_ctrl_flow_v2_connt_act() 215: wdev_idx=2
Wed May 17 21:38:06 2023 kern.notice kernel: [ 8328.947291] 7981@C15L3,WPABuildPairMsg1() 5286: <=== send Msg1 of 4-way
Wed May 17 21:38:06 2023 kern.notice kernel: [ 8328.953917] 7981@C15L3,PeerPairMsg2Action() 6209: ===>Receive msg 2
Wed May 17 21:38:06 2023 kern.notice kernel: [ 8328.960641] 7981@C15L3,WPABuildPairMsg3() 5571: <=== send Msg3 of 4-way
Wed May 17 21:38:06 2023 kern.notice kernel: [ 8328.967276] 7981@C15L3,PeerPairMsg4Action() 6639: ===>Receive msg 4
Wed May 17 21:38:07 2023 kern.warn kernel: [ 8328.978871] 7981@C15L2,PeerPairMsg4Action() 7002: AP SETKEYS DONE(rax0) - AKMMap=WPA2PSK, PairwiseCipher=AES, GroupCipher=AES, wcid=10 from xx:xx:xx:xx:xx:xx
Wed May 17 21:38:07 2023 kern.warn kernel: [ 8328.978871]
Wed May 17 21:38:07 2023 daemon.info dnsmasq-dhcp[6324]: DHCPREQUEST(br-lan) 192.168.1.201 xx:xx:xx:xx:xx:xx
Wed May 17 21:38:07 2023 daemon.info dnsmasq-dhcp[6324]: DHCPACK(br-lan) 192.168.1.201 xx:xx:xx:xx:xx:xx resetko-laptop
Wed May 17 21:40:22 2023 kern.warn kernel: [ 8464.807610] Unknown case of dp, iif=80 --> rax0
Wed May 17 21:40:50 2023 kern.err kernel: [ 8492.521624] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 21:40:50 2023 kern.err kernel: [ 8492.529978] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 21:41:53 2023 kern.err kernel: [ 8555.152299] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 21:43:14 2023 kern.warn kernel: [ 8636.242388] Unknown case of dp, iif=80 --> rax0
Wed May 17 21:44:05 2023 kern.warn kernel: [ 8687.480633] Unknown case of dp, iif=80 --> rax0
Wed May 17 21:45:29 2023 kern.err kernel: [ 8771.008587] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 21:46:29 2023 kern.warn kernel: [ 8831.606262] Unknown case of dp, iif=80 --> rax0
Wed May 17 21:47:25 2023 kern.err kernel: [ 8887.443624] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 21:47:26 2023 kern.warn kernel: [ 8887.997151] Unknown case of dp, iif=80 --> rax0
Wed May 17 21:51:36 2023 kern.warn kernel: [ 9138.141653] Unknown case of dp, iif=80 --> rax0
Wed May 17 21:54:17 2023 kern.err kernel: [ 9299.386484] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 21:54:35 2023 kern.err kernel: [ 9316.849155] 7981@C13L1,tx_free_v3_notify_handler() 3526: ContTxFailCntTotal = 5, ContTxFailCnt300ms = 5
Wed May 17 21:54:35 2023 kern.err kernel: [ 9316.858554] 7981@C13L1,tx_free_v3_notify_handler() 3529: token used by current wcid = 1, free_token_cnt = 2303
Wed May 17 21:54:59 2023 kern.warn kernel: [ 9341.196382] Unknown case of dp, iif=80 --> rax0
Wed May 17 21:57:49 2023 kern.warn kernel: [ 9511.308596] Unknown case of dp, iif=80 --> rax0
Wed May 17 21:59:53 2023 kern.err kernel: [ 9635.092229] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 21:59:53 2023 kern.err kernel: [ 9635.100591] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:02:25 2023 kern.warn kernel: [ 9787.149298] Unknown case of dp, iif=80 --> rax0
Wed May 17 22:02:25 2023 kern.warn kernel: [ 9787.154141] Unknown case of dp, iif=80 --> rax0
Wed May 17 22:04:43 2023 kern.err kernel: [ 9925.299322] 7981@C13L1,tx_free_v3_notify_handler() 3526: ContTxFailCntTotal = 3, ContTxFailCnt300ms = 3
Wed May 17 22:04:43 2023 kern.err kernel: [ 9925.308720] 7981@C13L1,tx_free_v3_notify_handler() 3529: token used by current wcid = 1, free_token_cnt = 2301
Wed May 17 22:06:39 2023 kern.warn kernel: [10040.563342] Unknown case of dp, iif=80 --> rax0
Wed May 17 22:08:49 2023 kern.err kernel: [10171.118712] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:08:49 2023 kern.err kernel: [10171.133569] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:09:19 2023 kern.warn kernel: [10201.311790] Unknown case of dp, iif=80 --> rax0
Wed May 17 22:09:28 2023 kern.err kernel: [10210.011917] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:09:48 2023 kern.err kernel: [10230.184188] 7981@C13L1,tx_free_v3_notify_handler() 3526: ContTxFailCntTotal = 1, ContTxFailCnt300ms = 1
Wed May 17 22:09:48 2023 kern.err kernel: [10230.193593] 7981@C13L1,tx_free_v3_notify_handler() 3529: token used by current wcid = 1, free_token_cnt = 2303
Wed May 17 22:09:52 2023 kern.warn kernel: [10233.523941] Unknown case of dp, iif=80 --> rax0
Wed May 17 22:11:04 2023 kern.warn kernel: [10305.632875] Unknown case of dp, iif=80 --> rax0
Wed May 17 22:19:42 2023 kern.warn kernel: [10824.238268] Unknown case of dp, iif=80 --> rax0
Wed May 17 22:20:27 2023 kern.err kernel: [10868.930634] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:20:27 2023 kern.err kernel: [10868.939005] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:20:27 2023 kern.err kernel: [10868.947352] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:20:27 2023 kern.err kernel: [10868.955697] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:20:27 2023 kern.err kernel: [10868.964061] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:20:27 2023 kern.err kernel: [10868.972954] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:20:27 2023 kern.err kernel: [10868.981317] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:20:27 2023 kern.err kernel: [10868.989662] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:20:27 2023 kern.err kernel: [10868.998007] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:20:27 2023 kern.err kernel: [10869.006352] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:22:30 2023 kern.warn kernel: [10991.720801] Unknown case of dp, iif=80 --> rax0
Wed May 17 22:24:52 2023 kern.err kernel: [11133.734917] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:24:52 2023 kern.err kernel: [11133.743285] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:25:00 2023 kern.err kernel: [11141.845128] 7981@C13L1,tx_free_v3_notify_handler() 3526: ContTxFailCntTotal = 3, ContTxFailCnt300ms = 3
Wed May 17 22:25:00 2023 kern.err kernel: [11141.854528] 7981@C13L1,tx_free_v3_notify_handler() 3529: token used by current wcid = 1, free_token_cnt = 2303
Wed May 17 22:26:56 2023 kern.err kernel: [11257.320158] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:26:56 2023 kern.err kernel: [11257.328541] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:26:56 2023 kern.err kernel: [11257.336890] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:26:56 2023 kern.err kernel: [11257.345235] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:26:56 2023 kern.err kernel: [11257.353579] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:27:17 2023 kern.err kernel: [11278.880902] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:27:17 2023 kern.err kernel: [11278.889288] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:27:17 2023 kern.err kernel: [11278.897635] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:27:46 2023 kern.warn kernel: [11308.205662] Unknown case of dp, iif=80 --> rax0
Wed May 17 22:27:58 2023 kern.warn kernel: [11319.949849] Unknown case of dp, iif=80 --> rax0
Wed May 17 22:29:51 2023 kern.err kernel: [11432.761858] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:29:51 2023 kern.err kernel: [11432.770219] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:29:51 2023 kern.err kernel: [11432.778578] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:30:07 2023 kern.err kernel: [11449.019569] 7981@C13L1,tx_free_v3_notify_handler() 3526: ContTxFailCntTotal = 1, ContTxFailCnt300ms = 1
Wed May 17 22:30:07 2023 kern.err kernel: [11449.028978] 7981@C13L1,tx_free_v3_notify_handler() 3529: token used by current wcid = 1, free_token_cnt = 2303
Wed May 17 22:30:53 2023 kern.warn kernel: [11494.965795] Unknown case of dp, iif=80 --> rax0
Wed May 17 22:31:57 2023 kern.err kernel: [11558.553361] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:31:57 2023 kern.err kernel: [11558.561720] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:31:57 2023 kern.err kernel: [11558.570076] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:31:57 2023 kern.err kernel: [11558.578422] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:34:01 2023 kern.warn kernel: [11683.099518] Unknown case of dp, iif=80 --> rax0
Wed May 17 22:34:01 2023 kern.warn kernel: [11683.104087] Unknown case of dp, iif=80 --> rax0
Wed May 17 22:34:12 2023 kern.warn kernel: [11694.059845] Unknown case of dp, iif=80 --> rax0
Wed May 17 22:35:10 2023 kern.err kernel: [11751.820591] 7981@C13L1,tx_free_v3_notify_handler() 3526: ContTxFailCntTotal = 1, ContTxFailCnt300ms = 1
Wed May 17 22:35:10 2023 kern.err kernel: [11751.829994] 7981@C13L1,tx_free_v3_notify_handler() 3529: token used by current wcid = 1, free_token_cnt = 2302
Wed May 17 22:35:11 2023 kern.err kernel: [11752.540545] 7981@C13L1,tx_free_v3_notify_handler() 3526: ContTxFailCntTotal = 3, ContTxFailCnt300ms = 3
Wed May 17 22:35:11 2023 kern.err kernel: [11752.549948] 7981@C13L1,tx_free_v3_notify_handler() 3529: token used by current wcid = 1, free_token_cnt = 2303
Wed May 17 22:35:13 2023 kern.err kernel: [11754.179544] 7981@C13L1,tx_free_v3_notify_handler() 3526: ContTxFailCntTotal = 5, ContTxFailCnt300ms = 5
Wed May 17 22:35:13 2023 kern.err kernel: [11754.188935] 7981@C13L1,tx_free_v3_notify_handler() 3529: token used by current wcid = 1, free_token_cnt = 2303
Wed May 17 22:35:14 2023 kern.err kernel: [11755.569665] 7981@C13L1,tx_free_v3_notify_handler() 3526: ContTxFailCntTotal = 2, ContTxFailCnt300ms = 2
Wed May 17 22:35:14 2023 kern.err kernel: [11755.579060] 7981@C13L1,tx_free_v3_notify_handler() 3529: token used by current wcid = 1, free_token_cnt = 2303
Wed May 17 22:36:51 2023 kern.err kernel: [11852.998002] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:38:17 2023 kern.err kernel: [11938.748707] 7981@C11L1,header_packet_process() 7108: header_packet_process(): CheckRxError!
Wed May 17 22:39:27 2023 kern.warn kernel: [12008.496097] Unknown case of dp, iif=80 --> rax0
This keeps to happen each 10-30 minutes, hard to say exactly how much time between those lags it takes to repeat, but attaching some portion of logs for short period of time. There are also more logs like these.
- OpenWrt 21.02-SNAPSHOT r15812+879-46b6ee7ffc
Linux beryl-ax-home 5.4.211 #0 SMP Fri Apr 14 11:34:26 2023 aarch64 GNU/Linux
Is it some known issue? Or maybe there is a good way to debug it further?