search

espressif / ESP8266_RTOS_SDK

Latest ESP8266 SDK based on FreeRTOS, esp-idf style.
http://bbs.espressif.com
Apache License 2.0
3.35k stars 1.57k forks source link

[Bug] `wifi: err2,exceed max time value,max val is 198A5759` (GIT8266O-859) #1282

Open apollo80 opened 5 months ago

apollo80 commented 5 months ago

Environment

Problem Description

Hi. I have a firmware for esp8266 implementing bridge wifi-uart. The udp protocol is used for network communication. After load testing, an error occurs:

(15025923) wifi: err2,exceed max time value,max val is 198A5759

Expected Behavior

Stable firmware operation is required for a week.

Actual Behavior

The error occurs after several hours of operation.

(15025923) wifi: err2,exceed max time value,max val is 198A5759

Steps to repropduce

  1. Download and install last ESP8266_RTOS_SDK to ${HOME}/esp/ESP8266_RTOS_SDK
  2. Download wireless-klipper, branch esp8266-rtos-sdk
  3. Build wireless-klipper firmware: 
    ...$ cd wireless-klipper
...$ source ~/esp/ESP8266_RTOS_SDK/export.sh
...$ cp sdkconfig.debug sdkconfig
...$ idf.py build flash
  4. Run load testing (need install python modules): 
    ...$ cd wireless-klipper/test
...$ python3 test_net.debug.udp.py

    and see log __main__.log in wireless-klipper/test directory.

Release Logs

