Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

WIFI-MESH: Node lost connection after Root reboot (IDFGH-11273) #12431

Closed
3 tasks done
yanmartins opened this issue Oct 18, 2023 · 32 comments
Closed
3 tasks done

WIFI-MESH: Node lost connection after Root reboot (IDFGH-11273) #12431

yanmartins opened this issue Oct 18, 2023 · 32 comments
Assignees
Labels
Resolution: Done Issue is done internally Status: Done Issue is done internally

Comments

@yanmartins
Copy link

yanmartins commented Oct 18, 2023

Answers checklist.

  • I have read the documentation ESP-IDF Programming Guide and the issue is not addressed there.
  • I have updated my IDF branch (master or release) to the latest version and checked that the issue is present there.
  • I have searched the issue tracker for a similar issue and not found a similar issue.

General issue report

Hello,

I'm working in a project using ESP32-S3 with 5.0.1 ESP-IDF.
I run the ip_internal_network example with this init:

//! initialize the wifi stack in "mode" with config in RAM
wifi_init_config_t wifi_init_config = WIFI_INIT_CONFIG_DEFAULT();
ESP_LOGI(TAG, "srv_wifi_init [WIFI_INIT_CONFIG_DEFAULT]");

ESP_ERROR_CHECK(esp_wifi_init(&wifi_init_config));
ESP_LOGI(TAG, "srv_wifi_init [esp_wifi_init]");

ESP_ERROR_CHECK(esp_wifi_set_storage(WIFI_STORAGE_RAM));
ESP_LOGI(TAG, "srv_wifi_init [esp_wifi_set_storage]");

ESP_ERROR_CHECK(esp_wifi_set_mode(WIFI_MODE_APSTA));
ESP_LOGI(TAG, "srv_wifi_init [esp_wifi_set_mode]");

//! Create a network interfaces for mesh (only station instance saved for further manipulation, soft AP instance ignored
ESP_ERROR_CHECK(mesh_netifs_init(recv_cb));

ESP_ERROR_CHECK(esp_event_handler_register(IP_EVENT, IP_EVENT_STA_GOT_IP, &wifi_sta_event_handler, NULL));

ESP_ERROR_CHECK(esp_wifi_start());
/*  mesh initialization */
ESP_ERROR_CHECK(esp_mesh_init());

ESP_ERROR_CHECK(esp_mesh_set_xon_qsize(128));
ESP_ERROR_CHECK(esp_mesh_allow_root_conflicts(false));

esp_mesh_fix_root(true);
if(wifi_mesh_config->is_root) esp_mesh_set_type(MESH_ROOT);

ESP_ERROR_CHECK(esp_event_handler_register(MESH_EVENT, ESP_EVENT_ANY_ID, &mesh_event_handler, NULL));
ESP_ERROR_CHECK(esp_mesh_set_max_layer(6));

mesh_cfg_t cfg = MESH_INIT_CONFIG_DEFAULT();
/* mesh ID */
memcpy((uint8_t *) &cfg.mesh_id, MESH_ID, 6);

/* router */
cfg.channel         = 0;
cfg.router.ssid_len = strlen("xx");
memcpy((uint8_t *) &cfg.router.ssid, "xx", cfg.router.ssid_len);
memcpy((uint8_t *) &cfg.router.password, "yy", strlen("yy"));
/* mesh softAP */
ESP_ERROR_CHECK(esp_mesh_set_ap_authmode(WIFI_AUTH_WPA2_PSK));
cfg.mesh_ap.max_connection         = 6;
cfg.mesh_ap.nonmesh_max_connection = 0;
memcpy((uint8_t *) &cfg.mesh_ap.password, "MAP_PASSWD", strlen("MAP_PASSWD"));
ESP_ERROR_CHECK(esp_mesh_set_config(&cfg));

/* mesh start */
ESP_ERROR_CHECK(esp_mesh_start());
ESP_LOGI(TAG, "mesh starts successfully, heap: %lu, %s\n", esp_get_free_heap_size(), esp_mesh_is_root_fixed() ? "root fixed" : "root not fixed");

Following is the log output of the program on a NODE.

ROOT is turned off, after the MESH_EVENT_PARENT_CONNECTED event on the NODE. But when ROOT is turned on again, NODE never gets a connection. Remaining infinitely in this loop. What could be causing this?

[2023-10-18 15:04:33.271] I (6038468) wifi:<ba-add>idx:0 (ifx:0, 34:85:18:8f:4d:79), tid:5, ssn:0, winSize:64
[2023-10-18 15:04:33.271] I (6038488) app_comm_connectivity: NETWORK_STATUS_DISCONNECTED - network_status_handler - INTERFACE: 5
[2023-10-18 15:04:33.271] I (6038498) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-10-18 15:04:33.271] I (6038498) mesh: <MESH_NWK_ROOT_ADDR>from assoc, layer:2, root_addr:34:85:18:8f:4d:79, root_cap:1
[2023-10-18 15:04:33.271] I (6038508) wifi:Set ps type: 1
[2023-10-18 15:04:33.271] 
[2023-10-18 15:04:33.271] I (6038518) mesh: 2004<arm>parent monitor, my layer:2(cap:6)(node), interval:8159ms, retries:1<normal connected>
[2023-10-18 15:04:33.271] I (6038528) srv_wifi: <MESH_EVENT_PARENT_CONNECTED>layer:2-->2, parent:34:85:18:8f:4d:79<layer2>, ID:77:77:77:77:77:76
[2023-10-18 15:04:33.271] I (6038528) mesh: <MESH_NWK_ROOT_ADDR>node, layer:2, root_addr:34:85:18:8f:4d:79, conflict_roots.num:0<>
[2023-10-18 15:04:33.271] I (6038548) srv_wifi_mesh: It was a wifi station removing stuff
[2023-10-18 15:04:33.602] I (6038908) app_main: Heap size -> 8217844
[2023-10-18 15:04:34.607] I (6039908) app_main: Heap size -> 8217792
[2023-10-18 15:04:35.067] W (6040358) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:1492, no_wnd_count:0, timeout_count:0
[2023-10-18 15:04:35.584] I (6040908) app_main: Heap size -> 8217728
[2023-10-18 15:04:36.594] I (6041908) app_main: Heap size -> 8217664
[2023-10-18 15:04:36.872] W (6042158) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:1492, no_wnd_count:0, timeout_count:1
[2023-10-18 15:04:37.595] I (6042908) app_main: Heap size -> 8217616
[2023-10-18 15:04:38.608] I (6043908) app_main: Heap size -> 8217568
[2023-10-18 15:04:38.663] W (6043958) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:1492, no_wnd_count:0, timeout_count:2
[2023-10-18 15:04:39.612] I (6044908) app_main: Heap size -> 8217520
[2023-10-18 15:04:40.469] W (6045758) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:1492, no_wnd_count:0, timeout_count:3
[2023-10-18 15:04:40.585] I (6045908) app_main: Heap size -> 8217456
[2023-10-18 15:04:40.795] I (6046078) wifi:pm start, type: 1
[2023-10-18 15:04:40.795] 
[2023-10-18 15:04:40.795] I (6046078) wifi:set rx beacon pti, rx_bcn_pti: 14, bcn_timeout: 14, mt_pti: 25000, mt_time: 10000
[2023-10-18 15:04:41.094] I (6046378) wifi:AP's beacon interval = 307200 us, DTIM period = 1
[2023-10-18 15:04:41.572] I (6046878) mesh: 5156<active>parent layer:0(node), channel:13, rssi:-17, assoc:0(cnx rssi threshold:-120)my_assoc:0
[2023-10-18 15:04:41.611] I (6046908) app_main: Heap size -> 8217440
[2023-10-18 15:04:42.241] W (6047558) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:1492, no_wnd_count:0, timeout_count:4
[2023-10-18 15:04:42.597] I (6047908) app_main: Heap size -> 8217532
[2023-10-18 15:04:43.600] I (6048908) app_main: Heap size -> 8217500
[2023-10-18 15:04:44.063] W (6049358) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:1492, no_wnd_count:0, timeout_count:5
[2023-10-18 15:04:44.597] I (6049908) app_main: Heap size -> 8217500
[2023-10-18 15:04:45.593] I (6050908) app_main: Heap size -> 8217500
[2023-10-18 15:04:45.868] W (6051158) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:1492, no_wnd_count:0, timeout_count:6
[2023-10-18 15:04:46.604] I (6051908) app_main: Heap size -> 8217500
[2023-10-18 15:04:47.596] I (6052908) app_main: Heap size -> 8217500
[2023-10-18 15:04:47.660] W (6052958) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:1492, no_wnd_count:0, timeout_count:7
[2023-10-18 15:04:48.593] I (6053908) app_main: Heap size -> 8217500
[2023-10-18 15:04:49.458] W (6054758) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:1492, no_wnd_count:0, timeout_count:8
[2023-10-18 15:04:49.600] I (6054908) app_main: Heap size -> 8217500
[2023-10-18 15:04:50.603] I (6055908) app_main: Heap size -> 8217500
[2023-10-18 15:04:51.260] W (6056558) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:1492, no_wnd_count:0, timeout_count:9
[2023-10-18 15:04:51.606] I (6056908) app_main: Heap size -> 8217500
[2023-10-18 15:04:52.597] I (6057908) app_main: Heap size -> 8217500
[2023-10-18 15:04:53.067] W (6058358) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:1492, no_wnd_count:0, timeout_count:10
[2023-10-18 15:04:53.590] I (6058908) app_main: Heap size -> 8217500
[2023-10-18 15:04:54.601] I (6059908) app_main: Heap size -> 8217500
[2023-10-18 15:04:54.871] W (6060158) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:1492, no_wnd_count:0, timeout_count:11
[2023-10-18 15:04:55.604] I (6060908) app_main: Heap size -> 8217500
[2023-10-18 15:04:56.598] I (6061908) app_main: Heap size -> 8217500
[2023-10-18 15:04:56.664] W (6061958) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:1492, no_wnd_count:0, timeout_count:12
[2023-10-18 15:04:57.601] I (6062908) app_main: Heap size -> 8217500
[2023-10-18 15:04:58.465] W (6063758) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:1492, no_wnd_count:0, timeout_count:13
[2023-10-18 15:04:58.597] I (6063908) app_main: Heap size -> 8217500
[2023-10-18 15:04:59.603] I (6064908) app_main: Heap size -> 8217500
[2023-10-18 15:05:00.262] W (6065558) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:1492, no_wnd_count:0, timeout_count:14
[2023-10-18 15:05:00.606] I (6065908) app_main: Heap size -> 8217500
[2023-10-18 15:05:01.610] I (6066908) app_main: Heap size -> 8217500
[2023-10-18 15:05:02.063] W (6067358) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:1492, no_wnd_count:0, timeout_count:15

In other cases, the output is:

I (138518) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
I (138518) mesh: <MESH_NWK_ROOT_ADDR>from assoc, layer:2, root_addr:34:85:18:8f:4d:79, root_cap:1
@espressif-bot espressif-bot added the Status: Opened Issue is new label Oct 18, 2023
@github-actions github-actions bot changed the title WIFI-MESH: Node lost connection after Root reboot WIFI-MESH: Node lost connection after Root reboot (IDFGH-11273) Oct 18, 2023
@yanmartins
Copy link
Author

yanmartins commented Oct 19, 2023

I noticed that, in the example, mesh_netifs_destroy function is never called. This is necessary to delete the RX task, right?

@yanmartins
Copy link
Author

yanmartins commented Oct 23, 2023

This problem is displayed only in the root. But all devices have the same config:

[2023-10-23 09:13:52.539] I (940256) app_main: Heap size -> 8255420
[2023-10-23 09:13:52.539] I (940266) coexist: [940251263] Error! Should enable WiFi modem sleep when both WiFi and Bluetooth
[2023-10-23 09:13:52.539] 
[2023-10-23 09:13:52.620] abort() was called at PC 0x420e769c on core 0

@zhangyanjiaoesp
Copy link
Collaborator

@yanmartins
Can you update your IDF branch to 2048d1e to see whether the issue has been resolved?

@zhangyanjiaoesp
Copy link
Collaborator

I (940266) coexist: [940251263] Error! Should enable WiFi modem sleep when both WiFi and Bluetooth

The log means you should open power save when using coexist.

@espressif-bot espressif-bot added Status: In Progress Work is in progress and removed Status: Opened Issue is new labels Oct 24, 2023
@yanmartins
Copy link
Author

yanmartins commented Oct 24, 2023

I (940266) coexist: [940251263] Error! Should enable WiFi modem sleep when both WiFi and Bluetooth

The log means you should open power save when using coexist.

This setting was active. But I had to disable sleep via software

@yanmartins Can you update your IDF branch to 2048d1e to see whether the issue has been resolved?

Thanks, I updated to version 5.0.3. Which contains this mentioned update. I'll test it and get back with the results

I noticed that, in the example, mesh_netifs_destroy function is never called. This is necessary to delete the RX task, right?

@zhangyanjiaoesp Do you have any information about this?

@zhangyanjiaoesp
Copy link
Collaborator

I noticed that, in the example, mesh_netifs_destroy function is never called. This is necessary to delete the RX task, right?

yes, If you want to delete the RX task, you can call mesh_netif_destroy.

@yanmartins
Copy link
Author

@zhangyanjiaoesp in my tests, the ip_internal_network example create the RX task in the NODE after each ROOT reboot. Which generated a memory leak that took a while to resolve.

It might be interesting to update this example to avoid this type of problem.

@yanmartins
Copy link
Author

@yanmartins Can you update your IDF branch to 2048d1e to see whether the issue has been resolved?

And yes, updating to version 5.0.3 apparently fixed the reconnection issues

@zhangyanjiaoesp
Copy link
Collaborator

@zhangyanjiaoesp in my tests, the ip_internal_network example create the RX task in the NODE after each ROOT reboot. Which generated a memory leak that took a while to resolve.

It might be interesting to update this example to avoid this type of problem.

thanks, we will check it.

@zhangyanjiaoesp
Copy link
Collaborator

zhangyanjiaoesp commented Oct 27, 2023

@yanmartins
For the NODE device, when there is MESH_EVENT_PARENT_CONNECTED event, it will call mesh_netifs_start() -> mesh_create_if_driver() , the mesh_create_if_driver() will create the RX task at the first time, then the receive_task_is_running = true, this will ensure the RX task won't be created again.

image

I'm not clear about your memory leak issue, can you describe it in more details?

@yanmartins
Copy link
Author

Thanks, @zhangyanjiaoesp
I found my problem. I added a receive_task_is_running = false in mesh_delete_if_driver and forgot to remove it.
Sorry

@zhangyanjiaoesp
Copy link
Collaborator

If your problem has been solved, please close this ticket, thanks.

@espressif-bot espressif-bot added Status: Done Issue is done internally Resolution: Done Issue is done internally and removed Status: In Progress Work is in progress labels Oct 31, 2023
@yanmartins
Copy link
Author

yanmartins commented Oct 31, 2023

@zhangyanjiaoesp after modifying the ip_internal_network example to use a fixed root using PPP data. The same error came back in the NODE:

W (2711466) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:804, no_wnd_count:0, timeout_count:2
I (2712156) app_main: Heap size -> 8232400
I (2712886) wifi:bcn_timeout,ap_probe_send_start
I (2712886) wifi:pm stop, total sleep time: 278186851 us / 578216509 us

