Puli AX repeater mode not working - help?

cemhamami · December 5, 2023, 5:54pm

Hello,

I recently received my Puli AX and I’m pleased with everything about it so far except the Wifi repeater functionality. When I try to connect to a Wifi network:

If the network has both 2.4Ghz and 5Ghz channels, I observe that for some reason Puli AX will only show the 2.4Ghz channel for some reason. The 5Ghz channels seem to be missing from the wifi list altogether, even though my phone discovers them just fine.
When I try to connect to the network, it tries for a long time and then fails with the following error: “Connection failed, will retry later…
While attempting to reconnect, your router will periodically scan for Wi-Fi networks, and your guest Wi-Fi network will be temporarily unavailable during this scan.”

So far I haven’t been able to get this working at all with any Wifi I tried so far. I know for certain that none of the networks I’m trying to connect to have captive portal, I also tried with my phone hotspot and that doesn’t work either. Also I’m not new to the Gl.inet user interface, I have been using a Mudi for more than a year and never had this issue. The device is in the latest firmware version available (Version4.0 with Type 0403release6 and Compile Time 2023-10-26)

Could you please advise what could be causing this and how to potentially get it working?

Thanks!
Cem

admon · December 5, 2023, 6:04pm

Do you know the exact settings of the 5 GHz channels? Maybe 160 MHz channel bandwith, for example? Could be a problem with some settings the Puli does not support.

For example, valid channels for 5 GHz: 1/2/3/5/7/8/12/13/14/18/20/25/26/28/29/30/38/40/41/48/66/70/
71/75/76/77/78/79 (as far as the internet is right about it)

Could a problem with the country setting within luci as well.
Are you familiar with luci?

cemhamami · December 5, 2023, 6:10pm

Hello admon,

It doesn’t work with any network I have tried so far including my cellphone hotspot which makes it unlikely that the network’s 5Ghz config is the issue.

I’m somewhat familiar with luci but I haven’t enabled the advanced luci interface for my Puli AX yet. Is the country setting only visible from that UI?

admon · December 5, 2023, 6:14pm

Yep, only in luci you can configure it.

After login in luci switch to Network > Wireless in the menu, select the 2 and 5 GHz network adapter, press edit, go to advanced settings and change the country. Click Save and Save & Apply

For the iPhone 5 GHz it might be important to disable Compatibility mode:

cemhamami · December 5, 2023, 8:27pm

Hey admon,

When I go to the wireless settings in luci and select the 5G and 2Ghz networks, under Device Configuration there’s only one tab, “General Setup”, the Advanced Setup tab doesn’t show up at all. Any idea why that might be? I only see “Advanced” tab under Interface Config which doesn’t have this setting either.

admon · December 5, 2023, 8:50pm

Could be a difference between our firmwares.
Maybe you can try to look around if you find the country setting somewhere?

I remember that there are firmwares where the wireless settings are behind a menu called MTKwifi or something line that.

cemhamami · December 5, 2023, 10:46pm

Here’s my firmware version from the Luci status page: OpenWrt 21.02-SNAPSHOT r15812+879-46b6ee7ffc / LuCI openwrt-21.02 branch git-22.245.77575-63bfee6

I have been searching for the country code setting for the wireless config for a while now but I haven’t been able to locate it. I don’t see the Advanced tab under 5Ghz/2Ghz wireless and there doesn’t seem to be a menu called MTKwifi either.

cemhamami · December 5, 2023, 11:44pm

I finally found the solution. Everything started working as expected after I enabled this setting under Repeater Options in the main UI: “Allow Repeating Of DFS Channels”

Now the router finds both 2.4Ghz and 5Ghz channels and the repeater functionality works fine.

Reading the description of this setting, it’s not very clear to me why this setting is disabled by default as I’m guessing many others would run into this issue with WiFis that are using these channels. Maybe someone from the gl.inet staff can comment to give more information.

yuxin.zou · December 6, 2023, 6:40am

The firmware version 4.5 has been changed to enable it by default.
Initially this was due to the channel availability scan that occurs when repeating a DFS channel, which interrupts WiFi.

cemhamami · December 6, 2023, 5:55pm

Thank you for the information @yuxin.zou.

After enabling the DFS option, I have more success with the WiFi repeater, however some networks (without EAC) are still failing to connect. I’m not sure why as there doesn’t seem to be a detailed error message other than “Connection failed, will retry later…”.

Do you think this could be due to a setting like @admon was suggesting (country code, etc.)? I wasn’t able to locate where to change this in my firmware as the advanced tab doesn’t show up in the wireless settings. For reference, I’m on version OpenWrt 21.02-SNAPSHOT r15812+879-46b6ee7ffc / LuCI openwrt-21.02 branch git-22.245.77575-63bfee6

Thanks,
Cem

admon · December 6, 2023, 5:56pm

The log inside the GL GUI will show you more information, in detail the system log

cemhamami · December 6, 2023, 7:58pm

Attaching part 1 of the log below, I marked some information REDACTED to remove personal info:

