SSL: Unknown error code 0x4C

[LittleBuster]

ESP32S3 N16R8
Framework ver.: 2.0.17

Заметил, что во время работы бота раз в 3 минуты в сериал SSL клиент печатает сообщение о неизвестной ошибке

[36217174][E][ssl_client.cpp:37] _handle_error(): [data_to_read():361]: (-76) UNKNOWN ERROR CODE (004C)
[36427928][E][ssl_client.cpp:37] _handle_error(): [data_to_read():361]: (-76) UNKNOWN ERROR CODE (004C)
[36638264][E][ssl_client.cpp:37] _handle_error(): [data_to_read():361]: (-76) UNKNOWN ERROR CODE (004C)
[36848603][E][ssl_client.cpp:37] _handle_error(): [data_to_read():361]: (-76) UNKNOWN ERROR CODE (004C)
[37058941][E][ssl_client.cpp:37] _handle_error(): [data_to_read():361]: (-76) UNKNOWN ERROR CODE (004C)
[37269379][E][ssl_client.cpp:37] _handle_error(): [data_to_read():361]: (-76) UNKNOWN ERROR CODE (004C)
[79424049][E][ssl_client.cpp:37] _handle_error(): [data_to_read():361]: (-76) UNKNOWN ERROR CODE (004C)
[79635205][E][ssl_client.cpp:37] _handle_error(): [data_to_read():361]: (-76) UNKNOWN ERROR CODE (004C)
[79845543][E][ssl_client.cpp:37] _handle_error(): [data_to_read():361]: (-76) UNKNOWN ERROR CODE (004C)
[80055883][E][ssl_client.cpp:37] _handle_error(): [data_to_read():361]: (-76) UNKNOWN ERROR CODE (004C)
[80266220][E][ssl_client.cpp:37] _handle_error(): [data_to_read():361]: (-76) UNKNOWN ERROR CODE (004C)

[Basoil]

на ESP32-S3 N16
Framework 3.0.7

регулярное в простое выдает:

2024-11-30 17:50:46 | [15564212][E][NetworkClient.cpp:319] setSocketOption(): fail on 0, errno: 9, "Bad file number"
2024-11-30 18:18:40 | [17238947][E][ssl_client.cpp:36] _handle_error(): [data_to_read():401]: (-76) UNKNOWN ERROR CODE (004C)
2024-11-30 18:18:41 | [17238957][E][NetworkClientSecure.cpp:309] available(): Closing connection on failed available check

[thedemoncat]

Подтверждаю, ошибка есть

сперва так :

[587332][E][ssl_client.cpp:37] _handle_error(): [data_to_read():361]: (-76) UNKNOWN ERROR CODE (004C)

а затем так:

[592689][D][ssl_client.cpp:176] start_ssl_client(): WARNING: Skipping SSL Verification. INSECURE!

сразу после нее всегда падает с исключением

`
0x4037d3af: _xt_context_save at /home/runner/work/esp32-arduino-lib-builder/esp32-arduino-lib-builder/esp-idf/components/freertos/port/xtensa/xtensa_context.S:194
0x42085b92: mbedtls_sha256_finish_ret at /home/runner/work/esp32-arduino-lib-builder/esp32-arduino-lib-builder/esp-idf/components/mbedtls/port/sha/dma/esp_sha256.c:254
0x4037d3af: _xt_context_save at /home/runner/work/esp32-arduino-lib-builder/esp32-arduino-lib-builder/esp-idf/components/freertos/port/xtensa/xtensa_context.S:194
0x4037407d: _xt_alloca_exc at /home/runner/work/esp32-arduino-lib-builder/esp32-arduino-lib-builder/esp-idf/components/freertos/port/xtensa/xtensa_vectors.S:1806
0x4037e1b9: xQueueSemaphoreTake at /home/runner/work/esp32-arduino-lib-builder/esp32-arduino-lib-builder/esp-idf/components/freertos/queue.c:1554

`

[GyverLibs]

Я читал что для 32 ещё не изобрели стабильную версию ssl клиента

[thedemoncat]

Возможно. пока не добрался до него.

Кстати, FastBot2 нельзя отключить проверку ssl или же наоборот указать отпечаток? хотя бы как временное решение, что бы проверить гипотезу, что проблема явно в нем

Немного понаблюдал за проблемой и оставлю для потомков)