I (2712886) wifi:(mesh)pm stop, sleep:265 secs, total:551 secs, current active set:10, running:0.48
I (2713156) app_main: Heap size -> 8232400
W (2713266) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:804, no_wnd_count:0, timeout_count:3
I (2714156) app_main: Heap size -> 8232400
W (2715066) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:804, no_wnd_count:0, timeout_count:4
I (2715156) app_main: Heap size -> 8232400
I (2715406) wifi:ap_probe_send over, resett wifi status to disassoc
I (2715406) wifi:state: run -> init (c800)
I (2715406) wifi:<ba-del>idx
I (2715406) wifi:new:<1,0>, old:<1,1>, ap:<1,1>, sta:<1,1>, prof:1
I (2715406) mesh: [wifi]disconnected reason:200(beacon timeout), continuous:1/max:12, non-root, vote(,)<><>
I (2715406) srv_wifi: <MESH_EVENT_PARENT_DISCONNECTED>reason:200
I (2715416) wifi:new:<1,1>, old:<1,0>, ap:<1,1>, sta:<1,1>, prof:1
I (2715436) wifi:state: init -> auth (b0)
I (2716156) app_main: Heap size -> 8232792
W (2716866) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:804, no_wnd_count:0, timeout_count:5
I (2716936) wifi:state: auth -> init (200)
I (2716936) wifi:new:<1,0>, old:<1,1>, ap:<1,1>, sta:<1,1>, prof:1
I (2717156) app_main: Heap size -> 8232832
I (2718156) app_main: Heap size -> 8232832
W (2718666) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:804, no_wnd_count:0, timeout_count:6
I (2719156) app_main: Heap size -> 8232832
I (2720156) app_main: Heap size -> 8232832
W (2720466) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:804, no_wnd_count:0, timeout_count:7
I (2721156) app_main: Heap size -> 8232832

I just comment this configs:

/* router */
cfg.channel         = 0;
cfg.router.ssid_len = strlen((char *) wifi_params->ssid);
memcpy((uint8_t *) &cfg.router.ssid, wifi_params->ssid, cfg.router.ssid_len);
memcpy((uint8_t *) &cfg.router.password, wifi_params->password, sizeof(wifi_params->password));

Add this in the init:

esp_mesh_fix_root(true);
if(wifi_mesh_config->is_root) esp_mesh_set_type(MESH_ROOT);

And this in the PPP GOT IP event:

if(!esp_mesh_is_root()) return false;

mesh_netifs_start(true);
esp_mesh_post_toDS_state(true);
return mesh_netif_start_root_ap(true, dns_addr);

@yanmartins yanmartins reopened this Oct 31, 2023
@zhangyanjiaoesp
Copy link
Collaborator

@yanmartins Can you provide a complete log of the Node when the reconnection works well?

By the way, I think there are two things you can optimize in your code:

  1. Call esp_mesh_set_xon_qsize(128) to enlarge the xon window .
  2. The default mesh packet lifetime is BLOCKING(0xffffffff), you can call esp_mesh_send_block_time() to set the mesh packet lifetime, then the mesh node will not always show W (32119) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:2, 1800 ms timeout, seqno:0, xseqno:1, no_wnd_count:0, timeout_count:13, it will return TX_TIMEOUT when the lifetime has reached.

@espressif-bot espressif-bot added Status: In Progress Work is in progress and removed Status: Done Issue is done internally Resolution: Done Issue is done internally labels Nov 1, 2023
@zhangyanjiaoesp
Copy link
Collaborator

zhangyanjiaoesp commented Nov 1, 2023

I just comment this configs:

/* router */
cfg.channel         = 0;
cfg.router.ssid_len = strlen((char *) wifi_params->ssid);
memcpy((uint8_t *) &cfg.router.ssid, wifi_params->ssid, cfg.router.ssid_len);
memcpy((uint8_t *) &cfg.router.password, wifi_params->password, sizeof(wifi_params->password));

How about you don't comment the router configure lines?

@yanmartins
Copy link
Author

@yanmartins Can you provide a complete log of the Node when the reconnection works well?

Yes! I am sending both cases.

No reconnection:

