Fail to Associate with Certain WiFi Routers after IDF3.3.4 to 5.1.1 Upgrade
Posted: Mon Nov 06, 2023 10:59 pm
We've got a situation where we are trying to upgrade our fleet from IDF 3.3.4 to 5.1.1. We are getting reports of failure to connect to WiFi after upgrade. We were able to reproduce in our lab with one TRENDnet TEW-711BR WiFi AP.
Interestingly, our firmware successfully connects with IDF 3.3.4, but using the Espressif example firmware in IDF 3.3.4 (examples/wifi/getting_started/station), it does NOT succeed.
Does anyone know what might be going on, or what settings we could tweak here?
Logs from our product firmware follow, showing success and failure versions. Verbosity is cranked up on the WiFi logs.
# With IDF 3.3.4 (success)
# With IDF 5.1.1 (failure)
Interestingly, our firmware successfully connects with IDF 3.3.4, but using the Espressif example firmware in IDF 3.3.4 (examples/wifi/getting_started/station), it does NOT succeed.
Does anyone know what might be going on, or what settings we could tweak here?
Logs from our product firmware follow, showing success and failure versions. Verbosity is cranked up on the WiFi logs.
# With IDF 3.3.4 (success)
Code: Select all
I (1552) wifi:wifi driver task: 3ffd5818, prio:23, stack:3584, core=0 [0/12090]
I (1554) wifi:wifi firmware version: 74b0970
I (1555) wifi:config NVS flash: disabled
I (1555) wifi:config nano formating: disabled
I (1556) wifi:Init dynamic tx buffer num: 32
I (1556) wifi:Init data frame dynamic rx buffer num: 32
I (1556) wifi:Init management frame dynamic rx buffer num: 32
I (1557) wifi:Init management short buffer num: 32
I (1557) wifi:Init static tx buffer num: 16
I (1559) wifi:Init static rx buffer size: 1600
I (1560) wifi:Init static rx buffer num: 10
I (1560) wifi:Init dynamic rx buffer num: 32
I (1562) wifi:set country: cc=USI schan=1 nchan=11 policy=0
I (1563) wifi:Set ps type: 0
E (1578) emac: Can only configure flow_ctrl_enable==true if DMA_RX_BUF_NUM in menuconfig is >9. Disabling flow control.
I (1584) emac: emac reset done
1522: BWIFI_ESP32:232 : USER : [Interface 2] - Hostname: Bond-ZPEE65133
1524: Sentry:151 : INFO : found coredump, size 0x00010000
1540: Troy:273 : INFO : no record found
1541: Troy:330 : INFO : not enabled
1543: BGroup:67 : DEBUG: bgroup_load
1558: BondSync:773 : DEBUG: BondSync_Init
1563: BSK_Learn:59 : INFO : Closed learn window.
1578: BWIFI:471 : INFO : BWIFI_Restore()
1580: BWIFI:481 : INFO : BWIFI_Restore: restored saved config
1580: BWIFI:303 : INFO : event: STA_LOST_IP: Lost IP from Home WiFi
1582: BWIFI_ESP32:630 : ERROR: esp_wifi_sta_get_ap_info error: ESP_ERR_WIFI_CONN
1591: BWIFI_ESP32:508 : INFO : wifi mode -> APSTA
1593: BWIFI_ESP32:529 : INFO : ssid = 'TRENDnet711'
00000000 54 52 45 4E 44 6E 65 74 37 31 31 |TRENDnet711|
D (1660) wifi:clear blacklist
I (1826) phy: phy_version: 4180, cb3948e, Sep 12 2019, 16:39:13, 0, 2
D (1828) wifi:filter: set rx policy=0
I (1830) wifi:mode : sta (f4:4e:38:03:d4:6c) + softAP (f4:4e:38:03:d4:6d)
D (1830) wifi:filter: set rx policy=1
D (1831) wifi:connect status 0 -> 0
D (1831) wifi:filter: set rx policy=9
I (1832) wifi:Total power save buffer number: 8
I (1833) wifi:Init max length of beacon: 752/752
I (1834) wifi:Init max length of beacon: 752/752
1771: BWIFI_ESP32:643 : INFO : Config AP: HIDDEN
D (1837) wifi:filter: set rx policy=10
D (1837) wifi:filter: set rx policy=10
D (1838) wifi:filter: set rx policy=9
I (1839) wifi:Total power save buffer number: 8
1776: BWIFI_ESP32:400 : INFO : Not starting a new scan: Still connecting... (20 sec until timeout).
1777: BWIFI:281 : INFO : event: STA_DISCONNECTED: Disconnected from Home WiFi
W (1843) wifi:Haven't to connect to a suitable AP now!
1779: BWIFI_ESP32:630 : ERROR: esp_wifi_sta_get_ap_info error: ESP_ERR_WIFI_NOT_CONNECT
1789: BWIFI_ESP32:232 : USER : [Interface 0] - Hostname: Bond-ZPEE65133
D (1854) wifi:Start wifi connect
D (1854) wifi:connect status 0 -> 0
D (1855) wifi:connect chan=0
D (1855) wifi:first chan=1
D (1855) wifi:connect status 0 -> 1
D (1856) wifi:filter: set rx policy=3
D (1856) wifi:clear scan ap list
D (1856) wifi:start scan: type=0x50f, priority=2, cb=0x401d3dcc, arg=0x0, ss_state=0x1, time=30010, index=0
0x401d3dcc: cnx_start_handoff_cb at ??:?
D (1857) wifi:perform scan: ss_state=0x9, chan<1,0>, dur<0,120>
1808: BEthernet:213 : INFO : bethernet_restore: Ethernet not configured
1811: BBCAP:566 : DEBUG: Init
1814: BBCAP:432 : DEBUG: bbcap_load: BBCAP not configured
1824: BPower:36 : INFO : Power Sources: USB [X] POE [ ]
1838: BTime_RTC:85 : INFO : RTC valid: 1699311126
1863: main:509 : INFO : Going to warning verbosity.
D (1980) wifi:scan end: arg=0x0, status=0, ss_state=0x3
D (2007) wifi:profile match: ss_state=0x5
D (2012) wifi:perform scan: ss_state=0xd, chan<2,0>, dur<0,120>
D (2135) wifi:scan end: arg=0x0, status=0, ss_state=0x7
D (2167) wifi:perform scan: ss_state=0xd, chan<3,0>, dur<0,120>
D (2290) wifi:scan end: arg=0x0, status=0, ss_state=0x7
D (2314) wifi:set max rate: from <rate=130, phy=3, sig=0> to <rate=144, phy=3 sig=0>
D (2315) wifi:sig_b=0, sig_g=0, sig_n=0, max_b=22, max_g=108, max_n=144
D (2315) wifi:profile match: ss_state=0x5
D (2322) wifi:perform scan: ss_state=0xd, chan<4,0>, dur<0,120>
D (2445) wifi:scan end: arg=0x0, status=0, ss_state=0x7
D (2477) wifi:perform scan: ss_state=0xd, chan<5,0>, dur<0,120>
D (2601) wifi:scan end: arg=0x0, status=0, ss_state=0x7
interrupt pin terminate
D (2621) wifi:profile match: ss_state=0x5
interrupt pin terminate
D (2633) wifi:perform scan: ss_state=0xd, chan<6,0>, dur<0,120>
interrupt pin terminate
D (2756) wifi:scan end: arg=0x0, status=0, ss_state=0x7
D (2788) wifi:perform scan: ss_state=0xd, chan<7,0>, dur<0,120>
D (2911) wifi:scan end: arg=0x0, status=0, ss_state=0x7
D (2929) wifi:profile match: ss_state=0x5
D (2944) wifi:perform scan: ss_state=0xd, chan<8,0>, dur<0,120>
===============***************************************************CHIPREV = 22
PART = 4461
PBUILD = 00
ID = 8600
CUSTOMER = 00
ROMID = 06
D (3066) wifi:scan end: arg=0x0, status=0, ss_state=0x7
D (3098) wifi:perform scan: ss_state=0xd, chan<9,0>, dur<0,120>
D (3221) wifi:scan end: arg=0x0, status=0, ss_state=0x7
D (3236) wifi:profile match: ss_state=0x5
D (3253) wifi:perform scan: ss_state=0xd, chan<10,0>, dur<0,120>
D (3376) wifi:scan end: arg=0x0, status=0, ss_state=0x7
D (3408) wifi:perform scan: ss_state=0xd, chan<11,0>, dur<0,120>
D (3416) wifi:profile match: ss_state=0x7
D (3440) wifi:profile match: ss_state=0x7
D (3530) wifi:scan end: arg=0x0, status=0, ss_state=0x7
D (3543) wifi:profile match: ss_state=0x5
D (3561) wifi:filter: set rx policy=4
D (3562) wifi:first chan=1
D (3562) wifi:handoff_cb: status=0
D (3563) wifi:ap found, mac=d8:eb:97:1d:40:e9
D (3563) wifi:new_bss=0x3f800028, cur_bss=0x0, new_chan=<11,0>, cur_chan=11
D (3564) wifi:filter: set rx policy=5
I (3564) wifi:new:<11,0>, old:<11,0>, ap:<11,0>, sta:<11,0>, prof:11
D (3565) wifi:connect_op: status=0, auth=3, cipher=3
D (3565) wifi:auth mode is not none
D (4711) wifi:connect_bss: auth=1, reconnect=0
I (4712) wifi:state: init -> auth (b0)
D (4713) wifi:start 1s AUTH timer
D (4713) wifi:clear scan ap list
D (4722) wifi:recv auth: seq=2, status=0
I (4723) wifi:state: auth -> assoc (0)
D (4724) wifi:restart connect 1s timer for assoc
D (4732) wifi:recv assoc: type=0x10
D (4734) wifi:filter: set rx policy=6
I (4734) wifi:state: assoc -> run (10)
D (4735) wifi:start 10s connect timer for 4 way handshake
I (4753) wifi:connected with TRENDnet711, aid = 1, channel 11, BW20, bssid = d8:eb:97:1d:40:e9
I (4754) wifi:security type: 2, phy: bgn, rssi: -27
D (4754) wifi:remove all except d8:eb:97:1d:40:e9 from rc list
D (4755) wifi:clear blacklist
D (4755) wifi:filter: set rx policy=7
I (4756) wifi:pm start, type: 0
D (4756) wifi:Send sta connected event
D (4757) wifi:connect status 1 -> 5
D (4757) wifi:obss scan is disabled
D (4757) wifi:start obss scan: obss scan is stopped
I (4772) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (6800) event: sta ip: 192.168.10.101, mask: 255.255.255.0, gw: 192.168.10.1
# With IDF 5.1.1 (failure)
Code: Select all
24199: BWIFI:56 : WARN : AP connect timeout: within 20 s
24200: BWIFI:71 : DEBUG: Trying to connect to AP...
V (26003) esp_adapter: thread sem create: sem=0x3fff2b54
V (26004) esp_adapter: thread sem get: sem=0x3fff2b54
D (26005) wifi:Start wifi connect
D (26006) wifi:connect status 3 -> 0
D (26006) wifi:connect chan=0
V (26007) wifi:nvs=0, ssid=TRENDnet711, channel=255
V (26007) wifi:ssid=TRENDnet711 match nvs 0, channel=255
D (26008) wifi:first chan=1
D (26008) wifi:connect status 0 -> 1
D (26009) wifi:filter: set rx policy=3
D (26010) wifi:clear scan ap list
D (26010) wifi:start scan: type=0x50f, priority=2, cb=0x401c04b4, arg=0x0, ss_state=0x1, time=23242168, index=0
0x401c04b4: cnx_start_handoff_cb at ??:?
V (26011) wifi:inter_channel_timeout: arg=0x0, ss_state=0x1
D (26012) wifi:perform scan: ss_state=0x9, chan<1,0>, dur<0,120>
V (26012) wifi:change: chan<1,0>, dur<0,120>
V (26013) wifi:ht20 freq=2412, chan=1
V (26014) wifi:enter start op, arg=0x3f8012d2
V (26015) wifi:scan operation: state=0x3, chan<1,0>, arg=0x3f8012d2, status=0
V (26016) wifi:scan specific ssid=TRENDnet711
V (26017) wifi:start max timer
D (26138) wifi:scan end: arg=0x0, status=0, ss_state=0x3
V (26140) wifi:back home chan=<11,0>, current chan=<1,0>
V (26140) wifi:ht20 freq=2462, chan=11
V (26142) wifi:start scan timer
V (26173) wifi:oper_channel: arg=0x0 time=23404604
D (26174) wifi:perform scan: ss_state=0x9, chan<2,0>, dur<0,120>
V (26175) wifi:change: chan<2,0>, dur<0,120>
V (26175) wifi:ht20 freq=2417, chan=2
V (26177) wifi:enter start op, arg=0x3f8012d2
V (26177) wifi:scan operation: state=0x3, chan<2,0>, arg=0x3f8012d2, status=0
V (26178) wifi:scan specific ssid=TRENDnet711
V (26179) wifi:start max timer
D (26300) wifi:scan end: arg=0x0, status=0, ss_state=0x3
V (26302) wifi:back home chan=<11,0>, current chan=<2,0>
V (26303) wifi:ht20 freq=2462, chan=11
V (26304) wifi:start scan timer
V (26335) wifi:oper_channel: arg=0x0 time=23566868
D (26336) wifi:perform scan: ss_state=0x9, chan<3,0>, dur<0,120>
V (26337) wifi:change: chan<3,0>, dur<0,120>
V (26338) wifi:ht20 freq=2422, chan=3
V (26339) wifi:enter start op, arg=0x3f8012d2
V (26339) wifi:scan operation: state=0x3, chan<3,0>, arg=0x3f8012d2, status=0
V (26340) wifi:scan specific ssid=TRENDnet711
V (26341) wifi:start max timer
D (26462) wifi:scan end: arg=0x0, status=0, ss_state=0x3
V (26464) wifi:back home chan=<11,0>, current chan=<3,0>
V (26464) wifi:ht20 freq=2462, chan=11
V (26466) wifi:start scan timer
V (26496) wifi:oper_channel: arg=0x0 time=23728458
D (26497) wifi:perform scan: ss_state=0x9, chan<4,0>, dur<0,120>
V (26498) wifi:change: chan<4,0>, dur<0,120>
V (26499) wifi:ht20 freq=2427, chan=4
V (26500) wifi:enter start op, arg=0x3f8012d2
V (26500) wifi:scan operation: state=0x3, chan<4,0>, arg=0x3f8012d2, status=0
V (26501) wifi:scan specific ssid=TRENDnet711
V (26502) wifi:start max timer
D (26623) wifi:scan end: arg=0x0, status=0, ss_state=0x3
V (26625) wifi:back home chan=<11,0>, current chan=<4,0>
V (26626) wifi:ht20 freq=2462, chan=11
V (26627) wifi:start scan timer
V (26658) wifi:oper_channel: arg=0x0 time=23889627
D (26659) wifi:perform scan: ss_state=0x9, chan<5,0>, dur<0,120>
V (26660) wifi:change: chan<5,0>, dur<0,120>
V (26660) wifi:ht20 freq=2432, chan=5
V (26661) wifi:enter start op, arg=0x3f8012d2
V (26662) wifi:scan operation: state=0x3, chan<5,0>, arg=0x3f8012d2, status=0
V (26663) wifi:scan specific ssid=TRENDnet711
V (26664) wifi:start max timer
D (26785) wifi:scan end: arg=0x0, status=0, ss_state=0x3
V (26787) wifi:back home chan=<11,0>, current chan=<5,0>
V (26787) wifi:ht20 freq=2462, chan=11
V (26788) wifi:start scan timer
V (26819) wifi:oper_channel: arg=0x0 time=24051369
D (26821) wifi:perform scan: ss_state=0x9, chan<6,0>, dur<0,120>
V (26822) wifi:change: chan<6,0>, dur<0,120>
V (26822) wifi:ht20 freq=2437, chan=6
V (26823) wifi:enter start op, arg=0x3f8012d2
V (26824) wifi:scan operation: state=0x3, chan<6,0>, arg=0x3f8012d2, status=0
V (26825) wifi:scan specific ssid=TRENDnet711
V (26826) wifi:start max timer
D (26947) wifi:scan end: arg=0x0, status=0, ss_state=0x3
V (26949) wifi:back home chan=<11,0>, current chan=<6,0>
V (26949) wifi:ht20 freq=2462, chan=11
V (26951) wifi:start scan timer
V (26981) wifi:oper_channel: arg=0x0 time=24213482
D (26983) wifi:perform scan: ss_state=0x9, chan<7,0>, dur<0,120>
V (26984) wifi:change: chan<7,0>, dur<0,120>
V (26984) wifi:ht20 freq=2442, chan=7
V (26985) wifi:enter start op, arg=0x3f8012d2
V (26986) wifi:scan operation: state=0x3, chan<7,0>, arg=0x3f8012d2, status=0
V (26987) wifi:scan specific ssid=TRENDnet711
V (26988) wifi:start max timer
D (27109) wifi:scan end: arg=0x0, status=0, ss_state=0x3
V (27111) wifi:back home chan=<11,0>, current chan=<7,0>
V (27112) wifi:ht20 freq=2462, chan=11
V (27113) wifi:start scan timer
V (27144) wifi:oper_channel: arg=0x0 time=24375707
D (27145) wifi:perform scan: ss_state=0x9, chan<8,0>, dur<0,120>
V (27146) wifi:change: chan<8,0>, dur<0,120>
V (27147) wifi:ht20 freq=2447, chan=8
V (27148) wifi:enter start op, arg=0x3f8012d2
V (27148) wifi:scan operation: state=0x3, chan<8,0>, arg=0x3f8012d2, status=0
V (27149) wifi:scan specific ssid=TRENDnet711
V (27150) wifi:start max timer
D (27271) wifi:scan end: arg=0x0, status=0, ss_state=0x3
V (27273) wifi:back home chan=<11,0>, current chan=<8,0>
V (27274) wifi:ht20 freq=2462, chan=11
V (27275) wifi:start scan timer
V (27306) wifi:oper_channel: arg=0x0 time=24537781
D (27307) wifi:perform scan: ss_state=0x9, chan<9,0>, dur<0,120>
V (27308) wifi:change: chan<9,0>, dur<0,120>
V (27309) wifi:ht20 freq=2452, chan=9
V (27310) wifi:enter start op, arg=0x3f8012d2
V (27310) wifi:scan operation: state=0x3, chan<9,0>, arg=0x3f8012d2, status=0
V (27311) wifi:scan specific ssid=TRENDnet711
V (27312) wifi:start max timer
D (27433) wifi:scan end: arg=0x0, status=0, ss_state=0x3
V (27435) wifi:back home chan=<11,0>, current chan=<9,0>
V (27436) wifi:ht20 freq=2462, chan=11
V (27437) wifi:start scan timer
V (27468) wifi:oper_channel: arg=0x0 time=24699870
D (27469) wifi:perform scan: ss_state=0x9, chan<10,0>, dur<0,120>
V (27470) wifi:change: chan<10,0>, dur<0,120>
V (27470) wifi:ht20 freq=2457, chan=10
V (27471) wifi:enter start op, arg=0x3f8012d2
V (27472) wifi:scan operation: state=0x3, chan<10,0>, arg=0x3f8012d2, status=0
V (27473) wifi:scan specific ssid=TRENDnet711
V (27474) wifi:start max timer
D (27595) wifi:scan end: arg=0x0, status=0, ss_state=0x3
V (27597) wifi:back home chan=<11,0>, current chan=<10,0>
V (27597) wifi:ht20 freq=2462, chan=11
V (27599) wifi:start scan timer
V (27629) wifi:oper_channel: arg=0x0 time=24861500
D (27631) wifi:perform scan: ss_state=0x9, chan<11,0>, dur<0,120>
V (27632) wifi:change: chan<11,0>, dur<0,120>
V (27632) wifi:enter start op, arg=0x3f8012d2
V (27633) wifi:scan operation: state=0x3, chan<11,0>, arg=0x3f8012d2, status=0
V (27633) wifi:scan specific ssid=TRENDnet711
V (27635) wifi:start max timer
D (27756) wifi:scan end: arg=0x0, status=0, ss_state=0x3
V (27757) wifi:back home chan=<11,0>, current chan=<11,0>
V (27758) wifi:start scan timer
V (27789) wifi:oper_channel: arg=0x0 time=25020654
D (27790) wifi:perform scan: ss_state=0x9, chan<12,0>, dur<360,360>
V (27791) wifi:change: chan<12,0>, dur<360,360>
V (27791) wifi:ht20 freq=2467, chan=12
V (27792) wifi:enter start op, arg=0x3f8012d2
V (27793) wifi:scan operation: state=0x3, chan<12,0>, arg=0x3f8012d2, status=0
V (27794) wifi:passive scan, listen only
V (27794) wifi:start max timer
D (28155) wifi:scan end: arg=0x0, status=0, ss_state=0x3
V (28157) wifi:back home chan=<11,0>, current chan=<12,0>
V (28157) wifi:ht20 freq=2462, chan=11
V (28159) wifi:start scan timer
V (28189) wifi:oper_channel: arg=0x0 time=25421411
D (28191) wifi:perform scan: ss_state=0x9, chan<13,0>, dur<360,360>
V (28192) wifi:change: chan<13,0>, dur<360,360>
V (28192) wifi:ht20 freq=2472, chan=13
V (28193) wifi:enter start op, arg=0x3f8012d2
V (28194) wifi:scan operation: state=0x3, chan<13,0>, arg=0x3f8012d2, status=0
V (28194) wifi:passive scan, listen only
V (28195) wifi:start max timer
D (28556) wifi:scan end: arg=0x0, status=0, ss_state=0x3
V (28557) wifi:back home chan=<11,0>, current chan=<13,0>
V (28558) wifi:ht20 freq=2462, chan=11
V (28559) wifi:start scan timer
V (28590) wifi:oper_channel: arg=0x0 time=25821906
D (28591) wifi:perform scan: ss_state=0x9, chan<14,0>, dur<360,360>
V (28592) wifi:change: chan<14,0>, dur<360,360>
V (28593) wifi:ht20 freq=2484, chan=14
V (28594) wifi:enter start op, arg=0x3f8012d2
V (28595) wifi:scan operation: state=0x3, chan<14,0>, arg=0x3f8012d2, status=0
V (28595) wifi:passive scan, listen only
V (28596) wifi:start max timer
D (28956) wifi:scan end: arg=0x0, status=0, ss_state=0x3
V (28958) wifi:back home chan=<11,0>, current chan=<14,0>
V (28959) wifi:ht20 freq=2462, chan=11
V (28960) wifi:start scan timer
V (28991) wifi:oper_channel: arg=0x0 time=26222623
V (28992) wifi:back home chan=<11,0>, current chan=<11,0>
D (28993) wifi:filter: set rx policy=4
D (28993) wifi:first chan=1
V (28994) wifi:scan_done: arg=0x0, status=0, cur_time=26225951, scan_id=128, scan state=0
V (28995) wifi:call scan_done cb, arg=0x0
D (28995) wifi:handoff_cb: status=0
D (28996) wifi:clear rc list
D (28996) wifi:clear blacklist
D (28997) wifi:Send disconnect event, reason=201
D (28997) wifi:connect status 1 -> 3
D (28998) wifi:disable connect timer
D (28998) wifi:clear scan ap list
D (28999) event: running post WIFI_EVENT:5 with handler 0x4016459c and context 0x3ffddf64 on loop 0x3ffdade4
0x4016459c: wifi_default_action_sta_disconnected at /Users/cmerck/src/olibra/bond-core/bsp/esp-idf/components/esp_wifi/src/wifi_default.c:104
D (29000) esp_netif_handlers: esp_netif action disconnected with netif0x3f81cfc8 from event_id=5
D (29001) esp_netif_lwip: check: remote, if=0x3f81cfc8 fn=0x40182ed4
0x40182ed4: esp_netif_down_api at /Users/cmerck/src/olibra/bond-core/bsp/esp-idf/components/esp_netif/lwip/esp_netif_lwip.c:1638
D (29002) esp_netif_lwip: esp_netif_down_api esp_netif:0x3f81cfc8
D (29002) esp_netif_lwip: esp_netif_start_ip_lost_timer esp_netif:0x3f81cfc8
D (29003) esp_netif_lwip: if0x3f81cfc8 start ip lost tmr: no need start because netif=0x3f81d02c interval=120 ip=0
D (29004) esp_netif_lwip: check: local, if=0x3f81cfc8 fn=0x40183fa0
0x40183fa0: esp_netif_update_default_netif_lwip at /Users/cmerck/src/olibra/bond-core/bsp/esp-idf/components/esp_netif/lwip/esp_netif_lwip.c:327
D (29005) esp_netif_lwip: esp_netif_update_default_netif_lwip 0x3f81cfc8
V (29006) esp_netif_objects: esp_netif_next_unsafe 0x0
V (29007) esp_netif_lwip: esp_netif_is_netif_up esp_netif:0x3f829f6c
V (29007) esp_netif_objects: esp_netif_next_unsafe 0x3f829f6c
V (29008) esp_netif_lwip: esp_netif_is_netif_up esp_netif:0x3f81d12c
V (29009) esp_netif_objects: esp_netif_next_unsafe 0x3f81d12c
V (29010) esp_netif_lwip: esp_netif_is_netif_up esp_netif:0x3f81cfc8
V (29011) esp_netif_objects: esp_netif_next_unsafe 0x3f81cfc8
V (29011) esp_netif_lwip: esp_netif_is_netif_up esp_netif:0x3f81d12c
D (29012) esp_netif_lwip: call api in lwip: ret=0x0, give sem
D (29013) event: running post WIFI_EVENT:5 with handler 0x400e06c0 and context 0x3ffdec14 on loop 0x3ffdade4
0x400e06c0: bwifi_esp32_wifi_event_handler at /Users/cmerck/src/olibra/bond-core/hal/BWIFI/BWIFI_ESP32.c:254
27211: BWIFI:258 : INFO : event: STA_DISCONNECTED: Disconnected from Home WiFi
27213: BWIFI_API:85 : DEBUG: bwifi_populate_response
V (29017) esp_adapter: thread sem get: sem=0x3ffe68c8
W (29017) wifi:Haven't to connect to a suitable AP now!
27215: BWIFI_ESP32:740 : ERROR: esp_wifi_sta_get_ap_info error: ESP_ERR_WIFI_NOT_CONNECT
D (29019) esp_netif_lwip: esp_netif_get_ip_info esp_netif:0x3f81cfc8
D (29020) esp_netif_lwip: check: remote, if=0x3f81cfc8 fn=0x4018272c
0x4018272c: esp_netif_get_dns_info_api at /Users/cmerck/src/olibra/bond-core/bsp/esp-idf/components/esp_netif/lwip/esp_netif_lwip.c:1913
D (29021) esp_netif_lwip: esp_netif_get_dns_info: esp_netif=0x3f81cfc8 type=0
D (29021) esp_netif_lwip: call api in lwip: ret=0x0, give sem
D (29022) esp_netif_lwip: check: remote, if=0x3f81cfc8 fn=0x4018272c
0x4018272c: esp_netif_get_dns_info_api at /Users/cmerck/src/olibra/bond-core/bsp/esp-idf/components/esp_netif/lwip/esp_netif_lwip.c:1913
D (29023) esp_netif_lwip: esp_netif_get_dns_info: esp_netif=0x3f81cfc8 type=1
D (29024) esp_netif_lwip: call api in lwip: ret=0x0, give sem
27225: BWIFI_API:146 : DEBUG: bwifi_populate_response_eth
V (29028) esp_netif_lwip: esp_netif_is_netif_up esp_netif:0x3f829f6c
D (29029) esp_netif_lwip: esp_netif_get_ip_info esp_netif:0x3f829f6c
D (29030) esp_netif_lwip: check: remote, if=0x3f829f6c fn=0x4018272c
0x4018272c: esp_netif_get_dns_info_api at /Users/cmerck/src/olibra/bond-core/bsp/esp-idf/components/esp_netif/lwip/esp_netif_lwip.c:1913
D (29031) esp_netif_lwip: esp_netif_get_dns_info: esp_netif=0x3f829f6c type=0
D (29031) esp_netif_lwip: call api in lwip: ret=0x0, give sem
D (29032) esp_netif_lwip: check: remote, if=0x3f829f6c fn=0x4018272c
0x4018272c: esp_netif_get_dns_info_api at /Users/cmerck/src/olibra/bond-core/bsp/esp-idf/components/esp_netif/lwip/esp_netif_lwip.c:1913
D (29033) esp_netif_lwip: esp_netif_get_dns_info: esp_netif=0x3f829f6c type=1
D (29034) esp_netif_lwip: call api in lwip: ret=0x0, give sem
D (29037) event: running post WIFI_EVENT:5 with handler 0x400e92ec and context 0x3ffdf598 on loop 0x3ffdade4
0x400e92ec: mdns_preset_if_handle_system_event at /Users/cmerck/src/olibra/bond-core/proto/BMDNS/mdns.c:4154