[0;31m[2815481][E][ssl_client.cpp:37] _handle_error(): [data_to_read():361]: (-76) UNKNOWN ERROR CODE (004C)�[

возникает стабильно раз вв ~561640 мс (9.36 минут) и несет за собой утечку памяти.
В моем случае бот запускается так

void TelegramBotClient::init() {
    vTaskDelay(pdMS_TO_TICKS(10000)); // Пауза для предотвращения перегрузки
    while (!SsvcOpenConnect::getInstance().isOnline())
    {
        ESP_LOGD("TelegramBotClient", "Waiting for connection...");
        vTaskDelay(pdMS_TO_TICKS(5000)); // Пауза для предотвращения перегрузки
    }
    ESP_LOGI("TelegramBotClient", "Initializing TelegramBotClient");
    {
        const auto token = GlobalConfig::config().get<String>(bootName, "token", "");
        ESP_LOGI("TelegramBotClient", "Token found: %s", token);
        _bot.setToken(token.c_str());
    }

    {
        const int64_t chat_id = GlobalConfig::config().get<int64_t>(bootName, "chat_id", 0);
        ESP_LOGI("TelegramBotClient", "Chat ID: %lld", chat_id);
        chatID = chat_id;
    }

    ESP_LOGD("TelegramBotClient", "attachUpdate");
    ESP_LOGD("TelegramBotClient", "setLimit");
    _bot.setLimit(MESSAGE_LIMIT_BY_UPDATE);
    ESP_LOGD("TelegramBotClient", "setPollMode");
    // _bot.setPollMode(fb::Poll::Long, 20000);
    ESP_LOGD("TelegramBotClient", "tick");
    _bot.tick();
    ESP_LOGD("TelegramBotClient", "sendHello");
    sendHello();

    initTelemetryTaskSender();
}

и через RTOS задачку c определенной периодичностью обновляется сообщение:

    xTaskCreatePinnedToCore(
        statusMessageSender,
        "TelegramBotClient",
        6144,
        this,
        tskIDLE_PRIORITY,
        nullptr,
        1
    );

... 


[[noreturn]] void TelegramBotClient::statusMessageSender(void* params) {
    const auto manager = static_cast<TelegramBotClient*>(params);
    while (true) {
        ESP_LOGD("TelegramBotClient", "statusMessageSender - tick");
        ESP_LOGI("TelegramBotClient", "Free stack: %u", uxTaskGetStackHighWaterMark(nullptr));
        vTaskDelay(pdMS_TO_TICKS(5000));

        if (manager->_botMutex != nullptr) {
            try {
                MutexLocker lock(manager->_botMutex);

                std::string msg = manager->createStatusMessage();
                if (manager->statusMessageID != 0)
                {
                    const uint32_t start = millis();
                    manager->updateMessage(msg, manager->statusMessageID);
                    if (millis() - start > MAX_SEND_TIME_MS) {
                        ESP_LOGW("TelegramBotClient", "Message sending took too long!");
                    }
                }else
                {
                    if (!WiFi.isConnected()) {
                        ESP_LOGE("TelegramBotClient", "No network!");
                        continue;
                    }
                    manager->statusMessageID = manager->sendMessage(msg);
                }
            } catch (...) {
                ESP_LOGE("TelegramBotClient", "Exception in statusMessageSender");
                vTaskDelay(pdMS_TO_TICKS(1000)); // Пауза перед повторной попыткой
            }
        }
    }
}

...


uint32_t TelegramBotClient::sendMessage(const std::string& message)
{

    fb::Message msg;
    msg.mode = fb::Message::Mode::HTML;
    msg.text = message.c_str();
    msg.chatID = chatID;
    while (!_bot.isPolling())
    {
        fb::Result result = _bot.sendMessage(msg);
        ESP_LOGD("TelegramBotClient", "result - %s", result.getRaw().c_str());
        vTaskDelay(pdMS_TO_TICKS(100));
        return _bot.lastBotMessage();
    }
    return 0;
}


void TelegramBotClient::updateMessage(const std::string& message, uint32_t messageId)
{
    fb::TextEdit et;
    et.mode = fb::Message::Mode::HTML;
    et.text = message.c_str();
    et.chatID = chatID;
    et.messageID = messageId;
    _bot.editText(et);
}

Средний интервал между ошибками SSL в логах: ~561640 мс (9.36 минут)
Возникновение ошибки снижает размер стека с начального
Free stack: 4492
пока не дойдет до
Free stack: 1480
После этого стек стабилизируется и больше не растет

Как временное решение что бы не ловить двойное исключение, пришлось увеличить размер стека.
Хочу попробовать переинициализировать FastBot2 - но не знаю поможет ли.

[GyverLibs]

Режима с отпечатком нет, не дошли руки сделать. Также читал что не рекомендуется разбивать работу с ссл клиентом на таски - оно обязательно посыпется. Особенно если учесть что в fb2 используется один клиент, он общий на чтение и отправку. Если разделить на таски - гарантировано будут проблемы

[thedemoncat]

Тогда мне лучше все делать во одном методе? создать клиента, отправить сообщение и удалить?

[GyverLibs]

Да, можно одного "бота" оставить глобально чтобы он пуллил апдейты, а сообщения отправлять с локальных ботов с флагом wait. Первое подключение к серверу занимает время, но в рамках другого таска это наверное неважно

[thedemoncat]

Спасибо большое, попробую