[2023-11-01 11:25:45.706] I (3667) pp: pp rom version: e7ae62f
[2023-11-01 11:25:45.706] I (3667) net80211: net80211 rom version: e7ae62f
[2023-11-01 11:25:45.722] I (3687) wifi:wifi driver task: 3fcc8a68, prio:23, stack:6656, core=0
[2023-11-01 11:25:45.722] I (3687) system_api: Base MAC address is not set
[2023-11-01 11:25:45.722] I (3687) system_api: read default base MAC address from EFUSE
[2023-11-01 11:25:45.736] I (3697) wifi:wifi firmware version: e2d0ad8
[2023-11-01 11:25:45.736] I (3697) wifi:wifi certification version: v7.0
[2023-11-01 11:25:45.736] I (3707) wifi:config NVS flash: enabled
[2023-11-01 11:25:45.751] I (3707) wifi:config nano formating: disabled
[2023-11-01 11:25:45.751] I (3707) wifi:Init data frame dynamic rx buffer num: 64
[2023-11-01 11:25:45.751] I (3717) wifi:Init management frame dynamic rx buffer num: 64
[2023-11-01 11:25:45.751] I (3717) wifi:Init management short buffer num: 32
[2023-11-01 11:25:45.767] I (3727) wifi:Init static tx buffer num: 6
[2023-11-01 11:25:45.767] I (3727) wifi:Init tx cache buffer num: 32
[2023-11-01 11:25:45.775] I (3737) wifi:Init static tx FG buffer num: 2
[2023-11-01 11:25:45.775] I (3737) wifi:Init static rx buffer size: 1600
[2023-11-01 11:25:45.775] I (3737) wifi:Init static rx buffer num: 6
[2023-11-01 11:25:45.775] I (3747) wifi:Init dynamic rx buffer num: 64
[2023-11-01 11:25:45.791] I (3747) wifi_init: rx ba win: 6
[2023-11-01 11:25:45.791] I (3757) wifi_init: tcpip mbox: 32
[2023-11-01 11:25:45.791] I (3757) wifi_init: udp mbox: 6
[2023-11-01 11:25:45.791] I (3757) wifi_init: tcp mbox: 6
[2023-11-01 11:25:45.806] I (3767) wifi_init: tcp tx win: 5744
[2023-11-01 11:25:45.806] I (3767) wifi_init: tcp rx win: 5744
[2023-11-01 11:25:45.806] I (3777) wifi_init: tcp mss: 624
[2023-11-01 11:25:45.822] I (3777) wifi_init: WiFi/LWIP prefer SPIRAM
[2023-11-01 11:25:45.822] I (3777) wifi_init: WiFi IRAM OP enabled
[2023-11-01 11:25:45.822] I (3787) wifi_init: WiFi RX IRAM OP enabled
[2023-11-01 11:25:45.836] I (3787) srv_wifi: srv_wifi_init [esp_wifi_init]
[2023-11-01 11:25:45.836] I (3797) srv_wifi: srv_wifi_init [esp_wifi_set_storage]
[2023-11-01 11:25:45.836] I (3807) srv_wifi: srv_wifi_init [esp_wifi_set_mode]
[2023-11-01 11:25:45.852] I (3807) srv_wifi: srv_wifi_init [OUT]
[2023-11-01 11:25:45.852] I (3817) phy_init: phy_version 610,2bff4c8,Jul 27 2023,20:22:14
[2023-11-01 11:25:45.907] I (3867) wifi:mode : sta (34:85:18:8f:4c:7c) + softAP (34:85:18:8f:4c:7d)
[2023-11-01 11:25:45.907] I (3867) wifi:enable tsf
[2023-11-01 11:25:45.907] I (3867) wifi:Total power save buffer number: 3
[2023-11-01 11:25:45.907] I (3867) wifi:Init max length of beacon: 752/752
[2023-11-01 11:25:45.907] I (3877) wifi:Init max length of beacon: 752/752
[2023-11-01 11:25:45.923] I (3877) mesh: [CONFIG]invalid router settings, ssid_len:0, ssid:, bssid:00:00:00:00:00:00
[2023-11-01 11:25:46.937] I (4897) wifi:Total power save buffer number: 3
[2023-11-01 11:25:47.937] I (5897) srv_wifi: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
[2023-11-01 11:25:47.937] I (5897) srv_wifi: wifi_config->router.ssid: 
[2023-11-01 11:25:47.937] I (5897) srv_wifi: wifi_config->router.password: 
[2023-11-01 11:25:47.953] I (5907) srv_wifi: wifi_config->mesh_ap.password: MAP_PASSWD
[2023-11-01 11:25:47.953] I (5917) srv_wifi: wifi_config->mesh_ap.max_connection: 6
[2023-11-01 11:25:47.953] I (5917) srv_wifi: wifi_config->mesh_id: 119:119:119:119:119:118
[2023-11-01 11:25:47.968] I (5927) srv_wifi: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
[2023-11-01 11:25:47.968] I (5937) wifi:mode : sta (34:85:18:8f:4c:7c)
[2023-11-01 11:25:47.984] I (5937) mesh: <MESH_NWK_LOOK_FOR_NETWORK>need_scan:0x2, need_scan_router:0x0, look_for_nwk_count:0
[2023-11-01 11:25:47.988] I (5947) srv_wifi: <MESH_EVENT_MESH_STARTED>ID:77:77:77:77:77:76
[2023-11-01 11:25:47.988] I (5947) srv_wifi: mesh starts successfully, heap: 8393248, root fixed
[2023-11-01 11:25:48.004] I (5967) app_comm_manager: interface init
[2023-11-01 11:25:51.209] I (9177) app_main: Heap size -> 8263788
[2023-11-01 11:25:52.190] I (10147) mesh: [FIND][ch:0]AP:16, otherID:0, MAP:0, idle:0, candidate:0, root:0[00:00:00:00:00:00][FIXED-ROOT]
[2023-11-01 11:25:52.190] I (10147) mesh: [FIND:1]fail to find a network, channel:0, cfg<channel:0, router:, 00:00:00:00:00:00>
[2023-11-01 11:25:52.190] 
[2023-11-01 11:25:52.205] I (10157) mesh: <MESH_NWK_LOOK_FOR_NETWORK>need_scan:0x2, need_scan_router:0x0, look_for_nwk_count:0
[2023-11-01 11:25:52.205] I (10177) app_main: Heap size -> 8265088
[2023-11-01 11:25:53.204] I (11177) app_main: Heap size -> 8264788
[2023-11-01 11:25:54.209] I (12177) app_main: Heap size -> 8264688
[2023-11-01 11:25:55.211] I (13177) app_main: Heap size -> 8264688
[2023-11-01 11:25:56.214] I (14177) app_main: Heap size -> 8263988
[2023-11-01 11:25:56.415] I (14377) mesh: [FIND][ch:0]AP:11, otherID:0, MAP:0, idle:0, candidate:0, root:0[00:00:00:00:00:00][FIXED-ROOT]
[2023-11-01 11:25:56.415] I (14377) mesh: [FIND:2]fail to find a network, channel:0, cfg<channel:0, router:, 00:00:00:00:00:00>
[2023-11-01 11:25:56.415] 
[2023-11-01 11:25:56.430] I (14377) mesh: <MESH_NWK_LOOK_FOR_NETWORK>need_scan:0x2, need_scan_router:0x0, look_for_nwk_count:0
[2023-11-01 11:25:57.199] I (15177) app_main: Heap size -> 8264588
[2023-11-01 11:25:58.201] I (16177) app_main: Heap size -> 8264188
[2023-11-01 11:25:59.206] I (17177) app_main: Heap size -> 8264188
[2023-11-01 11:26:00.213] I (18177) app_main: Heap size -> 8263388
[2023-11-01 11:26:00.632] I (18597) mesh: [FIND][ch:0]AP:17, otherID:0, MAP:0, idle:0, candidate:0, root:0[00:00:00:00:00:00][FIXED-ROOT]
[2023-11-01 11:26:00.632] I (18597) mesh: [FIND:3]fail to find a network, channel:0, cfg<channel:0, router:, 00:00:00:00:00:00>
[2023-11-01 11:26:00.632] 
[2023-11-01 11:26:00.647] I (18607) mesh: <MESH_NWK_LOOK_FOR_NETWORK>need_scan:0x2, need_scan_router:0x0, look_for_nwk_count:0
[2023-11-01 11:26:01.205] I (19177) app_main: Heap size -> 8264388
[2023-11-01 11:26:02.212] I (20177) app_main: Heap size -> 8264188
[2023-11-01 11:26:03.210] I (21177) app_main: Heap size -> 8264188
[2023-11-01 11:26:04.212] I (22177) app_main: Heap size -> 8263688
[2023-11-01 11:26:04.847] I (22817) mesh: [FIND][ch:0]AP:16, otherID:0, MAP:0, idle:0, candidate:0, root:0[00:00:00:00:00:00][FIXED-ROOT]
[2023-11-01 11:26:04.863] I (22817) mesh: [FIND:4]fail to find a network, channel:0, cfg<channel:0, router:, 00:00:00:00:00:00>
[2023-11-01 11:26:04.863] 
[2023-11-01 11:26:04.863] I (22827) mesh: <MESH_NWK_LOOK_FOR_NETWORK>need_scan:0x2, need_scan_router:0x0, look_for_nwk_count:0
[2023-11-01 11:26:05.203] I (23177) app_main: Heap size -> 8264388
[2023-11-01 11:26:06.214] I (24177) app_main: Heap size -> 8264388
[2023-11-01 11:26:07.215] I (25177) app_main: Heap size -> 8264088
[2023-11-01 11:26:08.201] I (26177) app_main: Heap size -> 8263588
[2023-11-01 11:26:09.071] I (27037) mesh: [FIND][ch:0]AP:17, otherID:0, MAP:0, idle:0, candidate:0, root:0[00:00:00:00:00:00][FIXED-ROOT]
[2023-11-01 11:26:09.087] I (27047) mesh: [FIND:5]fail to find a network, channel:0, cfg<channel:0, router:, 00:00:00:00:00:00>
[2023-11-01 11:26:09.087] 
[2023-11-01 11:26:09.087] I (27047) mesh: <MESH_NWK_LOOK_FOR_NETWORK>need_scan:0x2, need_scan_router:0x0, look_for_nwk_count:0
[2023-11-01 11:26:09.203] I (27177) app_main: Heap size -> 8264500
[2023-11-01 11:26:10.204] I (28177) app_main: Heap size -> 8264400
[2023-11-01 11:26:11.205] I (29177) app_main: Heap size -> 8264200
[2023-11-01 11:26:12.207] I (30177) app_main: Heap size -> 8263700
[2023-11-01 11:26:13.209] I (31177) app_main: Heap size -> 8263400
[2023-11-01 11:26:13.294] I (31267) mesh: find root:ESPM_8F4D28, root_cap:1(max:300), new channel:1, old channel:0
[2023-11-01 11:26:13.309] I (31267) mesh: [FIND][ch:0]AP:16, otherID:0, MAP:1, idle:0, candidate:0, root:1[00:00:00:00:00:00][FIXED-ROOT]
[2023-11-01 11:26:13.309] I (31267) mesh: [FIND:6]find a network, channel:1, cfg<channel:0, router:, 00:00:00:00:00:00>
[2023-11-01 11:26:13.309] 
[2023-11-01 11:26:13.330] I (31277) srv_wifi: <MESH_EVENT_FIND_NETWORK>new channel:1, router BSSID:00:00:00:00:00:00
[2023-11-01 11:26:13.332] I (31277) wifi:mode : sta (34:85:18:8f:4c:7c) + softAP (34:85:18:8f:4c:7d)
[2023-11-01 11:26:13.332] W (31297) wifi:<MESH AP>adjust channel:1, secondary channel offset:1(40U)
[2023-11-01 11:26:13.348] I (31307) wifi:Total power save buffer number: 3
[2023-11-01 11:26:13.651] I (31607) mesh: [SCAN][ch:1]AP:8, other(ID:0, RD:0), MAP:1, idle:0, candidate:1, root:1, topMAP:0[c:0,i:0][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:26:13.656] I (31607) mesh: 7370[selection]try rssi_threshold:-78, backoff times:0, max:5<-78,-82,-85>
[2023-11-01 11:26:13.666] I (31617) mesh: [DONE]connect to parent:ESPM_8F4D28, channel:1, rssi:-32, 34:85:18:8f:4d:29[layer:1, assoc:0], my_vote_num:0/voter_num:0, rc[00:00:00:00:00:00/-120/0]
[2023-11-01 11:26:13.728] I (31687) wifi:new:<1,1>, old:<1,1>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:26:14.692] I (32647) wifi:state: init -> auth (b0)
[2023-11-01 11:26:14.696] I (32657) app_main: Heap size -> 8261972
[2023-11-01 11:26:14.702] I (32667) wifi:state: auth -> assoc (0)
[2023-11-01 11:26:14.715] I (32677) wifi:state: assoc -> run (10)
[2023-11-01 11:26:14.715] I (32687) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-01 11:26:14.715] I (32687) mesh: <MESH_NWK_ROOT_ADDR>from assoc, layer:2, root_addr:34:85:18:8f:4d:29, root_cap:1
[2023-11-01 11:26:14.732] I (32687) srv_wifi: unknown id:24
[2023-11-01 11:26:14.732] I (32687) mesh: <MESH_NWK_ROOT_ADDR>idle, layer:2, root_addr:34:85:18:8f:4d:29, conflict_roots.num:0<>
[2023-11-01 11:26:14.751] I (32697) wifi:connected with ESPM_8F4D28, aid = 1, channel 1, 40U, bssid = 34:85:18:8f:4d:29
[2023-11-01 11:26:14.753] I (32707) wifi:security: WPA2-PSK, phy: bgn, rssi: -34
[2023-11-01 11:26:14.765] I (32717) srv_wifi: <MESH_EVENT_ROOT_ADDRESS>root address:34:85:18:8f:4d:29
[2023-11-01 11:26:14.767] I (32727) wifi:Set ps type: 1
[2023-11-01 11:26:14.767] 
[2023-11-01 11:26:14.767] I (32727) mesh: [scan]new scanning time:600ms, beacon interval:300ms
[2023-11-01 11:26:14.783] I (32727) mesh: 2004<arm>parent monitor, my layer:2(cap:6)(node), interval:10864ms, retries:1<normal connected>
[2023-11-01 11:26:14.783] I (32737) srv_wifi: <MESH_EVENT_PARENT_CONNECTED>layer:0-->2, parent:34:85:18:8f:4d:29<layer2>, ID:77:77:77:77:77:76
[2023-11-01 11:26:14.803] I (32767) srv_wifi_mesh: It was a wifi station removing stuff
[2023-11-01 11:26:14.815] I (32767) wifi:<ba-add>idx:0 (ifx:0, 34:85:18:8f:4d:29), tid:5, ssn:0, winSize:64
[2023-11-01 11:26:14.816] I (32777) srv_wifi: <MESH_EVENT_TODS_REACHABLE>state:0
[2023-11-01 11:26:14.821] I (32777) srv_wifi: <MESH_EVENT_ROOT_ADDRESS>root address:34:85:18:8f:4d:29
[2023-11-01 11:26:15.682] I (33657) app_main: Heap size -> 8258424
[2023-11-01 11:26:16.685] I (34657) app_main: Heap size -> 8258424
[2023-11-01 11:26:17.201] I (35167) wifi:pm start, type: 1
[2023-11-01 11:26:17.201] 
[2023-11-01 11:26:17.217] I (35167) wifi:set rx beacon pti, rx_bcn_pti: 0, bcn_timeout: 0, mt_pti: 25000, mt_time: 10000
[2023-11-01 11:26:17.301] I (35267) wifi:AP's beacon interval = 102400 us, DTIM period = 1
[2023-11-01 11:26:17.686] I (35657) app_main: Heap size -> 8258424
[2023-11-01 11:26:18.690] I (36657) app_main: Heap size -> 8258424
[2023-11-01 11:26:19.695] I (37657) app_main: Heap size -> 8258424
[2023-11-01 11:26:20.466] W (38437) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:26:20.692] I (38657) app_main: Heap size -> 8258424
[2023-11-01 11:26:21.693] I (39657) app_main: Heap size -> 8258424
[2023-11-01 11:26:22.017] I (39977) mesh: <MESH_NWK_MIE_CHANGE><><TODS><><><><><>
[2023-11-01 11:26:22.020] I (39977) srv_wifi: <MESH_EVENT_TODS_REACHABLE>state:1
[2023-11-01 11:26:22.681] I (40657) app_main: Heap size -> 8258424
[2023-11-01 11:26:23.539] W (41507) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:26:23.571] I (41537) srv_wifi: ## IP: 10.0.0.2
[2023-11-01 11:26:23.571] I (41537) srv_wifi: ## MASK: 255.255.0.0
[2023-11-01 11:26:23.571] I (41537) srv_wifi: ## GW: 10.0.0.1
[2023-11-01 11:26:23.586] I (41547) app_comm_connectivity: NETWORK_STATUS_CONNECTED 5
[2023-11-01 11:26:23.671] I (41627) app_comm_protocol: MQTT Protocol initialized successfully
[2023-11-01 11:26:23.687] I (41657) app_main: Heap size -> 8238396
[2023-11-01 11:26:24.688] I (42657) app_main: Heap size -> 8237616
[2023-11-01 11:26:25.684] I (43657) app_main: Heap size -> 8237616
[2023-11-01 11:26:25.901] I (43867) mesh: 5157<active>parent layer:1(node), channel:1, rssi:-32, assoc:0(cnx rssi threshold:-120)my_assoc:0
[2023-11-01 11:26:26.690] I (44657) app_main: Heap size -> 8237292
[2023-11-01 11:26:27.406] I (45367) mesh: 5948<scan>parent layer:1, rssi:-30, assoc:1(cnx rssi threshold:-120)
[2023-11-01 11:26:27.420] I (45367) mesh: [SCAN][ch:1]AP:1, other(ID:0, RD:0), MAP:1, idle:0, candidate:1, root:1, topMAP:0[c:2,i:2][00:00:00:00:00:00][FIXED-ROOT]<weak>
[2023-11-01 11:26:27.427] I (45377) mesh: 7370[weak]try rssi_threshold:-120, backoff times:0, max:5<-78,-82,-85>
[2023-11-01 11:26:27.434] I (45387) mesh: 716[monitor]no change, parent:34:85:18:8f:4d:29, rssi:-30
[2023-11-01 11:26:27.442] I (45397) mesh: 2004<arm>parent monitor, my layer:2(cap:6)(node), interval:6299ms, retries:2<>
[2023-11-01 11:26:27.680] I (45657) app_main: Heap size -> 8227760
[2023-11-01 11:26:28.252] W (46217) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:26:28.686] I (46657) app_main: Heap size -> 8227912
[2023-11-01 11:26:29.073] W (47037) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:26:29.694] I (47657) app_main: Heap size -> 8227912
[2023-11-01 11:26:30.700] I (48657) app_main: Heap size -> 8227912
[2023-11-01 11:26:31.689] I (49657) app_main: Heap size -> 8227912
[2023-11-01 11:26:32.695] I (50657) app_main: Heap size -> 8227912
[2023-11-01 11:26:33.686] I (51657) app_main: Heap size -> 8234036
[2023-11-01 11:26:33.887] I (51847) mesh: 5157<active>parent layer:1(node), channel:1, rssi:-32, assoc:1(cnx rssi threshold:-120)my_assoc:0
[2023-11-01 11:26:34.693] I (52657) app_main: Heap size -> 8232184
[2023-11-01 11:26:35.397] I (53357) mesh: 5948<scan>parent layer:1, rssi:-31, assoc:1(cnx rssi threshold:-120)
[2023-11-01 11:26:35.406] I (53357) mesh: [SCAN][ch:1]AP:1, other(ID:0, RD:0), MAP:1, idle:0, candidate:1, root:1, topMAP:0[c:2,i:2][00:00:00:00:00:00][FIXED-ROOT]<weak>
[2023-11-01 11:26:35.413] I (53367) mesh: 7370[weak]try rssi_threshold:-120, backoff times:0, max:5<-78,-82,-85>
[2023-11-01 11:26:35.419] I (53367) mesh: 716[monitor]no change, parent:34:85:18:8f:4d:29, rssi:-31
[2023-11-01 11:26:35.423] I (53377) mesh: 2004<arm>parent monitor, my layer:2(cap:6)(node), interval:7165ms, retries:3<>
[2023-11-01 11:26:35.681] I (53657) app_main: Heap size -> 8232920
[2023-11-01 11:26:36.683] I (54657) app_main: Heap size -> 8232920
[2023-11-01 11:26:37.684] I (55657) app_main: Heap size -> 8234116
[2023-11-01 11:26:38.696] I (56657) app_main: Heap size -> 8234856
[2023-11-01 11:26:38.876] I (56827) srv_mqtt: MQTT_EVENT_CONNECTED
[2023-11-01 11:26:38.881] I (56827) app_comm_protocol: PROTOCOL_STATUS_CONNECTED 5
...
[2023-11-01 11:26:42.761] I (60727) mesh: 5157<active>parent layer:1(node), channel:1, rssi:-32, assoc:1(cnx rssi threshold:-120)my_assoc:0
[2023-11-01 11:26:43.695] I (61657) app_main: Heap size -> 8233660
[2023-11-01 11:26:44.270] I (62227) mesh: 5948<scan>parent layer:1, rssi:-30, assoc:1(cnx rssi threshold:-120)
[2023-11-01 11:26:44.276] I (62227) mesh: [SCAN][ch:1]AP:1, other(ID:0, RD:0), MAP:1, idle:0, candidate:1, root:1, topMAP:0[c:2,i:2][00:00:00:00:00:00][FIXED-ROOT]<weak>
[2023-11-01 11:26:44.280] I (62237) mesh: 7370[weak]try rssi_threshold:-120, backoff times:0, max:5<-78,-82,-85>
[2023-11-01 11:26:44.295] I (62247) mesh: 716[monitor]no change, parent:34:85:18:8f:4d:29, rssi:-30
[2023-11-01 11:26:44.296] I (62247) mesh: 2004<arm>parent monitor, my layer:2(cap:6)(node), interval:374707ms, retries:3<>
...
[2023-11-01 11:27:02.693] I (80657) app_main: Heap size -> 8229108
[2023-11-01 11:27:03.694] W (81267) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:0
[2023-11-01 11:27:03.694] I (81657) app_main: Heap size -> 8229108
[2023-11-01 11:27:04.694] I (82657) app_main: Heap size -> 8229108
[2023-11-01 11:27:05.119] W (83067) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:1
[2023-11-01 11:27:05.119] W (83077) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:27:05.327] W (83287) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:27:05.695] I (83657) app_main: Heap size -> 8229108
[2023-11-01 11:27:06.351] W (84307) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:27:06.683] I (84657) app_main: Heap size -> 8229108
[2023-11-01 11:27:07.690] W (84867) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:2
[2023-11-01 11:27:07.690] I (85657) app_main: Heap size -> 8229108
[2023-11-01 11:27:08.692] I (86657) app_main: Heap size -> 8229108
[2023-11-01 11:27:09.695] W (86667) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:3
[2023-11-01 11:27:09.695] I (87657) app_main: Heap size -> 8229108
[2023-11-01 11:27:10.683] W (88467) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:4
[2023-11-01 11:27:10.683] I (88657) app_main: Heap size -> 8229108
[2023-11-01 11:27:10.817] I (88787) wifi:bcn_timeout,ap_probe_send_start
[2023-11-01 11:27:10.817] I (88787) wifi:pm stop, total sleep time: 22777590 us / 53614853 us
[2023-11-01 11:27:10.817] 
[2023-11-01 11:27:10.833] I (88787) wifi:(mesh)pm stop, sleep:21 secs, total:51 secs, current active set:10, running:0.42
[2023-11-01 11:27:11.694] I (89657) app_main: Heap size -> 8228864
[2023-11-01 11:27:12.683] W (90267) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:5
[2023-11-01 11:27:12.683] I (90657) app_main: Heap size -> 8228864
[2023-11-01 11:27:13.336] I (91297) wifi:ap_probe_send over, resett wifi status to disassoc
[2023-11-01 11:27:13.336] I (91297) wifi:state: run -> init (c800)
[2023-11-01 11:27:13.336] I (91297) wifi:<ba-del>idx
[2023-11-01 11:27:13.336] I (91297) wifi:new:<1,0>, old:<1,1>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:27:13.336] I (91307) srv_wifi: <MESH_EVENT_PARENT_DISCONNECTED>reason:200
[2023-11-01 11:27:13.351] I (91307) mesh: [wifi]disconnected reason:200(beacon timeout), continuous:1/max:12, non-root, vote(,)<><>
[2023-11-01 11:27:13.351] I (91317) wifi:new:<1,1>, old:<1,0>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:27:13.367] I (91327) wifi:state: init -> auth (b0)
[2023-11-01 11:27:13.684] I (91657) app_main: Heap size -> 8229272
[2023-11-01 11:27:14.687] W (92067) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:6
[2023-11-01 11:27:14.687] I (92657) app_main: Heap size -> 8229272
[2023-11-01 11:27:14.871] I (92827) wifi:state: auth -> init (200)
[2023-11-01 11:27:14.874] I (92827) wifi:new:<1,0>, old:<1,1>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:27:15.687] I (93657) app_main: Heap size -> 8229296
[2023-11-01 11:27:16.689] W (93867) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:7
[2023-11-01 11:27:16.689] I (94657) app_main: Heap size -> 8229296
[2023-11-01 11:27:17.690] I (95657) app_main: Heap size -> 8229296
[2023-11-01 11:27:18.692] W (95667) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:8
[2023-11-01 11:27:18.692] I (96657) app_main: Heap size -> 8229296
[2023-11-01 11:27:19.687] W (97467) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:9
[2023-11-01 11:27:19.687] I (97657) app_main: Heap size -> 8229296
[2023-11-01 11:27:20.695] I (98657) app_main: Heap size -> 8229296
[2023-11-01 11:27:21.302] W (99267) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:10
[2023-11-01 11:27:21.688] I (99657) app_main: Heap size -> 8229296
[2023-11-01 11:27:22.686] I (100657) app_main: Heap size -> 8229296
[2023-11-01 11:27:23.100] W (101067) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:11
[2023-11-01 11:27:23.684] I (101657) app_main: Heap size -> 8229296
[2023-11-01 11:27:24.685] I (102657) app_main: Heap size -> 8229296
[2023-11-01 11:27:24.901] W (102867) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:12
[2023-11-01 11:27:25.687] I (103657) app_main: Heap size -> 8229296
[2023-11-01 11:27:26.687] I (104657) app_main: Heap size -> 8229296
[2023-11-01 11:27:26.703] W (104667) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:13
[2023-11-01 11:27:27.689] I (105657) app_main: Heap size -> 8229296
[2023-11-01 11:27:28.491] W (106467) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:14
[2023-11-01 11:27:28.692] I (106657) app_main: Heap size -> 8229296
[2023-11-01 11:27:29.695] I (107657) app_main: Heap size -> 8229296
[2023-11-01 11:27:30.292] W (108267) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:15
[2023-11-01 11:27:30.693] I (108657) app_main: Heap size -> 8229296
[2023-11-01 11:27:31.693] I (109657) app_main: Heap size -> 8229296
[2023-11-01 11:27:32.094] W (110067) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:16
[2023-11-01 11:27:32.695] I (110657) app_main: Heap size -> 8229296
[2023-11-01 11:27:33.681] I (111657) app_main: Heap size -> 8229296
[2023-11-01 11:27:33.897] W (111867) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:17
[2023-11-01 11:27:34.682] I (112657) app_main: Heap size -> 8229296
[2023-11-01 11:27:35.683] I (113657) app_main: Heap size -> 8229296
[2023-11-01 11:27:35.699] W (113667) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:18
[2023-11-01 11:27:36.694] I (114657) app_main: Heap size -> 8229296
[2023-11-01 11:27:37.501] W (115467) mesh: [mesh_schedule.c,3131] [WND-RX]max_wnd:9, 1800 ms timeout, seqno:0, xseqno:49, no_wnd_count:0, timeout_count:19