Wed Dec  6 11:40:42 2023 daemon.info repeater: (/usr/sbin/repeater:1472) scan finished
Wed Dec  6 11:40:42 2023 daemon.info repeater: (/usr/sbin/repeater:838) Switching...
Wed Dec  6 11:40:42 2023 daemon.info repeater: (/usr/sbin/repeater:1294) prepare connect 'REDACTED_WIFI_NAME(80:20:da:59:01:21)', WPA2PSK/AES channel 1
Wed Dec  6 11:40:42 2023 daemon.info repeater: (/usr/sbin/repeater:1392) connecting 'REDACTED_WIFI_NAME 80:20:da:59:01:21'...
Wed Dec  6 11:40:42 2023 daemon.notice netifd: Wireless device 'mt798111' is now down
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.409437] 7981@C08L2,mbss_virtual_if_open() 134:  ===> rax1
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.415242] 7981@C01L2,RTMP_COM_IoctlHandle() 1511: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_INIT
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.423339] 7981@C01L2,RTMP_COM_IoctlHandle() 1488:  -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_UP
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.431348] WiFi@C01L3,wifi_sys_open() 856: wdev idx = 3
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.436950] 7981@C18L3,BuildChannelList() 484:
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.441515] 7981@C23L3,operate_loader_phy() 394: oper_cfg: prim_ch(161), ht_bw(1), extcha(1), vht_bw(1), cen_ch_2(0), PhyMode=177!
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.453454] 7981@C23L3,operate_loader_phy() 408: oper_dev after adjust: bw(2), prim_ch(161), cen_ch_1(155), cen_ch_2(0),ext_cha(3)!
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.465370] 7981@C23L3,operate_loader_phy() 428: oper_radio after decision: bw(2), prim_ch(161), cen_ch_1(155), cen_ch_2(0)!
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.476679] 7981@C03L3,MtCmdChannelSwitch() 2538: ctrl_chl=161, ctrl_ch2=0, cent_ch=155 DBDCIdx=1, ChBand=1, BW=2, TXStream=3, RXStream=3, scan(0)
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.489999] 7981@C03L3,MtCmdSetTxRxPath() 2799: ctrl_chl=161, ctrl_ch2=0, cent_ch=155, RxPath=7, BandIdx=1, ChBand=1, BW=2,TXStream=3, RXStream=7, scan(0)
Wed Dec  6 11:40:43 2023 kern.err kernel: [ 7448.504002] 7981@C12L1,EDCCAInit() 21006: EDCCA compensation: uni_compensation=0, bw_compensation=0, final compensation=0
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.515225] WiFi@C00L2,dump_ht_cap() 29: Caller: wlan_operate_init+0xbc/0x160 [mt_wifi]
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.523261] wdev_attr_update(): wdevId3 = 96:83:c4:a0:4c:17
Wed Dec  6 11:40:43 2023 kern.err kernel: [ 7448.528860] WiFi@C12L1,wifi_sys_open() 907: wifi_sys_open():band1 group_idx[3]=25, bw_grp=16 omac=17
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.538032] 7981@C08L3,hw_ctrl_flow_v2_open() 13: wdev_idx=3
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.544910] 7981@C02L2,AsicRadioOnOffCtrl() 2340: DbdcIdx=1 RadioOn
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.551331] 7981@C00L2,ApAutoChannelAtBootUp() 333: ----------------->
Wed Dec  6 11:40:43 2023 kern.err kernel: [ 7448.557977] 7981@C03L1,MtCmdSetMacTxRx() 10246: (ret = 0)
Wed Dec  6 11:40:43 2023 kern.err kernel: [ 7448.563540] 7981@C03L1,MtCmdSetMacTxRx() 10246: (ret = 0)
Wed Dec  6 11:40:43 2023 kern.err kernel: [ 7448.568997] 7981@C00L1,ApAutoChannelAtBootUp() 491: zero-wait DFS is not enabled
Wed Dec  6 11:40:43 2023 kern.err kernel: [ 7448.576397] 7981@C08L1,ap_run_at_boot() 623: e[41m ACS is disable !!e[m
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.583226] 7981@C08L2,APStartUpForMbss() 983: ===>(caller:ap_inf_open+0x144/0x4e0 [mt_wifi]), mbss_idx:3, CfgMode:78
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.593956] 7981@C15L2,APPMFInit() 121: [PMF] Security is not WPA2/WPA2PSK AES
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.601228] 7981@C15L2,APPMFInit() 133: [PMF] apidx=3, MFPC=0, MFPR=0, SHA256=0
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.608546] 7981@C18L3,BuildChannelList() 484:
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.613306] WiFi@C00L2,dump_ht_cap() 29: Caller: dump_ht_cap+0x10/0x20 [mt_wifi]
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.621007] WiFi@C08L2,ap_link_up() 7143: (caller:wdev_do_linkup+0x20/0x38 [mt_wifi]), wdev(3)
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.629640] 7981@C01L3,wifi_sys_linkup() 1231: wdev idx = 3
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.635430] 7981@C00L3,wtc_acquire_groupkey_wcid() 84:  Found a non-occupied wtbl_idx:543 for WDEV_TYPE:1
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.635430]  LinkToOmacIdx = 11, LinkToWdevType = 1
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.649942] 7981@C08L3,hw_ctrl_flow_v2_link_up() 72: wdev_idx=3
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.660305] 7981@C08L3,UpdateBeaconHandler() 1911: BCN_UPDATE_INIT, OmacIdx = 11 (rax1)
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.668364] 7981@C08L2,bcn_buf_init() 170: BcnPkt is allocated!, bcn offload=1
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.675590] 7981@C08L3,UpdateBeaconHandler() 1924: 	Band1 BcnInitedRnd = 108
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.694981] PrintSrCmd:
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.694981] u1CmdSubId = 1, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.694981] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 1
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.709000] PrintSrCmd:
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.709000] u1CmdSubId = 5, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.709000] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.723002] PrintSrCmd:
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.723002] u1CmdSubId = 3, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.723002] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 1
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.737002] PrintSrCmd:
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.737002] u1CmdSubId = 23, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.737002] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.751079] PrintSrCmd:
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.751079] u1CmdSubId = 31, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.751079] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.765175] PrintSrCmd:
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.765175] u1CmdSubId = 33, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.765175] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0
Wed Dec  6 11:40:43 2023 kern.err kernel: [ 7448.779273] 7981@C12L1,EDCCAInit() 21006: EDCCA compensation: uni_compensation=0, bw_compensation=0, final compensation=0
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.790239] 7981@C03L3,MtCmdTxPwrShowInfo() 13746: ucTxPowerInfoCatg: 2, BandIdx: 1
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.797992] 7981@C03L3,MtCmdTxPwrShowInfo() 13775: (ret = 0)
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.798182] [mtf_txpower_all_rate_info] band_idx:1 pwr:45 ChBand:5G ePAGain:0
Wed Dec  6 11:40:43 2023 kern.info kernel: [ 7448.803659] extif_set_dev(rax1)
Wed Dec  6 11:40:43 2023 kern.info kernel: [ 7448.813909] mtk_ppe_dev_register_hook : ineterface rax1 register (1)
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.826981] 7981@C13L2,Set_DisConnectAllSta_Proc() 22426: Send DeAuth (Reason=2) to f4:d4:88:88:35:95
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.841863] 7981@C01L2,wifi_sys_disconn_act() 1002:  wdev_idx=2
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.849310] 7981@C08L3,hw_ctrl_flow_v2_disconnt_act() 172: wdev_idx=2
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.856620] 7981@C13L2,MacTableDeleteEntry() 1793: Del Sta:f4:d4:88:88:35:95
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.866985] 7981@C01L2,RTMP_COM_IoctlHandle() 1511: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_INIT
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.875108] 7981@C01L2,RTMP_COM_IoctlHandle() 1488:  -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_UP
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.883120] WiFi@C01L3,wifi_sys_open() 856: wdev idx = 0
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.888705] 7981@C18L3,BuildChannelList() 484:
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.893279] 7981@C23L3,operate_loader_phy() 394: oper_cfg: prim_ch(6), ht_bw(0), extcha(0), vht_bw(0), cen_ch_2(0), PhyMode=78!
Wed Dec  6 11:40:43 2023 daemon.notice netifd: wgclient (1881): Try again: `jcfREDACTED.glddns.com:REDACTED'. Trying again in 1.20 seconds...
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.904765] 7981@C23L3,operate_loader_phy() 408: oper_dev after adjust: bw(0), prim_ch(6), cen_ch_1(6), cen_ch_2(0),ext_cha(0)!
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.916246] 7981@C23L3,operate_loader_phy() 428: oper_radio after decision: bw(0), prim_ch(6), cen_ch_1(6), cen_ch_2(0)!
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.927317] WiFi@C00L2,dump_ht_cap() 29: Caller: wlan_operate_init+0xbc/0x160 [mt_wifi]
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7448.935335] wdev_attr_update(): wdevId0 = 94:83:c4:a0:4c:16
Wed Dec  6 11:40:43 2023 kern.err kernel: [ 7448.940940] WiFi@C12L1,wifi_sys_open() 907: wifi_sys_open():band0 group_idx[0]=0, bw_grp=16 omac=0
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7448.949945] 7981@C08L3,hw_ctrl_flow_v2_open() 13: wdev_idx=0
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.955814] 7981@C02L2,AsicRadioOnOffCtrl() 2340: DbdcIdx=0 RadioOn
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.962248] 7981@C00L2,ApAutoChannelAtBootUp() 333: ----------------->
Wed Dec  6 11:40:43 2023 kern.err kernel: [ 7448.968906] 7981@C03L1,MtCmdSetMacTxRx() 10246: (ret = 0)
Wed Dec  6 11:40:43 2023 kern.err kernel: [ 7448.974463] 7981@C03L1,MtCmdSetMacTxRx() 10246: (ret = 0)
Wed Dec  6 11:40:43 2023 kern.err kernel: [ 7448.979868] 7981@C00L1,ApAutoChannelAtBootUp() 497: not A band
Wed Dec  6 11:40:43 2023 kern.err kernel: [ 7448.985699] 7981@C08L1,ap_run_at_boot() 623: e[41m ACS is disable !!e[m
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7448.992532] 7981@C08L2,APStartUpForMbss() 983: ===>(caller:ap_inf_open+0x144/0x4e0 [mt_wifi]), mbss_idx:0, CfgMode:78
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7449.003175] 7981@C15L2,APPMFInit() 121: [PMF] Security is not WPA2/WPA2PSK AES
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7449.010405] 7981@C15L2,APPMFInit() 133: [PMF] apidx=0, MFPC=0, MFPR=0, SHA256=0
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7449.017720] 7981@C18L3,BuildChannelList() 484:
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7449.022482] WiFi@C00L2,dump_ht_cap() 29: Caller: dump_ht_cap+0x10/0x20 [mt_wifi]
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7449.029898] 7981@C08L2,APOverlappingBSSScan() 5147: leverage result of wdev(0@BN0), FallBack=1 (remaining 49116 ms)
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7449.040340] 7981@C23L3,operate_loader_phy() 394: oper_cfg: prim_ch(6), ht_bw(0), extcha(0), vht_bw(0), cen_ch_2(0), PhyMode=78!
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7449.051810] 7981@C23L3,operate_loader_phy() 408: oper_dev after adjust: bw(0), prim_ch(6), cen_ch_1(6), cen_ch_2(0),ext_cha(0)!
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7449.063291] 7981@C23L3,operate_loader_phy() 428: oper_radio after decision: bw(0), prim_ch(6), cen_ch_1(6), cen_ch_2(0)!
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7449.074398] WiFi@C08L2,ap_link_up() 7143: (caller:wdev_do_linkup+0x20/0x38 [mt_wifi]), wdev(0)
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7449.083053] 7981@C01L3,wifi_sys_linkup() 1231: wdev idx = 0
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7449.088855] 7981@C00L3,wtc_acquire_groupkey_wcid() 84:  Found a non-occupied wtbl_idx:541 for WDEV_TYPE:1
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7449.088855]  LinkToOmacIdx = 0, LinkToWdevType = 1
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7449.103290] 7981@C08L3,hw_ctrl_flow_v2_link_up() 72: wdev_idx=0
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7449.113664] 7981@C08L3,UpdateBeaconHandler() 1911: BCN_UPDATE_INIT, OmacIdx = 0 (ra0)
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7449.121541] 7981@C08L2,bcn_buf_init() 170: BcnPkt is allocated!, bcn offload=1
Wed Dec  6 11:40:43 2023 kern.err kernel: [ 7449.128977] 7981@C00L1,TxCCKStreamCtrl() 20470: set wrong parameters
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.145461] PrintSrCmd:
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.145461] u1CmdSubId = 1, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.145461] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 1
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.159463] PrintSrCmd:
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.159463] u1CmdSubId = 5, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.159463] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.173468] PrintSrCmd:
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.173468] u1CmdSubId = 3, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.173468] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 1
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.187477] PrintSrCmd:
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.187477] u1CmdSubId = 23, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.187477] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.201567] PrintSrCmd:
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.201567] u1CmdSubId = 31, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.201567] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.215653] PrintSrCmd:
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.215653] u1CmdSubId = 33, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.215653] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0
Wed Dec  6 11:40:43 2023 kern.err kernel: [ 7449.229776] 7981@C12L1,EDCCAInit() 21006: EDCCA compensation: uni_compensation=0, bw_compensation=0, final compensation=0
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7449.240740] 7981@C03L3,MtCmdTxPwrShowInfo() 13746: ucTxPowerInfoCatg: 2, BandIdx: 0
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7449.248494] 7981@C03L3,MtCmdTxPwrShowInfo() 13775: (ret = 0)
Wed Dec  6 11:40:43 2023 kern.debug kernel: [ 7449.248601] [mtf_txpower_all_rate_info] band_idx:0 pwr:47 ChBand:2G ePAGain:0
Wed Dec  6 11:40:43 2023 kern.info kernel: [ 7449.254160] extif_set_dev(ra0)
Wed Dec  6 11:40:43 2023 kern.info kernel: [ 7449.264316] mtk_ppe_dev_register_hook : ineterface ra0 register (3)
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7449.273338] 7981@C08L2,mbss_virtual_if_open() 134:  ===> ra1
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7449.279038] 7981@C01L2,RTMP_COM_IoctlHandle() 1511: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_INIT
Wed Dec  6 11:40:43 2023 kern.warn kernel: [ 7449.287162] 7981@C01L2,RTMP_COM_IoctlHandle() 1488:  -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_UP
Wed Dec  6 11:40:43 2023 kern.notice kernel: [ 7449.295178] WiFi@C01L3,wifi_sys_open() 856: wdev idx = 1

cemhamami · December 6, 2023, 9:25pm

Part 2 of the relevant logs until connection failure:

Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.300788] 7981@C18L3,BuildChannelList() 484:
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.305407] 7981@C23L3,operate_loader_phy() 394: oper_cfg: prim_ch(6), ht_bw(0), extcha(0), vht_bw(0), cen_ch_2(0), PhyMode=78!
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.316920] 7981@C23L3,operate_loader_phy() 408: oper_dev after adjust: bw(0), prim_ch(6), cen_ch_1(6), cen_ch_2(0),ext_cha(0)!
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.328417] 7981@C23L3,operate_loader_phy() 428: oper_radio after decision: bw(0), prim_ch(6), cen_ch_1(6), cen_ch_2(0)!
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.339540] WiFi@C00L2,dump_ht_cap() 29: Caller: wlan_operate_init+0xbc/0x160 [mt_wifi]
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.347564] wdev_attr_update(): wdevId1 = 96:83:c4:a0:4c:16
Wed Dec  6 11:40:44 2023 kern.err kernel: [ 7449.353164] WiFi@C12L1,wifi_sys_open() 907: wifi_sys_open():band0 group_idx[1]=9, bw_grp=16 omac=17
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.362267] 7981@C08L3,hw_ctrl_flow_v2_open() 13: wdev_idx=1
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.368145] 7981@C02L2,AsicRadioOnOffCtrl() 2340: DbdcIdx=0 RadioOn
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.374682] 7981@C00L2,ApAutoChannelAtBootUp() 333: ----------------->
Wed Dec  6 11:40:44 2023 kern.err kernel: [ 7449.381354] 7981@C03L1,MtCmdSetMacTxRx() 10246: (ret = 0)
Wed Dec  6 11:40:44 2023 kern.err kernel: [ 7449.386882] 7981@C03L1,MtCmdSetMacTxRx() 10246: (ret = 0)
Wed Dec  6 11:40:44 2023 kern.err kernel: [ 7449.392292] 7981@C00L1,ApAutoChannelAtBootUp() 497: not A band
Wed Dec  6 11:40:44 2023 kern.err kernel: [ 7449.398126] 7981@C08L1,ap_run_at_boot() 623: e[41m ACS is disable !!e[m
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.404948] 7981@C08L2,APStartUpForMbss() 983: ===>(caller:ap_inf_open+0x144/0x4e0 [mt_wifi]), mbss_idx:1, CfgMode:78
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.415590] 7981@C15L2,APPMFInit() 121: [PMF] Security is not WPA2/WPA2PSK AES
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.422821] 7981@C15L2,APPMFInit() 133: [PMF] apidx=1, MFPC=0, MFPR=0, SHA256=0
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.430129] 7981@C18L3,BuildChannelList() 484:
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.434891] WiFi@C00L2,dump_ht_cap() 29: Caller: dump_ht_cap+0x10/0x20 [mt_wifi]
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.442306] 7981@C08L2,APOverlappingBSSScan() 5147: leverage result of wdev(0@BN0), FallBack=1 (remaining 48704 ms)
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.452741] 7981@C23L3,operate_loader_phy() 394: oper_cfg: prim_ch(6), ht_bw(0), extcha(0), vht_bw(0), cen_ch_2(0), PhyMode=78!
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.464216] 7981@C23L3,operate_loader_phy() 408: oper_dev after adjust: bw(0), prim_ch(6), cen_ch_1(6), cen_ch_2(0),ext_cha(0)!
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.475700] 7981@C23L3,operate_loader_phy() 428: oper_radio after decision: bw(0), prim_ch(6), cen_ch_1(6), cen_ch_2(0)!
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.486803] WiFi@C08L2,ap_link_up() 7143: (caller:wdev_do_linkup+0x20/0x38 [mt_wifi]), wdev(1)
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.495443] 7981@C01L3,wifi_sys_linkup() 1231: wdev idx = 1
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.501240] 7981@C00L3,wtc_acquire_groupkey_wcid() 84:  Found a non-occupied wtbl_idx:540 for WDEV_TYPE:1
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.501240]  LinkToOmacIdx = 11, LinkToWdevType = 1
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.515760] 7981@C08L3,hw_ctrl_flow_v2_link_up() 72: wdev_idx=1
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.526018] 7981@C08L3,UpdateBeaconHandler() 1911: BCN_UPDATE_INIT, OmacIdx = 11 (ra1)
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.533990] 7981@C08L2,bcn_buf_init() 170: BcnPkt is allocated!, bcn offload=1
Wed Dec  6 11:40:44 2023 kern.err kernel: [ 7449.541395] 7981@C00L1,TxCCKStreamCtrl() 20470: set wrong parameters
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.557818] PrintSrCmd:
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.557818] u1CmdSubId = 1, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.557818] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 1
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.571827] PrintSrCmd:
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.571827] u1CmdSubId = 5, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.571827] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.585833] PrintSrCmd:
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.585833] u1CmdSubId = 3, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.585833] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 1
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.599829] PrintSrCmd:
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.599829] u1CmdSubId = 23, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.599829] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.613926] PrintSrCmd:
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.613926] u1CmdSubId = 31, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.613926] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.628024] PrintSrCmd:
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.628024] u1CmdSubId = 33, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.628024] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0
Wed Dec  6 11:40:44 2023 kern.err kernel: [ 7449.642142] 7981@C12L1,EDCCAInit() 21006: EDCCA compensation: uni_compensation=0, bw_compensation=0, final compensation=0
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.653106] 7981@C03L3,MtCmdTxPwrShowInfo() 13746: ucTxPowerInfoCatg: 2, BandIdx: 0
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.660861] 7981@C03L3,MtCmdTxPwrShowInfo() 13775: (ret = 0)
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.660967] [mtf_txpower_all_rate_info] band_idx:0 pwr:47 ChBand:2G ePAGain:0
Wed Dec  6 11:40:44 2023 kern.info kernel: [ 7449.666526] extif_set_dev(ra1)
Wed Dec  6 11:40:44 2023 kern.info kernel: [ 7449.676693] mtk_ppe_dev_register_hook : ineterface ra1 register (4)
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.685898] 7981@C01L2,RTMP_COM_IoctlHandle() 1511: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_INIT
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.694002] 7981@C01L2,RTMP_COM_IoctlHandle() 1488:  -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_UP
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.702021] WiFi@C01L3,wifi_sys_open() 856: wdev idx = 4
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.707601] 7981@C18L3,BuildChannelList() 484:
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.712170] 7981@C23L3,operate_loader_phy() 394: oper_cfg: prim_ch(6), ht_bw(0), extcha(0), vht_bw(0), cen_ch_2(0), PhyMode=78!
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.723647] 7981@C23L3,operate_loader_phy() 408: oper_dev after adjust: bw(0), prim_ch(6), cen_ch_1(6), cen_ch_2(0),ext_cha(0)!
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.735156] 7981@C23L3,operate_loader_phy() 428: oper_radio after decision: bw(0), prim_ch(6), cen_ch_1(6), cen_ch_2(0)!
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.746242] WiFi@C00L2,dump_ht_cap() 29: Caller: wlan_operate_init+0xbc/0x160 [mt_wifi]
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.754291] wdev_attr_update(): wdevId4 = 92:83:c4:a0:4c:16
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.759907] 7981@C08L3,hw_ctrl_flow_v2_open() 13: wdev_idx=4
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.766033] WiFi@C00L2,dump_ht_cap() 29: Caller: dump_ht_cap+0x10/0x20 [mt_wifi]
Wed Dec  6 11:40:44 2023 kern.info kernel: [ 7449.773473] extif_set_dev(apcli0)
Wed Dec  6 11:40:44 2023 kern.info kernel: [ 7449.776793] mtk_ppe_dev_register_hook : ineterface apcli0 register (5)
Wed Dec  6 11:40:44 2023 kern.err kernel: [ 7449.784421] 7981@C12L1,Set_ApCliPMFMFPC_Proc() 2003: [PMF]: MFPC=1, MFPR=0, SHA256=0
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7449.792901] SetApCliEnableByWdev: I/F(apcli0) (enable = 1)
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.804242] 7981@C01L2,RTMP_COM_IoctlHandle() 1499:  -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_DOWN
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.812689] WiFi@C08L2,ap_link_down() 7161: (caller:wdev_do_linkdown+0x20/0x38 [mt_wifi]), wdev(0)
Wed Dec  6 11:40:44 2023 kern.err kernel: [ 7449.822046] 7981@C08L1,UpdateBeaconHandler() 1891: wdev(0) bss not ready (state:2, caller:ap_link_down+0x84/0x130 [mt_wifi])!!
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.833454] 7981@C01L3,wifi_sys_linkdown() 1363: wdev idx = 0
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.839456] 7981@C08L3,hw_ctrl_flow_v2_link_down() 123: wdev_idx=0
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.846550] WiFi@C01L3,wifi_sys_close() 945: wdev idx = 0
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.852309] 7981@C08L3,hw_ctrl_flow_v2_close() 41: wdev_idx=0
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.858418] 7981@C01L2,RTMP_COM_IoctlHandle() 1522: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_DEINIT
Wed Dec  6 11:40:44 2023 kern.info kernel: [ 7449.866714] extif_put_dev(ra0)
Wed Dec  6 11:40:44 2023 kern.info kernel: [ 7449.869776] mtk_ppe_dev_unregister_hook : ineterface ra0 set null (3)
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.884768] 7981@C08L2,mbss_virtual_if_close() 171:  ===> ra1
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.890559] 7981@C01L2,RTMP_COM_IoctlHandle() 1499:  -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_DOWN
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.899035] WiFi@C08L2,ap_link_down() 7161: (caller:wdev_do_linkdown+0x20/0x38 [mt_wifi]), wdev(1)
Wed Dec  6 11:40:44 2023 kern.err kernel: [ 7449.908543] 7981@C08L1,UpdateBeaconHandler() 1891: wdev(1) bss not ready (state:2, caller:ap_link_down+0x84/0x130 [mt_wifi])!!
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.919988] 7981@C01L3,wifi_sys_linkdown() 1363: wdev idx = 1
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.926029] 7981@C08L3,hw_ctrl_flow_v2_link_down() 123: wdev_idx=1
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.933705] WiFi@C01L3,wifi_sys_close() 945: wdev idx = 1
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7449.939406] 7981@C08L3,hw_ctrl_flow_v2_close() 41: wdev_idx=1
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.945452] 7981@C01L2,RTMP_COM_IoctlHandle() 1522: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_DEINIT
Wed Dec  6 11:40:44 2023 kern.info kernel: [ 7449.953740] extif_put_dev(ra1)
Wed Dec  6 11:40:44 2023 kern.info kernel: [ 7449.956796] mtk_ppe_dev_unregister_hook : ineterface ra1 set null (4)
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.970938] 7981@C08L2,mbss_virtual_if_close() 171:  ===> rax1
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.976792] 7981@C01L2,RTMP_COM_IoctlHandle() 1499:  -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_DOWN
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7449.985201] WiFi@C08L2,ap_link_down() 7161: (caller:wdev_do_linkdown+0x20/0x38 [mt_wifi]), wdev(3)
Wed Dec  6 11:40:44 2023 kern.err kernel: [ 7449.994518] 7981@C08L1,UpdateBeaconHandler() 1891: wdev(3) bss not ready (state:2, caller:ap_link_down+0x84/0x130 [mt_wifi])!!
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7450.005924] 7981@C01L3,wifi_sys_linkdown() 1363: wdev idx = 3
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7450.011924] 7981@C08L3,hw_ctrl_flow_v2_link_down() 123: wdev_idx=3
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7450.019054] WiFi@C01L3,wifi_sys_close() 945: wdev idx = 3
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7450.024774] 7981@C08L3,hw_ctrl_flow_v2_close() 41: wdev_idx=3
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7450.030716] 7981@C01L2,RTMP_COM_IoctlHandle() 1522: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_DEINIT
Wed Dec  6 11:40:44 2023 kern.info kernel: [ 7450.039052] extif_put_dev(rax1)
Wed Dec  6 11:40:44 2023 kern.info kernel: [ 7450.042231] mtk_ppe_dev_unregister_hook : ineterface rax1 set null (1)
Wed Dec  6 11:40:44 2023 daemon.notice netifd: Wireless device 'mt798111' is now up
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7450.218482] 7981@C08L3,ap_peer_auth_req_at_idle_action() 717: AUTH - MBSS(2), Rcv AUTH seq#1, Alg=0, Status=0 from [wcid=1023]f4:d4:88:88:35:95
Wed Dec  6 11:40:44 2023 kern.debug kernel: [ 7450.231420] entrytb_aid_aquire(): found non-occupied aid:4, allocated from:4
Wed Dec  6 11:40:44 2023 kern.warn kernel: [ 7450.238491] 7981@C13L2,MacTableInsertEntry() 1537: New Sta:f4:d4:88:88:35:95
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7450.247505] 7981@C08L3,ap_cmm_peer_assoc_req_action() 1647:  Recv Assoc from STA - f4:d4:88:88:35:95
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7450.256858] 7981@C08L3,ap_cmm_peer_assoc_req_action() 2170: ASSOC Send ASSOC response (Status=0)...
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7450.265974] 7981@C01L3,wifi_sys_conn_act() 1115: wdev idx = 2
Wed Dec  6 11:40:44 2023 kern.notice kernel: [ 7450.272032] 7981@C08L3,hw_ctrl_flow_v2_connt_act() 215: wdev_idx=2
Wed Dec  6 11:40:45 2023 kern.notice kernel: [ 7450.392742] 7981@C15L3,WPABuildPairMsg1() 5280: <=== send Msg1 of 4-way
Wed Dec  6 11:40:45 2023 kern.notice kernel: [ 7450.399372] 7981@C15L3,PeerPairMsg2Action() 6202: ===>Receive msg 2
Wed Dec  6 11:40:45 2023 kern.notice kernel: [ 7450.406156] 7981@C15L3,WPABuildPairMsg3() 5557: <=== send Msg3 of 4-way
Wed Dec  6 11:40:45 2023 kern.debug kernel: [ 7450.412032] BcnCheck start after 3300 ms (ra0)
Wed Dec  6 11:40:45 2023 kern.notice kernel: [ 7450.412818] 7981@C15L3,PeerPairMsg4Action() 6632: ===>Receive msg 4
Wed Dec  6 11:40:45 2023 kern.warn kernel: [ 7450.428707] 7981@C15L2,PeerPairMsg4Action() 6994: AP SETKEYS DONE(rax0) - AKMMap=WPA2PSK, PairwiseCipher=AES, GroupCipher=AES, wcid=1 from f4:d4:88:88:35:95
Wed Dec  6 11:40:45 2023 kern.warn kernel: [ 7450.428707]
Wed Dec  6 11:40:45 2023 kern.warn kernel: [ 7450.936442] 7981@C09L2,cntl_connect_request() 395: type=2,len=0,caller:ApCliIfUp+0x190/0x348 [mt_wifi]
Wed Dec  6 11:40:45 2023 kern.debug kernel: [ 7450.946107] PrintSrCmd:
Wed Dec  6 11:40:45 2023 kern.debug kernel: [ 7450.946107] u1CmdSubId = 3, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0
Wed Dec  6 11:40:45 2023 kern.debug kernel: [ 7450.946107] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0
Wed Dec  6 11:40:45 2023 kern.debug kernel: [ 7450.960205] PrintSrCmd:
Wed Dec  6 11:40:45 2023 kern.debug kernel: [ 7450.960205] u1CmdSubId = 1, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0
Wed Dec  6 11:40:45 2023 kern.debug kernel: [ 7450.960205] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0
Wed Dec  6 11:40:46 2023 kern.debug kernel: [ 7451.456397] Set_ByPassCac_Proc(): set CAC value to 1
Wed Dec  6 11:40:46 2023 user.notice mwan3track[31872]: Interface modem_0001 (rmnet_mhi0) is offline
Wed Dec  6 11:40:46 2023 daemon.info dnsmasq-dhcp[1877]: DHCPREQUEST(br-lan) 192.168.8.180 f4:d4:88:88:35:95
Wed Dec  6 11:40:46 2023 daemon.info dnsmasq-dhcp[1877]: DHCPACK(br-lan) 192.168.8.180 f4:d4:88:88:35:95 REDACTED_computer_name
Wed Dec  6 11:40:47 2023 kern.warn kernel: [ 7452.991449] 7981@C09L2,sync_fsm_join_timeout_action() 1091: ProbeTimeoutAtJoinAction
Wed Dec  6 11:40:47 2023 kern.debug kernel: [ 7453.027425] BcnCheck start after 800 ms (ra0)
Wed Dec  6 11:40:47 2023 kern.warn kernel: [ 7453.032298] 7981@C09L2,cntl_connect_request() 395: type=2,len=0,caller:ApCliIfUp+0x190/0x348 [mt_wifi]
Wed Dec  6 11:40:47 2023 kern.err kernel: [ 7453.041619] 7981@C09L1,cntl_connect_request() 412: Return since CNTL not IDLE,CNTL(1),SYNC(2),AUTH(0),ASSOC(0)
Wed Dec  6 11:40:49 2023 kern.warn kernel: [ 7455.006978] 7981@C09L2,sync_fsm_join_timeout_action() 1091: ProbeTimeoutAtJoinAction
Wed Dec  6 11:40:49 2023 daemon.notice netifd: wgclient (1881): Try again: `jcfREDACTED.glddns.com:REDACTED'. Trying again in 1.44 seconds...
Wed Dec  6 11:40:49 2023 kern.warn kernel: [ 7455.131446] 7981@C09L2,cntl_connect_request() 395: type=2,len=0,caller:ApCliIfUp+0x190/0x348 [mt_wifi]
Wed Dec  6 11:40:49 2023 kern.err kernel: [ 7455.140773] 7981@C09L1,cntl_connect_request() 412: Return since CNTL not IDLE,CNTL(1),SYNC(2),AUTH(0),ASSOC(0)
Wed Dec  6 11:40:51 2023 kern.warn kernel: [ 7457.022511] 7981@C09L2,sync_fsm_join_timeout_action() 1091: ProbeTimeoutAtJoinAction
Wed Dec  6 11:40:51 2023 kern.warn kernel: [ 7457.230957] 7981@C09L2,cntl_connect_request() 395: type=2,len=0,caller:ApCliIfUp+0x190/0x348 [mt_wifi]
Wed Dec  6 11:40:51 2023 kern.err kernel: [ 7457.240314] 7981@C09L1,cntl_connect_request() 412: Return since CNTL not IDLE,CNTL(1),SYNC(2),AUTH(0),ASSOC(0)
Wed Dec  6 11:40:53 2023 kern.warn kernel: [ 7459.038058] 7981@C09L2,sync_fsm_join_timeout_action() 1091: ProbeTimeoutAtJoinAction
Wed Dec  6 11:40:54 2023 kern.warn kernel: [ 7459.330577] 7981@C09L2,cntl_connect_request() 395: type=2,len=0,caller:ApCliIfUp+0x190/0x348 [mt_wifi]
Wed Dec  6 11:40:56 2023 kern.warn kernel: [ 7461.341514] 7981@C09L2,sync_fsm_join_timeout_action() 1091: ProbeTimeoutAtJoinAction
Wed Dec  6 11:40:56 2023 kern.warn kernel: [ 7461.418007] 7981@C09L2,cntl_connect_request() 395: type=2,len=0,caller:ApCliIfUp+0x190/0x348 [mt_wifi]
Wed Dec  6 11:40:56 2023 kern.err kernel: [ 7461.427336] 7981@C09L1,cntl_connect_request() 412: Return since CNTL not IDLE,CNTL(1),SYNC(2),AUTH(0),ASSOC(0)
Wed Dec  6 11:40:56 2023 daemon.notice netifd: wgclient (1881): Try again: `jcfREDACTED.glddns.com:REDACTED'. Trying again in 1.73 seconds...
Wed Dec  6 11:40:58 2023 kern.warn kernel: [ 7463.357089] 7981@C09L2,sync_fsm_join_timeout_action() 1091: ProbeTimeoutAtJoinAction
Wed Dec  6 11:40:58 2023 kern.warn kernel: [ 7463.521552] 7981@C09L2,cntl_connect_request() 395: type=2,len=0,caller:ApCliIfUp+0x190/0x348 [mt_wifi]
Wed Dec  6 11:40:58 2023 kern.err kernel: [ 7463.530898] 7981@C09L1,cntl_connect_request() 412: Return since CNTL not IDLE,CNTL(1),SYNC(2),AUTH(0),ASSOC(0)
Wed Dec  6 11:41:00 2023 kern.warn kernel: [ 7465.372582] 7981@C09L2,sync_fsm_join_timeout_action() 1091: ProbeTimeoutAtJoinAction
Wed Dec  6 11:41:00 2023 kern.warn kernel: [ 7465.625047] 7981@C09L2,cntl_connect_request() 395: type=2,len=0,caller:ApCliIfUp+0x190/0x348 [mt_wifi]
Wed Dec  6 11:41:00 2023 kern.err kernel: [ 7465.634377] 7981@C09L1,cntl_connect_request() 412: Return since CNTL not IDLE,CNTL(1),SYNC(2),AUTH(0),ASSOC(0)
Wed Dec  6 11:41:02 2023 kern.warn kernel: [ 7467.388115] 7981@C09L2,sync_fsm_join_timeout_action() 1091: ProbeTimeoutAtJoinAction
Wed Dec  6 11:41:02 2023 kern.warn kernel: [ 7467.724544] 7981@C09L2,cntl_connect_request() 395: type=2,len=0,caller:ApCliIfUp+0x190/0x348 [mt_wifi]
Wed Dec  6 11:41:02 2023 kern.err kernel: [ 7467.733874] 7981@C09L1,cntl_connect_request() 412: Return since CNTL not IDLE,CNTL(1),SYNC(2),AUTH(0),ASSOC(0)
Wed Dec  6 11:41:02 2023 daemon.notice netifd: wgclient (1881): Try again: `jcfREDACTED.glddns.com:REDACTED'. Trying again in 2.07 seconds...
Wed Dec  6 11:41:04 2023 kern.warn kernel: [ 7469.403689] 7981@C09L2,sync_fsm_join_timeout_action() 1091: ProbeTimeoutAtJoinAction
Wed Dec  6 11:41:04 2023 kern.warn kernel: [ 7469.824055] 7981@C09L2,cntl_connect_request() 395: type=2,len=0,caller:ApCliIfUp+0x190/0x348 [mt_wifi]
Wed Dec  6 11:41:04 2023 kern.err kernel: [ 7469.833388] 7981@C09L1,cntl_connect_request() 412: Return since CNTL not IDLE,CNTL(1),SYNC(2),AUTH(0),ASSOC(0)
Wed Dec  6 11:41:06 2023 kern.warn kernel: [ 7471.419189] 7981@C09L2,sync_fsm_join_timeout_action() 1091: ProbeTimeoutAtJoinAction
Wed Dec  6 11:41:06 2023 kern.warn kernel: [ 7471.923585] 7981@C09L2,cntl_connect_request() 395: type=2,len=0,caller:ApCliIfUp+0x190/0x348 [mt_wifi]
Wed Dec  6 11:41:06 2023 kern.err kernel: [ 7471.932939] 7981@C09L1,cntl_connect_request() 412: Return since CNTL not IDLE,CNTL(1),SYNC(2),AUTH(0),ASSOC(0)
Wed Dec  6 11:41:08 2023 kern.warn kernel: [ 7473.434718] 7981@C09L2,sync_fsm_join_timeout_action() 1091: ProbeTimeoutAtJoinAction
Wed Dec  6 11:41:08 2023 daemon.notice netifd: modem_0001 (31474): [12-06_11:40:03:521] requestGetSIMStatus SIMStatus: SIM_READY
Wed Dec  6 11:41:08 2023 daemon.notice netifd: modem_0001 (31474): [12-06_11:40:03:524] requestGetProfile[1] h2g2///0/IPV4V6
Wed Dec  6 11:41:08 2023 daemon.notice netifd: modem_0001 (31474): [12-06_11:40:03:524] requestSetProfile[1] h2g2///0/IPV4V6
Wed Dec  6 11:41:08 2023 daemon.notice netifd: modem_0001 (31474): [12-06_11:40:03:524] no need to set skip the rest
Wed Dec  6 11:41:08 2023 daemon.notice netifd: modem_0001 (31474): [12-06_11:40:03:527] requestRegistrationState2 MCC: 0, MNC: 0, PS: Detached, DataCap: UNKNOW
Wed Dec  6 11:41:08 2023 daemon.notice netifd: modem_0001 (31474): [12-06_11:40:03:530] requestRegistrationState2 MCC: 0, MNC: 0, PS: Detached, DataCap: UNKNOW
Wed Dec  6 11:41:08 2023 daemon.notice netifd: modem_0001 (31474): [12-06_11:40:03:534] requestQueryDataCall IPv4ConnectionStatus: DISCONNECTED
Wed Dec  6 11:41:08 2023 daemon.notice netifd: modem_0001 (31474): [12-06_11:40:03:536] requestQueryDataCall IPv6ConnectionStatus: DISCONNECTED
Wed Dec  6 11:41:08 2023 daemon.notice netifd: modem_0001 (31474): [12-06_11:40:05:992] requestRegistrationState2 MCC: 0, MNC: 0, PS: Detached, DataCap: UNKNOW
Wed Dec  6 11:41:08 2023 daemon.notice netifd: modem_0001 (31474): [12-06_11:40:08:658] requestRegistrationState2 MCC: 0, MNC: 0, PS: Detached, DataCap: UNKNOW
Wed Dec  6 11:41:08 2023 daemon.notice netifd: modem_0001 (31474): [12-06_11:40:23:677] requestRegistrationState2 MCC: 0, MNC: 0, PS: Detached, DataCap: UNKNOW
Wed Dec  6 11:41:08 2023 daemon.notice netifd: modem_0001 (31474): [12-06_11:40:38:693] requestRegistrationState2 MCC: 0, MNC: 0, PS: Detached, DataCap: UNKNOW
Wed Dec  6 11:41:08 2023 daemon.notice netifd: modem_0001 (31474): [12-06_11:40:53:697] requestRegistrationState2 MCC: 0, MNC: 0, PS: Detached, DataCap: UNKNOW
Wed Dec  6 11:41:08 2023 kern.warn kernel: [ 7474.031183] 7981@C09L2,cntl_connect_request() 395: type=2,len=0,caller:ApCliIfUp+0x190/0x348 [mt_wifi]
Wed Dec  6 11:41:08 2023 kern.err kernel: [ 7474.040524] 7981@C09L1,cntl_connect_request() 412: Return since CNTL not IDLE,CNTL(1),SYNC(2),AUTH(0),ASSOC(0)
Wed Dec  6 11:41:09 2023 daemon.info repeater: (/usr/sbin/repeater:603) shutdown apcli0
Wed Dec  6 11:41:09 2023 daemon.err repeater: (/usr/sbin/repeater:1249) connect fail
Wed Dec  6 11:41:09 2023 daemon.err repeater: (/usr/sbin/repeater:1252) reconnect in 30 seconds...

