search

ruuvi / ruuvi.gateway_esp.c

Ruuvi Gateway ESP32 code
BSD 3-Clause "New" or "Revised" License
24 stars 15 forks source link

MIC_FAILURE error prevents gateway from connecting to Wi-Fi #1040

Closed TheSomeMan closed 1 month ago

TheSomeMan commented 1 month ago 
I (41988231) wifi_manager: [Tmr Svc/24] wifi_manager_timer_cb_reconnect: wifiman_msg_send_cmd_connect_sta: CONNECTION_REQUEST_AUTO_RECONNECT
I (41988233) wifi_manager: [Tmr Svc/24] Send msg: ORDER_CONNECT_STA, conn_req_code=2
I (41988241) wifi_manager: [wifi_manager/5] MESSAGE: ORDER_CONNECT_STA: CONNECTION_REQUEST_AUTO_RECONNECT, event_bits=0x0001
I (41988253) wifi_manager: [wifi_manager/5] WIFI_MANAGER:EV_STATE: Clear WIFI_MANAGER_REQUEST_DISCONNECT_BIT
I (41988287) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (41988288) wifi:state: init -> auth (b0)
I (41988295) wifi:state: auth -> assoc (0)
I (41988300) wifi:state: assoc -> run (10)
I (41988304) wifi:state: run -> init (ec0)
I (41988305) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (41988305) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (41988312) wifi_manager: [sys_evt/20] WIFI_EVENT_STA_DISCONNECTED, reason=14 (MIC_FAILURE)
I (41988321) wifi_manager: [wifi_manager/5] MESSAGE: EVENT_STA_DISCONNECTED with Reason code: 14 (MIC_FAILURE), event_bits=0x1001
I (41988332) wifi_manager: [wifi_manager/5] lost connection
I (41988339) wifi_manager: [wifi_manager/5] wifi_handle_ev_sta_disconnected: activate reconnection after timeout
I (41988350) wifi_manager: [wifi_manager/5] Set STA IP String to: 0.0.0.0
I (41988357) wifi_manager: [wifi_manager/5] WIFI_MANAGER:EV_STATE: Clear WIFI_MANAGER_WIFI_CONNECTED_BIT | WIFI_MANAGER_ETH_CONNECTED_BIT
I (41989208) ADV_POST_TASK: [adv_post_task/8] Advs cnt: 8
I (41989349) wifi_manager: [Tmr Svc/24] wifi_manager_timer_cb_reconnect: wifiman_msg_send_cmd_connect_sta: CONNECTION_REQUEST_AUTO_RECONNECT
I (41989351) wifi_manager: [Tmr Svc/24] Send msg: ORDER_CONNECT_STA, conn_req_code=2
I (41989360) wifi_manager: [wifi_manager/5] MESSAGE: ORDER_CONNECT_STA: CONNECTION_REQUEST_AUTO_RECONNECT, event_bits=0x0001
I (41989371) wifi_manager: [wifi_manager/5] WIFI_MANAGER:EV_STATE: Clear WIFI_MANAGER_REQUEST_DISCONNECT_BIT
I (41989414) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (41989415) wifi:state: init -> auth (b0)
I (41989420) wifi:state: auth -> assoc (0)
I (41989428) wifi:state: assoc -> run (10)
I (41989432) wifi:state: run -> init (ec0)
I (41989433) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (41989433) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (41989441) wifi_manager: [sys_evt/20] WIFI_EVENT_STA_DISCONNECTED, reason=14 (MIC_FAILURE)
I (41989449) wifi_manager: [wifi_manager/5] MESSAGE: EVENT_STA_DISCONNECTED with Reason code: 14 (MIC_FAILURE), event_bits=0x1001
I (41989461) wifi_manager: [wifi_manager/5] lost connection
I (41989467) wifi_manager: [wifi_manager/5] wifi_handle_ev_sta_disconnected: activate reconnection after timeout
I (41989479) wifi_manager: [wifi_manager/5] Set STA IP String to: 0.0.0.0
I (41989486) wifi_manager: [wifi_manager/5] WIFI_MANAGER:EV_STATE: Clear WIFI_MANAGER_WIFI_CONNECTED_BIT | WIFI_MANAGER_ETH_CONNECTED_BIT
I (41990208) ADV_POST_TASK: [adv_post_task/8] Advs cnt: 4
I (41990478) wifi_manager: [Tmr Svc/24] wifi_manager_timer_cb_reconnect: wifiman_msg_send_cmd_connect_sta: CONNECTION_REQUEST_AUTO_RECONNECT
I (41990480) wifi_manager: [Tmr Svc/24] Send msg: ORDER_CONNECT_STA, conn_req_code=2
I (41990489) wifi_manager: [wifi_manager/5] MESSAGE: ORDER_CONNECT_STA: CONNECTION_REQUEST_AUTO_RECONNECT, event_bits=0x0001
I (41990500) wifi_manager: [wifi_manager/5] WIFI_MANAGER:EV_STATE: Clear WIFI_MANAGER_REQUEST_DISCONNECT_BIT
I (41990540) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (41990541) wifi:state: init -> auth (b0)
I (41990545) wifi:state: auth -> assoc (0)
I (41990551) wifi:state: assoc -> run (10)
I (41990555) wifi:state: run -> init (ec0)
I (41990556) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (41990557) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (41990565) wifi_manager: [sys_evt/20] WIFI_EVENT_STA_DISCONNECTED, reason=14 (MIC_FAILURE)
I (41990574) wifi_manager: [wifi_manager/5] MESSAGE: EVENT_STA_DISCONNECTED with Reason code: 14 (MIC_FAILURE), event_bits=0x1001
I (41990585) wifi_manager: [wifi_manager/5] lost connection
I (41990591) wifi_manager: [wifi_manager/5] wifi_handle_ev_sta_disconnected: activate reconnection after timeout
I (41990603) wifi_manager: [wifi_manager/5] Set STA IP String to: 0.0.0.0
I (41990610) wifi_manager: [wifi_manager/5] WIFI_MANAGER:EV_STATE: Clear WIFI_MANAGER_WIFI_CONNECTED_BIT | WIFI_MANAGER_ETH_CONNECTED_BIT
I (41990691) ruuvi_gateway: [main/1] free heap: 118460 .. 118852
E (41990823) esp-tls: [adv_post_task] esp_tls_low_level_conn: [network.ruuvi.com] hostname resolving failed
E (41990823) esp-tls: [adv_post_task] esp_tls_low_level_conn: [network.ruuvi.com] failed to open a new connection
E (41990833) transport_base: [adv_post_task] esp_tls_connect_async: [network.ruuvi.com:443] esp_tls_conn_new_async: Failed, res=-1
I (41990846) transport_base: [adv_post_task] [network.ruuvi.com] Unlock TLS saved session for ssl=0x3ffdfbdc, session=0x0
E (41990857) HTTP_CLIENT: Connection failed
E (41990863) HTTP_CLIENT: Connection failed: esp_tls_last_esp_err=32769 (ESP_ERR_ESP_TLS_CANNOT_RESOLVE_HOSTNAME)
E (41990873) http: [adv_post_task/8] ./main/http.c:672 {http_async_poll}: ### HTTP POST to URL=https://network.ruuvi.com/record: failed, err=32769 (ESP_ERR_ESP_TLS_CANNOT_RESOLVE_HOSTNAME)
I (41990891) LEDS: [adv_post_task/8] Notify: HTTP(1) data sent fail
W (41990898) LEDS: [leds_task/8] LEDS_TASK_SIG_ON_EV_HTTP1_DATA_SENT_FAIL (ready=1)
I (41990906) ADV_POST_TASK: [adv_post_task/5] Cur free heap: 129452
I (41991208) ADV_POST_TASK: [adv_post_task/5] Advs cnt: 5
I (41991602) wifi_manager: [Tmr Svc/24] wifi_manager_timer_cb_reconnect: wifiman_msg_send_cmd_connect_sta: CONNECTION_REQUEST_AUTO_RECONNECT
TheSomeMan commented 1 month ago

Discussion of this problem: https://github.com/espressif/esp-idf/issues/6920 According to this comment (https://github.com/espressif/esp-idf/issues/6920#issuecomment-1318365017), it was fixed here: https://github.com/espressif/esp-idf/commit/0960aa8d87779e2edaf49b2affa4147d00990206

markoaamunkajo commented 1 month ago

According to logs, this issue is now seen rarely and connection is recovered. Closing as solved.

TheSomeMan commented 1 month ago

what_is_going_on.txt