With reconnection:

[2023-11-01 11:39:47.628] I (3747) srv_wifi: srv_wifi_mesh_init
[2023-11-01 11:39:47.628] I (3757) srv_wifi: wifi_init
[2023-11-01 11:39:47.628] I (3757) srv_wifi: srv_wifi_init [memset]
[2023-11-01 11:39:47.643] I (3757) srv_wifi: srv_wifi_init [WIFI_INIT_CONFIG_DEFAULT]
[2023-11-01 11:39:47.643] I (3767) pp: pp rom version: e7ae62f
[2023-11-01 11:39:47.643] I (3767) net80211: net80211 rom version: e7ae62f
[2023-11-01 11:39:47.659] I (3787) wifi:wifi driver task: 3fcc8a68, prio:23, stack:6656, core=0
[2023-11-01 11:39:47.675] I (3797) wifi:wifi firmware version: e2d0ad8
[2023-11-01 11:39:47.675] I (3797) wifi:wifi certification version: v7.0
[2023-11-01 11:39:47.675] I (3807) wifi:config NVS flash: enabled
[2023-11-01 11:39:47.675] I (3807) wifi:config nano formating: disabled
[2023-11-01 11:39:47.694] I (3807) wifi:Init data frame dynamic rx buffer num: 64
[2023-11-01 11:39:47.694] I (3817) wifi:Init management frame dynamic rx buffer num: 64
[2023-11-01 11:39:47.694] I (3817) wifi:Init management short buffer num: 32
[2023-11-01 11:39:47.694] I (3827) wifi:Init static tx buffer num: 6
[2023-11-01 11:39:47.709] I (3827) wifi:Init tx cache buffer num: 32
[2023-11-01 11:39:47.709] I (3837) wifi:Init static tx FG buffer num: 2
[2023-11-01 11:39:47.709] I (3837) wifi:Init static rx buffer size: 1600
[2023-11-01 11:39:47.709] I (3837) wifi:Init static rx buffer num: 6
[2023-11-01 11:39:47.725] I (3847) wifi:Init dynamic rx buffer num: 64
[2023-11-01 11:39:47.729] I (3847) wifi_init: rx ba win: 6
[2023-11-01 11:39:47.729] I (3857) wifi_init: tcpip mbox: 32
[2023-11-01 11:39:47.729] I (3857) wifi_init: udp mbox: 6
[2023-11-01 11:39:47.729] I (3857) wifi_init: tcp mbox: 6
[2023-11-01 11:39:47.744] I (3867) wifi_init: tcp tx win: 5744
[2023-11-01 11:39:47.744] I (3867) wifi_init: tcp rx win: 5744
[2023-11-01 11:39:47.744] I (3877) wifi_init: tcp mss: 624
[2023-11-01 11:39:47.760] I (3877) wifi_init: WiFi/LWIP prefer SPIRAM
[2023-11-01 11:39:47.760] I (3877) wifi_init: WiFi IRAM OP enabled
[2023-11-01 11:39:47.760] I (3887) wifi_init: WiFi RX IRAM OP enabled
[2023-11-01 11:39:47.760] I (3887) srv_wifi: srv_wifi_init [esp_wifi_init]
[2023-11-01 11:39:47.776] I (3897) srv_wifi: srv_wifi_init [esp_wifi_set_storage]
[2023-11-01 11:39:47.776] I (3907) srv_wifi: srv_wifi_init [esp_wifi_set_mode]
[2023-11-01 11:39:47.776] I (3907) srv_wifi: srv_wifi_init [OUT]
[2023-11-01 11:39:47.794] I (3917) phy_init: phy_version 610,2bff4c8,Jul 27 2023,20:22:14
[2023-11-01 11:39:47.829] I (3957) wifi:mode : sta (34:85:18:8f:4c:7c) + softAP (34:85:18:8f:4c:7d)
[2023-11-01 11:39:47.829] I (3957) wifi:enable tsf
[2023-11-01 11:39:47.829] I (3967) wifi:Total power save buffer number: 3
[2023-11-01 11:39:47.845] I (3967) wifi:Init max length of beacon: 752/752
[2023-11-01 11:39:47.845] I (3967) wifi:Init max length of beacon: 752/752
[2023-11-01 11:39:47.845] I (3977) mesh: [CONFIG]invalid router settings, ssid_len:0, ssid:, bssid:00:00:00:00:00:00
[2023-11-01 11:39:48.869] I (4997) wifi:Total power save buffer number: 3
[2023-11-01 11:39:49.862] I (5997) srv_wifi: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
[2023-11-01 11:39:49.862] I (5997) srv_wifi: wifi_config->router.ssid: 
[2023-11-01 11:39:49.878] I (5997) srv_wifi: wifi_config->router.password: 
[2023-11-01 11:39:49.878] I (5997) srv_wifi: wifi_config->mesh_ap.password: MAP_PASSWD
[2023-11-01 11:39:49.878] I (6007) srv_wifi: wifi_config->mesh_ap.max_connection: 6
[2023-11-01 11:39:49.896] I (6017) srv_wifi: wifi_config->mesh_id: 119:119:119:119:119:118
[2023-11-01 11:39:49.896] I (6017) srv_wifi: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
[2023-11-01 11:39:49.896] I (6027) wifi:mode : sta (34:85:18:8f:4c:7c)
[2023-11-01 11:39:49.912] I (6027) mesh: <MESH_NWK_LOOK_FOR_NETWORK>need_scan:0x2, need_scan_router:0x0, look_for_nwk_count:0
[2023-11-01 11:39:49.912] I (6037) srv_wifi: <MESH_EVENT_MESH_STARTED>ID:77:77:77:77:77:76
[2023-11-01 11:39:49.928] I (6047) srv_wifi: mesh starts successfully, heap: 8393512, root fixed
...
[2023-11-01 11:39:54.118] I (10247) mesh: find root:ESPM_8F4D28, root_cap:1(max:300), new channel:1, old channel:0
[2023-11-01 11:39:54.118] I (10247) mesh: [FIND][ch:0]AP:16, otherID:0, MAP:1, idle:0, candidate:0, root:1[00:00:00:00:00:00][FIXED-ROOT]
[2023-11-01 11:39:54.133] I (10257) mesh: [FIND:1]find a network, channel:1, cfg<channel:0, router:, 00:00:00:00:00:00>
[2023-11-01 11:39:54.133] I (10257) srv_wifi: <MESH_EVENT_FIND_NETWORK>new channel:1, router BSSID:00:00:00:00:00:00
[2023-11-01 11:39:54.149] I (10257) wifi:mode : sta (34:85:18:8f:4c:7c) + softAP (34:85:18:8f:4c:7d)
[2023-11-01 11:39:54.149] W (10277) wifi:<MESH AP>adjust channel:1, secondary channel offset:1(40U)
[2023-11-01 11:39:54.165] I (10287) wifi:Total power save buffer number: 3
[2023-11-01 11:39:54.165] I (10287) app_main: Heap size -> 8261960
[2023-11-01 11:39:54.456] I (10587) mesh: [SCAN][ch:1]AP:4, other(ID:0, RD:0), MAP:1, idle:0, candidate:1, root:1, topMAP:0[c:0,i:0][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:39:54.471] I (10587) mesh: 7370[selection]try rssi_threshold:-78, backoff times:0, max:5<-78,-82,-85>
[2023-11-01 11:39:54.490] I (10597) mesh: [DONE]connect to parent:ESPM_8F4D28, channel:1, rssi:-39, 34:85:18:8f:4d:29[layer:1, assoc:0], my_vote_num:0/voter_num:0, rc[00:00:00:00:00:00/-120/0]
[2023-11-01 11:39:54.582] I (10707) wifi:new:<1,1>, old:<1,1>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:39:55.584] I (11707) wifi:state: init -> auth (b0)
[2023-11-01 11:39:55.584] I (11717) app_main: Heap size -> 8261996
[2023-11-01 11:39:55.599] I (11717) wifi:state: auth -> assoc (0)
[2023-11-01 11:39:55.622] I (11747) wifi:state: assoc -> run (10)
[2023-11-01 11:39:55.622] I (11757) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-01 11:39:55.622] I (11757) mesh: <MESH_NWK_ROOT_ADDR>from assoc, layer:2, root_addr:34:85:18:8f:4d:29, root_cap:1
[2023-11-01 11:39:55.638] I (11757) srv_wifi: unknown id:24
[2023-11-01 11:39:55.638] I (11757) mesh: <MESH_NWK_ROOT_ADDR>idle, layer:2, root_addr:34:85:18:8f:4d:29, conflict_roots.num:0<>
[2023-11-01 11:39:55.653] I (11767) srv_wifi: <MESH_EVENT_ROOT_ADDRESS>root address:34:85:18:8f:4d:29
[2023-11-01 11:39:55.653] I (11767) wifi:connected with ESPM_8F4D28, aid = 1, channel 1, 40U, bssid = 34:85:18:8f:4d:29
[2023-11-01 11:39:55.670] I (11787) wifi:security: WPA2-PSK, phy: bgn, rssi: -39
[2023-11-01 11:39:55.673] I (11797) wifi:Set ps type: 1
[2023-11-01 11:39:55.673] 
[2023-11-01 11:39:55.676] I (11797) mesh: [scan]new scanning time:600ms, beacon interval:300ms
[2023-11-01 11:39:55.676] I (11797) mesh: 2004<arm>parent monitor, my layer:2(cap:6)(node), interval:11438ms, retries:1<normal connected>
[2023-11-01 11:39:55.694] I (11807) srv_wifi: <MESH_EVENT_PARENT_CONNECTED>layer:0-->2, parent:34:85:18:8f:4d:29<layer2>, ID:77:77:77:77:77:76
[2023-11-01 11:39:55.700] I (11827) srv_wifi_mesh: It was a wifi station removing stuff
[2023-11-01 11:39:55.724] I (11847) wifi:<ba-add>idx:0 (ifx:0, 34:85:18:8f:4d:29), tid:5, ssn:0, winSize:64
[2023-11-01 11:39:55.728] I (11847) srv_wifi: <MESH_EVENT_TODS_REACHABLE>state:1
[2023-11-01 11:39:55.728] I (11847) srv_wifi: <MESH_EVENT_ROOT_ADDRESS>root address:34:85:18:8f:4d:29
[2023-11-01 11:39:56.586] I (12717) app_main: Heap size -> 8258444
[2023-11-01 11:39:56.710] I (12847) srv_wifi: ## IP: 10.0.0.2
[2023-11-01 11:39:56.710] I (12847) srv_wifi: ## MASK: 255.255.0.0
[2023-11-01 11:39:56.725] I (12847) srv_wifi: ## GW: 10.0.0.1
[2023-11-01 11:39:56.725] I (12857) app_comm_connectivity: NETWORK_STATUS_CONNECTED 5
[2023-11-01 11:39:56.826] I (12937) app_comm_protocol: MQTT Protocol initialized successfully
[2023-11-01 11:39:57.579] I (13717) app_main: Heap size -> 8237792
[2023-11-01 11:39:58.168] I (14287) wifi:pm start, type: 1
[2023-11-01 11:39:58.168] 
[2023-11-01 11:39:58.168] I (14287) wifi:set rx beacon pti, rx_bcn_pti: 0, bcn_timeout: 0, mt_pti: 25000, mt_time: 10000
[2023-11-01 11:39:58.268] I (14387) wifi:AP's beacon interval = 102400 us, DTIM period = 1
[2023-11-01 11:39:58.595] I (14717) app_main: Heap size -> 8228004
[2023-11-01 11:39:59.592] I (15717) app_main: Heap size -> 8228152
[2023-11-01 11:40:00.585] I (16717) app_main: Heap size -> 8228152
[2023-11-01 11:40:01.578] I (17717) app_main: Heap size -> 8228144
[2023-11-01 11:40:02.602] I (18727) app_main: Heap size -> 8223088
[2023-11-01 11:40:03.612] I (19737) app_main: Heap size -> 8232456
[2023-11-01 11:40:04.611] I (20737) app_main: Heap size -> 8232456
[2023-11-01 11:40:05.597] I (21737) app_main: Heap size -> 8232456
[2023-11-01 11:40:06.613] I (22737) app_main: Heap size -> 8232456
[2023-11-01 11:40:07.405] I (23527) mesh: 5157<active>parent layer:1(node), channel:1, rssi:-33, assoc:0(cnx rssi threshold:-120)my_assoc:0
[2023-11-01 11:40:07.614] I (23737) app_main: Heap size -> 8232216
[2023-11-01 11:40:08.607] I (24737) app_main: Heap size -> 8232956
[2023-11-01 11:40:08.904] I (25027) mesh: 5948<scan>parent layer:1, rssi:-37, assoc:1(cnx rssi threshold:-120)
[2023-11-01 11:40:08.910] I (25027) mesh: [SCAN][ch:1]AP:1, other(ID:0, RD:0), MAP:1, idle:0, candidate:1, root:1, topMAP:0[c:2,i:2][00:00:00:00:00:00][FIXED-ROOT]<weak>
[2023-11-01 11:40:08.910] I (25037) mesh: 7370[weak]try rssi_threshold:-120, backoff times:0, max:5<-78,-82,-85>
[2023-11-01 11:40:08.926] I (25037) mesh: 716[monitor]no change, parent:34:85:18:8f:4d:29, rssi:-37
[2023-11-01 11:40:08.926] I (25047) mesh: 2004<arm>parent monitor, my layer:2(cap:6)(node), interval:10781ms, retries:2<>
[2023-11-01 11:40:09.612] I (25737) app_main: Heap size -> 8232952
[2023-11-01 11:40:10.598] I (26737) app_main: Heap size -> 8232952
[2023-11-01 11:40:11.599] I (27737) app_main: Heap size -> 8232952
[2023-11-01 11:40:12.616] I (28737) app_main: Heap size -> 8234144
[2023-11-01 11:40:13.250] I (29367) app_comm_protocol: PROTOCOL_STATUS_CONNECTED 5
...
[2023-11-01 11:40:19.978] I (36097) mesh: 5157<active>parent layer:1(node), channel:1, rssi:-33, assoc:1(cnx rssi threshold:-120)my_assoc:0
[2023-11-01 11:40:20.603] I (36737) app_main: Heap size -> 8234664
[2023-11-01 11:40:21.480] I (37597) mesh: 5948<scan>parent layer:1, rssi:-39, assoc:1(cnx rssi threshold:-120)
[2023-11-01 11:40:21.480] I (37597) mesh: [SCAN][ch:1]AP:1, other(ID:0, RD:0), MAP:1, idle:0, candidate:1, root:1, topMAP:0[c:2,i:2][00:00:00:00:00:00][FIXED-ROOT]<weak>
[2023-11-01 11:40:21.495] I (37607) mesh: 7370[weak]try rssi_threshold:-120, backoff times:0, max:5<-78,-82,-85>
[2023-11-01 11:40:21.495] I (37617) mesh: 716[monitor]no change, parent:34:85:18:8f:4d:29, rssi:-39
[2023-11-01 11:40:21.495] I (37627) mesh: 2004<arm>parent monitor, my layer:2(cap:6)(node), interval:11908ms, retries:3<>
[2023-11-01 11:40:21.511] W (37637) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:21.611] I (37737) app_main: Heap size -> 8234532
...
[2023-11-01 11:40:22.598] I (38737) app_main: Heap size -> 8229064
[2023-11-01 11:40:22.735] W (38857) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:22.937] W (39067) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:23.152] W (39267) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:23.352] W (39477) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:23.552] W (39677) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:23.599] I (39737) app_main: Heap size -> 8229232
[2023-11-01 11:40:23.752] W (39887) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:23.968] W (40087) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:24.168] W (40297) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:24.368] W (40497) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:24.584] W (40707) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:24.600] I (40737) app_main: Heap size -> 8229232
[2023-11-01 11:40:24.787] W (40907) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:24.995] W (41117) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:25.186] W (41317) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:25.406] W (41527) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:25.601] W (41727) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:25.601] I (41737) app_main: Heap size -> 8229232
[2023-11-01 11:40:25.814] W (41937) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:26.023] W (42137) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:26.223] W (42347) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:26.419] W (42547) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:26.605] I (42737) app_main: Heap size -> 8229232
[2023-11-01 11:40:26.622] W (42757) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:26.832] W (42957) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:27.038] W (43167) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:27.247] W (43367) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:27.453] W (43577) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:27.614] I (43737) app_main: Heap size -> 8229232
[2023-11-01 11:40:27.658] W (43777) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:27.860] W (43977) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:28.062] W (44187) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:28.273] W (44387) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:28.472] W (44597) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:28.605] I (44737) app_main: Heap size -> 8229232
[2023-11-01 11:40:28.672] W (44797) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:28.887] W (45007) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:29.089] W (45207) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:29.295] W (45417) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:29.500] W (45617) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:29.604] I (45737) app_main: Heap size -> 8229232
[2023-11-01 11:40:29.703] W (45827) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:29.905] W (46027) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:30.107] W (46237) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:30.310] W (46437) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:30.525] W (46647) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:30.610] I (46737) app_main: Heap size -> 8229232
[2023-11-01 11:40:30.726] W (46847) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:30.928] W (47057) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:40:31.098] I (47227) wifi:bcn_timeout,ap_probe_send_start
[2023-11-01 11:40:31.098] I (47227) wifi:pm stop, total sleep time: 10117802 us / 32937472 us
[2023-11-01 11:40:31.098] 
[2023-11-01 11:40:31.114] I (47227) wifi:(mesh)pm stop, sleep:9 secs, total:31 secs, current active set:10, running:0.31
[2023-11-01 11:40:31.602] I (47737) app_main: Heap size -> 8229020
[2023-11-01 11:40:32.608] I (48737) app_main: Heap size -> 8229020
[2023-11-01 11:40:33.600] I (49737) app_main: Heap size -> 8229020
[2023-11-01 11:40:33.616] I (49737) wifi:ap_probe_send over, resett wifi status to disassoc
[2023-11-01 11:40:33.616] I (49737) wifi:state: run -> init (c800)
[2023-11-01 11:40:33.616] I (49737) wifi:<ba-del>idx
[2023-11-01 11:40:33.616] I (49737) wifi:new:<1,0>, old:<1,1>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:40:33.631] I (49747) mesh: [wifi]disconnected reason:200(beacon timeout), continuous:1/max:12, non-root, vote(,)<><>
[2023-11-01 11:40:33.631] I (49747) srv_wifi: <MESH_EVENT_PARENT_DISCONNECTED>reason:200
[2023-11-01 11:40:33.631] I (49757) wifi:new:<1,1>, old:<1,0>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:40:33.647] I (49767) wifi:state: init -> auth (b0)
[2023-11-01 11:40:33.666] E (49787) srv_wifi_mesh: Send with err code 16395 ESP_ERR_MESH_DISCONNECTED
[2023-11-01 11:40:33.666] E (49787) srv_wifi_mesh: Send with err code 16395 ESP_ERR_MESH_DISCONNECTED
[2023-11-01 11:40:33.666] E (49797) srv_wifi_mesh: Send with err code 16395 ESP_ERR_MESH_DISCONNECTED
[2023-11-01 11:40:33.682] E (49797) srv_wifi_mesh: Send with err code 16395 ESP_ERR_MESH_DISCONNECTED
[2023-11-01 11:40:33.697] E (49807) transport_base: poll_read select error 113, errno = Software caused connection abort, fd = 54
...
[2023-11-01 11:40:34.608] I (50737) app_main: Heap size -> 8258668
[2023-11-01 11:40:35.145] I (51267) wifi:state: auth -> init (200)
[2023-11-01 11:40:35.145] I (51267) wifi:new:<1,0>, old:<1,1>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:40:35.145] I (51277) mesh: [wifi]disconnected reason:2(auth expire), continuous:2/max:12, non-root, vote(,)<><>
[2023-11-01 11:40:35.161] I (51277) srv_wifi: <MESH_EVENT_PARENT_DISCONNECTED>reason:2
[2023-11-01 11:40:35.167] I (51277) wifi:new:<1,1>, old:<1,0>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:40:35.167] I (51287) wifi:state: init -> auth (b0)
...
[2023-11-01 11:40:36.612] I (52737) app_main: Heap size -> 8258680
[2023-11-01 11:40:36.675] I (52797) wifi:state: auth -> init (200)
[2023-11-01 11:40:36.675] I (52797) wifi:new:<1,0>, old:<1,1>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:40:36.681] I (52797) mesh: [wifi]disconnected reason:2(auth expire), continuous:3/max:12, non-root, vote(,)<><>
[2023-11-01 11:40:36.687] I (52797) srv_wifi: <MESH_EVENT_PARENT_DISCONNECTED>reason:2
[2023-11-01 11:40:36.694] I (52807) wifi:new:<1,1>, old:<1,0>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:40:36.696] I (52817) wifi:state: init -> auth (b0)
...
[2023-11-01 11:40:37.606] I (53737) app_main: Heap size -> 8258680
[2023-11-01 11:40:38.187] I (54317) wifi:state: auth -> init (200)
[2023-11-01 11:40:38.202] I (54317) wifi:new:<1,0>, old:<1,1>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:40:38.202] I (54317) srv_wifi: <MESH_EVENT_PARENT_DISCONNECTED>reason:2
[2023-11-01 11:40:38.202] I (54317) mesh: [wifi]disconnected reason:2(auth expire), continuous:4/max:12, non-root, vote(,)<><>
[2023-11-01 11:40:38.218] I (54337) app_comm_connectivity: connectivity_handler - INTERFACE: 5
[2023-11-01 11:40:38.218] I (54337) wifi:new:<1,1>, old:<1,0>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:40:38.218] I (54347) wifi:state: init -> auth (b0)
...
[2023-11-01 11:40:39.721] I (55847) wifi:state: auth -> init (200)
[2023-11-01 11:40:39.721] I (55847) wifi:new:<1,0>, old:<1,1>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:40:39.721] I (55847) mesh: [wifi]disconnected reason:2(auth expire), continuous:5/max:12, non-root, vote(,)<><>
[2023-11-01 11:40:39.736] I (55847) srv_wifi: <MESH_EVENT_PARENT_DISCONNECTED>reason:2
[2023-11-01 11:40:39.736] I (55857) wifi:new:<1,1>, old:<1,0>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:40:39.751] I (55867) wifi:state: init -> auth (b0)
...
[2023-11-01 11:40:39.790] I (55907) app_comm_connectivity: NETWORK_STATUS_DISCONNECTED - network_status_handler - INTERFACE: 5
[2023-11-01 11:40:40.607] I (56737) app_main: Heap size -> 8258680
[2023-11-01 11:40:41.239] I (57377) wifi:state: auth -> init (200)
[2023-11-01 11:40:41.239] I (57377) wifi:new:<1,0>, old:<1,1>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:40:41.255] I (57377) mesh: [wifi]disconnected reason:2(auth expire), continuous:6/max:12, non-root, vote(,)<><>
[2023-11-01 11:40:41.263] I (57377) srv_wifi: <MESH_EVENT_PARENT_DISCONNECTED>reason:2
[2023-11-01 11:40:41.263] I (57377) wifi:new:<1,1>, old:<1,0>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:40:41.263] I (57397) wifi:state: init -> auth (b0)
...
[2023-11-01 11:40:41.608] I (57737) app_main: Heap size -> 8258680
[2023-11-01 11:40:42.611] I (58737) app_main: Heap size -> 8258680
[2023-11-01 11:40:42.776] I (58897) wifi:state: auth -> init (200)
[2023-11-01 11:40:42.780] I (58897) wifi:new:<1,0>, old:<1,1>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:40:42.780] I (58897) mesh: [wifi]disconnected reason:2(auth expire), continuous:0/max:12, non-root, vote(,)<><>
[2023-11-01 11:40:42.780] I (58897) srv_wifi: <MESH_EVENT_PARENT_DISCONNECTED>reason:2
[2023-11-01 11:40:42.795] I (58907) mesh: [auth expire]looking for a new parent, [L:2]try layer:1
...
[2023-11-01 11:40:42.842] I (58967) srv_wifi: <MESH_EVENT_NETWORK_STATE>is_rootless:1
[2023-11-01 11:40:43.397] I (59517) mesh: [SCAN][ch:1]AP:10, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:43.397] I (59517) mesh: [FAIL][1]root:0, fail:1, normal:0
[2023-11-01 11:40:43.397] 
[2023-11-01 11:40:43.598] I (59737) app_main: Heap size -> 8258504
[2023-11-01 11:40:44.002] I (60127) mesh: [SCAN][ch:1]AP:7, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:44.002] I (60127) mesh: [FAIL][2]root:0, fail:2, normal:0
[2023-11-01 11:40:44.002] 
[2023-11-01 11:40:44.607] I (60737) app_main: Heap size -> 8258016
[2023-11-01 11:40:44.607] I (60737) mesh: [SCAN][ch:1]AP:9, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:44.607] I (60737) mesh: [FAIL][3]root:0, fail:3, normal:0
[2023-11-01 11:40:44.607] 
[2023-11-01 11:40:44.739] I (60867) mesh: [SCAN][ch:1]AP:5, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:44.739] I (60867) mesh: [FAIL][4]root:0, fail:4, normal:0
[2023-11-01 11:40:44.739] 
[2023-11-01 11:40:45.344] I (61477) mesh: [SCAN][ch:1]AP:8, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:45.344] I (61477) mesh: [FAIL][5]root:0, fail:5, normal:0
[2023-11-01 11:40:45.344] 
[2023-11-01 11:40:45.611] I (61737) app_main: Heap size -> 8258204
[2023-11-01 11:40:45.966] I (62077) mesh: [SCAN][ch:1]AP:8, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:45.967] I (62077) mesh: [FAIL][6]root:0, fail:6, normal:0
[2023-11-01 11:40:45.967] 
[2023-11-01 11:40:46.090] I (62207) mesh: [SCAN][ch:1]AP:3, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:46.098] I (62207) mesh: [FAIL][7]root:0, fail:7, normal:0
[2023-11-01 11:40:46.098] 
[2023-11-01 11:40:46.612] I (62737) app_main: Heap size -> 8258204
[2023-11-01 11:40:46.695] I (62817) mesh: [SCAN][ch:1]AP:7, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:46.700] I (62817) mesh: [FAIL][8]root:0, fail:8, normal:0
[2023-11-01 11:40:46.700] 
[2023-11-01 11:40:47.307] I (63427) mesh: [SCAN][ch:1]AP:6, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:47.311] I (63427) mesh: [FAIL][9]root:0, fail:9, normal:0
[2023-11-01 11:40:47.311] 
[2023-11-01 11:40:47.436] I (63547) mesh: [SCAN][ch:1]AP:3, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:47.440] I (63557) mesh: [FAIL][10]root:0, fail:10, normal:0
[2023-11-01 11:40:47.440] 
[2023-11-01 11:40:47.612] I (63737) app_main: Heap size -> 8258516
[2023-11-01 11:40:48.030] I (64157) mesh: [SCAN][ch:1]AP:7, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:48.030] I (64157) mesh: [FAIL][11]root:0, fail:11, normal:0
[2023-11-01 11:40:48.030] 
[2023-11-01 11:40:48.608] I (64737) app_main: Heap size -> 8258004
[2023-11-01 11:40:48.636] I (64767) mesh: [SCAN][ch:1]AP:10, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:48.652] I (64767) mesh: [FAIL][12]root:0, fail:12, normal:0
[2023-11-01 11:40:48.652] 
[2023-11-01 11:40:48.768] I (64897) mesh: [SCAN][ch:1]AP:5, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:48.783] I (64897) mesh: [FAIL][13]root:0, fail:13, normal:0
[2023-11-01 11:40:48.783] 
[2023-11-01 11:40:49.372] I (65507) mesh: [SCAN][ch:1]AP:8, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:49.388] I (65507) mesh: [FAIL][14]root:0, fail:14, normal:0
[2023-11-01 11:40:49.388] 
[2023-11-01 11:40:49.608] I (65737) app_main: Heap size -> 8258316
[2023-11-01 11:40:49.992] I (66117) mesh: [SCAN][ch:1]AP:9, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:49.992] I (66117) mesh: [FAIL][15]root:0, fail:15, normal:0
[2023-11-01 11:40:49.992] 
[2023-11-01 11:40:50.121] I (66237) mesh: [SCAN][ch:1]AP:7, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:50.126] I (66247) mesh: [FAIL][16]root:0, fail:16, normal:0
[2023-11-01 11:40:50.126] 
[2023-11-01 11:40:50.610] I (66737) app_main: Heap size -> 8258216
[2023-11-01 11:40:50.726] I (66847) mesh: [SCAN][ch:1]AP:7, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:50.726] I (66847) mesh: [FAIL][17]root:0, fail:17, normal:0
[2023-11-01 11:40:50.726] 
[2023-11-01 11:40:51.326] I (67457) mesh: [SCAN][ch:1]AP:9, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:51.342] I (67457) mesh: [FAIL][18]root:0, fail:18, normal:0
[2023-11-01 11:40:51.342] 
[2023-11-01 11:40:51.465] I (67587) mesh: [SCAN][ch:1]AP:6, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:51.465] I (67587) mesh: [FAIL][19]root:0, fail:19, normal:0
[2023-11-01 11:40:51.465] 
[2023-11-01 11:40:51.613] I (67737) app_main: Heap size -> 8258504
[2023-11-01 11:40:52.066] I (68197) mesh: [SCAN][ch:1]AP:9, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:52.066] I (68197) mesh: [FAIL][20]root:0, fail:20, normal:0
[2023-11-01 11:40:52.066] 
[2023-11-01 11:40:52.599] I (68737) app_main: Heap size -> 8258316
[2023-11-01 11:40:52.682] I (68797) mesh: [SCAN][ch:1]AP:6, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:52.686] I (68807) mesh: [FAIL][21]root:0, fail:21, normal:0
[2023-11-01 11:40:52.686] 
[2023-11-01 11:40:52.800] I (68927) mesh: [SCAN][ch:1]AP:4, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:52.800] I (68927) mesh: [FAIL][22]root:0, fail:22, normal:0
[2023-11-01 11:40:52.800] 
[2023-11-01 11:40:53.405] I (69537) mesh: [SCAN][ch:1]AP:8, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:53.421] I (69537) mesh: [FAIL][23]root:0, fail:23, normal:0
[2023-11-01 11:40:53.421] 
[2023-11-01 11:40:53.607] I (69737) app_main: Heap size -> 8258304
[2023-11-01 11:40:54.011] I (70147) mesh: [SCAN][ch:1]AP:7, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:54.026] I (70147) mesh: [FAIL][24]root:0, fail:24, normal:0
[2023-11-01 11:40:54.026] 
[2023-11-01 11:40:54.143] I (70277) mesh: [SCAN][ch:1]AP:7, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:54.156] I (70277) mesh: [FAIL][25]root:0, fail:25, normal:0
[2023-11-01 11:40:54.156] 
[2023-11-01 11:40:54.600] I (70737) app_main: Heap size -> 8258208
[2023-11-01 11:40:54.757] I (70877) mesh: [SCAN][ch:1]AP:8, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:54.757] I (70887) mesh: [FAIL][26]root:0, fail:26, normal:0
[2023-11-01 11:40:54.757] 
[2023-11-01 11:40:55.358] I (71487) mesh: [SCAN][ch:1]AP:9, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:55.373] I (71487) mesh: [FAIL][27]root:0, fail:27, normal:0
[2023-11-01 11:40:55.373] 
[2023-11-01 11:40:55.493] I (71617) mesh: [SCAN][ch:1]AP:5, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:55.493] I (71617) mesh: [FAIL][28]root:0, fail:28, normal:0
[2023-11-01 11:40:55.493] 
[2023-11-01 11:40:55.610] I (71737) app_main: Heap size -> 8258420
[2023-11-01 11:40:56.100] I (72227) mesh: [SCAN][ch:1]AP:7, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:56.100] I (72227) mesh: [FAIL][29]root:0, fail:29, normal:0
[2023-11-01 11:40:56.100] 
[2023-11-01 11:40:56.605] I (72737) app_main: Heap size -> 8258408
[2023-11-01 11:40:56.705] I (72827) mesh: [SCAN][ch:1]AP:6, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:56.705] I (72837) mesh: [FAIL][30]root:0, fail:30, normal:0
[2023-11-01 11:40:56.705] 
[2023-11-01 11:40:56.838] I (72957) mesh: [SCAN][ch:1]AP:7, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:56.838] I (72957) mesh: [FAIL][31]root:0, fail:31, normal:0
[2023-11-01 11:40:56.838] 
[2023-11-01 11:40:57.448] I (73567) mesh: [SCAN][ch:1]AP:8, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:57.448] I (73567) mesh: [FAIL][32]root:0, fail:32, normal:0
[2023-11-01 11:40:57.448] 
[2023-11-01 11:40:57.597] I (73737) app_main: Heap size -> 8258720
[2023-11-01 11:40:58.047] I (74177) mesh: [SCAN][ch:1]AP:10, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:58.047] I (74177) mesh: [FAIL][33]root:0, fail:33, normal:0
[2023-11-01 11:40:58.047] 
[2023-11-01 11:40:58.178] I (74307) mesh: [SCAN][ch:1]AP:6, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:58.178] I (74307) mesh: [FAIL][34]root:0, fail:34, normal:0
[2023-11-01 11:40:58.178] 
[2023-11-01 11:40:58.606] I (74737) app_main: Heap size -> 8258220
[2023-11-01 11:40:58.794] I (74917) mesh: [SCAN][ch:1]AP:8, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:58.808] I (74927) mesh: [FAIL][35]root:0, fail:35, normal:0
[2023-11-01 11:40:58.808] 
[2023-11-01 11:40:59.410] I (75527) mesh: [SCAN][ch:1]AP:7, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:59.414] I (75527) mesh: [FAIL][36]root:0, fail:36, normal:0
[2023-11-01 11:40:59.414] 
[2023-11-01 11:40:59.530] I (75657) mesh: [SCAN][ch:1]AP:3, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:40:59.530] I (75657) mesh: [FAIL][37]root:0, fail:37, normal:0
[2023-11-01 11:40:59.530] 
[2023-11-01 11:40:59.612] I (75737) app_main: Heap size -> 8258804
[2023-11-01 11:41:00.137] I (76267) mesh: [SCAN][ch:1]AP:8, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:00.152] I (76267) mesh: [FAIL][38]root:0, fail:38, normal:0
[2023-11-01 11:41:00.152] 
[2023-11-01 11:41:00.613] I (76737) app_main: Heap size -> 8258116
[2023-11-01 11:41:00.742] I (76877) mesh: [SCAN][ch:1]AP:8, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:00.758] I (76877) mesh: [FAIL][39]root:0, fail:39, normal:0
[2023-11-01 11:41:00.758] 
[2023-11-01 11:41:00.880] I (77007) mesh: [SCAN][ch:1]AP:4, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:00.880] I (77007) mesh: [FAIL][40]root:0, fail:40, normal:0
[2023-11-01 11:41:00.880] 
[2023-11-01 11:41:01.481] I (77607) mesh: [SCAN][ch:1]AP:8, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:01.481] I (77607) mesh: [FAIL][41]root:0, fail:41, normal:0
[2023-11-01 11:41:01.481] 
[2023-11-01 11:41:01.612] I (77737) app_main: Heap size -> 8258724
[2023-11-01 11:41:02.097] I (78217) mesh: [SCAN][ch:1]AP:6, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:02.097] I (78217) mesh: [FAIL][42]root:0, fail:42, normal:0
[2023-11-01 11:41:02.097] 
[2023-11-01 11:41:02.216] I (78347) mesh: [SCAN][ch:1]AP:5, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:02.232] I (78347) mesh: [FAIL][43]root:0, fail:43, normal:0
[2023-11-01 11:41:02.232] 
[2023-11-01 11:41:02.598] I (78737) app_main: Heap size -> 8258132
[2023-11-01 11:41:02.829] I (78957) mesh: [SCAN][ch:1]AP:8, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:02.829] I (78957) mesh: [FAIL][44]root:0, fail:44, normal:0
[2023-11-01 11:41:02.829] 
[2023-11-01 11:41:03.430] I (79557) mesh: [SCAN][ch:1]AP:7, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:03.446] I (79567) mesh: [FAIL][45]root:0, fail:45, normal:0
[2023-11-01 11:41:03.446] 
[2023-11-01 11:41:03.568] I (79687) mesh: [SCAN][ch:1]AP:6, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:03.568] I (79687) mesh: [FAIL][46]root:0, fail:46, normal:0
[2023-11-01 11:41:03.568] 
[2023-11-01 11:41:03.599] I (79737) app_main: Heap size -> 8258944
[2023-11-01 11:41:04.169] I (80297) mesh: [SCAN][ch:1]AP:6, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:04.185] I (80297) mesh: [FAIL][47]root:0, fail:47, normal:0
[2023-11-01 11:41:04.185] 
[2023-11-01 11:41:04.602] I (80737) app_main: Heap size -> 8258332
[2023-11-01 11:41:04.786] I (80907) mesh: [SCAN][ch:1]AP:6, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:04.786] I (80907) mesh: [FAIL][48]root:0, fail:48, normal:0
[2023-11-01 11:41:04.786] 
[2023-11-01 11:41:04.901] I (81037) mesh: [SCAN][ch:1]AP:5, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:04.916] I (81037) mesh: [FAIL][49]root:0, fail:49, normal:0
[2023-11-01 11:41:04.916] 
[2023-11-01 11:41:05.518] I (81637) mesh: [SCAN][ch:1]AP:7, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:05.518] I (81647) mesh: [FAIL][50]root:0, fail:50, normal:0
[2023-11-01 11:41:05.518] 
[2023-11-01 11:41:05.603] I (81737) app_main: Heap size -> 8258744
[2023-11-01 11:41:06.121] I (82247) mesh: [SCAN][ch:1]AP:6, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:06.137] I (82247) mesh: [FAIL][51]root:0, fail:51, normal:0
[2023-11-01 11:41:06.137] 
[2023-11-01 11:41:06.253] I (82377) mesh: [SCAN][ch:1]AP:3, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:06.253] I (82377) mesh: [FAIL][52]root:0, fail:52, normal:0
[2023-11-01 11:41:06.253] 
[2023-11-01 11:41:06.611] I (82737) app_main: Heap size -> 8258344
[2023-11-01 11:41:06.859] I (82987) mesh: [SCAN][ch:1]AP:8, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:06.859] I (82987) mesh: [FAIL][53]root:0, fail:53, normal:0
[2023-11-01 11:41:06.859] 
[2023-11-01 11:41:07.472] I (83597) mesh: [SCAN][ch:1]AP:7, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:07.472] I (83597) mesh: [FAIL][54]root:0, fail:54, normal:0
[2023-11-01 11:41:07.472] 
[2023-11-01 11:41:07.588] I (83717) mesh: [SCAN][ch:1]AP:4, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:07.603] I (83727) mesh: [FAIL][55]root:0, fail:55, normal:0
[2023-11-01 11:41:07.603] 
[2023-11-01 11:41:07.603] I (83737) app_main: Heap size -> 8258932
[2023-11-01 11:41:08.208] I (84327) mesh: [SCAN][ch:1]AP:8, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:08.208] I (84327) mesh: [FAIL][56]root:0, fail:56, normal:0
[2023-11-01 11:41:08.208] 
[2023-11-01 11:41:08.611] I (84737) app_main: Heap size -> 8258344
[2023-11-01 11:41:08.813] I (84937) mesh: [SCAN][ch:1]AP:6, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:08.813] I (84937) mesh: [FAIL][57]root:0, fail:57, normal:0
[2023-11-01 11:41:08.813] 
[2023-11-01 11:41:08.946] I (85067) mesh: [SCAN][ch:1]AP:4, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:08.946] I (85067) mesh: [FAIL][58]root:0, fail:58, normal:0
[2023-11-01 11:41:08.946] 
[2023-11-01 11:41:09.555] I (85677) mesh: [SCAN][ch:1]AP:7, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:09.555] I (85677) mesh: [FAIL][59]root:0, fail:59, normal:0
[2023-11-01 11:41:09.555] 
[2023-11-01 11:41:09.614] I (85737) app_main: Heap size -> 8258832
[2023-11-01 11:41:10.157] I (86277) mesh: [SCAN][ch:1]AP:9, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:10.157] I (86287) mesh: [FAIL][60]root:0, fail:60, normal:0
[2023-11-01 11:41:10.157] 
[2023-11-01 11:41:10.176] I (86287) mesh: <MESH_NWK_PARENT_SELECTION>no parent found, set ROOTLESS, scan times:60, heap:67631
[2023-11-01 11:41:10.182] I (86297) srv_wifi: <MESH_EVENT_NO_PARENT_FOUND>scan times:60
[2023-11-01 11:41:10.611] I (86737) app_main: Heap size -> 8258344
[2023-11-01 11:41:12.442] I (87737) app_main: Heap size -> 8257944
[2023-11-01 11:41:12.611] I (88737) app_main: Heap size -> 8257244
[2023-11-01 11:41:12.830] I (88947) mesh: [SCAN][ch:0]AP:17, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:12.830] I (88957) mesh: [FAIL][61]root:0, fail:61, normal:0
[2023-11-01 11:41:12.830] 
[2023-11-01 11:41:13.612] I (89737) app_main: Heap size -> 8258332
[2023-11-01 11:41:14.597] I (90737) app_main: Heap size -> 8258136
[2023-11-01 11:41:15.599] I (91737) app_main: Heap size -> 8258036
[2023-11-01 11:41:16.600] I (92737) app_main: Heap size -> 8257536
[2023-11-01 11:41:17.466] I (93587) mesh: [SCAN][ch:0]AP:18, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:17.466] I (93587) mesh: [FAIL][62]root:0, fail:62, normal:0
[2023-11-01 11:41:17.466] 
[2023-11-01 11:41:17.604] I (93737) app_main: Heap size -> 8258448
[2023-11-01 11:41:18.607] I (94737) app_main: Heap size -> 8258248
[2023-11-01 11:41:19.612] I (95737) app_main: Heap size -> 8257744
[2023-11-01 11:41:20.606] I (96737) app_main: Heap size -> 8257644
[2023-11-01 11:41:21.610] I (97737) app_main: Heap size -> 8256744
[2023-11-01 11:41:22.095] I (98227) mesh: [SCAN][ch:0]AP:22, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:22.095] I (98227) mesh: [FAIL][63]root:0, fail:63, normal:0
[2023-11-01 11:41:22.095] 
[2023-11-01 11:41:22.611] I (98737) app_main: Heap size -> 8258132
[2023-11-01 11:41:23.613] I (99737) app_main: Heap size -> 8257732
[2023-11-01 11:41:24.598] I (100737) app_main: Heap size -> 8256932
[2023-11-01 11:41:24.762] I (100887) mesh: [SCAN][ch:0]AP:20, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:24.762] I (100887) mesh: [FAIL][64]root:0, fail:64, normal:0
[2023-11-01 11:41:24.762] 
[2023-11-01 11:41:25.599] I (101737) app_main: Heap size -> 8258244
[2023-11-01 11:41:26.600] I (102737) app_main: Heap size -> 8257844
[2023-11-01 11:41:27.602] I (103737) app_main: Heap size -> 8257644
[2023-11-01 11:41:28.604] I (104737) app_main: Heap size -> 8257144
[2023-11-01 11:41:29.391] I (105527) mesh: [SCAN][ch:0]AP:21, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:29.406] I (105527) mesh: [FAIL][65]root:0, fail:65, normal:0
[2023-11-01 11:41:29.406] 
[2023-11-01 11:41:29.606] I (105737) app_main: Heap size -> 8258832
[2023-11-01 11:41:30.609] I (106737) app_main: Heap size -> 8258732
[2023-11-01 11:41:31.612] I (107737) app_main: Heap size -> 8258332
[2023-11-01 11:41:32.600] I (108737) app_main: Heap size -> 8258232
[2023-11-01 11:41:33.609] I (109737) app_main: Heap size -> 8257432
[2023-11-01 11:41:34.035] I (110157) mesh: [SCAN][ch:0]AP:16, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:34.035] I (110157) mesh: [FAIL][66]root:0, fail:66, normal:0
[2023-11-01 11:41:34.035] 
[2023-11-01 11:41:34.602] I (110737) app_main: Heap size -> 8258448
[2023-11-01 11:41:35.610] I (111737) app_main: Heap size -> 8257248
[2023-11-01 11:41:36.613] I (112737) app_main: Heap size -> 8256948
[2023-11-01 11:41:36.689] I (112817) mesh: [SCAN][ch:0]AP:21, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:36.704] I (112827) mesh: [FAIL][67]root:0, fail:67, normal:0
[2023-11-01 11:41:36.704] 
[2023-11-01 11:41:37.611] I (113737) app_main: Heap size -> 8258236
[2023-11-01 11:41:38.605] I (114737) app_main: Heap size -> 8258036
...
[2023-11-01 11:41:39.612] I (115737) app_main: Heap size -> 8257932
[2023-11-01 11:41:40.612] I (116737) app_main: Heap size -> 8257432
[2023-11-01 11:41:41.339] I (117457) mesh: [SCAN][ch:0]AP:18, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:41.344] I (117457) mesh: [FAIL][68]root:0, fail:68, normal:0
[2023-11-01 11:41:41.344] 
[2023-11-01 11:41:41.612] I (117737) app_main: Heap size -> 8258544
[2023-11-01 11:41:42.613] I (118737) app_main: Heap size -> 8258544
[2023-11-01 11:41:43.612] I (119737) app_main: Heap size -> 8258044
[2023-11-01 11:41:44.612] I (120737) app_main: Heap size -> 8258044
[2023-11-01 11:41:45.597] I (121737) app_main: Heap size -> 8257244
[2023-11-01 11:41:45.972] I (122097) mesh: [SCAN][ch:0]AP:18, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:45.972] I (122097) mesh: [FAIL][69]root:0, fail:69, normal:0
[2023-11-01 11:41:45.972] 
[2023-11-01 11:41:46.612] I (122737) app_main: Heap size -> 8258432
[2023-11-01 11:41:47.612] I (123737) app_main: Heap size -> 8257532
[2023-11-01 11:41:48.611] I (124737) app_main: Heap size -> 8257232
[2023-11-01 11:41:48.637] I (124757) mesh: [SCAN][ch:0]AP:18, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:48.640] I (124757) mesh: [FAIL][70]root:0, fail:70, normal:0
[2023-11-01 11:41:48.640] 
...
[2023-11-01 11:41:49.611] I (125737) app_main: Heap size -> 8258344
[2023-11-01 11:41:50.612] I (126737) app_main: Heap size -> 8257844
[2023-11-01 11:41:51.609] I (127737) app_main: Heap size -> 8257844
[2023-11-01 11:41:52.603] I (128737) app_main: Heap size -> 8257244
[2023-11-01 11:41:53.267] I (129387) mesh: [SCAN][ch:0]AP:21, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:53.267] I (129397) mesh: [FAIL][71]root:0, fail:71, normal:0
[2023-11-01 11:41:53.267] 
[2023-11-01 11:41:53.610] I (129737) app_main: Heap size -> 8258132
[2023-11-01 11:41:54.611] I (130737) app_main: Heap size -> 8258136
[2023-11-01 11:41:55.612] I (131737) app_main: Heap size -> 8257936
[2023-11-01 11:41:56.611] I (132737) app_main: Heap size -> 8257936
[2023-11-01 11:41:57.603] I (133737) app_main: Heap size -> 8257136
[2023-11-01 11:41:57.909] I (134027) mesh: [SCAN][ch:0]AP:19, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:41:57.916] I (134027) mesh: [FAIL][72]root:0, fail:72, normal:0
[2023-11-01 11:41:57.916] 
[2023-11-01 11:41:58.616] I (134737) app_main: Heap size -> 8258448
[2023-11-01 11:41:59.614] I (135737) app_main: Heap size -> 8257544
[2023-11-01 11:42:00.557] I (136687) mesh: [SCAN][ch:0]AP:17, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:42:00.572] I (136687) mesh: [FAIL][73]root:0, fail:73, normal:0
[2023-11-01 11:42:00.572] 
[2023-11-01 11:42:00.606] I (136737) app_main: Heap size -> 8258932
[2023-11-01 11:42:01.614] I (137737) app_main: Heap size -> 8258232
[2023-11-01 11:42:02.604] I (138737) app_main: Heap size -> 8257932
[2023-11-01 11:42:03.610] I (139737) app_main: Heap size -> 8257832
[2023-11-01 11:42:04.609] I (140737) app_main: Heap size -> 8257132
[2023-11-01 11:42:05.212] I (141327) mesh: [SCAN][ch:0]AP:19, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:42:05.213] I (141327) mesh: [FAIL][74]root:0, fail:74, normal:0
[2023-11-01 11:42:05.213] 
[2023-11-01 11:42:05.611] I (141737) app_main: Heap size -> 8258244
[2023-11-01 11:42:06.603] I (142737) app_main: Heap size -> 8258144
[2023-11-01 11:42:07.611] I (143737) app_main: Heap size -> 8257844
[2023-11-01 11:42:08.605] I (144737) app_main: Heap size -> 8257344
[2023-11-01 11:42:09.604] I (145737) app_main: Heap size -> 8256944
[2023-11-01 11:42:09.833] I (145967) mesh: [SCAN][ch:0]AP:20, other(ID:0, RD:0), MAP:0, idle:0, candidate:0, root:0, topMAP:0[c:2,i:1][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:42:09.849] I (145967) mesh: [FAIL][75]root:0, fail:75, normal:0
[2023-11-01 11:42:09.849] 
[2023-11-01 11:42:10.606] I (146737) app_main: Heap size -> 8258224
[2023-11-01 11:42:11.606] I (147737) app_main: Heap size -> 8257224
[2023-11-01 11:42:12.507] I (148627) mesh: [SCAN][ch:0]AP:20, other(ID:0, RD:0), MAP:1, idle:0, candidate:1, root:1, topMAP:0[c:2,i:0][00:00:00:00:00:00][FIXED-ROOT]<>
[2023-11-01 11:42:12.507] I (148627) mesh: 7370[selection]try rssi_threshold:-78, backoff times:0, max:5<-78,-82,-85>
[2023-11-01 11:42:12.523] I (148637) mesh: [DONE]connect to parent:ESPM_8F4D28, channel:1, rssi:-33, 34:85:18:8f:4d:29[layer:1, assoc:0], my_vote_num:0/voter_num:0, rc[00:00:00:00:00:00/-120/2]
[2023-11-01 11:42:12.591] I (148717) wifi:new:<1,1>, old:<1,0>, ap:<1,1>, sta:<1,1>, prof:1
[2023-11-01 11:42:13.599] I (149727) wifi:state: init -> auth (b0)
[2023-11-01 11:42:13.610] I (149727) app_main: Heap size -> 8258896
[2023-11-01 11:42:13.611] I (149727) wifi:state: auth -> assoc (0)
[2023-11-01 11:42:13.628] I (149747) wifi:state: assoc -> run (10)
[2023-11-01 11:42:13.628] I (149747) mesh: <MESH_NWK_MIE_CHANGE><><><><><ROOT LOST><><>
[2023-11-01 11:42:13.628] I (149757) srv_wifi: <MESH_EVENT_NETWORK_STATE>is_rootless:0
[2023-11-01 11:42:13.649] I (149767) wifi:connected with ESPM_8F4D28, aid = 1, channel 1, 40U, bssid = 34:85:18:8f:4d:29
[2023-11-01 11:42:13.651] I (149767) wifi:security: WPA2-PSK, phy: bgn, rssi: -37
[2023-11-01 11:42:13.653] I (149777) wifi:Set ps type: 1
[2023-11-01 11:42:13.653] 
[2023-11-01 11:42:13.663] I (149777) mesh: 2004<arm>parent monitor, my layer:2(cap:6)(node), interval:6172ms, retries:1<normal connected>
[2023-11-01 11:42:13.674] I (149787) srv_wifi: <MESH_EVENT_PARENT_CONNECTED>layer:2-->2, parent:34:85:18:8f:4d:29<layer2>, ID:77:77:77:77:77:76
[2023-11-01 11:42:13.680] I (149807) wifi:<ba-add>idx:0 (ifx:0, 34:85:18:8f:4d:29), tid:5, ssn:0, winSize:64
[2023-11-01 11:42:13.680] I (149807) srv_wifi: <MESH_EVENT_TODS_REACHABLE>state:0
[2023-11-01 11:42:13.695] I (149817) srv_wifi: <MESH_EVENT_ROOT_ADDRESS>root address:34:85:18:8f:4d:29
[2023-11-01 11:42:13.715] I (149847) mesh: 5117<assoc>parent layer:1, channel:1, rssi:-37, assoc:1, rssi threshold<-78,-82,-85>
[2023-11-01 11:42:14.602] I (150727) app_main: Heap size -> 8257736
[2023-11-01 11:42:15.598] I (151727) app_main: Heap size -> 8257736
[2023-11-01 11:42:16.171] I (152297) wifi:pm start, type: 1
[2023-11-01 11:42:16.171] 
[2023-11-01 11:42:16.171] I (152297) wifi:set rx beacon pti, rx_bcn_pti: 0, bcn_timeout: 0, mt_pti: 25000, mt_time: 10000
[2023-11-01 11:42:16.284] I (152407) wifi:AP's beacon interval = 102400 us, DTIM period = 1
[2023-11-01 11:42:16.600] I (152727) app_main: Heap size -> 8257736
[2023-11-01 11:42:17.588] I (153727) app_main: Heap size -> 8257736
[2023-11-01 11:42:18.594] I (154727) app_main: Heap size -> 8257736
...
[2023-11-01 11:42:19.592] I (155727) app_main: Heap size -> 8257632
[2023-11-01 11:42:19.986] I (156107) mesh: 5157<active>parent layer:1(node), channel:1, rssi:-33, assoc:1(cnx rssi threshold:-120)my_assoc:0
[2023-11-01 11:42:20.594] I (156727) app_main: Heap size -> 8257408
[2023-11-01 11:42:21.480] I (157607) mesh: 5948<scan>parent layer:1, rssi:-41, assoc:1(cnx rssi threshold:-120)
[2023-11-01 11:42:21.495] I (157607) mesh: [SCAN][ch:1]AP:1, other(ID:0, RD:0), MAP:1, idle:0, candidate:1, root:1, topMAP:0[c:2,i:2][00:00:00:00:00:00][FIXED-ROOT]<weak>
[2023-11-01 11:42:21.495] I (157617) mesh: 7370[weak]try rssi_threshold:-120, backoff times:0, max:5<-78,-82,-85>
[2023-11-01 11:42:21.511] I (157627) mesh: 716[monitor]no change, parent:34:85:18:8f:4d:29, rssi:-41
[2023-11-01 11:42:21.518] I (157627) mesh: 2004<arm>parent monitor, my layer:2(cap:6)(node), interval:6565ms, retries:2<>
[2023-11-01 11:42:21.596] W (157717) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:42:21.596] I (157727) app_main: Heap size -> 8257404
[2023-11-01 11:42:22.535] I (158657) mesh: <MESH_NWK_MIE_CHANGE><><TODS><><><><><>
[2023-11-01 11:42:22.535] I (158657) srv_wifi: <MESH_EVENT_TODS_REACHABLE>state:1
[2023-11-01 11:42:22.601] I (158727) app_main: Heap size -> 8257404
[2023-11-01 11:42:23.599] I (159727) app_main: Heap size -> 8257404
[2023-11-01 11:42:24.601] I (160727) app_main: Heap size -> 8257404
[2023-11-01 11:42:25.485] W (161617) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:42:25.602] I (161727) app_main: Heap size -> 8257404
[2023-11-01 11:42:25.899] W (162017) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:42:26.103] W (162227) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:42:26.503] W (162637) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:42:26.603] I (162727) app_main: Heap size -> 8257228
[2023-11-01 11:42:26.719] W (162837) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:42:26.881] I (163007) srv_wifi: ## IP: 10.0.0.2
[2023-11-01 11:42:26.881] I (163007) srv_wifi: ## MASK: 255.255.0.0
[2023-11-01 11:42:26.881] I (163007) srv_wifi: ## GW: 10.0.0.1
[2023-11-01 11:42:26.897] I (163017) app_comm_connectivity: NETWORK_STATUS_CONNECTED 5
...
[2023-11-01 11:42:26.992] I (163107) app_comm_protocol: MQTT Protocol initialized successfully
[2023-11-01 11:42:27.121] W (163247) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:42:27.602] I (163727) app_main: Heap size -> 8237372
[2023-11-01 11:42:28.153] W (164277) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:42:28.238] I (164367) mesh: 5157<active>parent layer:1(node), channel:1, rssi:-33, assoc:1(cnx rssi threshold:-120)my_assoc:0
[2023-11-01 11:42:28.595] I (164727) app_main: Heap size -> 8237356
[2023-11-01 11:42:28.971] W (165097) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:42:29.170] W (165297) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:42:29.589] I (165727) app_main: Heap size -> 8237356
[2023-11-01 11:42:29.740] I (165867) mesh: 5948<scan>parent layer:1, rssi:-37, assoc:1(cnx rssi threshold:-120)
[2023-11-01 11:42:29.740] I (165867) mesh: [SCAN][ch:1]AP:1, other(ID:0, RD:0), MAP:1, idle:0, candidate:1, root:1, topMAP:0[c:2,i:2][00:00:00:00:00:00][FIXED-ROOT]<weak>
[2023-11-01 11:42:29.756] I (165877) mesh: 7370[weak]try rssi_threshold:-120, backoff times:0, max:5<-78,-82,-85>
[2023-11-01 11:42:29.756] I (165887) mesh: 716[monitor]no change, parent:34:85:18:8f:4d:29, rssi:-37
[2023-11-01 11:42:29.771] I (165887) mesh: 2004<arm>parent monitor, my layer:2(cap:6)(node), interval:10868ms, retries:3<>
[2023-11-01 11:42:29.792] W (165917) wifi:(->sleep)busy, waked:1, dream:0, sleep:0
[2023-11-01 11:42:30.599] I (166727) app_main: Heap size -> 8236792
[2023-11-01 11:42:31.602] I (167727) app_main: Heap size -> 8227076
[2023-11-01 11:42:32.598] I (168727) app_main: Heap size -> 8232512
[2023-11-01 11:42:33.603] I (169727) app_main: Heap size -> 8232512
[2023-11-01 11:42:34.596] I (170727) app_main: Heap size -> 8232532
[2023-11-01 11:42:35.590] I (171727) app_main: Heap size -> 8232532
[2023-11-01 11:42:36.598] I (172727) app_main: Heap size -> 8232532
[2023-11-01 11:42:37.592] I (173727) app_main: Heap size -> 8232532
[2023-11-01 11:42:38.601] I (174727) app_main: Heap size -> 8232532
[2023-11-01 11:42:39.596] I (175727) app_main: Heap size -> 8227244
[2023-11-01 11:42:40.589] I (176727) app_main: Heap size -> 8227244
[2023-11-01 11:42:40.909] I (177027) mesh: 5157<active>parent layer:1(node), channel:1, rssi:-33, assoc:1(cnx rssi threshold:-120)my_assoc:0
[2023-11-01 11:42:41.600] I (177727) app_main: Heap size -> 8227252
[2023-11-01 11:42:42.408] I (178527) mesh: 5948<scan>parent layer:1, rssi:-40, assoc:1(cnx rssi threshold:-120)
[2023-11-01 11:42:42.408] I (178527) mesh: [SCAN][ch:1]AP:1, other(ID:0, RD:0), MAP:1, idle:0, candidate:1, root:1, topMAP:0[c:2,i:2][00:00:00:00:00:00][FIXED-ROOT]<weak>
[2023-11-01 11:42:42.426] I (178537) mesh: 7370[weak]try rssi_threshold:-120, backoff times:0, max:5<-78,-82,-85>
[2023-11-01 11:42:42.426] I (178547) mesh: 716[monitor]no change, parent:34:85:18:8f:4d:29, rssi:-40
[2023-11-01 11:42:42.426] I (178557) mesh: 2004<arm>parent monitor, my layer:2(cap:6)(node), interval:329862ms, retries:3<>
...
[2023-11-01 11:43:39.231] I (235347) srv_mqtt: MQTT_EVENT_CONNECTED