juncai · December 7, 2023, 9:21am

Hi, cemhamami
The current situation is that the 2.4Ghz and 5Ghz channels can be scanned and showed in wifi list but can`t be connected? The log shows that the connection to 2.4g failed！

cemhamami · December 7, 2023, 5:33pm

Before enabling the DFS setting, some networks were missing from the WiFi list and attempts to connect to any network that do show up was failing in this way. After enabling the DFS setting, all 2.4Ghz and 5Ghz show up in the list as expected now. However, I’m still having trouble connecting to some networks often, in this case I picked a 2.4Ghz from the list but the connection failed.

This is anecdotal but so far the 5Ghz ones seem to be succeeding and I haven’t had any luck connecting to 2.4Ghz ones, if that provides any clue.

cemhamami · December 7, 2023, 9:28pm

I just tried with a WiFi at a different location and the 2.4Ghz one still failed to connect while the 5.8Ghz one succeeded, so I think it’s very likely that something related to 2.4Ghz is the issue.

juncai · December 8, 2023, 2:06am

Is 2.4G wifi enabled?

cemhamami · December 8, 2023, 5:19pm

Yes the 2.4G wifi is enabled.

juncai · December 11, 2023, 7:07am

Hi cemhamami,
I tried to reproduce the problem, but it didn’t happen locally. Can you export the configuration on the luci page and send it to me?

cemhamami · December 14, 2023, 11:14pm

Hey @juncai

I had the 2.4Ghz channel disabled during normal usage but only had turned it on before enabling the repeater mode, and in this scenario it wasn’t working. However, after rebooting the device with the 2.4Ghz enabled, the 2.4Ghz network also started working as expected for repeater mode.

I’ll mark the DFS channel toggle as the accepter answer, but just a note that there may be a bug with needing to reboot the device for repeater mode if the 2.4Ghz has been recently enabled.