This thread has been locked.

If you have a related question, please click the "Ask a related question" button in the top right corner. The newly created question will be automatically linked to this question.

WL1837MOD: WL1837: Change firmware log level for gLogger

Part Number: WL1837MOD

In order to help debug a new board I am wanting to increase the firmware log level so that more information is available. At an application level when I do a scan I get a scan event back but it empty.  It is using 8.9.0.0.78 (for historic reasons)

My first attempt to debug it was to send a CMD_CONFIG_FWLOGGER command with log_severity set to 1 (also tried 2,4 and FF). I see no increase in log output with any of these options.

I have another board type which does work and I get an identical log file in gLogger from it even when I know it has scanned correctly. From this I deduce that the default fw scan level does not show any scan activity.

Is it possible to get some information from fw? I am sure wl18xx-conf is setup correctly which why I am looking to the firmware to get some hints.

Thanks, Iain

  • Iain ,

    I believe firmware scan logs are enabled by default.

    I just tested with latest firmware version 8.9.0.0.86 and did notice scan related firmware logs

    Best

    Saurabh

  • Hi Saurabh,

    Even when running this latest .89 fw I am not seeing any scan related logs. I can now get scan results in the stack. This is an RTOS stack so I don't have any linux scripts or driver running which might set the log level.

    Which linux kernel are you using so I can cross check the source? Below is what I see in the log during the boot and scan.

    Iain

    Type,Level,Time,Firmware TSF,TSF Delta [uS],File Name,Bin,Function Name,Message,Packet
    0,0,27:52.2,,,,,,Connected to COM5 successfully,
    0,0,27:55.8,,,,,,Logger Started.,
    0,0,27:55.8,,,,,,Syncing: 1 of 8 valid packets received,
    0,0,27:55.8,,,,,,Syncing: 2 of 8 valid packets received,
    0,0,27:55.8,,,,,,Syncing: 3 of 8 valid packets received,
    0,0,27:55.8,,,,,,Syncing: 4 of 8 valid packets received,
    0,0,27:55.8,,,,,,Syncing: 5 of 8 valid packets received,
    0,0,27:55.8,,,,,,Syncing: 6 of 8 valid packets received,
    0,0,27:55.8,,,,,,Syncing: 7 of 8 valid packets received,
    0,0,27:55.8,,,,,,Syncing: 8 of 8 valid packets received,
    0,0,27:55.8,,,,,,Synced.,
    1,0,27:55.8,0,0,00:00,1,0,---- FW keep alive ----,
    1,0,27:55.8,0,0,release.c:46,1,PrintRevision,Firmware Revision (FRev) Rev 8.9.0.0.86,800015dd0100
    1,0,27:55.8,0,0,thermal_sensor.c:895,1,ThermalSensor_FsmInit,"Thermal Sensor is Enabled, isMimoDevice=1",8000a960000001
    1,0,27:55.8,0,0,thermal_sensor.c:958,1,ThermalSensor_FsmInit,fs_val=3,8.00024E+13
    1,0,27:55.8,0,0,thermal_sensor.c:971,1,ThermalSensor_FsmInit,"thrLowDefault=80, thrHighDefault=121, thr_high_max=140",80007d61000050798c
    1,0,27:55.8,0,0,thermal_sensor.c:200,1,ThermalSensor_SetThresholdLow,Setting threshold LOW to 80,8000715a000050
    1,0,27:55.8,0,0,thermal_sensor.c:221,1,ThermalSensor_SetThresholdHigh,Setting threshold HIGH to 121,80008f5a000079
    1,0,27:55.8,0,0,thermal_sensor.c:981,1,ThermalSensor_FsmInit,"Alpha factor=80/100 , fsm_enabled_delay =320",80049f610000504001
    1,0,27:55.8,0,0,thermal_sensor.c:995,1,ThermalSensor_FsmInit,"Band=0, thr_low=80, thr_high=121",8000cf610000005079
    1,0,27:55.8,0,0,tx_power_control.c:544,1,TxPwrCtrl_InitTxPowerTable_Phase1,"TxPwrCtrl: INIT1 - board HP: 0, board user config ini value 1",8000cfc4b0800001
    1,0,27:55.8,0,0,mac_phy_api.c:264,1,PHY_newPhyInit,PHY API version mismatch MAC_PHY_API_VERSION [41] vs. [40],80007b7f11002928
    1,0,27:55.8,0,0,tx_power_control.c:634,1,TxPwrCtrl_InitTxPowerTable_Phase2,"TxPwrCtrl: INIT - num of ant 2.4 - 1, threshold 18000",8.00463E+17
    1,0,27:55.8,0,0,tx_power_control.c:638,1,TxPwrCtrl_InitTxPowerTable_Phase2,TX Power values high/med/low 20000 / 10000 / 20000,801571260100204e1027204e
    1,0,27:55.8,0,0,tx_power_control.c:644,1,TxPwrCtrl_InitTxPowerTable_Phase2,"TxPwrCtrl: INIT_Ph2 - NON Serving idx 3, free idxs  role 0 0xf role 1 0xf",800079260100030f0f
    1,0,27:55.8,0,0,tx_power_control.c:662,1,TxPwrCtrl_InitTxPowerTable_Phase2,TxPwrCtrl: INIT - Config IMM RESPONSE REG NON tx pwr idx 3,8000a726010003
    1,0,27:55.8,0,0,calib_agent.c:146,1,calibAgentInit,Calibration Agent Init Complete PHY calib results pointer 80920c1a,8003116f00001a0c9280
    1,0,27:55.8,0,0,links.c:390,1,Links_ClearLinksTables,Init the links database,8000adbdb080
    1,0,27:55.8,0,0,links.c:1507,1,Links_ClearLinkContext,"Removing link context, flid 0, frid 0",8.00023E+15
    1,0,27:55.8,0,0,links.c:1507,1,Links_ClearLinkContext,"Removing link context, flid 1, frid 0",8.00023E+15
    1,0,27:55.8,0,0,links.c:1507,1,Links_ClearLinkContext,"Removing link context, flid 2, frid 0",8.00023E+15
    1,0,27:55.8,0,0,links.c:1507,1,Links_ClearLinkContext,"Removing link context, flid 3, frid 0",8.00023E+15
    1,0,27:55.9,0,0,links.c:1507,1,Links_ClearLinkContext,"Removing link context, flid 4, frid 0",8.00023E+15
    1,0,27:55.9,0,0,links.c:1507,1,Links_ClearLinkContext,"Removing link context, flid 5, frid 0",8.00023E+15
    1,0,27:55.9,0,0,links.c:1507,1,Links_ClearLinkContext,"Removing link context, flid 6, frid 0",8.00023E+15
    1,0,27:55.9,0,0,links.c:1507,1,Links_ClearLinkContext,"Removing link context, flid 7, frid 0",8.00023E+15
    1,0,27:55.9,0,0,links.c:1507,1,Links_ClearLinkContext,"Removing link context, flid 8, frid 0",8.00023E+15
    1,0,27:55.9,0,0,links.c:1507,1,Links_ClearLinkContext,"Removing link context, flid 9, frid 0",8.00023E+15
    1,0,27:55.9,0,0,links.c:1507,1,Links_ClearLinkContext,"Removing link context, flid 10, frid 0",8000232111000a00
    1,0,27:55.9,0,0,links.c:1507,1,Links_ClearLinkContext,"Removing link context, flid 11, frid 0",8000232111000b00
    1,0,27:55.9,0,0,links.c:1507,1,Links_ClearLinkContext,"Removing link context, flid 12, frid 0",8000232111000c00
    1,0,27:55.9,0,0,links.c:1507,1,Links_ClearLinkContext,"Removing link context, flid 13, frid 0",8000232111000d00
    1,0,27:55.9,0,0,links.c:1507,1,Links_ClearLinkContext,"Removing link context, flid 14, frid 0",8.00E+12
    1,0,27:55.9,0,0,links.c:1507,1,Links_ClearLinkContext,"Removing link context, flid 15, frid 0",8000232111000f00
    1,0,27:55.9,0,0,links.c:846,1,Links_AddLinkContext,"At TSF=0x00062146, link Added, flid 0, frid 255, WMM mode 1",80022127110046210600ff01
    1,0,27:55.9,0,0,links.c:470,1,Links_Init,Link init,80004fbeb080
    1,0,27:55.9,0,0,tx_frame_templates.c:1145,1,TxFrameTemplates_Init,Allocated template num 0,8000d5b5b08000
    1,0,27:55.9,0,0,tx_frame_templates.c:1145,1,TxFrameTemplates_Init,Allocated template num 1,8000d5b5b08001
    1,0,27:55.9,0,0,tx_frame_templates.c:1145,1,TxFrameTemplates_Init,Allocated template num 2,8000d5b5b08002
    1,0,27:55.9,0,0,tx_frame_templates.c:1145,1,TxFrameTemplates_Init,Allocated template num 3,8000d5b5b08003
    1,0,27:55.9,0,0,tx_frame_templates.c:1145,1,TxFrameTemplates_Init,Allocated template num 4,8000d5b5b08004
    1,0,27:55.9,0,0,immediate_response.c:150,1,IrBasicRateSetCfg,"IMM_RES configure basic rate set for BSSID#1 registers, a rates CFG_LUT_0_A 0x66443210 b rates CFG_LUT_0_B 0x8888",8007cf4b0100103244668888
    1,0,27:55.9,0,0,immediate_response.c:164,1,IrBasicRateSetCfg,"IMM_RES configure basic rate set for BSSID#2 registers, a rates CFG_LUT_0_A_BSSID_2 0x66443210 b rates CFG_LUT_0_B_BSSID_2 0x8888",8007194c0100103244668888
    1,0,27:55.9,0,0,immediate_response.c:225,1,IrBasicMCSSetCfg,"IMM_RES configure mcs rate set for BSSID#1 registers, CFG_FW_LUT1 0x76543210",8003c34c010010325476
    1,0,27:55.9,0,0,immediate_response.c:237,1,IrBasicMCSSetCfg,"IMM_RES configure mcs rate set for BSSID#2 registers, CFG_FW_LUT1_BSSID_2 0x76543210",8003f54c010010325476
    1,0,27:55.9,0,0,traffic_analyzer.c:219,1,traffic_analyzer_Init,"traffic analyzer init, registered to suspend/resume observer callbacks, threshold = 10000",800173d7b0801027
    1,0,27:55.9,0,0,traffic_analyzer_handlers.c:1433,1,traffic_analyzer_handlers_RegisterStartRoleNotifier,"At TSF=0x00062341, registered start role notify function callback in index = 0",800291d7000041230600
    1,0,27:55.9,0,0,traffic_analyzer_handlers.c:1470,1,traffic_analyzer_handlers_RegisterStopRoleNotifier,"At TSF=0x0006234B, registered stop role notify function callback in index = 0",800261d700004b230600
    1,0,27:55.9,0,0,ap_sleep_adapter.c:654,1,AP_Sleep_Init,APSL-CC Local DB Init,8000af370100
    1,0,27:55.9,0,0,soft_gemini_30_configuration_control.c:165,1,SoftGemini_EnableMode,SG enable,8.00087E+11
    1,0,27:55.9,0,0,soft_gemini_30_bt_interface.c:611,1,BtInterface_NotifyBtAdaptorBtOff,"BT OFF: old ENV 0x0, time 402491",800825880000003b2406
    1,0,27:55.9,0,0,soft_gemini_30_bt_interface.c:275,1,SoftGemini_BtIPChangeOperationModeNotification,BT_IP_SWITCHED_OFF coex_state: COEX_OPERATION_ENABLED_MODE,8000e788000001
    1,0,27:55.9,0,0,soft_gemini_30_bt_interface.c:339,1,SoftGemini_BtIPChangeOperationModeNotification,"BT_IP_changed bt status 0 source 0 (0 - wlan, other - bt) sg 1 may TRIGGER_BT_PEER",8000ef880000000001
    1,0,27:55.9,0,0,main.c:1078,1,main,"Code Init Complete, PG version = 2 metal version = 2, RDL_2",8.00025E+17
    1,0,27:55.9,0,0,infoele.c:266,1,WriteInfoEle,"At TSF=0x00063082, COMMANDS_WRITE_INFOELE_ENUM - ACX_EVENT_MBOX_MASK",8002abc9010082300611
    1,0,27:55.9,0,0,asu.c:3401,1,ASU_NotifyFlowControlOnSuspend,"Update suspend flow control for TWIN Role 255 , Suspend bitmap is :0x0 , Resume bitmap is :0x0",800061331000ff0000
    1,0,27:55.9,0,0,asu.c:4747,1,__SWI_Entry_ASU_Task,"ASU_Task -suspended role Id=7 , twin role : 255, event entry : 7",8000e94c100007ff07
    1,0,27:55.9,0,0,asu.c:4563,1,ASU_HandleTaskSuspendComplete,"NOTIFY SUSPEND2SCHED: Role is SUSPENDED. planID=1, endPlanEntryIndex=255, desired switch=0x630A1, Deviation=0x32",80201335100001ffa1300632
    1,0,27:55.9,0,0,infoele.c:266,1,WriteInfoEle,"At TSF=0x0006329D, COMMANDS_WRITE_INFOELE_ENUM - ACX_HOST_IF_CFG_BITMAP",8002abc901009d320639
    1,0,27:55.9,0,0,host_slave_manager.c:249,1,HSM_Config,The amount of extra MemBlk is configured to 1,8000b108000001
    1,0,27:55.9,0,0,host_slave_manager.c:262,1,HSM_Config,"HS_TX_CFG 0x301000f, hostIfCfgBitmap 0x59, hostSdioBlockSize 0x100",8013f70800000f000103590001
    1,0,27:55.9,0,0,commands.c:185,1,CommandParser,"At TSF=0x000634D7, called Command CMD_SET_TEMPLATE",8002edd20100d734060c
    1,0,27:55.9,0,0,tx_frame_templates.c:1944,1,TxEngine_ConfigureTemplate,Entered TxEngine_ConfigureTemplate type: CFG_TEMPLATE_PROBE_REQ_2_4_ONE_SHOT   ROLEID : 255,8000a597000002ff
    1,0,27:55.9,0,0,commands.c:185,1,CommandParser,"At TSF=0x00063704, called Command CMD_SET_TEMPLATE",8002edd201000437060c
    1,0,27:55.9,0,0,tx_frame_templates.c:1944,1,TxEngine_ConfigureTemplate,Entered TxEngine_ConfigureTemplate type: CFG_TEMPLATE_PROBE_REQ_5_ONE_SHOT   ROLEID : 255,8000a597000003ff
    1,0,27:55.9,0,0,commands.c:185,1,CommandParser,"At TSF=0x0006392E, called Command CMD_SET_TEMPLATE",8002edd201002e39060c
    1,0,27:55.9,0,0,tx_frame_templates.c:1944,1,TxEngine_ConfigureTemplate,Entered TxEngine_ConfigureTemplate type: CFG_TEMPLATE_PROBE_REQ_2_4_PERIODIC   ROLEID : 255,8000a597000013ff
    1,0,27:55.9,0,0,commands.c:185,1,CommandParser,"At TSF=0x00063B5A, called Command CMD_SET_TEMPLATE",8002edd201005a3b060c
    1,0,27:55.9,0,0,tx_frame_templates.c:1944,1,TxEngine_ConfigureTemplate,Entered TxEngine_ConfigureTemplate type: CFG_TEMPLATE_PROBE_REQ_5_PERIODIC   ROLEID : 255,8000a597000014ff
    1,0,27:55.9,0,0,commands.c:185,1,CommandParser,"At TSF=0x00063DA3, called Command CMD_SET_TEMPLATE",8002edd20100a33d060c
    1,0,27:55.9,0,0,tx_frame_templates.c:1944,1,TxEngine_ConfigureTemplate,Entered TxEngine_ConfigureTemplate type: TEMPLATE_NULL_DATA   ROLEID : 255,8000a597000000ff
    1,0,27:55.9,0,0,commands.c:185,1,CommandParser,"At TSF=0x00063FEA, called Command CMD_SET_TEMPLATE",8002edd20100ea3f060c
    1,0,27:55.9,0,0,tx_frame_templates.c:1944,1,TxEngine_ConfigureTemplate,Entered TxEngine_ConfigureTemplate type: TEMPLATE_QOS_NULL_DATA   ROLEID : 255,8000a597000005ff
    1,0,27:55.9,0,0,commands.c:185,1,CommandParser,"At TSF=0x00064231, called Command CMD_SET_TEMPLATE",8002edd201003142060c
    1,0,27:55.9,0,0,tx_frame_templates.c:1944,1,TxEngine_ConfigureTemplate,Entered TxEngine_ConfigureTemplate type: TEMPLATE_PS_POLL   ROLEID : 255,8000a597000006ff
    1,0,27:55.9,0,0,commands.c:185,1,CommandParser,"At TSF=0x00064473, called Command CMD_SET_TEMPLATE",8002edd201007344060c
    1,0,27:55.9,0,0,tx_frame_templates.c:1944,1,TxEngine_ConfigureTemplate,Entered TxEngine_ConfigureTemplate type: TEMPLATE_PROBE_RESPONSE   ROLEID : 255,8000a597000004ff
    1,0,27:55.9,0,0,commands.c:185,1,CommandParser,"At TSF=0x000646B5, called Command CMD_SET_TEMPLATE",8002edd20100b546060c
    1,0,27:55.9,0,0,tx_frame_templates.c:1944,1,TxEngine_ConfigureTemplate,Entered TxEngine_ConfigureTemplate type: TEMPLATE_IBSS_BEACON   ROLEID : 255,8000a597000001ff
    1,0,27:55.9,0,0,commands.c:185,1,CommandParser,"At TSF=0x000648F7, called Command CMD_SET_TEMPLATE",8002edd20100f748060c
    1,0,27:55.9,0,0,tx_frame_templates.c:1944,1,TxEngine_ConfigureTemplate,Entered TxEngine_ConfigureTemplate type: TEMPLATE_ARP_RSP   ROLEID : 255,8000a59700000fff
    1,0,27:55.9,0,0,commands.c:185,1,CommandParser,"At TSF=0x00064B39, called Command CMD_SET_TEMPLATE",8002edd20100394b060c
    1,0,27:55.9,0,0,tx_frame_templates.c:1944,1,TxEngine_ConfigureTemplate,Entered TxEngine_ConfigureTemplate type: TEMPLATE_AP_PROBE_RESPONSE   ROLEID : 255,8000a59700000eff
    1,0,27:55.9,0,0,commands.c:185,1,CommandParser,"At TSF=0x00064D77, called Command CMD_SET_TEMPLATE",8002edd20100774d060c
    1,0,27:55.9,0,0,tx_frame_templates.c:1944,1,TxEngine_ConfigureTemplate,Entered TxEngine_ConfigureTemplate type: TEMPLATE_AP_BEACON   ROLEID : 255,8000a59700000dff
    1,0,27:55.9,0,0,commands.c:185,1,CommandParser,"At TSF=0x00064FB8, called Command CMD_SET_TEMPLATE",8002edd20100b84f060c
    1,0,27:55.9,0,0,tx_frame_templates.c:1944,1,TxEngine_ConfigureTemplate,Entered TxEngine_ConfigureTemplate type: TEMPLATE_DEAUTH_AP   ROLEID : 255,8000a597000010ff
    1,0,27:55.9,0,0,commands.c:185,1,CommandParser,"At TSF=0x00065200, called Command CMD_SET_TEMPLATE",8002edd201000052060c
    1,0,27:55.9,0,0,tx_frame_templates.c:1944,1,TxEngine_ConfigureTemplate,Entered TxEngine_ConfigureTemplate type: TEMPLATE_KLV   ROLEID : 255,8000a597000007ff
    1,0,27:55.9,0,0,commands.c:185,1,CommandParser,"At TSF=0x00065461, called Command CMD_SET_TEMPLATE",8002edd201006154060c
    1,0,27:55.9,0,0,tx_frame_templates.c:1944,1,TxEngine_ConfigureTemplate,Entered TxEngine_ConfigureTemplate type: TEMPLATE_KLV   ROLEID : 255,8000a597000007ff
    1,0,27:55.9,0,0,commands.c:185,1,CommandParser,"At TSF=0x000656A9, called Command CMD_SET_TEMPLATE",8002edd20100a956060c
    1,0,27:55.9,0,0,tx_frame_templates.c:1944,1,TxEngine_ConfigureTemplate,Entered TxEngine_ConfigureTemplate type: TEMPLATE_KLV   ROLEID : 255,8000a597000007ff
    1,0,27:55.9,0,0,commands.c:185,1,CommandParser,"At TSF=0x000658F2, called Command CMD_SET_TEMPLATE",8002edd20100f258060c
    1,0,27:55.9,0,0,tx_frame_templates.c:1944,1,TxEngine_ConfigureTemplate,Entered TxEngine_ConfigureTemplate type: TEMPLATE_KLV   ROLEID : 255,8000a597000007ff
    1,0,27:55.9,0,0,infoele.c:266,1,WriteInfoEle,"At TSF=0x00065B16, COMMANDS_WRITE_INFOELE_ENUM - ACX_MEM_CFG",8002abc90100165b0601
    1,0,27:55.9,0,0,mem_init.c:304,1,ConfigureDescriptorQueues,Configured a total of 32 TX descriptors,8.0004E+13
    1,0,27:55.9,0,0,mem_init.c:339,1,CalculatePoolsSize,"Pool size allocation, availableMBlocks 262, [POOL_TX].nTotal=0,[POOL_RX].nTotal=0",80019b53000006010000
    1,0,27:55.9,0,0,mem_init.c:339,1,CalculatePoolsSize,"Pool size allocation, availableMBlocks 262, [POOL_TX].nTotal=45,[POOL_RX].nTotal=209",80019b53000006012dd1
    1,0,27:55.9,0,0,mem_init.c:490,1,ConfigurePacketPools,"[CFG_MEM] configp->rxMemblockNumber 0x28, memMap.numRxMemBlks 0xd1",8000f354000028d1
    1,0,27:55.9,0,0,mem_init.c:491,1,ConfigurePacketPools,"[CFG_MEM] configp->txMinimumMemblockNumber 0x28, memMap.numTxMemBlks 0x2c",8000fb540000282c
    1,0,27:55.9,0,0,host_slave_manager.c:231,1,HSM_Init,HSM Init: Setting the head of the TX free list to HW to 0xb10760,80028f0800006007b1
    1,0,27:55.9,0,0,mem_init.c:1221,1,ConfigMemoryResources,"mem_cfg: freeDramStart 0x2040fac4, freeDramEnd 0x20410000, remainder 1340",801f51580000c4fa4020000041203c05
    1,0,27:55.9,0,0,mem_init.c:1226,1,ConfigMemoryResources,"mem_cfg: freeDramStart # allocated bytes 10880, # allocated bytes for alignment 2",80015b580000802a02

  • Hi Iain,

    I am running kernel 4.19. I will capture firmware log and share csv file

    Thanks

    Saurabh