[15293.358597] 7986@C00L1,set_datcfg_ack_cts_timeout() 13443: DAT config band(1) cck_timeout Fail! [15293.367297] 7986@C00L1,set_ack_timeout_mode_byband() 13356: CTS/ACK Timeout Range should between [0xFFFF:0]!! [15293.377193] 7986@C00L1,set_datcfg_ack_cts_timeout() 13454: DAT config band(1) ofdm_timeout Fail! [15293.385960] 7986@C00L1,set_ack_timeout_mode_byband() 13356: CTS/ACK Timeout Range should between [0xFFFF:0]!! [15293.395850] 7986@C00L1,set_datcfg_ack_cts_timeout() 13465: DAT config band(1) ofdma_timeout Fail! [15293.409241] :(op = 0) [15293.411633] 7986@C00L2,red_tail_drop_init() 82: red_tail_drop_init: available token:8192, whnat_en:1 [15293.420832] 7986@C00L1,MtCmdCr4RedSet() 913: MtCmdCr4RedSet: (ret = 0) [15293.427342] 7986@C00L1,SendRedCmd() 1048: SendRedCmd:(ret = 1) [15293.433513] 7986@C01L2,RTMP_COM_IoctlHandle() 1487: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_UP [15293.441523] WiFi@C01L3,wifi_sys_open() 855: wdev idx = 0 [15293.447491] 7986@C18L3,BuildChannelList() 484: [15293.452079] 7986@C00L3,BuildChannelList() 512: BandIdx = 0, PhyMode = 78, ChListNum = 13: [15293.460282] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15293.466615] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15293.473057] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15293.479390] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15293.485849] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15293.492220] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15293.498641] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15293.505003] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15293.511449] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15293.517781] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15293.524220] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15293.530567] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15293.536988] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15293.543371] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15293.549792] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15293.556232] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15293.562672] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15293.569005] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15293.575444] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15293.581803] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15293.588225] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15293.594604] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15293.601041] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15293.607374] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15293.613812] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15293.620160] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15293.626584] 7986@C23L1,DfsBuildChannelList() 3663: [RDM]: wdev is not 5G. [15293.633459] 7986@C23L3,RadarStateCheck() 243: Zero Wait State: 0 [15293.639472] 7986@C23L3,operate_loader_phy() 387: oper_cfg: prim_ch(0), ht_bw(1), extcha(1), vht_bw(0), cen_ch_2(0), PhyMode=78! [15293.650956] phy_freq_adjust : no prim_ch value for adjust! [15293.656430] 7986@C23L1,operate_loader_phy() 397: phy_freq_adjust failed! [15293.656997] WiFi@C00L2,dump_ht_cap() 29: Caller: wlan_operate_init+0xa4/0x148 [mt_wifi] [15293.671853] wdev_attr_update(): wdevId0 = ee:00:9a:01:27:2b [15293.677499] WiFi@C12L1,wifi_sys_open() 902: wifi_sys_open():band0 group_idx[0]=0, bw_grp=16 omac=0 [15293.686516] 7986@C08L3,hw_ctrl_flow_v2_open() 13: wdev_idx=0 [15293.692731] 7986@C23L1,HcGetBandByChannel() 1229: No hdev parking on channel:0, just return a default band_idx 0! [15293.702998] 7986@C02L2,AsicRadioOnOffCtrl() 2338: DbdcIdx=0 RadioOn [15293.709847] 7986@C00L2,ApAutoChannelAtBootUp() 333: -----------------> [15293.716461] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15293.721943] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15293.727914] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(0) bss not ready (state:0, caller:MTAPAutoSelectChannel+0xf4/0x3e8 [mt_wifi])!! [15293.740216] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15293.749341] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=1, ctrl_ch2=0, cent_ch=1 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15293.798083] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=1, ctrl_ch2=0, cent_ch=1, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15294.030268] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15294.039457] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=2, ctrl_ch2=0, cent_ch=2 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15294.088229] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=2, ctrl_ch2=0, cent_ch=2, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15294.319902] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15294.329058] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=3, ctrl_ch2=0, cent_ch=3 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15294.377814] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=3, ctrl_ch2=0, cent_ch=3, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15294.609719] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15294.618859] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=4, ctrl_ch2=0, cent_ch=4 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15294.667548] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=4, ctrl_ch2=0, cent_ch=4, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15294.899532] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15294.908661] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=5, ctrl_ch2=0, cent_ch=5 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15294.957331] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=5, ctrl_ch2=0, cent_ch=5, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15295.189491] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15295.198655] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=6, ctrl_ch2=0, cent_ch=6 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15295.247291] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=6, ctrl_ch2=0, cent_ch=6, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15295.479351] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15295.488516] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=7, ctrl_ch2=0, cent_ch=7 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15295.537198] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=7, ctrl_ch2=0, cent_ch=7, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15295.769152] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15295.778290] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=8, ctrl_ch2=0, cent_ch=8 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15295.826970] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=8, ctrl_ch2=0, cent_ch=8, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15296.068943] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15296.078063] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=9, ctrl_ch2=0, cent_ch=9 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15296.126723] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=9, ctrl_ch2=0, cent_ch=9, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15296.358792] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15296.367916] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=10, ctrl_ch2=0, cent_ch=10 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15296.416735] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=10, ctrl_ch2=0, cent_ch=10, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15296.648727] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15296.657876] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=11, ctrl_ch2=0, cent_ch=11 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15296.706732] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=11, ctrl_ch2=0, cent_ch=11, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15296.948610] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15296.957770] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=12, ctrl_ch2=0, cent_ch=12 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15297.006640] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=12, ctrl_ch2=0, cent_ch=12, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15297.238499] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15297.247636] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=13, ctrl_ch2=0, cent_ch=13 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15297.296489] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=13, ctrl_ch2=0, cent_ch=13, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15297.528807] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(0) bss not ready (state:0, caller:MTAPAutoSelectChannel+0x234/0x3e8 [mt_wifi])!! [15297.541116] 7986@C23L3,SelectClearChannelBusyTime() 1880: ==================================================================== [15297.552505] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 1 : Busy Time = 11955, Skip Channel = FALSE, BwCap = TRUE [15297.563874] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 2 : Busy Time = 14485, Skip Channel = FALSE, BwCap = TRUE [15297.575239] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 3 : Busy Time = 5223, Skip Channel = FALSE, BwCap = TRUE [15297.586606] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 4 : Busy Time = 6152, Skip Channel = FALSE, BwCap = TRUE [15297.597984] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 5 : Busy Time = 12880, Skip Channel = FALSE, BwCap = TRUE [15297.609350] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 6 : Busy Time = 8218, Skip Channel = FALSE, BwCap = TRUE [15297.620728] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 7 : Busy Time = 12961, Skip Channel = FALSE, BwCap = TRUE [15297.632093] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 8 : Busy Time = 16393, Skip Channel = FALSE, BwCap = TRUE [15297.643457] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 9 : Busy Time = 4624, Skip Channel = FALSE, BwCap = TRUE [15297.654826] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 10 : Busy Time = 15587, Skip Channel = FALSE, BwCap = TRUE [15297.666193] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 11 : Busy Time = 10703, Skip Channel = FALSE, BwCap = TRUE [15297.677559] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 12 : Busy Time = 7565, Skip Channel = FALSE, BwCap = TRUE [15297.688925] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 13 : Busy Time = 7405, Skip Channel = FALSE, BwCap = TRUE [15297.700289] 7986@C23L3,SelectClearChannelBusyTime() 1889: ==================================================================== [15297.711667] 7986@C23L3,SelectClearChannelBusyTime() 2124: Rule 3 Channel Busy time value : Select Primary Channel 9 [15297.722080] 7986@C23L3,SelectClearChannelBusyTime() 2126: Rule 3 Channel Busy time value : Min Channel Busy = 4624 [15297.732408] 7986@C23L3,SelectClearChannelBusyTime() 2128: Rule 3 Channel Busy time value : BW = 20 [15297.741347] 7986@C23L3,SelectClearChannelBusyTime() 2166: [SelectClearChannelBusyTime] - band0 END [15297.750338] 7986@C23L3,operate_loader_phy() 387: oper_cfg: prim_ch(9), ht_bw(1), extcha(1), vht_bw(0), cen_ch_2(0), PhyMode=78! [15297.761800] 7986@C23L3,operate_loader_phy() 402: oper_dev after adjust: bw(1), prim_ch(9), cen_ch_1(11), cen_ch_2(0),ext_cha(1)! [15297.773353] 7986@C23L3,operate_loader_phy() 423: oper_radio after decision: bw(1), prim_ch(9), cen_ch_1(11), cen_ch_2(0)! [15297.784443] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15297.793571] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=9, ctrl_ch2=0, cent_ch=11 DBDCIdx=0, ChBand=0, BW=1, TXStream=2, RXStream=2, scan(0) [15297.947387] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=9, ctrl_ch2=0, cent_ch=11, RxPath=3, BandIdx=0, ChBand=0, BW=1,TXStream=2, RXStream=3, scan(0) [15297.961049] 7986@C12L1,EDCCAInit() 21135: EDCCA compensation: uni_compensation=0, bw_compensation=0, final compensation=0 [15297.972021] 7986@C23L1,DfsBuildChannelList() 3663: [RDM]: wdev is not 5G. [15297.978800] 7986@C23L3,zero_wait_dfs_switch_ch() 924: outband ch 0, ch_stat 0 [15297.986481] 7986@C08L2,APStartUpForMbss() 983: ===>(caller:ap_inf_open+0x10c/0x4c0 [mt_wifi]), mbss_idx:0, CfgMode:0 [15297.997017] 7986@C15L2,APPMFInit() 130: [PMF] apidx=0, MFPC=1, MFPR=0, SHA256=0 [15298.043068] 7986@C18L3,BuildChannelList() 484: [15298.048180] WiFi@C00L2,dump_ht_cap() 29: Caller: dump_ht_cap+0x10/0x1c [mt_wifi] [15298.056045] WiFi@C08L2,ap_link_up() 7166: (caller:wdev_do_linkup+0x20/0x34 [mt_wifi]), wdev(0) [15298.064689] 7986@C01L3,wifi_sys_linkup() 1231: wdev idx = 0 [15298.121267] 7986@C00L3,wtc_acquire_groupkey_wcid() 81: Found a non-occupied wtbl_idx:543 for WDEV_TYPE:1 [15298.121267] LinkToOmacIdx = 0, LinkToWdevType = 1 [15298.135655] 7986@C08L3,hw_ctrl_flow_v2_link_up() 72: wdev_idx=0 [15298.156309] 7986@C08L3,UpdateBeaconHandler() 1910: BCN_UPDATE_INIT, OmacIdx = 0 (ra0) [15298.164148] 7986@C08L2,bcn_buf_init() 169: BcnPkt is allocated!, bcn offload=1 [15298.171357] 7986@C08L3,UpdateBeaconHandler() 1923: Band0 BcnInitedRnd = 42 [15298.178402] 7986@C00L1,TxCCKStreamCtrl() 20601: set wrong parameters [15298.193276] PrintSrCmd: [15298.193276] u1CmdSubId = 1, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0 [15298.193276] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 1 [15298.207267] PrintSrCmd: [15298.207267] u1CmdSubId = 5, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0 [15298.207267] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15298.221241] PrintSrCmd: [15298.221241] u1CmdSubId = 3, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0 [15298.221241] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 1 [15298.235226] PrintSrCmd: [15298.235226] u1CmdSubId = 23, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0 [15298.235226] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15298.249294] PrintSrCmd: [15298.249294] u1CmdSubId = 31, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0 [15298.249294] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15298.263362] PrintSrCmd: [15298.263362] u1CmdSubId = 33, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0 [15298.263362] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15298.277463] 7986@C12L1,EDCCAInit() 21135: EDCCA compensation: uni_compensation=0, bw_compensation=0, final compensation=0 [15298.288414] 7986@C03L3,MtCmdTxPwrShowInfo() 13745: ucTxPowerInfoCatg: 2, BandIdx: 0 [15298.296151] 7986@C03L3,MtCmdTxPwrShowInfo() 13774: (ret = 0) [15298.301825] [mtf_txpower_all_rate_info] band_idx:0 pwr:49 ChBand:2G ePAGain:0 [15298.309026] extif_set_dev(ra0) [15298.312068] mtk_ppe_dev_register_hook : ineterface ra0 register (1) [15298.318614] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15298.330017] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15298.335922] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15298.349575] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15298.375047] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15298.388075] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15298.394485] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15298.414753] br-lan: port 2(ra0) entered blocking state [15298.419981] br-lan: port 2(ra0) entered disabled state [15298.425158] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15298.430876] ra0: entered allmulticast mode [15298.435054] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15298.440884] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15298.446558] ra0: entered promiscuous mode [15298.450874] br-lan: port 2(ra0) entered blocking state [15298.456040] br-lan: port 2(ra0) entered forwarding state [15298.594920] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15298.600745] 7986@C08L2,mbss_virtual_if_open() 133: ===> rax0 [15298.606559] 7986@C01L2,RTMP_COM_IoctlHandle() 1510: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_INIT [15298.614698] 7986@C01L2,RTMP_COM_IoctlHandle() 1487: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_UP [15298.622744] WiFi@C01L3,wifi_sys_open() 855: wdev idx = 1 [15298.628502] 7986@C18L3,BuildChannelList() 484: [15298.633046] 7986@C00L3,BuildChannelList() 512: BandIdx = 1, PhyMode = 177, ChListNum = 13: [15298.641392] 7986@C23L3,DfsBuildChannelList() 3708: Done [15298.646665] 7986@C23L3,RadarStateCheck() 243: Zero Wait State: 0 [15298.652774] 7986@C23L3,RadarStateCheck() 290:  RD_NORMAL_MODE  [15298.659665] 7986@C23L3,operate_loader_phy() 387: oper_cfg: prim_ch(36), ht_bw(1), extcha(1), vht_bw(2), cen_ch_2(0), PhyMode=177! [15298.671354] 7986@C23L3,operate_loader_phy() 402: oper_dev after adjust: bw(3), prim_ch(36), cen_ch_1(50), cen_ch_2(0),ext_cha(1)! [15298.683033] 7986@C23L3,operate_loader_phy() 423: oper_radio after decision: bw(3), prim_ch(36), cen_ch_1(50), cen_ch_2(0)! [15298.694249] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15298.703440] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=36, ctrl_ch2=0, cent_ch=50 DBDCIdx=1, ChBand=1, BW=3, TXStream=3, RXStream=3, scan(0) [15298.957587] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=36, ctrl_ch2=0, cent_ch=50, RxPath=7, BandIdx=1, ChBand=1, BW=3,TXStream=3, RXStream=7, scan(0) [15298.976366] 7986@C12L1,EDCCAInit() 21135: EDCCA compensation: uni_compensation=0, bw_compensation=0, final compensation=0 [15298.987707] WiFi@C00L2,dump_ht_cap() 29: Caller: wlan_operate_init+0xa4/0x148 [mt_wifi] [15298.995742] wdev_attr_update(): wdevId1 = ee:00:9a:11:27:2b [15299.001402] WiFi@C12L1,wifi_sys_open() 902: wifi_sys_open():band1 group_idx[1]=4, bw_grp=16 omac=0 [15299.010425] 7986@C08L3,hw_ctrl_flow_v2_open() 13: wdev_idx=1 [15299.017565] 7986@C02L2,AsicRadioOnOffCtrl() 2338: DbdcIdx=1 RadioOn [15299.024472] 7986@C00L2,ApAutoChannelAtBootUp() 333: -----------------> [15299.031165] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15299.036681] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15299.037152] BcnCheck start after 4200 ms (ra0) [15299.042103] 7986@C00L1,ApAutoChannelAtBootUp() 490: zero-wait DFS is not enabled [15299.046983] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(1) bss not ready (state:0, caller:update_ap_qload_to_bcn+0xa8/0xd4 [mt_wifi])!! [15299.053914] 7986@C08L1,ap_run_at_boot() 622:  ACS is disable !! [15299.066632] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(1) bss not ready (state:0, caller:APUpdateOperationMode+0x114/0x274 [mt_wifi])!! [15299.072972] 7986@C08L2,APStartUpForMbss() 983: ===>(caller:ap_inf_open+0x10c/0x4c0 [mt_wifi]), mbss_idx:1, CfgMode:0 [15299.095481] 7986@C15L2,APPMFInit() 130: [PMF] apidx=1, MFPC=1, MFPR=0, SHA256=0 [15299.141376] 7986@C18L3,BuildChannelList() 484: [15299.146167] WiFi@C00L2,dump_ht_cap() 29: Caller: dump_ht_cap+0x10/0x1c [mt_wifi] [15299.153806] WiFi@C08L2,ap_link_up() 7166: (caller:wdev_do_linkup+0x20/0x34 [mt_wifi]), wdev(1) [15299.162417] 7986@C01L3,wifi_sys_linkup() 1231: wdev idx = 1 [15299.218824] 7986@C00L3,wtc_acquire_groupkey_wcid() 81: Found a non-occupied wtbl_idx:542 for WDEV_TYPE:1 [15299.218824] LinkToOmacIdx = 0, LinkToWdevType = 1 [15299.233197] 7986@C08L3,hw_ctrl_flow_v2_link_up() 72: wdev_idx=1 [15299.246016] 7986@C08L3,UpdateBeaconHandler() 1910: BCN_UPDATE_INIT, OmacIdx = 0 (rax0) [15299.253924] 7986@C08L2,bcn_buf_init() 169: BcnPkt is allocated!, bcn offload=1 [15299.261158] 7986@C08L3,UpdateBeaconHandler() 1923: Band1 BcnInitedRnd = 51 [15299.279170] PrintSrCmd: [15299.279170] u1CmdSubId = 1, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0 [15299.279170] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 1 [15299.293161] PrintSrCmd: [15299.293161] u1CmdSubId = 5, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0 [15299.293161] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15299.307134] PrintSrCmd: [15299.307134] u1CmdSubId = 3, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0 [15299.307134] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 1 [15299.321102] PrintSrCmd: [15299.321102] u1CmdSubId = 23, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0 [15299.321102] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15299.335158] PrintSrCmd: [15299.335158] u1CmdSubId = 31, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0 [15299.335158] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15299.349217] PrintSrCmd: [15299.349217] u1CmdSubId = 33, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0 [15299.349217] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15299.363314] 7986@C12L1,EDCCAInit() 21135: EDCCA compensation: uni_compensation=0, bw_compensation=0, final compensation=0 [15299.374261] 7986@C03L3,MtCmdTxPwrShowInfo() 13745: ucTxPowerInfoCatg: 2, BandIdx: 1 [15299.382010] 7986@C03L3,MtCmdTxPwrShowInfo() 13774: (ret = 0) [15299.382111] [mtf_txpower_all_rate_info] band_idx:1 pwr:50 ChBand:5G ePAGain:0 [15299.387675] extif_set_dev(rax0) [15299.397897] mtk_ppe_dev_register_hook : ineterface rax0 register (2) [15299.404471] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15299.446446] br-lan: port 3(rax0) entered blocking state [15299.451747] br-lan: port 3(rax0) entered disabled state [15299.457039] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15299.462708] rax0: entered allmulticast mode [15299.467009] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15299.472810] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15299.478514] rax0: entered promiscuous mode [15299.482771] br-lan: port 3(rax0) entered blocking state [15299.488035] br-lan: port 3(rax0) entered forwarding state [15301.754245] 7986@C08L3,ap_peer_auth_req_at_idle_action() 713: AUTH - MBSS(1), Rcv AUTH seq#1, Alg=3, Status=126 from [wcid=1023]b8:f7:75:77:76:7b [15301.825868] 7986@C08L3,ap_peer_auth_req_at_idle_action() 713: AUTH - MBSS(1), Rcv AUTH seq#2, Alg=3, Status=0 from [wcid=1023]b8:f7:75:77:76:7b [15301.838773] entrytb_aid_aquire(): found non-occupied aid:2, allocated from:2 [15301.845816] 7986@C13L2,MacTableInsertEntry() 1537: New Sta:b8:f7:75:77:76:7b [15301.852875] 7986@C08L1,PeerAssocReqCmmSanity() 984: DSCPPolicyEnable = 1 [15301.859562] 7986@C08L1,PeerAssocReqCmmSanity() 1445: IE_WLAN_EXTENSION: no handler for extension_id:137 [15301.868934] 7986@C08L3,ap_cmm_peer_assoc_req_action() 1645: Recv Assoc from STA - b8:f7:75:77:76:7b [15301.878260] 7986@C08L3,ap_cmm_peer_assoc_req_action() 2168: ASSOC Send ASSOC response (Status=0)... [15301.885709] BcnCheck start after 1700 ms (ra0) [15301.887321] 7986@C01L3,wifi_sys_conn_act() 1115: wdev idx = 1 [15301.891708] BcnCheck start after 2600 ms (ra0) [15301.902048] 7986@C08L3,hw_ctrl_flow_v2_connt_act() 215: wdev_idx=1 [15302.026177] 7986@C15L3,WPABuildPairMsg1() 5278: <=== send Msg1 of 4-way [15302.041204] 7986@C15L3,PeerPairMsg2Action() 6197: ===>Receive msg 2 [15302.047977] 7986@C15L3,WPABuildPairMsg3() 5555: <=== send Msg3 of 4-way [15302.054581] 7986@C15L3,PeerPairMsg4Action() 6627: ===>Receive msg 4 [15302.066165] 7986@C15L2,PeerPairMsg4Action() 6982: AP SETKEYS DONE(rax0) - AKMMap=WPA3PSK, PairwiseCipher=AES, GroupCipher=AES, wcid=1 from b8:f7:75:77:76:7b [15302.066165] [15304.674320] BcnCheck start after 100 ms (ra0) [15306.160655] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15318.102004] 7986@C01L2,wifi_sys_disconn_act() 1002: wdev_idx=1 [15318.108947] 7986@C08L3,hw_ctrl_flow_v2_disconnt_act() 172: wdev_idx=1 [15318.116172] 7986@C13L2,MacTableDeleteEntry() 1793: Del Sta:b8:f7:75:77:76:7b [15318.123551] 7986@C08L3,ap_peer_deauth_action() 427: AUTH - receive DE-AUTH(seq-25) from b8:f7:75:77:76:7b, reason=1 [15318.154205] 7986@C08L3,ap_peer_auth_req_at_idle_action() 713: AUTH - MBSS(1), Rcv AUTH seq#1, Alg=0, Status=0 from [wcid=1023]b8:f7:75:77:76:7b [15318.167237] entrytb_aid_aquire(): found non-occupied aid:2, allocated from:2 [15318.174382] 7986@C13L2,MacTableInsertEntry() 1537: New Sta:b8:f7:75:77:76:7b [15318.184886] 7986@C08L1,PeerAssocReqCmmSanity() 984: DSCPPolicyEnable = 1 [15318.191705] 7986@C08L1,PeerAssocReqCmmSanity() 1445: IE_WLAN_EXTENSION: no handler for extension_id:137 [15318.201138] 7986@C08L3,ap_cmm_peer_assoc_req_action() 1645: Recv Assoc from STA - b8:f7:75:77:76:7b [15318.210541] 7986@C08L3,ap_cmm_peer_assoc_req_action() 2168: ReASSOC Send ReASSOC response (Status=0)... [15318.220066] 7986@C01L3,wifi_sys_conn_act() 1115: wdev idx = 1 [15318.226212] 7986@C08L3,hw_ctrl_flow_v2_connt_act() 215: wdev_idx=1 [15318.256371] 7986@C15L1,store_pmkid_cache_in_sec_config() 3326: pStaCfg is null [15318.368268] 7986@C15L3,WPABuildPairMsg1() 5278: <=== send Msg1 of 4-way [15318.376613] 7986@C15L3,PeerPairMsg2Action() 6197: ===>Receive msg 2 [15318.383746] 7986@C15L3,WPABuildPairMsg3() 5555: <=== send Msg3 of 4-way [15318.392258] 7986@C15L3,PeerPairMsg4Action() 6627: ===>Receive msg 4 [15318.404025] 7986@C15L2,PeerPairMsg4Action() 6982: AP SETKEYS DONE(rax0) - AKMMap=WPA3PSK, PairwiseCipher=AES, GroupCipher=AES, wcid=1 from b8:f7:75:77:76:7b [15318.404025] [15319.824939] net wwan0: link_state 0x0 -> 0x1 [15319.832246] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15319.838177] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15319.843825] extif_set_dev(wwan0) [15319.847284] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15319.853297] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15319.859183] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15319.864856] extif_set_dev(wwan0_1) [15320.356916] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15320.363063] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15320.420762] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15320.427183] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15320.856444] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15328.285003] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15377.782871] qmi_wwan_q 2-1:1.4 wwan0: NETDEV WATCHDOG: CPU: 3: transmit queue 0 timed out 5670 ms [15395.333506] 7986@C01L2,wifi_sys_disconn_act() 1002: wdev_idx=1 [15395.339700] 7986@C08L3,hw_ctrl_flow_v2_disconnt_act() 172: wdev_idx=1 [15395.346770] 7986@C13L2,MacTableDeleteEntry() 1793: Del Sta:b8:f7:75:77:76:7b [15395.353925] 7986@C08L3,ap_peer_deauth_action() 427: AUTH - receive DE-AUTH(seq-37) from b8:f7:75:77:76:7b, reason=1 [15397.726924] 7986@C08L3,ap_peer_auth_req_at_idle_action() 713: AUTH - MBSS(1), Rcv AUTH seq#1, Alg=0, Status=0 from [wcid=1023]b8:f7:75:77:76:7b [15397.739819] entrytb_aid_aquire(): found non-occupied aid:2, allocated from:2 [15397.746865] 7986@C13L2,MacTableInsertEntry() 1537: New Sta:b8:f7:75:77:76:7b [15397.755618] 7986@C08L1,PeerAssocReqCmmSanity() 984: DSCPPolicyEnable = 1 [15397.762312] 7986@C08L1,PeerAssocReqCmmSanity() 1445: IE_WLAN_EXTENSION: no handler for extension_id:137 [15397.771691] 7986@C08L3,ap_cmm_peer_assoc_req_action() 1645: Recv Assoc from STA - b8:f7:75:77:76:7b [15397.780995] 7986@C08L3,ap_cmm_peer_assoc_req_action() 2168: ReASSOC Send ReASSOC response (Status=0)... [15397.790415] 7986@C01L3,wifi_sys_conn_act() 1115: wdev idx = 1 [15397.796421] 7986@C08L3,hw_ctrl_flow_v2_connt_act() 215: wdev_idx=1 [15397.826167] 7986@C15L1,store_pmkid_cache_in_sec_config() 3326: pStaCfg is null [15397.940852] 7986@C15L3,WPABuildPairMsg1() 5278: <=== send Msg1 of 4-way [15397.953878] 7986@C15L3,PeerPairMsg2Action() 6197: ===>Receive msg 2 [15397.960705] 7986@C15L3,WPABuildPairMsg3() 5555: <=== send Msg3 of 4-way [15397.967307] 7986@C15L3,PeerPairMsg4Action() 6627: ===>Receive msg 4 [15397.978915] 7986@C15L2,PeerPairMsg4Action() 6982: AP SETKEYS DONE(rax0) - AKMMap=WPA3PSK, PairwiseCipher=AES, GroupCipher=AES, wcid=1 from b8:f7:75:77:76:7b [15397.978915] [15401.767938] qmi_wwan_q 2-1:1.4 wwan0: NETDEV WATCHDOG: CPU: 3: transmit queue 0 timed out 5360 ms [15410.039764] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15410.045445] extif_put_dev(wwan0_1) [15410.110620] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15410.122608] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15410.243763] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15410.249686] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15410.255651] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15410.261320] extif_set_dev(wwan0_1) [15410.283030] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15410.288904] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15410.815440] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15410.821662] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15410.869010] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15410.874852] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15411.122698] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15419.756787] qmi_wwan_q 2-1:1.4 wwan0: NETDEV WATCHDOG: CPU: 3: transmit queue 0 timed out 5540 ms [15442.686284] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15442.696229] 7986@C01L2,RTMP_COM_IoctlHandle() 1498: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_DOWN [15442.704689] WiFi@C08L2,ap_link_down() 7184: (caller:wdev_do_linkdown+0x20/0x34 [mt_wifi]), wdev(0) [15442.714182] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(0) bss not ready (state:2, caller:ap_link_down+0x80/0x134 [mt_wifi])!! [15442.725635] 7986@C01L3,wifi_sys_linkdown() 1363: wdev idx = 0 [15442.732476] 7986@C08L3,hw_ctrl_flow_v2_link_down() 123: wdev_idx=0 [15442.741542] WiFi@C01L3,wifi_sys_close() 945: wdev idx = 0 [15442.747545] 7986@C08L3,hw_ctrl_flow_v2_close() 41: wdev_idx=0 [15442.753551] 7986@C01L2,RTMP_COM_IoctlHandle() 1521: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_DEINIT [15442.761805] extif_put_dev(ra0) [15442.764905] mtk_ppe_dev_unregister_hook : ineterface ra0 set null (1) [15442.781027] br-lan: port 2(ra0) entered disabled state [15442.788182] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15442.800997] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15442.806743] extif_put_dev(wwan0_1) [15442.926446] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15442.935557] ra0: left allmulticast mode [15442.939544] ra0: left promiscuous mode [15442.943470] br-lan: port 2(ra0) entered disabled state [15442.992538] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15442.998350] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15443.061999] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15443.073308] 7986@C08L2,mbss_virtual_if_close() 170: ===> rax0 [15443.079147] 7986@C01L2,RTMP_COM_IoctlHandle() 1498: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_DOWN [15443.087755] WiFi@C08L2,ap_link_down() 7184: (caller:wdev_do_linkdown+0x20/0x34 [mt_wifi]), wdev(1) [15443.097075] 7986@C01L2,wifi_sys_disconn_act() 1002: wdev_idx=1 [15443.103355] 7986@C08L3,hw_ctrl_flow_v2_disconnt_act() 172: wdev_idx=1 [15443.110653] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(1) bss not ready (state:2, caller:hw_ctrl_ops_v1_register+0x25c/0x888 [mt_wifi])!! [15443.123429] 7986@C13L2,MacTableDeleteEntry() 1793: Del Sta:b8:f7:75:77:76:7b [15443.130967] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(1) bss not ready (state:2, caller:ap_link_down+0x80/0x134 [mt_wifi])!! [15443.142401] 7986@C01L3,wifi_sys_linkdown() 1363: wdev idx = 1 [15443.148478] 7986@C08L3,hw_ctrl_flow_v2_link_down() 123: wdev_idx=1 [15443.157667] WiFi@C01L3,wifi_sys_close() 945: wdev idx = 1 [15443.163451] 7986@C08L3,hw_ctrl_flow_v2_close() 41: wdev_idx=1 [15443.169338] 7986@C01L2,RTMP_COM_IoctlHandle() 1521: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_DEINIT [15443.177848] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(0) bss not ready (state:0, caller:APStopByBss+0xd4/0x440 [mt_wifi])!! [15443.189151] 7986@C01L2,wifi_sys_disconn_act() 1002: wdev_idx=0 [15443.195622] WiFi@C08L2,ap_link_down() 7184: (caller:wdev_do_linkdown+0x20/0x34 [mt_wifi]), wdev(0) [15443.204868] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(1) bss not ready (state:0, caller:APStopByBss+0xd4/0x440 [mt_wifi])!! [15443.216488] WiFi@C08L2,ap_link_down() 7184: (caller:wdev_do_linkdown+0x20/0x34 [mt_wifi]), wdev(1) [15443.225615] ch_switch_monitor_exit:: Exit [15443.234655] current sync CR = 0x7 [15443.237986] current sync CR = 0x7 [15443.242288] current sync CR = 0x7 [15443.246593] current sync CR = 0x7 [15443.250892] current sync CR = 0x1 [15443.354728] wdma_dma_ctrl(): WDMA_GLO_CFG=50804e75, txrx = 0 [15443.360393] wdma_dma_ctrl(): WDMA_GLO_CFG=40804e74, txrx = 0 [15443.366060] warp_woctrl_send_msg_and_wait_cr(), idx:0, state:DISABLE [15443.372405] warp_woctrl_send_msg_and_wait_cr(): wait for wocpu [15443.378217] [tmp]warp_woctrl_send_msg_and_wait_cr(): wait for wocpu, value:1, value_done:1 [15443.386464] warp_woctrl_send_msg_and_wait_cr(): wocpu is ready, done_value:1 [15443.393495] warp_woctrl_kickout(), idx: 0, state:HALT [15443.398528] warp_woctrl_kickout(): wait for wocpu [15443.403219] [tmp]warp_woctrl_kickout(): wait for wocpu, value:7, value_done:7 [15443.410332] warp_woctrl_kickout(): wocpu is ready, done_value:7 [15443.445027] wdma_dma_ctrl(): WDMA_GLO_CFG=40804e70, txrx = 0 [15443.455102] [Nop_List_Backup] NopList Channel count:0 <---- [15443.460916] wed_ser_task(): wed_ser exist, wed->irq=123! [15443.481524] wo_exception_exit(0): wo_exception_exit done [15443.486931] warp_msg_receive(),go,warp,10581,0 [15443.491376] warp_msg_process(): module id(0), wed_idx(0), something wrong(ret = -1) [15443.499072] warp_msg_unregister():done [15443.503205] warp_client_remove(): warp entry clear done!! [15443.508611] extif_put_dev(rax0) [15443.511740] mtk_ppe_dev_unregister_hook : ineterface rax0 set null (2) [15443.537772] br-lan: port 3(rax0) entered disabled state [15443.545934] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15443.554236] rax0: left allmulticast mode [15443.558165] rax0: left promiscuous mode [15443.562119] br-lan: port 3(rax0) entered disabled state [15443.652272] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15443.658063] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.339051] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.348900] br-lan: port 1(eth0) entered disabled state [15444.394685] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.400584] mtk_soc_eth 15100000.ethernet eth0: left allmulticast mode [15444.407165] mtk_soc_eth 15100000.ethernet eth0: left promiscuous mode [15444.413683] br-lan: port 1(eth0) entered disabled state [15444.451528] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.457227] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.463360] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.469109] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.651814] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.662288] mtk_soc_eth 15100000.ethernet eth1: Link is Down [15444.688377] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.697679] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.707668] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.720464] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.730914] mtk_soc_eth 15100000.ethernet eth0: Link is Down [15444.738839] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.746936] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.753226] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.763240] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.771705] mtk_soc_eth 15100000.ethernet eth0: configuring for fixed/2500base-x link mode [15444.780160] mtk_soc_eth 15100000.ethernet eth0: Link is Up - 2.5Gbps/Full - flow control off [15444.780229] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.794378] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.800132] br-lan: port 1(eth0) entered blocking state [15444.805374] br-lan: port 1(eth0) entered disabled state [15444.810600] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.816260] mtk_soc_eth 15100000.ethernet eth0: entered allmulticast mode [15444.823129] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.828833] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.834500] mtk_soc_eth 15100000.ethernet eth0: entered promiscuous mode [15444.841324] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.847000] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.852795] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.859205] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.865020] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.870991] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.876659] br-lan: port 1(eth0) entered blocking state [15444.881873] br-lan: port 1(eth0) entered forwarding state [15444.887346] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.895644] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.901633] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.908826] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.914664] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.920432] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.926162] mtk_soc_eth 15100000.ethernet eth1: configuring for fixed/2500base-x link mode [15444.934645] mtk_soc_eth 15100000.ethernet eth1: Link is Up - 2.5Gbps/Full - flow control off [15444.934701] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15444.948988] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15445.093732] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15445.101139] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15445.130738] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15445.136518] 7986@C01L2,RTMP_COM_IoctlHandle() 1510: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_INIT [15445.145425] msta_ifname not found [15445.149135] warp_cap_support(): chip_id=7986 is in WHNAT support list [15445.155620] warp_entry_acquire(): bus slot:0, hook to WARP, entry id=0, entry: 00000000ab2988e2 [15445.164543] client_hif_specific_get(): hw->base_phy_addr 0x18000000!! [15445.171187] warp_entry_get_by_pdev(): return entry[0] [15445.176394] warp_msg_register():warp,pid,26343,id,0,wed_idx,0 [15445.182138] warp_dev6 15010000.wed: Using 32bit DMA for streaming map [15445.188560] warp_dev6 15010000.wed: Using 32bit DMA for coherent map [15445.195026] wed_init(0): res_start = 0x15010000, size = 1000, irq=123,base_addr=0xffffffc080f2b000 [15445.204382] wed_ring_init(): wed tx ring init result = 0 [15445.210225] wed_ring_init(): wed rx ring init result = 0 [15445.225957] create wed ok!!! [15445.228961] wdma_init(): wdma(0) base_addr=0xffffffc08108b800, base_phy_addr=0x15104800 [15445.237908] warp_woif_bus_init_hw(): bus:0000000077c4784b, txring: 20, pa: 0x00000000501f0000, 0x00000000501f0000, 24, 28, 3c [15445.249236] warp_woif_bus_init_hw(): bus:0000000077c4784b, rxring: 50, pa: 0x000000005143a000, 0x000000005143a000, 54, 58, 6c [15445.260600] get_region_info(): wed_idx = 0, base_addr = 0x00000000cc89b378, size = 0x40000, res.start = 0x4fd00000, shared:0 [15445.271926] get_region_info(): wed_idx = 0, base_addr = 0x0000000068387239, size = 0x8000, res.start = 0x151e0000, shared:0 [15445.283089] get_region_info(): wed_idx = 0, base_addr = 0x00000000a6a99320, size = 0x240000, res.start = 0x4fd80000, shared:1 [15445.294458] get_region_info(): wed_idx = 0, base_addr = 0x000000001f5ded8c, size = 0x1000, res.start = 0x15194000, shared:0 [15445.305569] warp_fwdl_mcu_mode(): mcu mode, need fwdl [15445.312752] ########## WO Firmware ########## [15445.317110] Chip ID: 0x0000 [15445.319889] ECO version:0 [15445.322522] Version: DEV_000000 [15445.325649] Build date: 20230814174927 [15445.329380] Total region:3 [15445.332078] ################################# [15445.336416] Parsing tailer region 0 [15445.339888] Target address: 0x151e0000 [15445.343712] Download size: 22112 [15445.347038] Parsing tailer region 1 [15445.350510] Target address: 0x4fd00000 [15445.354334] Download size: 87728 [15445.357706] Parsing tailer region 2 [15445.361183] Target address: 0x4fd80000 [15445.365001] Download size: 2345040 [15445.368961] warp_fwdl_ready_check_mcu_mode(): waiting for wocpu [15445.388560] warp_fwdl_ready_check_mcu_mode(): wocpu is ready [15445.394238] warp_woctrl_init_state(), wo_state: WO_STATE_ENABLE [15445.400147] wo_proc_init done 00000000e307ad2e [15445.404675] wo_exep_proc_init done 00000000e307ad2e [15445.409659] wo_exception_init(0): exp log= 0x000000002ce9ef7d, phy_addr= 0x0000000051698000 size= 32768 [15445.419189] warp_probe(): probe fail since warp is full! [15445.424502] warp_dev6: probe of 15010000.wed2 failed with error -1 [15445.430901] 7986@C01L3,mt_wifi_open() 342: whnat_en=1 [15445.473923] 7986@C12L1,RT_CfgSetMacAddress() 759: invalid length (0) [15445.480344] 7986@C12L1,RT_CfgSetMacAddress() 759: invalid length (0) [15445.486871] 7986@C12L1,RT_CfgSetMacAddress() 759: invalid length (0) [15445.493946] 7986@C12L1,RTMPWirelessModeCfg() 611: Init: BSS0 PhyMode=78 [15445.500575] 7986@C12L1,RTMPWirelessModeCfg() 611: Init: BSS1 PhyMode=177 [15445.507526] 7986@C23L3,auto_ch_select_set_cfg() 3456: BandIdx0, AutoChannelAtBootup=1, AutoChannelAlg = 3 [15445.517121] 7986@C23L3,auto_ch_select_set_cfg() 3456: BandIdx1, AutoChannelAtBootup=0, AutoChannelAlg = 0 [15445.544255] 7986@C15L1,Set_PMFMFPC_Proc() 1721: [PMF] :: apidx=0, Desired MFPC=1 [15445.551702] 7986@C15L1,Set_PMFMFPC_Proc() 1721: [PMF] :: apidx=1, Desired MFPC=1 [15445.559690] 7986@C12L1,RTMPSetProfileParameters() 8611: ra0, QoSMgmtCapa=0 [15445.566605] 7986@C12L1,RTMPSetProfileParameters() 8611: rax0, QoSMgmtCapa=0 [15445.574836] 7986@C12L1,Set_ApCliPMFMFPC_Proc() 2000: [PMF]: MFPC=0, MFPR=0, SHA256=0 [15445.582640] 7986@C12L1,Set_ApCliPMFMFPC_Proc() 2000: [PMF]: MFPC=0, MFPR=0, SHA256=0 [15445.590452] 7986@C12L1,Set_ApCliPMFMFPR_Proc() 2064: [PMF]: MFPC=0, MFPR=0, SHA256=0 [15445.598192] 7986@C12L1,Set_ApCliPMFMFPR_Proc() 2064: [PMF]: MFPC=0, MFPR=0, SHA256=0 [15445.606209] 7986@C12L1,rtmp_read_ap_client_from_file() 1638: APCLI[0] ApCliMuMimoDlEnable = 0 [15445.614716] 7986@C12L1,rtmp_read_ap_client_from_file() 1638: APCLI[1] ApCliMuMimoDlEnable = 0 [15445.623295] 7986@C12L1,rtmp_read_ap_client_from_file() 1657: APCLI[0] ApCliMuMimoUlEnable = 0 [15445.631801] 7986@C12L1,rtmp_read_ap_client_from_file() 1657: APCLI[1] ApCliMuMimoUlEnable = 0 [15445.668427] wdma_dma_ctrl(): WDMA_GLO_CFG=40804e70, txrx = 0 [15445.685026] wdma_dma_ctrl(): WDMA_GLO_CFG=40804e70, txrx = 0 [15445.708884] warp_tx_ring_init_hw(): configure ring 0 setting [15445.714654] warp_tx_ring_init_hw(): wed:00000000b690c3a7 wifi:0000000077cc8647: 24420=454a0000,24424=2048,24428=0 [15445.724931] warp_tx_ring_init_hw(): configure ring 1 setting [15445.730571] warp_tx_ring_init_hw(): wed:00000000b690c3a7 wifi:0000000077cc8647: 24430=508f0000,24434=2048,24438=0 [15445.740945] set_rrocfg,0 [15445.766442] wdma_dma_ctrl(): WDMA_GLO_CFG=40804e74, txrx = 3 [15445.772274]  sku:0x7, ADIE=MT7976  [15445.776625] using E1 ROM patch [15445.779673] using E1 RAM [15445.782296] current sync CR = 0x1 [15445.787883] Built date: 20230814174703a [15445.792060] [15445.793548] Platform: ALPS [15445.796336] HW/SW version: 0x8a108a10 [15445.800075] Patch version: 0xffffffff [15445.804603] current sync CR = 0x1 [15445.816972] Chip ID: 0x0f [15445.819730] Eco version: 0x00 [15445.823008] Region number: 0x0b [15445.826252] Format version: 0x02 [15445.829560] Format flag: 0x01 [15445.832676] Ram version: ____000000 [15445.836272] Built date: 20230814174718 [15445.840108] Common crc: 0xea70993a [15445.843630] Release info: header tag = 0, total length = 80 [15445.849295] tag 1, padding length = 1, tag length = 75 [15445.854559] payload: t-neptune-main-2111-7986-imp-MT7986_E1_ASIC_ROM_RAM_REBB_IMP-20230814174618 [15445.883157] Chip ID: 0x00 [15445.885921] Eco version: 0x00 [15445.888966] Region number: 0x03 [15445.892253] Format version: 0x02 [15445.895581] Format flag: 0x01 [15445.898626] Ram version: DEV_000000 [15445.902295] Built date: 20230814174901 [15445.906160] Common crc: 0xc02c55e5 [15445.909646] Release info: header tag = 0, total length = 0 [15446.012580] current sync CR = 0x7 [15446.016150] efuse_probe: efuse = deaddead [15446.020239]  sku:0x7, ADIE=MT7976  [15446.024768] Use default BIN from:/lib/firmware/MT7986_ePAeLNA_EEPROM_ONEADIE_DBDC.bin. [15446.032924] 7986@C02L1,rtmp_ee_flash_init() 343: The EEPROM in Flash is wrong, use default [15446.041303]  sku:0x7, ADIE=MT7976  [15446.047345] 7986@C02L1,is_cal_free_ic() 1007: is_cal_free_ic(1007) offset:0, c,0  [15446.055770]  sku:0x7, ADIE=MT7976  [15446.074335] 7986@C02L1,is_cal_free_ic() 1027:  iCal free [15446.080386]  sku:0x7, ADIE=MT7976  [15446.511857] net wwan0: link_state 0x1 -> 0x0 [15449.222041] 7986@C17L1,RTMPReadTxPwrPerRate() 450: (450): Don't Support this now! [15449.229941] 7986@C01L1,AntCfgInit() 3118: Not support for HIF_MT yet! [15449.253636] ch_switch_monitor_state_machine_init:: enter [15449.259100] ch_switch_monitor_cfg_reset:: enter [15449.263749] ch_switch_monitor_cfg_reset:: enter [15449.269474] 7986@C17L1,tx_pwr_comp_init() 641: NotSupportYet! [15449.275340] 7986@C12L1,SCS_init() 879: INIT SCSEnable [0]= 1 [15449.281170] 7986@C12L1,SCS_init() 886: INIT SCSEnable [1]= 0 [15449.287016] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15449.292671] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15449.298293] WiFi@C14L1,IPMacTable_init() 348: IPMacTable already inited! [15449.325314] wdma_dma_ctrl(): WDMA_GLO_CFG=50804e75, txrx = 3 [15449.331705] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15449.337283] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15449.343071] 7986@C00L1,set_ack_timeout_mode_byband() 13356: CTS/ACK Timeout Range should between [0xFFFF:0]!! [15449.353114] 7986@C00L1,set_datcfg_ack_cts_timeout() 13443: DAT config band(0) cck_timeout Fail! [15449.361925] 7986@C00L1,set_ack_timeout_mode_byband() 13356: CTS/ACK Timeout Range should between [0xFFFF:0]!! [15449.371931] 7986@C00L1,set_datcfg_ack_cts_timeout() 13454: DAT config band(0) ofdm_timeout Fail! [15449.380815] 7986@C00L1,set_ack_timeout_mode_byband() 13356: CTS/ACK Timeout Range should between [0xFFFF:0]!! [15449.390819] 7986@C00L1,set_datcfg_ack_cts_timeout() 13465: DAT config band(0) ofdma_timeout Fail! [15449.399745] 7986@C00L1,set_ack_timeout_mode_byband() 13356: CTS/ACK Timeout Range should between [0xFFFF:0]!! [15449.409714] 7986@C00L1,set_datcfg_ack_cts_timeout() 13443: DAT config band(1) cck_timeout Fail! [15449.418449] 7986@C00L1,set_ack_timeout_mode_byband() 13356: CTS/ACK Timeout Range should between [0xFFFF:0]!! [15449.428351] 7986@C00L1,set_datcfg_ack_cts_timeout() 13454: DAT config band(1) ofdm_timeout Fail! [15449.437144] 7986@C00L1,set_ack_timeout_mode_byband() 13356: CTS/ACK Timeout Range should between [0xFFFF:0]!! [15449.447055] 7986@C00L1,set_datcfg_ack_cts_timeout() 13465: DAT config band(1) ofdma_timeout Fail! [15449.461289] :(op = 0) [15449.463598] 7986@C00L2,red_tail_drop_init() 82: red_tail_drop_init: available token:8192, whnat_en:1 [15449.472733] 7986@C00L1,MtCmdCr4RedSet() 913: MtCmdCr4RedSet: (ret = 0) [15449.479248] 7986@C00L1,SendRedCmd() 1048: SendRedCmd:(ret = 1) [15449.485126] 7986@C01L2,RTMP_COM_IoctlHandle() 1487: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_UP [15449.493137] WiFi@C01L3,wifi_sys_open() 855: wdev idx = 0 [15449.499162] 7986@C18L3,BuildChannelList() 484: [15449.503701] 7986@C00L3,BuildChannelList() 512: BandIdx = 0, PhyMode = 78, ChListNum = 13: [15449.511913] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15449.518249] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15449.524676] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15449.531016] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15449.537437] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15449.543775] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15449.550202] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15449.556535] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15449.562960] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15449.569299] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15449.575720] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15449.582060] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15449.588485] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15449.594820] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15449.601245] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15449.607579] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15449.614004] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15449.620342] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15449.626763] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15449.633101] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15449.639525] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15449.645860] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15449.652285] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15449.658628] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15449.665058] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15449.671399] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15449.677822] 7986@C23L1,DfsBuildChannelList() 3663: [RDM]: wdev is not 5G. [15449.684639] 7986@C23L3,RadarStateCheck() 243: Zero Wait State: 0 [15449.690634] 7986@C23L3,operate_loader_phy() 387: oper_cfg: prim_ch(0), ht_bw(1), extcha(1), vht_bw(0), cen_ch_2(0), PhyMode=78! [15449.702089] phy_freq_adjust : no prim_ch value for adjust! [15449.707562] 7986@C23L1,operate_loader_phy() 397: phy_freq_adjust failed! [15449.708116] WiFi@C00L2,dump_ht_cap() 29: Caller: wlan_operate_init+0xa4/0x148 [mt_wifi] [15449.722797] wdev_attr_update(): wdevId0 = ee:00:9a:01:27:2b [15449.728396] WiFi@C12L1,wifi_sys_open() 902: wifi_sys_open():band0 group_idx[0]=0, bw_grp=16 omac=0 [15449.737364] 7986@C08L3,hw_ctrl_flow_v2_open() 13: wdev_idx=0 [15449.743226] 7986@C23L1,HcGetBandByChannel() 1229: No hdev parking on channel:0, just return a default band_idx 0! [15449.753492] 7986@C02L2,AsicRadioOnOffCtrl() 2338: DbdcIdx=0 RadioOn [15449.760355] 7986@C00L2,ApAutoChannelAtBootUp() 333: -----------------> [15449.766962] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15449.772438] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15449.778388] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(0) bss not ready (state:0, caller:MTAPAutoSelectChannel+0xf4/0x3e8 [mt_wifi])!! [15449.790693] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15449.799823] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=1, ctrl_ch2=0, cent_ch=1 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15449.848462] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=1, ctrl_ch2=0, cent_ch=1, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15450.078380] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15450.087505] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=2, ctrl_ch2=0, cent_ch=2 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15450.136169] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=2, ctrl_ch2=0, cent_ch=2, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15450.378253] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15450.387419] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=3, ctrl_ch2=0, cent_ch=3 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15450.436104] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=3, ctrl_ch2=0, cent_ch=3, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15450.668016] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15450.677174] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=4, ctrl_ch2=0, cent_ch=4 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15450.725828] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=4, ctrl_ch2=0, cent_ch=4, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15450.957847] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15450.966997] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=5, ctrl_ch2=0, cent_ch=5 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15451.015655] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=5, ctrl_ch2=0, cent_ch=5, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15451.247631] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15451.256779] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=6, ctrl_ch2=0, cent_ch=6 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15451.305529] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=6, ctrl_ch2=0, cent_ch=6, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15451.537486] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15451.546640] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=7, ctrl_ch2=0, cent_ch=7 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15451.595305] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=7, ctrl_ch2=0, cent_ch=7, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15451.827230] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15451.836368] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=8, ctrl_ch2=0, cent_ch=8 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15451.885031] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=8, ctrl_ch2=0, cent_ch=8, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15452.117108] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15452.126225] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=9, ctrl_ch2=0, cent_ch=9 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15452.174886] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=9, ctrl_ch2=0, cent_ch=9, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15452.406857] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15452.415977] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=10, ctrl_ch2=0, cent_ch=10 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15452.464767] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=10, ctrl_ch2=0, cent_ch=10, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15452.696797] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15452.705951] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=11, ctrl_ch2=0, cent_ch=11 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15452.754775] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=11, ctrl_ch2=0, cent_ch=11, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15452.986605] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15452.995781] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=12, ctrl_ch2=0, cent_ch=12 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15453.044615] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=12, ctrl_ch2=0, cent_ch=12, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15453.276506] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15453.285669] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=13, ctrl_ch2=0, cent_ch=13 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15453.334534] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=13, ctrl_ch2=0, cent_ch=13, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15453.566664] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(0) bss not ready (state:0, caller:MTAPAutoSelectChannel+0x234/0x3e8 [mt_wifi])!! [15453.578947] 7986@C23L3,SelectClearChannelBusyTime() 1880: ==================================================================== [15453.590324] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 1 : Busy Time = 14897, Skip Channel = FALSE, BwCap = TRUE [15453.601691] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 2 : Busy Time = 9338, Skip Channel = FALSE, BwCap = TRUE [15453.613068] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 3 : Busy Time = 23134, Skip Channel = FALSE, BwCap = TRUE [15453.624434] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 4 : Busy Time = 14861, Skip Channel = FALSE, BwCap = TRUE [15453.635800] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 5 : Busy Time = 10838, Skip Channel = FALSE, BwCap = TRUE [15453.647166] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 6 : Busy Time = 12499, Skip Channel = FALSE, BwCap = TRUE [15453.658540] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 7 : Busy Time = 24546, Skip Channel = FALSE, BwCap = TRUE [15453.669906] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 8 : Busy Time = 20998, Skip Channel = FALSE, BwCap = TRUE [15453.681271] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 9 : Busy Time = 6909, Skip Channel = FALSE, BwCap = TRUE [15453.692640] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 10 : Busy Time = 7521, Skip Channel = FALSE, BwCap = TRUE [15453.704006] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 11 : Busy Time = 7925, Skip Channel = FALSE, BwCap = TRUE [15453.715371] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 12 : Busy Time = 5714, Skip Channel = FALSE, BwCap = TRUE [15453.726735] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 13 : Busy Time = 5825, Skip Channel = FALSE, BwCap = TRUE [15453.738102] 7986@C23L3,SelectClearChannelBusyTime() 1889: ==================================================================== [15453.749478] 7986@C23L3,SelectClearChannelBusyTime() 2124: Rule 3 Channel Busy time value : Select Primary Channel 12 [15453.759977] 7986@C23L3,SelectClearChannelBusyTime() 2126: Rule 3 Channel Busy time value : Min Channel Busy = 5714 [15453.770305] 7986@C23L3,SelectClearChannelBusyTime() 2128: Rule 3 Channel Busy time value : BW = 20 [15453.779244] 7986@C23L3,SelectClearChannelBusyTime() 2166: [SelectClearChannelBusyTime] - band0 END [15453.788203] 7986@C23L3,operate_loader_phy() 387: oper_cfg: prim_ch(12), ht_bw(1), extcha(3), vht_bw(0), cen_ch_2(0), PhyMode=78! [15453.799751] 7986@C23L3,operate_loader_phy() 402: oper_dev after adjust: bw(1), prim_ch(12), cen_ch_1(10), cen_ch_2(0),ext_cha(3)! [15453.811397] 7986@C23L3,operate_loader_phy() 423: oper_radio after decision: bw(1), prim_ch(12), cen_ch_1(10), cen_ch_2(0)! [15453.822548] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15453.831674] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=12, ctrl_ch2=0, cent_ch=10 DBDCIdx=0, ChBand=0, BW=1, TXStream=2, RXStream=2, scan(0) [15453.993426] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=12, ctrl_ch2=0, cent_ch=10, RxPath=3, BandIdx=0, ChBand=0, BW=1,TXStream=2, RXStream=3, scan(0) [15454.007172] 7986@C12L1,EDCCAInit() 21135: EDCCA compensation: uni_compensation=0, bw_compensation=0, final compensation=0 [15454.018136] 7986@C23L1,DfsBuildChannelList() 3663: [RDM]: wdev is not 5G. [15454.024905] 7986@C23L3,zero_wait_dfs_switch_ch() 924: outband ch 0, ch_stat 0 [15454.032580] 7986@C08L2,APStartUpForMbss() 983: ===>(caller:ap_inf_open+0x10c/0x4c0 [mt_wifi]), mbss_idx:0, CfgMode:0 [15454.043102] 7986@C15L2,APPMFInit() 130: [PMF] apidx=0, MFPC=1, MFPR=0, SHA256=0 [15454.089142] 7986@C18L3,BuildChannelList() 484: [15454.094224] WiFi@C00L2,dump_ht_cap() 29: Caller: dump_ht_cap+0x10/0x1c [mt_wifi] [15454.102096] WiFi@C08L2,ap_link_up() 7166: (caller:wdev_do_linkup+0x20/0x34 [mt_wifi]), wdev(0) [15454.110713] 7986@C01L3,wifi_sys_linkup() 1231: wdev idx = 0 [15454.167233] 7986@C00L3,wtc_acquire_groupkey_wcid() 81: Found a non-occupied wtbl_idx:543 for WDEV_TYPE:1 [15454.167233] LinkToOmacIdx = 0, LinkToWdevType = 1 [15454.181630] 7986@C08L3,hw_ctrl_flow_v2_link_up() 72: wdev_idx=0 [15454.202170] 7986@C08L3,UpdateBeaconHandler() 1910: BCN_UPDATE_INIT, OmacIdx = 0 (ra0) [15454.209997] 7986@C08L2,bcn_buf_init() 169: BcnPkt is allocated!, bcn offload=1 [15454.217224] 7986@C08L3,UpdateBeaconHandler() 1923: Band0 BcnInitedRnd = 40 [15454.224229] 7986@C00L1,TxCCKStreamCtrl() 20601: set wrong parameters [15454.239355] PrintSrCmd: [15454.239355] u1CmdSubId = 1, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0 [15454.239355] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 1 [15454.253334] PrintSrCmd: [15454.253334] u1CmdSubId = 5, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0 [15454.253334] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15454.267307] PrintSrCmd: [15454.267307] u1CmdSubId = 3, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0 [15454.267307] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 1 [15454.281278] PrintSrCmd: [15454.281278] u1CmdSubId = 23, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0 [15454.281278] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15454.295335] PrintSrCmd: [15454.295335] u1CmdSubId = 31, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0 [15454.295335] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15454.309393] PrintSrCmd: [15454.309393] u1CmdSubId = 33, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0 [15454.309393] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15454.323461] 7986@C12L1,EDCCAInit() 21135: EDCCA compensation: uni_compensation=0, bw_compensation=0, final compensation=0 [15454.334404] 7986@C03L3,MtCmdTxPwrShowInfo() 13745: ucTxPowerInfoCatg: 2, BandIdx: 0 [15454.342139] 7986@C03L3,MtCmdTxPwrShowInfo() 13774: (ret = 0) [15454.342230] [mtf_txpower_all_rate_info] band_idx:0 pwr:49 ChBand:2G ePAGain:0 [15454.347799] extif_set_dev(ra0) [15454.357939] mtk_ppe_dev_register_hook : ineterface ra0 register (1) [15454.364480] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15454.375588] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15454.385475] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15454.391398] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15454.397488] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15454.403148] extif_put_dev(wwan0) [15454.411928] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15454.420412] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15454.447287] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15454.459849] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15454.465793] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15454.474749] br-lan: port 2(ra0) entered blocking state [15454.480024] br-lan: port 2(ra0) entered disabled state [15454.485201] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15454.490918] ra0: entered allmulticast mode [15454.495117] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15454.500993] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15454.506691] ra0: entered promiscuous mode [15454.510848] br-lan: port 2(ra0) entered blocking state [15454.516005] br-lan: port 2(ra0) entered forwarding state [15454.655503] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15454.661188] 7986@C08L2,mbss_virtual_if_open() 133: ===> rax0 [15454.667023] 7986@C01L2,RTMP_COM_IoctlHandle() 1510: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_INIT [15454.675101] 7986@C01L2,RTMP_COM_IoctlHandle() 1487: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_UP [15454.683134] WiFi@C01L3,wifi_sys_open() 855: wdev idx = 1 [15454.688817] 7986@C18L3,BuildChannelList() 484: [15454.693359] 7986@C00L3,BuildChannelList() 512: BandIdx = 1, PhyMode = 177, ChListNum = 13: [15454.701693] 7986@C23L3,DfsBuildChannelList() 3708: Done [15454.706967] 7986@C23L3,RadarStateCheck() 243: Zero Wait State: 0 [15454.712966] 7986@C23L3,RadarStateCheck() 290:  RD_NORMAL_MODE  [15454.719776] 7986@C23L3,operate_loader_phy() 387: oper_cfg: prim_ch(36), ht_bw(1), extcha(1), vht_bw(2), cen_ch_2(0), PhyMode=177! [15454.731437] 7986@C23L3,operate_loader_phy() 402: oper_dev after adjust: bw(3), prim_ch(36), cen_ch_1(50), cen_ch_2(0),ext_cha(1)! [15454.743111] 7986@C23L3,operate_loader_phy() 423: oper_radio after decision: bw(3), prim_ch(36), cen_ch_1(50), cen_ch_2(0)! [15454.754263] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15454.763444] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=36, ctrl_ch2=0, cent_ch=50 DBDCIdx=1, ChBand=1, BW=3, TXStream=3, RXStream=3, scan(0) [15455.017429] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=36, ctrl_ch2=0, cent_ch=50, RxPath=7, BandIdx=1, ChBand=1, BW=3,TXStream=3, RXStream=7, scan(0) [15455.036187] 7986@C12L1,EDCCAInit() 21135: EDCCA compensation: uni_compensation=0, bw_compensation=0, final compensation=0 [15455.047496] WiFi@C00L2,dump_ht_cap() 29: Caller: wlan_operate_init+0xa4/0x148 [mt_wifi] [15455.055529] wdev_attr_update(): wdevId1 = ee:00:9a:11:27:2b [15455.061129] WiFi@C12L1,wifi_sys_open() 902: wifi_sys_open():band1 group_idx[1]=4, bw_grp=16 omac=0 [15455.070132] 7986@C08L3,hw_ctrl_flow_v2_open() 13: wdev_idx=1 [15455.077031] 7986@C02L2,AsicRadioOnOffCtrl() 2338: DbdcIdx=1 RadioOn [15455.083893] 7986@C00L2,ApAutoChannelAtBootUp() 333: -----------------> [15455.090589] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15455.096122] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15455.101512] 7986@C00L1,ApAutoChannelAtBootUp() 490: zero-wait DFS is not enabled [15455.108939] 7986@C08L1,ap_run_at_boot() 622:  ACS is disable !! [15455.115867] 7986@C08L2,APStartUpForMbss() 983: ===>(caller:ap_inf_open+0x10c/0x4c0 [mt_wifi]), mbss_idx:1, CfgMode:0 [15455.126387] 7986@C15L2,APPMFInit() 130: [PMF] apidx=1, MFPC=1, MFPR=0, SHA256=0 [15455.172230] 7986@C18L3,BuildChannelList() 484: [15455.177017] WiFi@C00L2,dump_ht_cap() 29: Caller: dump_ht_cap+0x10/0x1c [mt_wifi] [15455.184643] WiFi@C08L2,ap_link_up() 7166: (caller:wdev_do_linkup+0x20/0x34 [mt_wifi]), wdev(1) [15455.193255] 7986@C01L3,wifi_sys_linkup() 1231: wdev idx = 1 [15455.249673] 7986@C00L3,wtc_acquire_groupkey_wcid() 81: Found a non-occupied wtbl_idx:542 for WDEV_TYPE:1 [15455.249673] LinkToOmacIdx = 0, LinkToWdevType = 1 [15455.264080] 7986@C08L3,hw_ctrl_flow_v2_link_up() 72: wdev_idx=1 [15455.274808] BcnCheck start after 4000 ms (ra0) [15455.277074] 7986@C08L3,UpdateBeaconHandler() 1910: BCN_UPDATE_INIT, OmacIdx = 0 (rax0) [15455.287252] 7986@C08L2,bcn_buf_init() 169: BcnPkt is allocated!, bcn offload=1 [15455.294457] 7986@C08L3,UpdateBeaconHandler() 1923: Band1 BcnInitedRnd = 50 [15455.312608] PrintSrCmd: [15455.312608] u1CmdSubId = 1, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0 [15455.312608] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 1 [15455.326608] PrintSrCmd: [15455.326608] u1CmdSubId = 5, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0 [15455.326608] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15455.340602] PrintSrCmd: [15455.340602] u1CmdSubId = 3, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0 [15455.340602] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 1 [15455.354584] PrintSrCmd: [15455.354584] u1CmdSubId = 23, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0 [15455.354584] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15455.368650] PrintSrCmd: [15455.368650] u1CmdSubId = 31, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0 [15455.368650] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15455.382708] PrintSrCmd: [15455.382708] u1CmdSubId = 33, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0 [15455.382708] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15455.396776] 7986@C12L1,EDCCAInit() 21135: EDCCA compensation: uni_compensation=0, bw_compensation=0, final compensation=0 [15455.407714] 7986@C03L3,MtCmdTxPwrShowInfo() 13745: ucTxPowerInfoCatg: 2, BandIdx: 1 [15455.415455] 7986@C03L3,MtCmdTxPwrShowInfo() 13774: (ret = 0) [15455.415546] [mtf_txpower_all_rate_info] band_idx:1 pwr:50 ChBand:5G ePAGain:0 [15455.421109] extif_set_dev(rax0) [15455.431350] mtk_ppe_dev_register_hook : ineterface rax0 register (2) [15455.437986] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15455.477936] br-lan: port 3(rax0) entered blocking state [15455.483168] br-lan: port 3(rax0) entered disabled state [15455.488505] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15455.494169] rax0: entered allmulticast mode [15455.498483] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15455.504278] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15455.510007] rax0: entered promiscuous mode [15455.514334] br-lan: port 3(rax0) entered blocking state [15455.519625] br-lan: port 3(rax0) entered forwarding state [15457.625783] 7986@C08L3,ap_peer_auth_req_at_idle_action() 713: AUTH - MBSS(1), Rcv AUTH seq#1, Alg=3, Status=126 from [wcid=1023]b8:f7:75:77:76:7b [15457.697444] 7986@C08L3,ap_peer_auth_req_at_idle_action() 713: AUTH - MBSS(1), Rcv AUTH seq#2, Alg=3, Status=0 from [wcid=1023]b8:f7:75:77:76:7b [15457.710365] entrytb_aid_aquire(): found non-occupied aid:2, allocated from:2 [15457.717410] 7986@C13L2,MacTableInsertEntry() 1537: New Sta:b8:f7:75:77:76:7b [15457.724486] 7986@C08L1,PeerAssocReqCmmSanity() 984: DSCPPolicyEnable = 1 [15457.731184] 7986@C08L1,PeerAssocReqCmmSanity() 1445: IE_WLAN_EXTENSION: no handler for extension_id:137 [15457.740564] 7986@C08L3,ap_cmm_peer_assoc_req_action() 1645: Recv Assoc from STA - b8:f7:75:77:76:7b [15457.749882] 7986@C08L3,ap_cmm_peer_assoc_req_action() 2168: ASSOC Send ASSOC response (Status=0)... [15457.758945] 7986@C01L3,wifi_sys_conn_act() 1115: wdev idx = 1 [15457.764986] 7986@C08L3,hw_ctrl_flow_v2_connt_act() 215: wdev_idx=1 [15457.893736] 7986@C15L3,WPABuildPairMsg1() 5278: <=== send Msg1 of 4-way [15457.908556] 7986@C15L3,PeerPairMsg2Action() 6197: ===>Receive msg 2 [15457.915350] 7986@C15L3,WPABuildPairMsg3() 5555: <=== send Msg3 of 4-way [15457.922872] 7986@C15L3,PeerPairMsg4Action() 6627: ===>Receive msg 4 [15457.934341] 7986@C15L2,PeerPairMsg4Action() 6982: AP SETKEYS DONE(rax0) - AKMMap=WPA3PSK, PairwiseCipher=AES, GroupCipher=AES, wcid=1 from b8:f7:75:77:76:7b [15457.934341] [15458.063086] BcnCheck start after 1500 ms (ra0) [15458.067537] BcnCheck start after 2500 ms (ra0) [15462.193318] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15475.563361] net wwan0: link_state 0x0 -> 0x1 [15475.570724] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15475.576646] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15475.582324] extif_set_dev(wwan0) [15475.585805] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15475.595144] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15475.600942] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15475.606681] extif_set_dev(wwan0_1) [15476.212748] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15476.218862] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15476.279891] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15476.286002] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15476.611829] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15483.786428] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15529.923772] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15530.049143] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15530.054892] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15530.065163] 7986@C01L2,RTMP_COM_IoctlHandle() 1498: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_DOWN [15530.073684] WiFi@C08L2,ap_link_down() 7184: (caller:wdev_do_linkdown+0x20/0x34 [mt_wifi]), wdev(0) [15530.083121] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(0) bss not ready (state:2, caller:ap_link_down+0x80/0x134 [mt_wifi])!! [15530.094551] 7986@C01L3,wifi_sys_linkdown() 1363: wdev idx = 0 [15530.100699] 7986@C08L3,hw_ctrl_flow_v2_link_down() 123: wdev_idx=0 [15530.109765] WiFi@C01L3,wifi_sys_close() 945: wdev idx = 0 [15530.115540] 7986@C08L3,hw_ctrl_flow_v2_close() 41: wdev_idx=0 [15530.121565] 7986@C01L2,RTMP_COM_IoctlHandle() 1521: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_DEINIT [15530.129873] extif_put_dev(ra0) [15530.132924] mtk_ppe_dev_unregister_hook : ineterface ra0 set null (1) [15530.148900] br-lan: port 2(ra0) entered disabled state [15530.155990] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15530.169722] ra0: left allmulticast mode [15530.173576] ra0: left promiscuous mode [15530.177460] br-lan: port 2(ra0) entered disabled state [15530.238639] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15530.244449] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15530.250868] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15530.256715] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15530.292262] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15530.297939] extif_put_dev(wwan0_1) [15530.321480] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15530.336347] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15530.347226] 7986@C08L2,mbss_virtual_if_close() 170: ===> rax0 [15530.353181] 7986@C01L2,RTMP_COM_IoctlHandle() 1498: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_DOWN [15530.361730] WiFi@C08L2,ap_link_down() 7184: (caller:wdev_do_linkdown+0x20/0x34 [mt_wifi]), wdev(1) [15530.371018] 7986@C01L2,wifi_sys_disconn_act() 1002: wdev_idx=1 [15530.378645] 7986@C08L3,hw_ctrl_flow_v2_disconnt_act() 172: wdev_idx=1 [15530.385891] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(1) bss not ready (state:2, caller:hw_ctrl_ops_v1_register+0x25c/0x888 [mt_wifi])!! [15530.398572] 7986@C13L2,MacTableDeleteEntry() 1793: Del Sta:b8:f7:75:77:76:7b [15530.405964] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(1) bss not ready (state:2, caller:ap_link_down+0x80/0x134 [mt_wifi])!! [15530.417368] 7986@C01L3,wifi_sys_linkdown() 1363: wdev idx = 1 [15530.423466] 7986@C08L3,hw_ctrl_flow_v2_link_down() 123: wdev_idx=1 [15530.432560] WiFi@C01L3,wifi_sys_close() 945: wdev idx = 1 [15530.438243] 7986@C08L3,hw_ctrl_flow_v2_close() 41: wdev_idx=1 [15530.444223] 7986@C01L2,RTMP_COM_IoctlHandle() 1521: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_DEINIT [15530.452783] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(0) bss not ready (state:0, caller:APStopByBss+0xd4/0x440 [mt_wifi])!! [15530.464083] 7986@C01L2,wifi_sys_disconn_act() 1002: wdev_idx=0 [15530.470484] WiFi@C08L2,ap_link_down() 7184: (caller:wdev_do_linkdown+0x20/0x34 [mt_wifi]), wdev(0) [15530.479652] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(1) bss not ready (state:0, caller:APStopByBss+0xd4/0x440 [mt_wifi])!! [15530.491207] WiFi@C08L2,ap_link_down() 7184: (caller:wdev_do_linkdown+0x20/0x34 [mt_wifi]), wdev(1) [15530.500327] ch_switch_monitor_exit:: Exit [15530.509342] current sync CR = 0x7 [15530.512665] current sync CR = 0x7 [15530.516964] current sync CR = 0x7 [15530.521279] current sync CR = 0x7 [15530.525578] current sync CR = 0x1 [15530.629246] wdma_dma_ctrl(): WDMA_GLO_CFG=50804e75, txrx = 0 [15530.634895] wdma_dma_ctrl(): WDMA_GLO_CFG=40804e74, txrx = 0 [15530.640583] warp_woctrl_send_msg_and_wait_cr(), idx:0, state:DISABLE [15530.646922] warp_woctrl_send_msg_and_wait_cr(): wait for wocpu [15530.652739] [tmp]warp_woctrl_send_msg_and_wait_cr(): wait for wocpu, value:1, value_done:1 [15530.660983] warp_woctrl_send_msg_and_wait_cr(): wocpu is ready, done_value:1 [15530.668008] warp_woctrl_kickout(), idx: 0, state:HALT [15530.673044] warp_woctrl_kickout(): wait for wocpu [15530.677728] [tmp]warp_woctrl_kickout(): wait for wocpu, value:7, value_done:7 [15530.684843] warp_woctrl_kickout(): wocpu is ready, done_value:7 [15530.710834] wdma_dma_ctrl(): WDMA_GLO_CFG=40804e70, txrx = 0 [15530.719871] [Nop_List_Backup] NopList Channel count:0 <---- [15530.725592] wed_ser_task(): wed_ser exist, wed->irq=123! [15530.739623] wo_exception_exit(0): wo_exception_exit done [15530.744954] warp_msg_receive(),go,warp,26343,0 [15530.749422] warp_msg_process(): module id(0), wed_idx(0), something wrong(ret = -1) [15530.757067] warp_msg_unregister():done [15530.760948] warp_client_remove(): warp entry clear done!! [15530.766337] extif_put_dev(rax0) [15530.769496] mtk_ppe_dev_unregister_hook : ineterface rax0 set null (2) [15530.785268] br-lan: port 3(rax0) entered disabled state [15530.792152] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15530.798309] rax0: left allmulticast mode [15530.802243] rax0: left promiscuous mode [15530.806182] br-lan: port 3(rax0) entered disabled state [15530.858393] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15530.864185] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15530.890901] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15530.896995] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15530.939366] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15530.945160] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15531.898739] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15531.908504] br-lan: port 1(eth0) entered disabled state [15531.946273] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15531.952793] mtk_soc_eth 15100000.ethernet eth0: left allmulticast mode [15531.959420] mtk_soc_eth 15100000.ethernet eth0: left promiscuous mode [15531.965929] br-lan: port 1(eth0) entered disabled state [15532.007453] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.013148] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.019297] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.025076] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.147807] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.158344] mtk_soc_eth 15100000.ethernet eth1: Link is Down [15532.184471] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.193835] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.203821] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.216735] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.227227] mtk_soc_eth 15100000.ethernet eth0: Link is Down [15532.235172] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.243294] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.249574] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.258822] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.267402] mtk_soc_eth 15100000.ethernet eth0: configuring for fixed/2500base-x link mode [15532.275870] mtk_soc_eth 15100000.ethernet eth0: Link is Up - 2.5Gbps/Full - flow control off [15532.275944] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.290092] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.295889] br-lan: port 1(eth0) entered blocking state [15532.301164] br-lan: port 1(eth0) entered disabled state [15532.306408] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.312068] mtk_soc_eth 15100000.ethernet eth0: entered allmulticast mode [15532.318910] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.324623] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.330276] mtk_soc_eth 15100000.ethernet eth0: entered promiscuous mode [15532.337024] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.342695] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.348428] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.354822] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.360638] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.366421] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.372089] br-lan: port 1(eth0) entered blocking state [15532.377304] br-lan: port 1(eth0) entered forwarding state [15532.382871] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.391307] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.397336] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.404423] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.410214] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.415969] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.421718] mtk_soc_eth 15100000.ethernet eth1: configuring for fixed/2500base-x link mode [15532.430191] mtk_soc_eth 15100000.ethernet eth1: Link is Up - 2.5Gbps/Full - flow control off [15532.430249] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.444509] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.602938] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.609139] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.679101] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.737266] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.743688] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15532.749590] 7986@C01L2,RTMP_COM_IoctlHandle() 1510: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_INIT [15532.757979] msta_ifname not found [15532.761663] warp_cap_support(): chip_id=7986 is in WHNAT support list [15532.768131] warp_entry_acquire(): bus slot:0, hook to WARP, entry id=0, entry: 00000000ab2988e2 [15532.776816] client_hif_specific_get(): hw->base_phy_addr 0x18000000!! [15532.783458] warp_entry_get_by_pdev(): return entry[0] [15532.788703] warp_msg_register():warp,pid,6754,id,0,wed_idx,0 [15532.794351] warp_dev7 15010000.wed: Using 32bit DMA for streaming map [15532.800788] warp_dev7 15010000.wed: Using 32bit DMA for coherent map [15532.807224] wed_init(0): res_start = 0x15010000, size = 1000, irq=123,base_addr=0xffffffc08139e000 [15532.816572] wed_ring_init(): wed tx ring init result = 0 [15532.822432] wed_ring_init(): wed rx ring init result = 0 [15532.838073] create wed ok!!! [15532.840992] wdma_init(): wdma(0) base_addr=0xffffffc0813a5800, base_phy_addr=0x15104800 [15532.849742] warp_woif_bus_init_hw(): bus:0000000077c4784b, txring: 20, pa: 0x0000000043f10000, 0x0000000043f10000, 24, 28, 3c [15532.861045] warp_woif_bus_init_hw(): bus:0000000077c4784b, rxring: 50, pa: 0x0000000051244000, 0x0000000051244000, 54, 58, 6c [15532.872415] get_region_info(): wed_idx = 0, base_addr = 0x00000000b4e9921f, size = 0x40000, res.start = 0x4fd00000, shared:0 [15532.883785] get_region_info(): wed_idx = 0, base_addr = 0x00000000d55f8d86, size = 0x8000, res.start = 0x151e0000, shared:0 [15532.895001] get_region_info(): wed_idx = 0, base_addr = 0x000000003f6d910c, size = 0x240000, res.start = 0x4fd80000, shared:1 [15532.906404] get_region_info(): wed_idx = 0, base_addr = 0x000000001c23e9ee, size = 0x1000, res.start = 0x15194000, shared:0 [15532.917540] warp_fwdl_mcu_mode(): mcu mode, need fwdl [15532.924837] ########## WO Firmware ########## [15532.929234] Chip ID: 0x0000 [15532.932017] ECO version:0 [15532.934624] Version: DEV_000000 [15532.937764] Build date: 20230814174927 [15532.941499] Total region:3 [15532.944190] ################################# [15532.948538] Parsing tailer region 0 [15532.952015] Target address: 0x151e0000 [15532.955842] Download size: 22112 [15532.959180] Parsing tailer region 1 [15532.962654] Target address: 0x4fd00000 [15532.966471] Download size: 87728 [15532.969866] Parsing tailer region 2 [15532.973339] Target address: 0x4fd80000 [15532.977161] Download size: 2345040 [15532.981045] warp_fwdl_ready_check_mcu_mode(): waiting for wocpu [15533.000712] warp_fwdl_ready_check_mcu_mode(): wocpu is ready [15533.006377] warp_woctrl_init_state(), wo_state: WO_STATE_ENABLE [15533.012289] wo_proc_init done 000000008c43ff3a [15533.016811] wo_exep_proc_init done 000000008c43ff3a [15533.021784] wo_exception_init(0): exp log= 0x00000000cc4fff99, phy_addr= 0x000000005d068000 size= 32768 [15533.031300] warp_probe(): probe fail since warp is full! [15533.036593] warp_dev7: probe of 15010000.wed2 failed with error -1 [15533.042910] 7986@C01L3,mt_wifi_open() 342: whnat_en=1 [15533.081406] 7986@C12L1,RT_CfgSetMacAddress() 759: invalid length (0) [15533.087805] 7986@C12L1,RT_CfgSetMacAddress() 759: invalid length (0) [15533.094185] 7986@C12L1,RT_CfgSetMacAddress() 759: invalid length (0) [15533.101060] 7986@C12L1,RTMPWirelessModeCfg() 611: Init: BSS0 PhyMode=78 [15533.107659] 7986@C12L1,RTMPWirelessModeCfg() 611: Init: BSS1 PhyMode=177 [15533.114465] 7986@C23L3,auto_ch_select_set_cfg() 3456: BandIdx0, AutoChannelAtBootup=1, AutoChannelAlg = 3 [15533.124009] 7986@C23L3,auto_ch_select_set_cfg() 3456: BandIdx1, AutoChannelAtBootup=0, AutoChannelAlg = 0 [15533.150699] 7986@C15L1,Set_PMFMFPC_Proc() 1721: [PMF] :: apidx=0, Desired MFPC=1 [15533.158078] 7986@C15L1,Set_PMFMFPC_Proc() 1721: [PMF] :: apidx=1, Desired MFPC=1 [15533.166015] 7986@C12L1,RTMPSetProfileParameters() 8611: ra0, QoSMgmtCapa=0 [15533.172874] 7986@C12L1,RTMPSetProfileParameters() 8611: rax0, QoSMgmtCapa=0 [15533.181021] 7986@C12L1,Set_ApCliPMFMFPC_Proc() 2000: [PMF]: MFPC=0, MFPR=0, SHA256=0 [15533.188747] 7986@C12L1,Set_ApCliPMFMFPC_Proc() 2000: [PMF]: MFPC=0, MFPR=0, SHA256=0 [15533.196536] 7986@C12L1,Set_ApCliPMFMFPR_Proc() 2064: [PMF]: MFPC=0, MFPR=0, SHA256=0 [15533.204260] 7986@C12L1,Set_ApCliPMFMFPR_Proc() 2064: [PMF]: MFPC=0, MFPR=0, SHA256=0 [15533.212297] 7986@C12L1,rtmp_read_ap_client_from_file() 1638: APCLI[0] ApCliMuMimoDlEnable = 0 [15533.220808] 7986@C12L1,rtmp_read_ap_client_from_file() 1638: APCLI[1] ApCliMuMimoDlEnable = 0 [15533.229383] 7986@C12L1,rtmp_read_ap_client_from_file() 1657: APCLI[0] ApCliMuMimoUlEnable = 0 [15533.237896] 7986@C12L1,rtmp_read_ap_client_from_file() 1657: APCLI[1] ApCliMuMimoUlEnable = 0 [15533.274179] wdma_dma_ctrl(): WDMA_GLO_CFG=40804e70, txrx = 0 [15533.284833] wdma_dma_ctrl(): WDMA_GLO_CFG=40804e70, txrx = 0 [15533.299460] warp_tx_ring_init_hw(): configure ring 0 setting [15533.305122] warp_tx_ring_init_hw(): wed:00000000b690c3a7 wifi:0000000077cc8647: 24420=4de78000,24424=2048,24428=0 [15533.315398] warp_tx_ring_init_hw(): configure ring 1 setting [15533.321051] warp_tx_ring_init_hw(): wed:00000000b690c3a7 wifi:0000000077cc8647: 24430=5ad58000,24434=2048,24438=0 [15533.331414] set_rrocfg,0 [15533.356812] wdma_dma_ctrl(): WDMA_GLO_CFG=40804e74, txrx = 3 [15533.362477]  sku:0x7, ADIE=MT7976  [15533.366849] using E1 ROM patch [15533.369894] using E1 RAM [15533.372433] current sync CR = 0x1 [15533.375982] Built date: 20230814174703a [15533.379999] [15533.381497] Platform: ALPS [15533.384284] HW/SW version: 0x8a108a10 [15533.388062] Patch version: 0xffffffff [15533.392185] current sync CR = 0x1 [15533.405454] Chip ID: 0x0f [15533.408312] Eco version: 0x00 [15533.411382] Region number: 0x0b [15533.414605] Format version: 0x02 [15533.417969] Format flag: 0x01 [15533.421037] Ram version: ____000000 [15533.424618] Built date: 20230814174718 [15533.428491] Common crc: 0xea70993a [15533.431994] Release info: header tag = 0, total length = 80 [15533.437672] tag 1, padding length = 1, tag length = 75 [15533.442899] payload: t-neptune-main-2111-7986-imp-MT7986_E1_ASIC_ROM_RAM_REBB_IMP-20230814174618 [15533.472462] Chip ID: 0x00 [15533.475293] Eco version: 0x00 [15533.478483] Region number: 0x03 [15533.481729] Format version: 0x02 [15533.485041] Format flag: 0x01 [15533.488218] Ram version: DEV_000000 [15533.491820] Built date: 20230814174901 [15533.495664] Common crc: 0xc02c55e5 [15533.499256] Release info: header tag = 0, total length = 0 [15533.604073] current sync CR = 0x7 [15533.607758] efuse_probe: efuse = deaddead [15533.611855]  sku:0x7, ADIE=MT7976  [15533.616258] Use default BIN from:/lib/firmware/MT7986_ePAeLNA_EEPROM_ONEADIE_DBDC.bin. [15533.624795] 7986@C02L1,rtmp_ee_flash_init() 343: The EEPROM in Flash is wrong, use default [15533.633163]  sku:0x7, ADIE=MT7976  [15533.639343] 7986@C02L1,is_cal_free_ic() 1007: is_cal_free_ic(1007) offset:0, c,0  [15533.647588]  sku:0x7, ADIE=MT7976  [15533.666033] 7986@C02L1,is_cal_free_ic() 1027:  iCal free [15533.672173]  sku:0x7, ADIE=MT7976  [15536.823822] 7986@C17L1,RTMPReadTxPwrPerRate() 450: (450): Don't Support this now! [15536.831928] 7986@C01L1,AntCfgInit() 3118: Not support for HIF_MT yet! [15536.855784] ch_switch_monitor_state_machine_init:: enter [15536.861202] ch_switch_monitor_cfg_reset:: enter [15536.865827] ch_switch_monitor_cfg_reset:: enter [15536.871053] 7986@C17L1,tx_pwr_comp_init() 641: NotSupportYet! [15536.877586] 7986@C12L1,SCS_init() 879: INIT SCSEnable [0]= 1 [15536.883510] 7986@C12L1,SCS_init() 886: INIT SCSEnable [1]= 0 [15536.889812] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15536.895510] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15536.901043] WiFi@C14L1,IPMacTable_init() 348: IPMacTable already inited! [15536.928492] wdma_dma_ctrl(): WDMA_GLO_CFG=50804e75, txrx = 3 [15536.934468] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15536.940358] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15536.946060] 7986@C00L1,set_ack_timeout_mode_byband() 13356: CTS/ACK Timeout Range should between [0xFFFF:0]!! [15536.956069] 7986@C00L1,set_datcfg_ack_cts_timeout() 13443: DAT config band(0) cck_timeout Fail! [15536.964799] 7986@C00L1,set_ack_timeout_mode_byband() 13356: CTS/ACK Timeout Range should between [0xFFFF:0]!! [15536.974716] 7986@C00L1,set_datcfg_ack_cts_timeout() 13454: DAT config band(0) ofdm_timeout Fail! [15536.983484] 7986@C00L1,set_ack_timeout_mode_byband() 13356: CTS/ACK Timeout Range should between [0xFFFF:0]!! [15536.993389] 7986@C00L1,set_datcfg_ack_cts_timeout() 13465: DAT config band(0) ofdma_timeout Fail! [15537.002242] 7986@C00L1,set_ack_timeout_mode_byband() 13356: CTS/ACK Timeout Range should between [0xFFFF:0]!! [15537.012133] 7986@C00L1,set_datcfg_ack_cts_timeout() 13443: DAT config band(1) cck_timeout Fail! [15537.020811] 7986@C00L1,set_ack_timeout_mode_byband() 13356: CTS/ACK Timeout Range should between [0xFFFF:0]!! [15537.030703] 7986@C00L1,set_datcfg_ack_cts_timeout() 13454: DAT config band(1) ofdm_timeout Fail! [15537.039501] 7986@C00L1,set_ack_timeout_mode_byband() 13356: CTS/ACK Timeout Range should between [0xFFFF:0]!! [15537.049421] 7986@C00L1,set_datcfg_ack_cts_timeout() 13465: DAT config band(1) ofdma_timeout Fail! [15537.062847] :(op = 0) [15537.065167] 7986@C00L2,red_tail_drop_init() 82: red_tail_drop_init: available token:8192, whnat_en:1 [15537.074309] 7986@C00L1,MtCmdCr4RedSet() 913: MtCmdCr4RedSet: (ret = 0) [15537.080821] 7986@C00L1,SendRedCmd() 1048: SendRedCmd:(ret = 1) [15537.086804] 7986@C01L2,RTMP_COM_IoctlHandle() 1487: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_UP [15537.094810] WiFi@C01L3,wifi_sys_open() 855: wdev idx = 0 [15537.100764] 7986@C18L3,BuildChannelList() 484: [15537.105311] 7986@C00L3,BuildChannelList() 512: BandIdx = 0, PhyMode = 78, ChListNum = 13: [15537.113475] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15537.119831] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15537.126258] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15537.132592] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15537.139019] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15537.145358] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15537.151778] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15537.158116] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15537.164576] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15537.170910] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15537.177360] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15537.183694] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15537.190135] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15537.196503] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15537.202926] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15537.209340] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15537.215831] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15537.222169] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15537.228616] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15537.234975] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15537.241399] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15537.247758] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15537.254215] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15537.260550] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15537.266985] 7986@C14L1,vht80_channel_group() 1566: invalid ch_band 0 [15537.273319] 7986@C14L1,vht160_channel_group() 1635: invalid ch_band 0 [15537.279760] 7986@C23L1,DfsBuildChannelList() 3663: [RDM]: wdev is not 5G. [15537.286623] 7986@C23L3,RadarStateCheck() 243: Zero Wait State: 0 [15537.292740] 7986@C23L3,operate_loader_phy() 387: oper_cfg: prim_ch(0), ht_bw(1), extcha(1), vht_bw(0), cen_ch_2(0), PhyMode=78! [15537.304233] phy_freq_adjust : no prim_ch value for adjust! [15537.309742] 7986@C23L1,operate_loader_phy() 397: phy_freq_adjust failed! [15537.310319] WiFi@C00L2,dump_ht_cap() 29: Caller: wlan_operate_init+0xa4/0x148 [mt_wifi] [15537.325032] wdev_attr_update(): wdevId0 = ee:00:9a:01:27:2b [15537.330654] WiFi@C12L1,wifi_sys_open() 902: wifi_sys_open():band0 group_idx[0]=0, bw_grp=16 omac=0 [15537.339622] 7986@C08L3,hw_ctrl_flow_v2_open() 13: wdev_idx=0 [15537.345835] 7986@C23L1,HcGetBandByChannel() 1229: No hdev parking on channel:0, just return a default band_idx 0! [15537.356105] 7986@C02L2,AsicRadioOnOffCtrl() 2338: DbdcIdx=0 RadioOn [15537.362965] 7986@C00L2,ApAutoChannelAtBootUp() 333: -----------------> [15537.369596] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15537.375085] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15537.381125] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(0) bss not ready (state:0, caller:MTAPAutoSelectChannel+0xf4/0x3e8 [mt_wifi])!! [15537.393604] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15537.402739] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=1, ctrl_ch2=0, cent_ch=1 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15537.451431] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=1, ctrl_ch2=0, cent_ch=1, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15537.684297] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15537.693433] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=2, ctrl_ch2=0, cent_ch=2 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15537.742102] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=2, ctrl_ch2=0, cent_ch=2, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15537.974095] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15537.983255] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=3, ctrl_ch2=0, cent_ch=3 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15538.031931] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=3, ctrl_ch2=0, cent_ch=3, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15538.264389] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15538.273557] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=4, ctrl_ch2=0, cent_ch=4 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15538.322239] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=4, ctrl_ch2=0, cent_ch=4, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15538.553716] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15538.562833] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=5, ctrl_ch2=0, cent_ch=5 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15538.611508] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=5, ctrl_ch2=0, cent_ch=5, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15538.843617] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15538.852778] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=6, ctrl_ch2=0, cent_ch=6 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15538.901505] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=6, ctrl_ch2=0, cent_ch=6, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15539.143392] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15539.152534] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=7, ctrl_ch2=0, cent_ch=7 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15539.201209] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=7, ctrl_ch2=0, cent_ch=7, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15539.433235] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15539.442414] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=8, ctrl_ch2=0, cent_ch=8 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15539.491094] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=8, ctrl_ch2=0, cent_ch=8, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15539.723019] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15539.732150] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=9, ctrl_ch2=0, cent_ch=9 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15539.780837] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=9, ctrl_ch2=0, cent_ch=9, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15540.012863] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15540.021995] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=10, ctrl_ch2=0, cent_ch=10 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15540.070750] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=10, ctrl_ch2=0, cent_ch=10, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15540.302708] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15540.311855] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=11, ctrl_ch2=0, cent_ch=11 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15540.360691] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=11, ctrl_ch2=0, cent_ch=11, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15540.592531] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15540.601686] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=12, ctrl_ch2=0, cent_ch=12 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15540.650527] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=12, ctrl_ch2=0, cent_ch=12, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15540.882955] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15540.892211] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=13, ctrl_ch2=0, cent_ch=13 DBDCIdx=0, ChBand=0, BW=0, TXStream=2, RXStream=2, scan(1) [15540.941261] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=13, ctrl_ch2=0, cent_ch=13, RxPath=3, BandIdx=0, ChBand=0, BW=0,TXStream=2, RXStream=3, scan(1) [15541.172655] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(0) bss not ready (state:0, caller:MTAPAutoSelectChannel+0x234/0x3e8 [mt_wifi])!! [15541.184954] 7986@C23L3,SelectClearChannelBusyTime() 1880: ==================================================================== [15541.196355] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 1 : Busy Time = 16327, Skip Channel = FALSE, BwCap = TRUE [15541.207837] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 2 : Busy Time = 10843, Skip Channel = FALSE, BwCap = TRUE [15541.219226] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 3 : Busy Time = 8562, Skip Channel = FALSE, BwCap = TRUE [15541.230596] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 4 : Busy Time = 7027, Skip Channel = FALSE, BwCap = TRUE [15541.241962] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 5 : Busy Time = 13841, Skip Channel = FALSE, BwCap = TRUE [15541.253329] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 6 : Busy Time = 41555, Skip Channel = FALSE, BwCap = TRUE [15541.264695] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 7 : Busy Time = 14454, Skip Channel = FALSE, BwCap = TRUE [15541.276061] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 8 : Busy Time = 15123, Skip Channel = FALSE, BwCap = TRUE [15541.287425] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 9 : Busy Time = 4809, Skip Channel = FALSE, BwCap = TRUE [15541.298796] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 10 : Busy Time = 12172, Skip Channel = FALSE, BwCap = TRUE [15541.310187] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 11 : Busy Time = 7856, Skip Channel = FALSE, BwCap = TRUE [15541.321557] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 12 : Busy Time = 6201, Skip Channel = FALSE, BwCap = TRUE [15541.332925] 7986@C23L3,SelectClearChannelBusyTime() 1883: Channel 13 : Busy Time = 7332, Skip Channel = FALSE, BwCap = TRUE [15541.344290] 7986@C23L3,SelectClearChannelBusyTime() 1889: ==================================================================== [15541.355666] 7986@C23L3,SelectClearChannelBusyTime() 2124: Rule 3 Channel Busy time value : Select Primary Channel 9 [15541.366081] 7986@C23L3,SelectClearChannelBusyTime() 2126: Rule 3 Channel Busy time value : Min Channel Busy = 4809 [15541.376407] 7986@C23L3,SelectClearChannelBusyTime() 2128: Rule 3 Channel Busy time value : BW = 20 [15541.385349] 7986@C23L3,SelectClearChannelBusyTime() 2166: [SelectClearChannelBusyTime] - band0 END [15541.394314] 7986@C23L3,operate_loader_phy() 387: oper_cfg: prim_ch(9), ht_bw(1), extcha(1), vht_bw(0), cen_ch_2(0), PhyMode=78! [15541.405775] 7986@C23L3,operate_loader_phy() 402: oper_dev after adjust: bw(1), prim_ch(9), cen_ch_1(11), cen_ch_2(0),ext_cha(1)! [15541.417331] 7986@C23L3,operate_loader_phy() 423: oper_radio after decision: bw(1), prim_ch(9), cen_ch_1(11), cen_ch_2(0)! [15541.428509] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15541.437642] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=9, ctrl_ch2=0, cent_ch=11 DBDCIdx=0, ChBand=0, BW=1, TXStream=2, RXStream=2, scan(0) [15541.591478] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=9, ctrl_ch2=0, cent_ch=11, RxPath=3, BandIdx=0, ChBand=0, BW=1,TXStream=2, RXStream=3, scan(0) [15541.605274] 7986@C12L1,EDCCAInit() 21135: EDCCA compensation: uni_compensation=0, bw_compensation=0, final compensation=0 [15541.616264] 7986@C23L1,DfsBuildChannelList() 3663: [RDM]: wdev is not 5G. [15541.623042] 7986@C23L3,zero_wait_dfs_switch_ch() 924: outband ch 0, ch_stat 0 [15541.630736] 7986@C08L2,APStartUpForMbss() 983: ===>(caller:ap_inf_open+0x10c/0x4c0 [mt_wifi]), mbss_idx:0, CfgMode:0 [15541.641276] 7986@C15L2,APPMFInit() 130: [PMF] apidx=0, MFPC=1, MFPR=0, SHA256=0 [15541.687324] 7986@C18L3,BuildChannelList() 484: [15541.692464] WiFi@C00L2,dump_ht_cap() 29: Caller: dump_ht_cap+0x10/0x1c [mt_wifi] [15541.700361] WiFi@C08L2,ap_link_up() 7166: (caller:wdev_do_linkup+0x20/0x34 [mt_wifi]), wdev(0) [15541.709024] 7986@C01L3,wifi_sys_linkup() 1231: wdev idx = 0 [15541.765817] 7986@C00L3,wtc_acquire_groupkey_wcid() 81: Found a non-occupied wtbl_idx:543 for WDEV_TYPE:1 [15541.765817] LinkToOmacIdx = 0, LinkToWdevType = 1 [15541.780221] 7986@C08L3,hw_ctrl_flow_v2_link_up() 72: wdev_idx=0 [15541.800809] 7986@C08L3,UpdateBeaconHandler() 1910: BCN_UPDATE_INIT, OmacIdx = 0 (ra0) [15541.808634] 7986@C08L2,bcn_buf_init() 169: BcnPkt is allocated!, bcn offload=1 [15541.815841] 7986@C08L3,UpdateBeaconHandler() 1923: Band0 BcnInitedRnd = 42 [15541.822862] 7986@C00L1,TxCCKStreamCtrl() 20601: set wrong parameters [15541.837781] PrintSrCmd: [15541.837781] u1CmdSubId = 1, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0 [15541.837781] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 1 [15541.851777] PrintSrCmd: [15541.851777] u1CmdSubId = 5, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0 [15541.851777] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15541.865751] PrintSrCmd: [15541.865751] u1CmdSubId = 3, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0 [15541.865751] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 1 [15541.879732] PrintSrCmd: [15541.879732] u1CmdSubId = 23, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0 [15541.879732] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15541.893790] PrintSrCmd: [15541.893790] u1CmdSubId = 31, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0 [15541.893790] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15541.907847] PrintSrCmd: [15541.907847] u1CmdSubId = 33, u1ArgNum = 0, u1DbdcIdx = 0, u1Status = 0 [15541.907847] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15541.921928] 7986@C12L1,EDCCAInit() 21135: EDCCA compensation: uni_compensation=0, bw_compensation=0, final compensation=0 [15541.932899] 7986@C03L3,MtCmdTxPwrShowInfo() 13745: ucTxPowerInfoCatg: 2, BandIdx: 0 [15541.940627] 7986@C03L3,MtCmdTxPwrShowInfo() 13774: (ret = 0) [15541.940741] [mtf_txpower_all_rate_info] band_idx:0 pwr:49 ChBand:2G ePAGain:0 [15541.946311] extif_set_dev(ra0) [15541.956442] mtk_ppe_dev_register_hook : ineterface ra0 register (1) [15541.962956] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15541.970705] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15541.976666] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15541.987426] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15541.993262] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15541.998914] extif_set_dev(wwan0_1) [15542.036627] br-lan: port 2(ra0) entered blocking state [15542.041803] br-lan: port 2(ra0) entered disabled state [15542.046965] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15542.052648] ra0: entered allmulticast mode [15542.056799] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15542.062536] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15542.068183] ra0: entered promiscuous mode [15542.072281] br-lan: port 2(ra0) entered blocking state [15542.077404] br-lan: port 2(ra0) entered forwarding state [15542.228603] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15542.234417] 7986@C08L2,mbss_virtual_if_open() 133: ===> rax0 [15542.240158] 7986@C01L2,RTMP_COM_IoctlHandle() 1510: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_INIT [15542.248494] 7986@C01L2,RTMP_COM_IoctlHandle() 1487: -> CMD_RTPRIV_IOCTL_VIRTUAL_INF_UP [15542.256562] WiFi@C01L3,wifi_sys_open() 855: wdev idx = 1 [15542.262222] 7986@C18L3,BuildChannelList() 484: [15542.266761] 7986@C00L3,BuildChannelList() 512: BandIdx = 1, PhyMode = 177, ChListNum = 13: [15542.275117] 7986@C23L3,DfsBuildChannelList() 3708: Done [15542.280399] 7986@C23L3,RadarStateCheck() 243: Zero Wait State: 0 [15542.286452] 7986@C23L3,RadarStateCheck() 290:  RD_NORMAL_MODE  [15542.293282] 7986@C23L3,operate_loader_phy() 387: oper_cfg: prim_ch(36), ht_bw(1), extcha(1), vht_bw(2), cen_ch_2(0), PhyMode=177! [15542.304970] 7986@C23L3,operate_loader_phy() 402: oper_dev after adjust: bw(3), prim_ch(36), cen_ch_1(50), cen_ch_2(0),ext_cha(1)! [15542.316653] 7986@C23L3,operate_loader_phy() 423: oper_radio after decision: bw(3), prim_ch(36), cen_ch_1(50), cen_ch_2(0)! [15542.327841] 7986@C00L1,mt7986_apply_dpd_flatness_data() 620: eeprom 0x19a bit 0 is 0, do runtime cal [15542.337039] 7986@C03L3,MtCmdChannelSwitch() 2534: ctrl_chl=36, ctrl_ch2=0, cent_ch=50 DBDCIdx=1, ChBand=1, BW=3, TXStream=3, RXStream=3, scan(0) [15542.590326] 7986@C03L3,MtCmdSetTxRxPath() 2795: ctrl_chl=36, ctrl_ch2=0, cent_ch=50, RxPath=7, BandIdx=1, ChBand=1, BW=3,TXStream=3, RXStream=7, scan(0) [15542.609110] 7986@C12L1,EDCCAInit() 21135: EDCCA compensation: uni_compensation=0, bw_compensation=0, final compensation=0 [15542.620443] WiFi@C00L2,dump_ht_cap() 29: Caller: wlan_operate_init+0xa4/0x148 [mt_wifi] [15542.628510] wdev_attr_update(): wdevId1 = ee:00:9a:11:27:2b [15542.634230] WiFi@C12L1,wifi_sys_open() 902: wifi_sys_open():band1 group_idx[1]=4, bw_grp=16 omac=0 [15542.643267] 7986@C08L3,hw_ctrl_flow_v2_open() 13: wdev_idx=1 [15542.650124] 7986@C02L2,AsicRadioOnOffCtrl() 2338: DbdcIdx=1 RadioOn [15542.656382] BcnCheck start after 4200 ms (ra0) [15542.656943] 7986@C08L1,UpdateBeaconHandler() 1889: wdev(1) bss not ready (state:0, caller:APUpdateOperationMode+0x114/0x274 [mt_wifi])!! [15542.674334] 7986@C00L2,ApAutoChannelAtBootUp() 333: -----------------> [15542.680957] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15542.686425] 7986@C03L1,MtCmdSetMacTxRx() 10245: (ret = 0) [15542.691815] 7986@C00L1,ApAutoChannelAtBootUp() 490: zero-wait DFS is not enabled [15542.699190] 7986@C08L1,ap_run_at_boot() 622:  ACS is disable !! [15542.706056] 7986@C08L2,APStartUpForMbss() 983: ===>(caller:ap_inf_open+0x10c/0x4c0 [mt_wifi]), mbss_idx:1, CfgMode:0 [15542.716566] 7986@C15L2,APPMFInit() 130: [PMF] apidx=1, MFPC=1, MFPR=0, SHA256=0 [15542.751949] net wwan0: link_state 0x1 -> 0x0 [15542.762459] 7986@C18L3,BuildChannelList() 484: [15542.767259] WiFi@C00L2,dump_ht_cap() 29: Caller: dump_ht_cap+0x10/0x1c [mt_wifi] [15542.774898] WiFi@C08L2,ap_link_up() 7166: (caller:wdev_do_linkup+0x20/0x34 [mt_wifi]), wdev(1) [15542.783508] 7986@C01L3,wifi_sys_linkup() 1231: wdev idx = 1 [15542.839763] 7986@C00L3,wtc_acquire_groupkey_wcid() 81: Found a non-occupied wtbl_idx:542 for WDEV_TYPE:1 [15542.839763] LinkToOmacIdx = 0, LinkToWdevType = 1 [15542.854113] 7986@C08L3,hw_ctrl_flow_v2_link_up() 72: wdev_idx=1 [15542.866822] 7986@C08L3,UpdateBeaconHandler() 1910: BCN_UPDATE_INIT, OmacIdx = 0 (rax0) [15542.874734] 7986@C08L2,bcn_buf_init() 169: BcnPkt is allocated!, bcn offload=1 [15542.881968] 7986@C08L3,UpdateBeaconHandler() 1923: Band1 BcnInitedRnd = 52 [15542.899950] PrintSrCmd: [15542.899950] u1CmdSubId = 1, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0 [15542.899950] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 1 [15542.913929] PrintSrCmd: [15542.913929] u1CmdSubId = 5, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0 [15542.913929] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15542.927902] PrintSrCmd: [15542.927902] u1CmdSubId = 3, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0 [15542.927902] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 1 [15542.941871] PrintSrCmd: [15542.941871] u1CmdSubId = 23, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0 [15542.941871] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15542.955927] PrintSrCmd: [15542.955927] u1CmdSubId = 31, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0 [15542.955927] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15542.970017] PrintSrCmd: [15542.970017] u1CmdSubId = 33, u1ArgNum = 0, u1DbdcIdx = 1, u1Status = 0 [15542.970017] u1DropTaIdx = 0, u1StaIdx = 0, u4Value = 0 [15542.984084] 7986@C12L1,EDCCAInit() 21135: EDCCA compensation: uni_compensation=0, bw_compensation=0, final compensation=0 [15542.995025] 7986@C03L3,MtCmdTxPwrShowInfo() 13745: ucTxPowerInfoCatg: 2, BandIdx: 1 [15543.002755] 7986@C03L3,MtCmdTxPwrShowInfo() 13774: (ret = 0) [15543.002848] [mtf_txpower_all_rate_info] band_idx:1 pwr:50 ChBand:5G ePAGain:0 [15543.008407] extif_set_dev(rax0) [15543.018639] mtk_ppe_dev_register_hook : ineterface rax0 register (2) [15543.025244] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15543.031310] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15543.036962] extif_put_dev(wwan0) [15543.046054] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15543.054936] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15543.068203] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15543.073936] extif_put_dev(wwan0_1) [15543.083645] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15543.095407] br-lan: port 3(rax0) entered blocking state [15543.100714] br-lan: port 3(rax0) entered disabled state [15543.105988] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15543.111707] rax0: entered allmulticast mode [15543.115978] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15543.121852] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15543.127521] rax0: entered promiscuous mode [15543.131886] br-lan: port 3(rax0) entered blocking state [15543.137111] br-lan: port 3(rax0) entered forwarding state [15544.279213] 7986@C08L3,ap_peer_auth_req_at_idle_action() 713: AUTH - MBSS(1), Rcv AUTH seq#1, Alg=3, Status=126 from [wcid=1023]b8:f7:75:77:76:7b [15544.351495] 7986@C08L3,ap_peer_auth_req_at_idle_action() 713: AUTH - MBSS(1), Rcv AUTH seq#2, Alg=3, Status=0 from [wcid=1023]b8:f7:75:77:76:7b [15544.364453] entrytb_aid_aquire(): found non-occupied aid:2, allocated from:2 [15544.371530] 7986@C13L2,MacTableInsertEntry() 1537: New Sta:b8:f7:75:77:76:7b [15544.378624] 7986@C08L1,PeerAssocReqCmmSanity() 984: DSCPPolicyEnable = 1 [15544.385351] 7986@C08L1,PeerAssocReqCmmSanity() 1445: IE_WLAN_EXTENSION: no handler for extension_id:137 [15544.394754] 7986@C08L3,ap_cmm_peer_assoc_req_action() 1645: Recv Assoc from STA - b8:f7:75:77:76:7b [15544.404203] 7986@C08L3,ap_cmm_peer_assoc_req_action() 2168: ASSOC Send ASSOC response (Status=0)... [15544.413397] 7986@C01L3,wifi_sys_conn_act() 1115: wdev idx = 1 [15544.419567] 7986@C08L3,hw_ctrl_flow_v2_connt_act() 215: wdev_idx=1 [15544.550284] 7986@C15L3,WPABuildPairMsg1() 5278: <=== send Msg1 of 4-way [15544.561519] 7986@C15L3,PeerPairMsg2Action() 6197: ===>Receive msg 2 [15544.568408] 7986@C15L3,WPABuildPairMsg3() 5555: <=== send Msg3 of 4-way [15544.584041] 7986@C15L3,PeerPairMsg4Action() 6627: ===>Receive msg 4 [15544.596039] 7986@C15L2,PeerPairMsg4Action() 6982: AP SETKEYS DONE(rax0) - AKMMap=WPA3PSK, PairwiseCipher=AES, GroupCipher=AES, wcid=1 from b8:f7:75:77:76:7b [15544.596039] [15545.449121] BcnCheck start after 1700 ms (ra0) [15545.453569] BcnCheck start after 2700 ms (ra0) [15548.247396] BcnCheck start after 200 ms (ra0) [15549.850666] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15563.470167] net wwan0: link_state 0x0 -> 0x1 [15563.478189] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15563.484188] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15563.489925] extif_set_dev(wwan0) [15563.493392] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15563.502884] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15563.508903] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15563.514562] extif_set_dev(wwan0_1) [15564.017234] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15564.023356] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15564.068707] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15564.074693] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15564.547588] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15571.733287] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15576.660925] 7986@C01L2,wifi_sys_disconn_act() 1002: wdev_idx=1 [15576.668102] 7986@C08L3,hw_ctrl_flow_v2_disconnt_act() 172: wdev_idx=1 [15576.675173] 7986@C13L2,MacTableDeleteEntry() 1793: Del Sta:b8:f7:75:77:76:7b [15576.682304] 7986@C08L3,ap_peer_deauth_action() 427: AUTH - receive DE-AUTH(seq-69) from b8:f7:75:77:76:7b, reason=1 [15576.713092] 7986@C08L3,ap_peer_auth_req_at_idle_action() 713: AUTH - MBSS(1), Rcv AUTH seq#1, Alg=0, Status=0 from [wcid=1023]b8:f7:75:77:76:7b [15576.726004] entrytb_aid_aquire(): found non-occupied aid:2, allocated from:2 [15576.733056] 7986@C13L2,MacTableInsertEntry() 1537: New Sta:b8:f7:75:77:76:7b [15576.741869] 7986@C08L1,PeerAssocReqCmmSanity() 984: DSCPPolicyEnable = 1 [15576.748556] 7986@C08L1,PeerAssocReqCmmSanity() 1445: IE_WLAN_EXTENSION: no handler for extension_id:137 [15576.757935] 7986@C08L3,ap_cmm_peer_assoc_req_action() 1645: Recv Assoc from STA - b8:f7:75:77:76:7b [15576.767247] 7986@C08L3,ap_cmm_peer_assoc_req_action() 2168: ReASSOC Send ReASSOC response (Status=0)... [15576.776768] 7986@C01L3,wifi_sys_conn_act() 1115: wdev idx = 1 [15576.782811] 7986@C08L3,hw_ctrl_flow_v2_connt_act() 215: wdev_idx=1 [15576.812590] 7986@C15L1,store_pmkid_cache_in_sec_config() 3326: pStaCfg is null [15576.930267] 7986@C15L3,WPABuildPairMsg1() 5278: <=== send Msg1 of 4-way [15576.937445] 7986@C15L3,PeerPairMsg2Action() 6197: ===>Receive msg 2 [15576.944247] 7986@C15L3,WPABuildPairMsg3() 5555: <=== send Msg3 of 4-way [15576.957005] 7986@C15L3,PeerPairMsg4Action() 6627: ===>Receive msg 4 [15576.968484] 7986@C15L2,PeerPairMsg4Action() 6982: AP SETKEYS DONE(rax0) - AKMMap=WPA3PSK, PairwiseCipher=AES, GroupCipher=AES, wcid=1 from b8:f7:75:77:76:7b [15576.968484] [15640.740575] qmi_wwan_q 2-1:1.4 wwan0: NETDEV WATCHDOG: CPU: 0: transmit queue 0 timed out 5030 ms [15660.938173] qmi_wwan_q 2-1:1.4 wwan0: NETDEV WATCHDOG: CPU: 0: transmit queue 0 timed out 5010 ms [15664.176203] usb 2-1: USB disconnect, device number 2 [15664.181397] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0 [15664.189559] option 2-1:1.0: device disconnected [15664.194409] option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1 [15664.202742] option 2-1:1.1: device disconnected [15664.207659] option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2 [15664.216000] option 2-1:1.2: device disconnected [15664.220901] option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3 [15664.229433] option 2-1:1.3: device disconnected [15664.234595] qmap_unregister_device(wwan0_1) [15664.239384] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15664.245035] extif_put_dev(wwan0_1) [15664.330406] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15664.342464] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15664.348667] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15664.364209] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15664.476436] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15664.482781] qmi_wwan_q 2-1:1.4 wwan0: unregister 'qmi_wwan_q' usb-11200000.usb-1, RMNET/USB device [15664.491866] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15664.497900] extif_put_dev(wwan0) [15664.521924] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15664.530928] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15664.537151] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15664.544640] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15664.550669] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15665.055010] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15665.061187] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15665.109537] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15665.116970] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15675.719299] usb 2-1: new SuperSpeed USB device number 3 using xhci-mtk [15675.751402] usb 2-1: LPM exit latency is zeroed, disabling LPM. [15675.773559] option 2-1:1.0: GSM modem (1-port) converter detected [15675.779957] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB0 [15675.787119] option 2-1:1.1: GSM modem (1-port) converter detected [15675.793543] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB1 [15675.800811] option 2-1:1.2: GSM modem (1-port) converter detected [15675.807178] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB2 [15675.814453] option 2-1:1.3: GSM modem (1-port) converter detected [15675.820948] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB3 [15675.829010] qmi_wwan_q 2-1:1.4: cdc-wdm0: USB WDM device [15675.834394] qmi_wwan_q: qmi_wwan_bind:if get zero mac, random mac addr 000000005dcbc7f7 [15675.842710] qmi_wwan_q 2-1:1.4: Quectel RM520N-GL work on RawIP mode [15675.851260] qmi_wwan_q 2-1:1.4: rx_urb_size = 31744 [15675.856182] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15675.862338] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15675.868115] qmi_wwan_q 2-1:1.4 wwan0: register 'qmi_wwan_q' at usb-11200000.usb-1, RMNET/USB device, ae:e0:da:eb:86:df [15675.878907] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15675.885051] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15675.890886] net wwan0: qmap_register_device wwan0_1 [15675.891120] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15676.257362] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15676.269215] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15692.260792] net wwan0: ul_data_aggregation_max_datagrams=11, ul_data_aggregation_max_size=8192, dl_minimum_padding=0 [15693.635126] net wwan0: link_state 0x0 -> 0x1 [15693.643131] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15693.649122] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15693.654813] extif_set_dev(wwan0) [15693.658399] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15693.664733] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15693.670610] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15693.676287] extif_set_dev(wwan0_1) [15694.226849] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15694.232977] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15694.278879] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15694.284865] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15694.707883] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15701.172320] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [15887.323429] 7986@C08L3,ap_peer_auth_req_at_idle_action() 713: AUTH - MBSS(1), Rcv AUTH seq#1, Alg=3, Status=126 from [wcid=1023]de:44:88:c3:78:62 [15887.395367] 7986@C08L3,ap_peer_auth_req_at_idle_action() 713: AUTH - MBSS(1), Rcv AUTH seq#2, Alg=3, Status=0 from [wcid=1023]de:44:88:c3:78:62 [15887.408280] entrytb_aid_aquire(): found non-occupied aid:3, allocated from:2 [15887.415328] 7986@C13L2,MacTableInsertEntry() 1537: New Sta:de:44:88:c3:78:62 [15887.422418] 7986@C08L1,PeerAssocReqCmmSanity() 1445: IE_WLAN_EXTENSION: no handler for extension_id:137 [15887.431813] 7986@C08L1,PeerAssocReqCmmSanity() 984: DSCPPolicyEnable = 1 [15887.438496] 7986@C08L3,ap_cmm_peer_assoc_req_action() 1645: Recv Assoc from STA - de:44:88:c3:78:62 [15887.447830] 7986@C08L3,ap_cmm_peer_assoc_req_action() 2168: ASSOC Send ASSOC response (Status=0)... [15887.456907] 7986@C01L3,wifi_sys_conn_act() 1115: wdev idx = 1 [15887.462945] 7986@C08L3,hw_ctrl_flow_v2_connt_act() 215: wdev_idx=1 [15887.590530] 7986@C15L3,WPABuildPairMsg1() 5278: <=== send Msg1 of 4-way [15887.597400] 7986@C15L3,PeerPairMsg2Action() 6197: ===>Receive msg 2 [15887.604224] 7986@C15L3,WPABuildPairMsg3() 5555: <=== send Msg3 of 4-way [15887.610832] 7986@C15L3,PeerPairMsg4Action() 6627: ===>Receive msg 4 [15887.622297] 7986@C15L2,PeerPairMsg4Action() 6982: AP SETKEYS DONE(rax0) - AKMMap=WPA3PSK, PairwiseCipher=AES, GroupCipher=AES, wcid=2 from de:44:88:c3:78:62 [15887.622297] [15963.504038] qmi_wwan_q 2-1:1.4 wwan0: NETDEV WATCHDOG: CPU: 2: transmit queue 0 timed out 5610 ms [15978.464947] qmi_wwan_q 2-1:1.4 wwan0: NETDEV WATCHDOG: CPU: 2: transmit queue 0 timed out 5330 ms [15997.483457] qmi_wwan_q 2-1:1.4 wwan0: NETDEV WATCHDOG: CPU: 2: transmit queue 0 timed out 5300 ms [16010.179783] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [16010.185447] extif_put_dev(wwan0_1) [16010.388980] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [16010.402036] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [16010.506091] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [16010.512067] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [16010.517996] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [16010.523652] extif_set_dev(wwan0_1) [16010.538850] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [16010.544702] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [16011.078674] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [16011.084858] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [16011.133788] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [16011.140139] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [16011.395391] ppd_dev_setting : now rx dev: eth0, tx dev: eth0 [16036.419996] qmi_wwan_q 2-1:1.4 wwan0: NETDEV WATCHDOG: CPU: 2: transmit queue 0 timed out 5640 ms [16139.592157] 7986@C01L2,wifi_sys_disconn_act() 1002: wdev_idx=1 [16139.598446] 7986@C08L3,hw_ctrl_flow_v2_disconnt_act() 172: wdev_idx=1 [16139.605391] 7986@C13L2,MacTableDeleteEntry() 1793: Del Sta:b8:f7:75:77:76:7b [16139.612605] 7986@C08L3,ap_peer_deauth_action() 427: AUTH - receive DE-AUTH(seq-79) from b8:f7:75:77:76:7b, reason=1 [16144.775033] WiFi@C15L1,RTMPDeletePMKIDCache() 1245: IF(1), del PMKID CacheIdx=0 [16239.783171] 7986@C08L3,ap_peer_auth_req_at_idle_action() 713: AUTH - MBSS(1), Rcv AUTH seq#1, Alg=3, Status=126 from [wcid=1023]b8:f7:75:77:76:7b [16239.854320] 7986@C08L3,ap_peer_auth_req_at_idle_action() 713: AUTH - MBSS(1), Rcv AUTH seq#2, Alg=3, Status=0 from [wcid=1023]b8:f7:75:77:76:7b [16239.867224] entrytb_aid_aquire(): found non-occupied aid:2, allocated from:2 [16239.874268] 7986@C13L2,MacTableInsertEntry() 1537: New Sta:b8:f7:75:77:76:7b [16239.881343] 7986@C08L1,PeerAssocReqCmmSanity() 984: DSCPPolicyEnable = 1 [16239.888045] 7986@C08L1,PeerAssocReqCmmSanity() 1445: IE_WLAN_EXTENSION: no handler for extension_id:137 [16239.897428] 7986@C08L3,ap_cmm_peer_assoc_req_action() 1645: Recv Assoc from STA - b8:f7:75:77:76:7b [16239.906652] 7986@C08L3,ap_cmm_peer_assoc_req_action() 2168: ASSOC Send ASSOC response (Status=0)... [16239.915714] 7986@C01L3,wifi_sys_conn_act() 1115: wdev idx = 1 [16239.921758] 7986@C08L3,hw_ctrl_flow_v2_connt_act() 215: wdev_idx=1 [16240.058244] 7986@C15L3,WPABuildPairMsg1() 5278: <=== send Msg1 of 4-way [16240.067040] 7986@C15L3,PeerPairMsg2Action() 6197: ===>Receive msg 2 [16240.073843] 7986@C15L3,WPABuildPairMsg3() 5555: <=== send Msg3 of 4-way [16240.080472] 7986@C15L3,PeerPairMsg4Action() 6627: ===>Receive msg 4 [16240.092136] 7986@C15L2,PeerPairMsg4Action() 6982: AP SETKEYS DONE(rax0) - AKMMap=WPA3PSK, PairwiseCipher=AES, GroupCipher=AES, wcid=1 from b8:f7:75:77:76:7b [16240.092136] [16339.051278] 7986@C01L2,wifi_sys_disconn_act() 1002: wdev_idx=1 [16339.057468] 7986@C08L3,hw_ctrl_flow_v2_disconnt_act() 172: wdev_idx=1 [16339.064429] 7986@C13L2,MacTableDeleteEntry() 1793: Del Sta:de:44:88:c3:78:62 [16339.071630] 7986@C08L3,ap_peer_deauth_action() 427: AUTH - receive DE-AUTH(seq-2144) from de:44:88:c3:78:62, reason=3 [16487.880492] WiFi@C15L1,RTMPDeletePMKIDCache() 1245: IF(1), del PMKID CacheIdx=1 [16655.091548] qmi_wwan_q 2-1:1.4 wwan0: NETDEV WATCHDOG: CPU: 2: transmit queue 0 timed out 5100 ms [16840.622981] WiFi@C15L1,RTMPDeletePMKIDCache() 1245: IF(1), del PMKID CacheIdx=0 [17583.513838] qmi_wwan_q 2-1:1.4 wwan0: NETDEV WATCHDOG: CPU: 2: transmit queue 0 timed out 5390 ms [18512.040994] qmi_wwan_q 2-1:1.4 wwan0: NETDEV WATCHDOG: CPU: 2: transmit queue 0 timed out 5670 ms