Fail to Associate with Certain WiFi Routers after IDF3.3.4 to 5.1.1 Upgrade

chrismerck
Posts: 76
Joined: Tue May 29, 2018 8:00 pm

Fail to Associate with Certain WiFi Routers after IDF3.3.4 to 5.1.1 Upgrade

Postby chrismerck » 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)

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


chrismerck
Posts: 76
Joined: Tue May 29, 2018 8:00 pm

Re: Fail to Associate with Certain WiFi Routers after IDF3.3.4 to 5.1.1 Upgrade

Postby chrismerck » Mon Nov 06, 2023 11:43 pm

Solved.

We had to change:

Code: Select all

wifi_config.sta.threshold.authmode = WIFI_AUTH_OPEN;
to

Code: Select all

wifi_config.sta.threshold.authmode = strlen(_m_bwifi->sta.password) ? WIFI_AUTH_WEP : WIFI_AUTH_OPEN;
Seems there was some change in the exact interpretation of the authmode field.

Who is online

Users browsing this forum: atx823, brushlow18, Google [Bot] and 213 guests