@yanmartins
Copy link
Author

yanmartins commented Nov 1, 2023

By the way, I think there are two things you can optimize in your code:

  1. Call esp_mesh_set_xon_qsize(128) to enlarge the xon window .

Yes, I already set esp_mesh_set_xon_qsize(128)

  1. The default mesh packet lifetime is BLOCKING(0xffffffff), you can call esp_mesh_send_block_time() to set the mesh packet lifetime, then the mesh node will not always show W (32119) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:2, 1800 ms timeout, seqno:0, xseqno:1, no_wnd_count:0, timeout_count:13, it will return TX_TIMEOUT when the lifetime has reached.

I will add this, but it seems that the error is something else, because the NODE stops looking for ROOT

I just comment this configs:

/* router */
cfg.channel         = 0;
cfg.router.ssid_len = strlen((char *) wifi_params->ssid);
memcpy((uint8_t *) &cfg.router.ssid, wifi_params->ssid, cfg.router.ssid_len);
memcpy((uint8_t *) &cfg.router.password, wifi_params->password, sizeof(wifi_params->password));

How about you don't comment the router configure lines?

You tell me to add some random value? If I do this, ROOT will be looking for this connection instead of connecting to PPP

@zhangyanjiaoesp
Copy link
Collaborator

you tell me to add some random value? If I do this, ROOT will be looking for this connection instead of connecting to PPP