2024-06-15 23:13:58,703 socket: data sent(size 604, net_index 104607, uart_index 104606): b'UjM4sT9HGJhIi+IRbT2oX9enPW9TSrAMEz+ty2aAWzwOu7jf+WWvEr9yHxt9Tdo0Bot
YgemoGPycVebsNT1VqCi1tQWkvE/dw4724W4OS6VnTE2WT4jMYWn+n5tA8f1397q2AsO76/KptG1k075Ic2mzh9T5Dbm8VdZTdTkufM7g5QdTigNs9g1ZRqlgYipBLYJRZLrvnUluzA8YVkNkb8k5E1JK6z/waq
2MBzi6ZUk/fuDdR1+iWy9yBTAXjDPbZBQdqk05VaJzwzLBCQ5HVKn1ZaAC+xC2GbliCb3U4x8ruQjNOMxT0YyCFaJSiegoISwWvp/d+OaYAXslpshnSRxwQsPzgMjZyyw21vi0qiXJ3PNUm1fKf5xPYQEOuuzab
LJasDCOo45KmeEWMJZ0L9iLLYiFPWe9uK/t0JHbiTPm9OhTPGfHxOtlU14Gu9i/ZslaCQbnGnRXN5OhRvp1iSG5Rnai4X4PhCwY2A26UKuQOKY3aqfYNxW3fiWTAqrpoZPTHmRFowVcWaObwimcm8AUMwVj/iYe
DvO9w5tbRr8+pAiBGhO7qJkxl5aUG9zFbAR6/qrbih4HjYoYWdyn7xqh1mw='
2024-06-15 23:13:58,707 socket: recv net_confirm msg: net index - 104607; uart index - 104606
2024-06-15 23:13:58,707 socket: recv confirm msg_net_index 104607, store_netIndex 104607 - ok
2024-06-15 23:13:58,785 serial: UjM4sT9HGJhIi+IRbT2oX9enPW9TSrAMEz+ty2aAWzwOu7jf+WWvEr9yHxt9Tdo0BotYgemoGPycVebsNT1VqCi1tQWkvE/dw4724W4OS6VnTE2WT4jMYWn+n5tA8f1
397q2AsO76/KptG1k075Ic2mzh9T5Dbm8VdZTdTkufM7g5QdTigNs9g1ZRqlgYipBLYJRZLrvnUluzA8YVkNkb8k5E1JK6z/waq2MBzi6ZUk/fuDdR1+iWy9yBTAXjDPbZBQdqk05VaJzwzLBCQ5HVKn1ZaAC+x
C2GbliCb3U4x8ruQjNOMxT0YyCFaJSiegoISwWvp/d+OaYAXslpshnSRxwQsPzgMjZyyw21vi0qiXJ3PNUm1fKf5xPYQEOuuzabLJasDCOo45KmeEWMJZ0L9iLLYiFPWe9uK/t0JHbiTPm9OhTPGfHxOtlU14Gu
9i/ZslaCQbnGnRXN5OhRvp1iSG5Rnai4X4PhCE (15025923) wifi: err2,exceed max time value,max val is 198A5759
2024-06-15 23:13:58,785 serial: pkg recv 547 from 604
2024-06-15 23:13:58,785 serial: pkg recv pkg failed
2024-06-15 23:13:58,785     socket send (604): b'UjM4sT9HGJhIi+IRbT2oX9enPW9TSrAMEz+ty2aAWzwOu7jf+WWvEr9yHxt9Tdo0BotYgemoGPycVebsNT1VqCi1tQWkvE/dw4724W4OS6VnTE
2WT4jMYWn+n5tA8f1397q2AsO76/KptG1k075Ic2mzh9T5Dbm8VdZTdTkufM7g5QdTigNs9g1ZRqlgYipBLYJRZLrvnUluzA8YVkNkb8k5E1JK6z/waq2MBzi6ZUk/fuDdR1+iWy9yBTAXjDPbZBQdqk05VaJzw
zLBCQ5HVKn1ZaAC+xC2GbliCb3U4x8ruQjNOMxT0YyCFaJSiegoISwWvp/d+OaYAXslpshnSRxwQsPzgMjZyyw21vi0qiXJ3PNUm1fKf5xPYQEOuuzabLJasDCOo45KmeEWMJZ0L9iLLYiFPWe9uK/t0JHbiTPm
9OhTPGfHxOtlU14Gu9i/ZslaCQbnGnRXN5OhRvp1iSG5Rnai4X4PhCwY2A26UKuQOKY3aqfYNxW3fiWTAqrpoZPTHmRFowVcWaObwimcm8AUMwVj/iYeDvO9w5tbRr8+pAiBGhO7qJkxl5aUG9zFbAR6/qrbih4
HjYoYWdyn7xqh1mw='
2024-06-15 23:13:58,785     serial recv (547): b'UjM4sT9HGJhIi+IRbT2oX9enPW9TSrAMEz+ty2aAWzwOu7jf+WWvEr9yHxt9Tdo0BotYgemoGPycVebsNT1VqCi1tQWkvE/dw4724W4OS6VnTE
2WT4jMYWn+n5tA8f1397q2AsO76/KptG1k075Ic2mzh9T5Dbm8VdZTdTkufM7g5QdTigNs9g1ZRqlgYipBLYJRZLrvnUluzA8YVkNkb8k5E1JK6z/waq2MBzi6ZUk/fuDdR1+iWy9yBTAXjDPbZBQdqk05VaJzw
zLBCQ5HVKn1ZaAC+xC2GbliCb3U4x8ruQjNOMxT0YyCFaJSiegoISwWvp/d+OaYAXslpshnSRxwQsPzgMjZyyw21vi0qiXJ3PNUm1fKf5xPYQEOuuzabLJasDCOo45KmeEWMJZ0L9iLLYiFPWe9uK/t0JHbiTPm
9OhTPGfHxOtlU14Gu9i/ZslaCQbnGnRXN5OhRvp1iSG5Rnai4X4PhCE (15025923) wifi: err2,exceed max time value,max val is 198A5759'
2024-06-15 23:13:58,785

Debug Logs

