efuse component in 4.1 fails to honor log level settings

[jsam589]

Related post describing similar problem with wifi component in 4.1: viewtopic.php?f=13&t=16409

Problem: efuse component in 4.1 (beta2 and release/v4.1 as of 04-July-2020) behaves incorrectly with respect to filtering of log level.

If application default log level is set for DEBUG and efuse is specifically set to show only INFO (and more severe), some of the efuse D-level messages still are printed. Specifically, the ones which contain text "coding scheme 0" are not properly filtered.

IDF 4.0 release works as expected, so this is a definite regression in 4.1.

Log captures below. Using wifi station example from release/4.1 (same behavior is observed in 4.1 beta2).
Application default log level is set as Debug. You can see that some efuse DEBUG messages are filtered but not all.

Capture 1. Also applies esp_log_level_set("efuse", ESP_LOG_INFO);
efuse DEBUG messages should not appear, e.g. "coding scheme 0" messages should not appear.

Code: Select all

W (876) wifi station: ESP_WIFI_MODE_STA
D (876) event: running task for loop 0x3ffbe760
D (876) event: created task for loop 0x3ffbe760
D (876) event: created event loop 0x3ffbe760
D (886) esp_netif_objects: esp_netif_add_to_list 0x3ffbf690
D (886) esp_netif_objects: esp_netif_add_to_list netif added successfully (total netifs: 1)
I (906) wifi:wifi driver task: 3ffc1bf0, prio:23, stack:6656, core=0
I (906) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE
D (916) efuse: coding scheme 0
D (916) efuse: coding scheme 0
D (916) efuse: coding scheme 0
D (926) efuse: coding scheme 0
D (926) efuse: coding scheme 0
D (926) efuse: coding scheme 0
D (936) efuse: coding scheme 0
I (936) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE
D (946) efuse: coding scheme 0
D (946) efuse: coding scheme 0
D (946) efuse: coding scheme 0
D (956) efuse: coding scheme 0
D (956) efuse: coding scheme 0
D (956) efuse: coding scheme 0
D (966) efuse: coding scheme 0
I (986) wifi:wifi firmware version: aa3b749
I (986) wifi:config NVS flash: enabled
I (986) wifi:config nano formating: disabled
I (986) wifi:Init dynamic tx buffer num: 32
I (996) wifi:Init data frame dynamic rx buffer num: 32
I (996) wifi:Init management frame dynamic rx buffer num: 32
I (1006) wifi:Init management short buffer num: 32
I (1006) wifi:Init static rx buffer size: 1600
I (1016) wifi:Init static rx buffer num: 10
I (1016) wifi:Init dynamic rx buffer num: 32
D (1016) phy_init: loading PHY init data from application binary
D (1026) efuse: coding scheme 0
D (1026) efuse: coding scheme 0
D (1036) efuse: coding scheme 0
D (1036) efuse: coding scheme 0
D (1036) efuse: coding scheme 0
D (1036) efuse: coding scheme 0
D (1046) efuse: coding scheme 0
D (1056) efuse: coding scheme 0
D (1056) efuse: coding scheme 0
D (1056) efuse: coding scheme 0
D (1056) efuse: coding scheme 0
D (1056) efuse: coding scheme 0
D (1066) efuse: coding scheme 0
D (1066) efuse: coding scheme 0
I (1156) phy: phy_version: 4180, cb3948e, Sep 12 2019, 16:39:13, 0, 0
I (1156) wifi:mode : sta (bc:dd:c2:ca:c9:f0)
D (1156) event: running post WIFI_EVENT:2 with handler 0x400dea28 on loop 0x3ffbe760

Capture 2. For reference, have not applied esp_log_level_set() change for efuse. efuse DEBUG messages are shown as expected.
These are the same messages as seen in 4.0 when DEBUG efuse messages are expected to display.

Code: Select all

W (877) wifi station: ESP_WIFI_MODE_STA
D (877) event: running task for loop 0x3ffbe6cc
D (877) event: created task for loop 0x3ffbe6cc
D (877) event: created event loop 0x3ffbe6cc
D (887) esp_netif_objects: esp_netif_add_to_list 0x3ffbf5fc
D (887) esp_netif_objects: esp_netif_add_to_list netif added successfully (total netifs: 1)
I (917) wifi:wifi driver task: 3ffc1b5c, prio:23, stack:6656, core=0
I (917) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE
D (917) efuse: coding scheme 0
D (917) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 8 bit
D (927) efuse: coding scheme 0
D (927) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 0 bit
D (937) efuse: coding scheme 0
D (937) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 24 bit
D (947) efuse: coding scheme 0
D (947) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 16 bit
D (957) efuse: coding scheme 0
D (957) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 8 bit
D (967) efuse: coding scheme 0
D (967) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 0 bit
D (977) efuse: coding scheme 0
D (977) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 16 bit
I (987) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE
D (997) efuse: coding scheme 0
D (997) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 8 bit
D (1007) efuse: coding scheme 0
D (1007) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 0 bit
D (1017) efuse: coding scheme 0
D (1017) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 24 bit
D (1027) efuse: coding scheme 0
D (1027) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 16 bit
D (1037) efuse: coding scheme 0
D (1047) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 8 bit
D (1047) efuse: coding scheme 0
D (1057) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 0 bit
D (1057) efuse: coding scheme 0
D (1067) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 16 bit
I (1087) wifi:wifi firmware version: aa3b749
I (1087) wifi:config NVS flash: enabled
I (1087) wifi:config nano formating: disabled
I (1087) wifi:Init dynamic tx buffer num: 32
I (1097) wifi:Init data frame dynamic rx buffer num: 32
I (1097) wifi:Init management frame dynamic rx buffer num: 32
I (1107) wifi:Init management short buffer num: 32
I (1107) wifi:Init static rx buffer size: 1600
I (1117) wifi:Init static rx buffer num: 10
I (1117) wifi:Init dynamic rx buffer num: 32
D (1197) phy_init: loading PHY init data from application binary
D (1207) efuse: coding scheme 0
D (1207) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 8 bit
D (1207) efuse: coding scheme 0
D (1207) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 0 bit
D (1217) efuse: coding scheme 0
D (1217) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 24 bit
D (1227) efuse: coding scheme 0
D (1227) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 16 bit
D (1237) efuse: coding scheme 0
D (1237) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 8 bit
D (1247) efuse: coding scheme 0
D (1247) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 0 bit
D (1257) efuse: coding scheme 0
D (1257) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 16 bit
D (1277) efuse: coding scheme 0
D (1277) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 8 bit
D (1277) efuse: coding scheme 0
D (1287) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 0 bit
D (1287) efuse: coding scheme 0
D (1297) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 24 bit
D (1297) efuse: coding scheme 0
D (1307) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 16 bit
D (1307) efuse: coding scheme 0
D (1317) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 8 bit
D (1317) efuse: coding scheme 0
D (1327) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 0 bit
D (1327) efuse: coding scheme 0
D (1337) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 16 bit
I (1427) phy: phy_version: 4180, cb3948e, Sep 12 2019, 16:39:13, 0, 0
I (1427) wifi:mode : sta (bc:dd:c2:ca:c9:f0)
D (1427) event: running post WIFI_EVENT:2 with handler 0x400dea24 on loop 0x3ffbe6cc

[wiwild]

I can confirm that this bug is still present in idf v4.3.1