Since you have set the root to fixed, then the root won't try to connect to router

@zhangyanjiaoesp
Copy link
Collaborator

zhangyanjiaoesp commented Nov 2, 2023

Is there any code difference between scenarios that can and cannot reconnect? (I see they are both fixed-root)
Or the code is same, the node reconnection does not fail every time, it sometimes can success, and sometimes fail?

@yanmartins
Copy link
Author

Is there any code difference between scenarios that can and cannot reconnect? (I see they are both fixed-root) Or the code is same.

The same code

The node reconnection does not fail every time, it sometimes can success, and sometimes fail?

Yes, it sometimes can success, and sometimes fail with the same code.

@yanmartins
Copy link
Author

yanmartins commented Nov 3, 2023

you tell me to add some random value? If I do this, ROOT will be looking for this connection instead of connecting to PPP

Since you have set the root to fixed, then the root won't try to connect to router

@zhangyanjiaoesp Root is fixed, but it continues searching for the wifi network

I (3602) wifi:wifi driver task: 3fcc89f4, prio:23, stack:6656, core=0
I (3602) system_api: Base MAC address is not set
I (3602) system_api: read default base MAC address from EFUSE
I (3612) wifi:wifi firmware version: e2d0ad8
I (3612) wifi:wifi certification version: v7.0
I (3622) wifi:config NVS flash: enabled
I (3622) wifi:config nano formating: disabled
I (3622) wifi:Init data frame dynamic rx buffer num: 64
I (3632) wifi:Init management frame dynamic rx buffer num: 64
I (3632) wifi:Init management short buffer num: 32
I (3642) wifi:Init static tx buffer num: 6
I (3642) wifi:Init tx cache buffer num: 32
I (3652) wifi:Init static tx FG buffer num: 2
I (3652) wifi:Init static rx buffer size: 1600
I (3652) wifi:Init static rx buffer num: 6
I (3662) wifi:Init dynamic rx buffer num: 64
I (3662) wifi_init: rx ba win: 6
I (3672) wifi_init: tcpip mbox: 32
I (3672) wifi_init: udp mbox: 6
I (3672) wifi_init: tcp mbox: 6
I (3682) wifi_init: tcp tx win: 5744
I (3682) wifi_init: tcp rx win: 5744
I (3692) wifi_init: tcp mss: 624
I (3692) wifi_init: WiFi/LWIP prefer SPIRAM
I (3692) wifi_init: WiFi IRAM OP enabled
I (3702) wifi_init: WiFi RX IRAM OP enabled
I (3732) phy_init: phy_version 610,2bff4c8,Jul 27 2023,20:22:14
I (3772) wifi:mode : sta (34:85:18:8f:4d:28) + softAP (34:85:18:8f:4d:29)
I (3772) wifi:enable tsf
I (3772) wifi:Total power save buffer number: 3
I (3772) wifi:Init max length of beacon: 752/752
I (3772) wifi:Init max length of beacon: 752/752
I (3782) mesh: [IO]disable self-organizing<reconnect>
I (4792) wifi:Total power save buffer number: 3
I (5722) srv_wifi: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
I (5722) srv_wifi: wifi_config->router.ssid: My_SSID
I (5722) srv_wifi: wifi_config->router.password: My_Passwd
I (5732) srv_wifi: wifi_config->mesh_ap.password: MAP_PASSWD
I (5732) srv_wifi: wifi_config->mesh_ap.max_connection: 6
I (5742) srv_wifi: wifi_config->mesh_id: 119:119:119:119:119:118
I (5752) srv_wifi: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
I (5762) mesh: [CONFIG]connect to router:My_SSID, 00:00:00:00:00:00
I (5762) srv_wifi: <MESH_EVENT_MESH_STARTED>ID:77:77:77:77:77:76
I (5772) srv_wifi: mesh starts successfully, heap: 8391936, root fixed
I (5792) srv_mobile_network: [IN] Init Mobile_Network
I (7202) esp-netif_lwip-ppp: User interrupt
I (7202) esp_modem_netif: PPP state changed event 5
I (8592) mesh: [wifi]disconnected reason:201(), continuous:1/max:12, root, vote(,)<><>
I (8592) srv_wifi: <MESH_EVENT_PARENT_DISCONNECTED>reason:201    
I (11432) mesh: [wifi]disconnected reason:201(), continuous:2/max:12, root, vote(,)<><>
I (11432) srv_wifi: <MESH_EVENT_PARENT_DISCONNECTED>reason:201  
I (14262) mesh: [wifi]disconnected reason:201(), continuous:3/max:12, root, vote(,)<><>
I (14262) srv_wifi: <MESH_EVENT_PARENT_DISCONNECTED>reason:201  
I (17102) mesh: [wifi]disconnected reason:201(), continuous:4/max:12, root, vote(,)<><>
I (17102) srv_wifi: <MESH_EVENT_PARENT_DISCONNECTED>reason:201