2024-06-16 16:59:18,241 socket: data sent(size 584, net_index 2108, uart_index 2107): b'Omae5RakIT0mvutMsXaqgxzzXZ3cBowSKuS34yQDeb+MhfMjY94r/C5p+B/jyumr4OZyte6
hr5YNPPb66+V2jIbrbXcgJ0x0ceZ4nTF85OoFH7zipDKPxnmEHzZxej9pufOKj9OdE8hVuK23CGN9iQt5tEDKi70+ybwaUGCL/VGc6gQ8WOnyy0QuAMNSjNrGEjS/M6vLx6vq9Sl6JRJhtNUArygrkTifTi5gyk
16dXYLAijyQob78f/Zn60GAt8HAMRUkYxDhVEp8z1mH6FbVhpul7qCA/Hm+/dmphki5n1DtiH9M3BkeDKdfptBt0nqfYL0Vdp1RyciWuOTZ8ECZnI/aBGZJfdMtxQDrpjMqYuQsgyzr34S3AA6AfIb2OaPWfqUc
AHrHKPvtaIzAWl6YVs8vq/6LcgyE+1QrOwxtkOj5sc0PZOuMVHXJjXe0k/JlUR8MC9nSfoxDBFSaEoHBqCqVFtuoU6gLBPYwO3bwOk24yN1YZrdcADJGPYXnFu5IpZIVv05ps+Y5/qXotK2O/5+RoC+P9qlNCcd
mc0AfAEINaXNwNdnZFEX5l3306f2WIPhNQ=='
2024-06-16 16:59:18,254 serial: I (453578) uart2udp: send msg - net_index 2107; uart_index: 2107; msg_size: 584 - try_count 0 ...
2024-06-16 16:59:18,263 serial: I (453589) udp2uart: recv msg - net_index: 2107; uart_index: 2107; msg_size: 0
2024-06-16 16:59:18,276 serial: I (453592) udp2uart: recv confirm msg - net_index 2107; uart_index: 2107 -> Ok
2024-06-16 16:59:18,287 socket: recv net_confirm msg: net index - 2108; uart index - 2107
2024-06-16 16:59:18,287 socket: recv confirm msg_net_index 2108, store_netIndex 2108 - ok
2024-06-16 16:59:18,289 serial: I (453598) uart2udp: sended msg confirmed - net_index 2107; uart_index: 2107; msg_size: 584 - try_count 0
2024-06-16 16:59:18,293 serial: I (453612) udp2uart: waiting data ...
2024-06-16 16:59:18,306 serial: I (453618) udp2uart: recv msg - net_index: 2108; uart_index: 2107; msg_size: 584
2024-06-16 16:59:18,314 serial: I (453630) udp2uart: send confirm msg - net_index 2108; uart_index: 2107
2024-06-16 16:59:18,340 serial: Omae5RakIT0mvutMsXaqgxzzXZ3cBowSKuS34yQDeb+MhfMjY94r/C5p+B/jyumr4OZyte6hr5YNPPb66+V2jIbrbXcgJ0x0ceZ4nTF85OoFH7zipDKPxnE (453663
) wifi: err2,exceed max time value,max val is 198A5759
2024-06-16 16:59:18,341 serial: pkg recv 181 from 584
2024-06-16 16:59:18,341 serial: pkg recv pkg failed
2024-06-16 16:59:18,341     socket send (584): b'Omae5RakIT0mvutMsXaqgxzzXZ3cBowSKuS34yQDeb+MhfMjY94r/C5p+B/jyumr4OZyte6hr5YNPPb66+V2jIbrbXcgJ0x0ceZ4nTF85OoFH7
zipDKPxnmEHzZxej9pufOKj9OdE8hVuK23CGN9iQt5tEDKi70+ybwaUGCL/VGc6gQ8WOnyy0QuAMNSjNrGEjS/M6vLx6vq9Sl6JRJhtNUArygrkTifTi5gyk16dXYLAijyQob78f/Zn60GAt8HAMRUkYxDhVEp8
z1mH6FbVhpul7qCA/Hm+/dmphki5n1DtiH9M3BkeDKdfptBt0nqfYL0Vdp1RyciWuOTZ8ECZnI/aBGZJfdMtxQDrpjMqYuQsgyzr34S3AA6AfIb2OaPWfqUcAHrHKPvtaIzAWl6YVs8vq/6LcgyE+1QrOwxtkOj
5sc0PZOuMVHXJjXe0k/JlUR8MC9nSfoxDBFSaEoHBqCqVFtuoU6gLBPYwO3bwOk24yN1YZrdcADJGPYXnFu5IpZIVv05ps+Y5/qXotK2O/5+RoC+P9qlNCcdmc0AfAEINaXNwNdnZFEX5l3306f2WIPhNQ=='
2024-06-16 16:59:18,341     serial recv (181): b'Omae5RakIT0mvutMsXaqgxzzXZ3cBowSKuS34yQDeb+MhfMjY94r/C5p+B/jyumr4OZyte6hr5YNPPb66+V2jIbrbXcgJ0x0ceZ4nTF85OoFH7
zipDKPxnE (453663) wifi: err2,exceed max time value,max val is 198A5759'
2024-06-16 16:59:18,341