@zhangyanjiaoesp
Copy link
Collaborator

you tell me to add some random value? If I do this, ROOT will be looking for this connection instead of connecting to PPP

Since you have set the root to fixed, then the root won't try to connect to router

@zhangyanjiaoesp Root is fixed, but it continues searching for the wifi network

I (3602) wifi:wifi driver task: 3fcc89f4, prio:23, stack:6656, core=0
I (3602) system_api: Base MAC address is not set
I (3602) system_api: read default base MAC address from EFUSE
I (3612) wifi:wifi firmware version: e2d0ad8
I (3612) wifi:wifi certification version: v7.0
I (3622) wifi:config NVS flash: enabled
I (3622) wifi:config nano formating: disabled
I (3622) wifi:Init data frame dynamic rx buffer num: 64
I (3632) wifi:Init management frame dynamic rx buffer num: 64
I (3632) wifi:Init management short buffer num: 32
I (3642) wifi:Init static tx buffer num: 6
I (3642) wifi:Init tx cache buffer num: 32
I (3652) wifi:Init static tx FG buffer num: 2
I (3652) wifi:Init static rx buffer size: 1600
I (3652) wifi:Init static rx buffer num: 6
I (3662) wifi:Init dynamic rx buffer num: 64
I (3662) wifi_init: rx ba win: 6
I (3672) wifi_init: tcpip mbox: 32
I (3672) wifi_init: udp mbox: 6
I (3672) wifi_init: tcp mbox: 6
I (3682) wifi_init: tcp tx win: 5744
I (3682) wifi_init: tcp rx win: 5744
I (3692) wifi_init: tcp mss: 624
I (3692) wifi_init: WiFi/LWIP prefer SPIRAM
I (3692) wifi_init: WiFi IRAM OP enabled
I (3702) wifi_init: WiFi RX IRAM OP enabled
I (3732) phy_init: phy_version 610,2bff4c8,Jul 27 2023,20:22:14
I (3772) wifi:mode : sta (34:85:18:8f:4d:28) + softAP (34:85:18:8f:4d:29)
I (3772) wifi:enable tsf
I (3772) wifi:Total power save buffer number: 3
I (3772) wifi:Init max length of beacon: 752/752
I (3772) wifi:Init max length of beacon: 752/752
I (3782) mesh: [IO]disable self-organizing<reconnect>
I (4792) wifi:Total power save buffer number: 3
I (5722) srv_wifi: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
I (5722) srv_wifi: wifi_config->router.ssid: My_SSID
I (5722) srv_wifi: wifi_config->router.password: My_Passwd
I (5732) srv_wifi: wifi_config->mesh_ap.password: MAP_PASSWD
I (5732) srv_wifi: wifi_config->mesh_ap.max_connection: 6
I (5742) srv_wifi: wifi_config->mesh_id: 119:119:119:119:119:118
I (5752) srv_wifi: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
I (5762) mesh: [CONFIG]connect to router:My_SSID, 00:00:00:00:00:00
I (5762) srv_wifi: <MESH_EVENT_MESH_STARTED>ID:77:77:77:77:77:76
I (5772) srv_wifi: mesh starts successfully, heap: 8391936, root fixed
I (5792) srv_mobile_network: [IN] Init Mobile_Network
I (7202) esp-netif_lwip-ppp: User interrupt
I (7202) esp_modem_netif: PPP state changed event 5
I (8592) mesh: [wifi]disconnected reason:201(), continuous:1/max:12, root, vote(,)<><>
I (8592) srv_wifi: <MESH_EVENT_PARENT_DISCONNECTED>reason:201    
I (11432) mesh: [wifi]disconnected reason:201(), continuous:2/max:12, root, vote(,)<><>
I (11432) srv_wifi: <MESH_EVENT_PARENT_DISCONNECTED>reason:201  
I (14262) mesh: [wifi]disconnected reason:201(), continuous:3/max:12, root, vote(,)<><>
I (14262) srv_wifi: <MESH_EVENT_PARENT_DISCONNECTED>reason:201  
I (17102) mesh: [wifi]disconnected reason:201(), continuous:4/max:12, root, vote(,)<><>
I (17102) srv_wifi: <MESH_EVENT_PARENT_DISCONNECTED>reason:201

you can call APIs in this order to stop reconnection:

esp_mesh_fix_root(true);
esp_mesh_set_type(ROOT);
esp_mesh_set_self_organized(false,false);

@zhangyanjiaoesp
Copy link
Collaborator

@yanmartins you can change the code as the above comment and test again. If the problem still exists, can you provide a demo for us to test locally? If you can't provide a demo, please provide the IDF branch commit id, we can try to add some debug log for you to test.

@yanmartins
Copy link
Author

yanmartins commented Nov 6, 2023

@yanmartins you can change the code as the above comment and test again. If the problem still exists, can you provide a demo for us to test locally? If you can't provide a demo, please provide the IDF branch commit id, we can try to add some debug log for you to test.

I changed the code as you asked. Everything is fine in ROOT, but the error remained the same in NODE

I can't provide a demo. But the branch commit is the 5.0.4 tag: 8fbf4ba

@zhangyanjiaoesp
Copy link
Collaborator

zhangyanjiaoesp commented Nov 7, 2023

@yanmartins please use the attachment to replace the esp32s3 wifi libs in the idf/components/esp_wifi/lib/esp32s3 file and retest.
s3_wifi_lib_1107.zip

If the replacement succeeds, the wifi firmware is :
image

And please provide the whole log (from start to error happen) of the root and node device, this will help to analyze the cause of the problem.

@yanmartins
Copy link
Author

yanmartins commented Nov 7, 2023

@zhangyanjiaoesp Thanks,

Succeed:
teraterm_node.log
teraterm_root.log

Error:
teraterm_node_fail.log
teraterm_root_fail.log

I turn off ROOT sometimes in failure scenario to force the error in NODE. With the timestamp I believe it is easy to observe this in both logs

When ROOT stops raising the MESH_EVENT_ROUTING_TABLE_ADD event, I know that the NODE has failed

@zhangyanjiaoesp
Copy link
Collaborator

@yanmartins
1.

3. The default mesh packet lifetime is BLOCKING(0xffffffff), you can call esp_mesh_send_block_time() to set the mesh packet lifetime, then the mesh node will not always show W (32119) mesh: [mesh_schedule.c,3130] [WND-RX]max_wnd:2, 1800 ms timeout, seqno:0, xseqno:1, no_wnd_count:0, timeout_count:13, it will return TX_TIMEOUT when the lifetime has reached.

I will add this, but it seems that the error is something else, because the NODE stops looking for ROOT

Have you ever tried to call the esp_mesh_send_block_time() api to change the lifetime of the package? I think this can solve the problem.

  1. Where did you call the esp_mesh_send() in your application? Do you call it in the mesh_event_handler() ?

@yanmartins
Copy link
Author

yanmartins commented Nov 8, 2023

@zhangyanjiaoesp

  1. Have you ever tried to call the esp_mesh_send_block_time() api to change the lifetime of the package? I think this can solve the problem.

Yes I tried but the error remained the same. Is five seconds a good amount of time?

Update: I increased the time to 5 seconds and the error seems to have stopped happening.

  1. Where did you call the esp_mesh_send() in your application? Do you call it in the mesh_event_handler() ?

I'm using the ip_internal_network example. So, esp_mesh_send() is called in the netif driver like this:

static esp_err_t mesh_netif_transmit_from_node_sta(void *h, void *buffer, size_t len)
{
    mesh_data_t data;
    ESP_LOGD(TAG, "Sending to root, dest addr: " MACSTR ", size: %d", MAC2STR((uint8_t *) buffer), len);
    data.data     = buffer;
    data.size     = len;
    data.proto    = MESH_PROTO_AP; //! Node's station transmits data to root's AP
    data.tos      = MESH_TOS_P2P;
    esp_err_t err = esp_mesh_send(NULL, &data, MESH_DATA_TODS, NULL, 0);

    if(err != ESP_OK) ESP_LOGE(TAG, "Send with err code %d %s", err, esp_err_to_name(err));
    return err;
}

@zhangyanjiaoesp
Copy link
Collaborator

sugget set the esp_mesh_send_block_time() at least 5s.

@yanmartins
Copy link
Author

yanmartins commented Nov 9, 2023

sugget set the esp_mesh_send_block_time() at least 5s.

Thanks @zhangyanjiaoesp. It looks like the reconnection problem is gone.

Just one more question: what causes so many prints like these? Is there some configuration I may have done wrong?
This happens on the second or third reconnection

[2023-11-09 10:04:14.977] I (58560203) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.992] I (58560213) mesh: <MESH_NWK_ROOT_ADDR>from assoc, layer:2, root_addr:34:85:18:8f:4d:29, root_cap:1
[2023-11-09 10:04:14.992] I (58560223) wifi:Set ps type: 1
[2023-11-09 10:04:14.992] 
[2023-11-09 10:04:14.992] I (58560223) mesh: 2004<arm>parent monitor, my layer:2(cap:6)(node), interval:8757ms, retries:1<normal connected>
[2023-11-09 10:04:14.992] I (58560233) srv_wifi: <MESH_EVENT_PARENT_CONNECTED>layer:2-->2, parent:34:85:18:8f:4d:29<layer2>, ID:01:02:03:04:05:06
[2023-11-09 10:04:14.992] I (58560233) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.992] I (58560253) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.992] I (58560253) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.992] I (58560263) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.992] I (58560263) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.992] I (58560273) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.992] I (58560273) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.992] I (58560283) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.992] I (58560293) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.995] I (58560293) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.995] I (58560303) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.995] I (58560303) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.995] I (58560313) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.995] I (58560313) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.995] I (58560323) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.995] I (58560333) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.995] I (58560333) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.995] I (58560343) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><>
[2023-11-09 10:04:14.995] I (58560343) mesh: <MESH_NWK_ROOT_ADDR>node, layer:2, root_addr:34:85:18:8f:4d:29, conflict_roots.num:0<>
[2023-11-09 10:04:15.037] I (58560393) srv_wifi: <MESH_EVENT_TODS_REACHABLE>state:0
[2023-11-09 10:04:15.037] I (58560393) srv_wifi: <MESH_EVENT_ROOT_ADDRESS>root address:34:85:18:8f:4d:29
[2023-11-09 10:04:15.037] I (58560403) srv_wifi: <MESH_EVENT_ROOT_ADDRESS>root address:34:85:18:8f:4d:29

@zhangyanjiaoesp
Copy link
Collaborator

when the mesh ie of the parent change, there will be [2023-11-09 10:04:14.995] I (58560333) mesh: <MESH_NWK_MIE_CHANGE><><><><ROOT ADDR><><><> , this seems normal.

@yanmartins
Copy link
Author

Thank you very much @zhangyanjiaoesp
With all the modifications you asked for, everything is working now.

@espressif-bot espressif-bot added Status: Done Issue is done internally Resolution: Done Issue is done internally and removed Status: In Progress Work is in progress labels Nov 12, 2023
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Resolution: Done Issue is done internally Status: Done Issue is done internally
Projects
None yet
Development

No branches or pull requests

3 participants