eclipse / paho.mqtt.c

An Eclipse Paho C client library for MQTT for Windows, Linux and MacOS. API documentation: https://eclipse.github.io/paho.mqtt.c/
https://eclipse.org/paho
Other
1.94k stars 1.08k forks source link

reconnect callback is not triggered #1132

Closed linxingyang closed 3 years ago

linxingyang commented 3 years ago

Hi i met the problem that reconnect callback is not triggered, detail describe as follow.

env:

scene:

  1. both cable and 4G are available at first.
  2. mqtt connect via cable.
  3. plug off cable after mqtt connected.
  4. the system detect the cable is unavailable, using 4G as default.
  5. mqtt will reconnect via 4G.
  6. plug in cable after mqtt reconnected.
  7. the system detect the cable is available, using cable as default.
  8. mqtt will (disconnect and) reconnect via cable again.

do step 3 to step 8 few times, i found that the reconnect callback is not triggered.

the reconnect code:

/**
 * @details reconnect sync.
 * @return 
 *       0 if reconnect success. \n
 *      -1 if instance not exists or already connected. \n
 *      -2 if call reconnect fail. \n
 *      -3 if not connected. \n */
int CWeconMqttClient::reconnect()
{
    int nRtn = 0;
    printf("int CWeconMqttClient::_reconnect() 1111\n");
    CAutoLock autolock(m_connectionLock);
    printf("int CWeconMqttClient::_reconnect() 2222\n");

    if (0 > (nRtn = _reconnect()))
    {
        printf("int CWeconMqttClient::_reconnect() 3333\n");
        return nRtn;
    }
    printf("int CWeconMqttClient::_reconnect() 4444\n");
    m_connectionActionType = WMQTT_CONNECTION_ACTION_RECONNECT;
    m_connectionSemaphore.create(1); 
    m_connectionSemaphore.wait();               /* block here wait for semaphore.  */
    printf("int CWeconMqttClient::_reconnect() 5555\n");
    if (!MQTTAsync_isConnected(m_mqtt)) 
    {
        return -3;
    }
    printf("int CWeconMqttClient::_reconnect() 6666\n");
    return 0;
}

int CWeconMqttClient::_reconnect()
{
    int nRtn = 0;

    if (!existsInstance() || isConnected())
    {
        return -1;
    }

    if (MQTTASYNC_SUCCESS != (nRtn = MQTTAsync_reconnect(m_mqtt)))
    {
        return -2;
    }

    return 0;
}

the connect relative callbacks.

void onMqttConnected(void * context, char * cause)
{
    printf(">>>>>>>>void onMqttConnected(void * context, char * cause)\n");
    CWeconMqttClient * wmqtt = (CWeconMqttClient *)context;
    wmqtt->m_connectionSemaphore.post();         /* notify the semaphore. */
    // ...
}

void onMqttConnectFailure(void * context, MQTTAsync_failureData * response)
{
    printf(">>>>>>>>void onMqttConnectFailure(void * context, MQTTAsync_failureData * response)\n");
    CWeconMqttClient * wmqtt = (CWeconMqttClient *)context;
    wmqtt->m_connectionSemaphore.post();         /* notify the semaphore. */
    // ...
}

void onMqttDisconnected(void * context, MQTTProperties * properties, enum MQTTReasonCodes reasonCode)
{
    printf(">>>>>>>>void onMqttDisconnected(void * context, MQTTProperties * properties, enum MQTTReasonCodes reasonCode)\n");
    // ...
}

for this test, the down side is the call tarce, we call reconnect 4 times. the upper side is the callback log, the first three times are all triggerd but the 4th time not. log

the first different point between the 3th log and the 4th log, SSLsocket error. log

the 3th one full log.

int CWeconMqttClient::_reconnect() 1111
int CWeconMqttClient::_reconnect() 2222
[3]:20210728 153311.647 (2880902208) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153311.647 (2880902208) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153311.647 (2880902208) (0)> MQTTAsync_reconnect:918
[1]:20210728 153311.647 Allocating 96 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/MQTTAsync.c line 938 ptr 0xb3900cc0

[3]:20210728 153311.647 (2880902208)  (1)> MQTTAsync_addCommand:791
[1]:20210728 153311.647 Allocating 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/LinkedList.c line 109 ptr 0xb39009b8

[3]:20210728 153311.647 (2880902208)   (2)> Thread_signal_cond:396
[3]:20210728 153311.647 (2880902208)   (2)< Thread_signal_cond:401 (0)
[3]:20210728 153311.647 (2880902208)  (1)< MQTTAsync_addCommand:912 (0)
[3]:20210728 153311.647 (2880902208) (0)< MQTTAsync_reconnect:955 (0)
int CWeconMqttClient::_reconnect() 4444
[3]:20210728 153311.881 (2923426880)  (1)< Thread_wait_cond:429 (0)
[3]:20210728 153311.881 (2923426880)  (1)> MQTTAsync_checkTimeouts:1659
[3]:20210728 153311.881 (2923426880)  (1)< MQTTAsync_checkTimeouts:1747
[3]:20210728 153311.881 (2923426880)  (1)> MQTTAsync_processCommand:1180
[1]:20210728 153311.881 Allocating 32 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/LinkedList.c line 54 ptr 0xee5bd0

[1]:20210728 153311.881 Freeing 32 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/LinkedList.c line 400, heap use now 1776 bytes

[1]:20210728 153311.881 Freeing 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/LinkedList.c line 229, heap use now 1744 bytes

[4]:20210728 153311.881 Connecting to serverURI mqtt.v-box.net:9883 with MQTT version 4
[3]:20210728 153311.881 (2923426880)   (2)> MQTTProtocol_connect:218
[3]:20210728 153311.881 (2923426880)    (3)> MQTTProtocol_addressPort:60
[3]:20210728 153311.881 (2923426880)    (3)< MQTTProtocol_addressPort:92
[3]:20210728 153311.881 (2923426880)    (3)> Socket_new:696
[1]:20210728 153311.881 Allocating 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/Socket.c line 706 ptr 0xe8ee58

[3]:20210728 153311.881 (3033527360) (0)> MQTTAsync_isConnected:1316
[1]:20210728 153311.881 Return code 0 from read select
[3]:20210728 153311.881 (2889290816)    (3)> Socket_continueWrites:995
[3]:20210728 153311.881 (2889290816)    (3)< Socket_continueWrites:1025 (0)
[1]:20210728 153311.881 Return code 0 from write select
[3]:20210728 153311.881 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153311.881 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153312.065 (2889290816)   (2)< MQTTAsync_sleep:110
udhcpc: no lease, failing
[3]:20210728 153312.065 New socket 19 for mqtt.v-box.net:9883, port 9883
[3]:20210728 153312.065 (2923426880)     (4)> Socket_addSocket:174
[1]:20210728 153312.065 Allocating 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/Socket.c line 184 ptr 0xee56c0

[1]:20210728 153312.065 Allocating 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/LinkedList.c line 92 ptr 0xe4c7b0

[3]:20210728 153312.065 (2923426880)      (5)> Socket_setnonblocking:86
[3]:20210728 153312.065 (2923426880)      (5)< Socket_setnonblocking:91 (0)
[3]:20210728 153312.065 (2923426880)     (4)< Socket_addSocket:209 (0)
[1]:20210728 153312.065 Allocating 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/Socket.c line 821 ptr 0xea4f70

[1]:20210728 153312.065 Allocating 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/LinkedList.c line 92 ptr 0xe8ddc8

[3]:20210728 153312.065 Connect pending
[1]:20210728 153312.065 Freeing 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/Socket.c line 850, heap use now 1808 bytes

[3]:20210728 153312.065 (2923426880)    (3)< Socket_new:852 (115)
[3]:20210728 153312.065 (2923426880)   (2)< MQTTProtocol_connect:340 (115)
[3]:20210728 153312.065 (2923426880)   (2)> MQTTProperties_free:401
[3]:20210728 153312.065 (2923426880)   (2)< MQTTProperties_free:424
[1]:20210728 153312.065 Freeing 96 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/MQTTAsyncUtils.c line 1058, heap use now 1792 bytes

[3]:20210728 153312.065 (2923426880)  (1)< MQTTAsync_processCommand:1571 (1)
[3]:20210728 153312.065 (2923426880)  (1)> Thread_wait_cond:414
[3]:20210728 153312.065 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153314.013 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153314.013 (2889290816)    (3)> MQTTProtocol_keepalive:664
[3]:20210728 153314.013 (2889290816)    (3)< MQTTProtocol_keepalive:716
[3]:20210728 153314.013 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153314.013 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153314.013 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153314.013 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153314.013 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153314.013 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153314.013 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153314.013 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153314.013 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153314.013 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[1]:20210728 153314.013 Return code 1 from read select
[3]:20210728 153314.013 (2889290816)    (3)> Socket_continueWrites:995
[3]:20210728 153314.013 (2889290816)    (3)< Socket_continueWrites:1025 (0)
[1]:20210728 153314.013 Return code 1 from write select
[3]:20210728 153314.013 (2889290816)    (3)> isReady:226
[1]:20210728 153314.013 Freeing 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/LinkedList.c line 224, heap use now 1696 bytes

[1]:20210728 153314.013 Freeing 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/LinkedList.c line 229, heap use now 1680 bytes

[3]:20210728 153314.100 (2889290816)    (3)< isReady:231 (1)
[3]:20210728 153314.100 (2889290816)   (2)< Socket_getReadySocket:328 (19)
[3]:20210728 153314.100 m->c->connect_state = 1
[3]:20210728 153314.100 (2889290816)   (2)> MQTTAsync_connecting:2675
[3]:20210728 153314.100 (2889290816)    (3)> MQTTProtocol_addressPort:60
[3]:20210728 153314.100 (2889290816)    (3)< MQTTProtocol_addressPort:92
[3]:20210728 153314.100 (2889290816)    (3)> SSLSocket_setSocketForSSL:709
[3]:20210728 153314.100 (2889290816)     (4)> SSLSocket_createContext:550
[3]:20210728 153314.100 (2889290816)     (4)< SSLSocket_createContext:699 (1)
[4]:20210728 153314.100 SSL cipher available: 0:TLS_AES_256_GCM_SHA384
[4]:20210728 153314.100 SSL cipher available: 1:TLS_CHACHA20_POLY1305_SHA256
[4]:20210728 153314.100 SSL cipher available: 2:TLS_AES_128_GCM_SHA256
[4]:20210728 153314.100 SSL cipher available: 3:ECDHE-ECDSA-AES256-GCM-SHA384
[4]:20210728 153314.100 SSL cipher available: 4:ECDHE-RSA-AES256-GCM-SHA384
[4]:20210728 153314.100 SSL cipher available: 5:DHE-RSA-AES256-GCM-SHA384
[4]:20210728 153314.100 SSL cipher available: 6:ECDHE-ECDSA-CHACHA20-POLY1305
[4]:20210728 153314.100 SSL cipher available: 7:ECDHE-RSA-CHACHA20-POLY1305
[4]:20210728 153314.100 SSL cipher available: 8:DHE-RSA-CHACHA20-POLY1305
[4]:20210728 153314.100 SSL cipher available: 9:ECDHE-ECDSA-AES128-GCM-SHA256
[4]:20210728 153314.100 SSL cipher available: 10:ECDHE-RSA-AES128-GCM-SHA256
[4]:20210728 153314.103 SSL cipher available: 11:DHE-RSA-AES128-GCM-SHA256
[4]:20210728 153314.103 SSL cipher available: 12:ECDHE-ECDSA-AES256-SHA384
[4]:20210728 153314.103 SSL cipher available: 13:ECDHE-RSA-AES256-SHA384
[4]:20210728 153314.103 SSL cipher available: 14:DHE-RSA-AES256-SHA256
[4]:20210728 153314.103 SSL cipher available: 15:ECDHE-ECDSA-AES128-SHA256
[4]:20210728 153314.103 SSL cipher available: 16:ECDHE-RSA-AES128-SHA256
[4]:20210728 153314.103 SSL cipher available: 17:DHE-RSA-AES128-SHA256
[4]:20210728 153314.103 SSL cipher available: 18:ECDHE-ECDSA-AES256-SHA
[4]:20210728 153314.103 SSL cipher available: 19:ECDHE-RSA-AES256-SHA
[4]:20210728 153314.103 SSL cipher available: 20:DHE-RSA-AES256-SHA
[4]:20210728 153314.103 SSL cipher available: 21:ECDHE-ECDSA-AES128-SHA
[4]:20210728 153314.103 SSL cipher available: 22:ECDHE-RSA-AES128-SHA
[4]:20210728 153314.103 SSL cipher available: 23:DHE-RSA-AES128-SHA
[4]:20210728 153314.103 SSL cipher available: 24:RSA-PSK-AES256-GCM-SHA384
[4]:20210728 153314.103 SSL cipher available: 25:DHE-PSK-AES256-GCM-SHA384
[4]:20210728 153314.103 SSL cipher available: 26:RSA-PSK-CHACHA20-POLY1305
[4]:20210728 153314.103 SSL cipher available: 27:DHE-PSK-CHACHA20-POLY1305
[4]:20210728 153314.103 SSL cipher available: 28:ECDHE-PSK-CHACHA20-POLY1305
[4]:20210728 153314.103 SSL cipher available: 29:AES256-GCM-SHA384
[4]:20210728 153314.103 SSL cipher available: 30:PSK-AES256-GCM-SHA384
[4]:20210728 153314.435 SSL cipher available: 31:PSK-CHACHA20-POLY1305
[4]:20210728 153314.435 SSL cipher available: 32:RSA-PSK-AES128-GCM-SHA256
[4]:20210728 153314.435 SSL cipher available: 33:DHE-PSK-AES128-GCM-SHA256
[4]:20210728 153314.435 SSL cipher available: 34:AES128-GCM-SHA256
[4]:20210728 153314.435 SSL cipher available: 35:PSK-AES128-GCM-SHA256
[4]:20210728 153314.435 SSL cipher available: 36:AES256-SHA256
[4]:20210728 153314.435 SSL cipher available: 37:AES128-SHA256
[4]:20210728 153314.435 SSL cipher available: 38:ECDHE-PSK-AES256-CBC-SHA384
[4]:20210728 153314.435 SSL cipher available: 39:ECDHE-PSK-AES256-CBC-SHA
[4]:20210728 153314.435 SSL cipher available: 40:SRP-RSA-AES-256-CBC-SHA
[4]:20210728 153314.435 SSL cipher available: 41:SRP-AES-256-CBC-SHA
[4]:20210728 153314.435 SSL cipher available: 42:RSA-PSK-AES256-CBC-SHA384
[4]:20210728 153314.435 SSL cipher available: 43:DHE-PSK-AES256-CBC-SHA384
[4]:20210728 153314.435 SSL cipher available: 44:RSA-PSK-AES256-CBC-SHA
[4]:20210728 153314.435 SSL cipher available: 45:DHE-PSK-AES256-CBC-SHA
[4]:20210728 153314.435 SSL cipher available: 46:AES256-SHA
[4]:20210728 153314.435 SSL cipher available: 47:PSK-AES256-CBC-SHA384
[4]:20210728 153314.435 SSL cipher available: 48:PSK-AES256-CBC-SHA
[4]:20210728 153314.435 SSL cipher available: 49:ECDHE-PSK-AES128-CBC-SHA256
[4]:20210728 153314.435 SSL cipher available: 50:ECDHE-PSK-AES128-CBC-SHA
[4]:20210728 153314.436 SSL cipher available: 51:SRP-RSA-AES-128-CBC-SHA
[4]:20210728 153314.436 SSL cipher available: 52:SRP-AES-128-CBC-SHA
[4]:20210728 153314.436 SSL cipher available: 53:RSA-PSK-AES128-CBC-SHA256
[4]:20210728 153314.436 SSL cipher available: 54:DHE-PSK-AES128-CBC-SHA256
[4]:20210728 153314.436 SSL cipher available: 55:RSA-PSK-AES128-CBC-SHA
[4]:20210728 153314.436 SSL cipher available: 56:DHE-PSK-AES128-CBC-SHA
[4]:20210728 153314.436 SSL cipher available: 57:AES128-SHA
[4]:20210728 153314.436 SSL cipher available: 58:PSK-AES128-CBC-SHA256
[4]:20210728 153314.436 SSL cipher available: 59:PSK-AES128-CBC-SHA
[3]:20210728 153314.436 (2953462848) (0)> MQTTAsync_isConnected:1316
[1]:20210728 153314.436 Allocating 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/SSLSocket.c line 737 ptr 0xaf507d68

[3]:20210728 153314.436 (2889290816)     (4)> MQTTStrncpy:933
[3]:20210728 153314.436 the src string is truncated
[3]:20210728 153314.436 (2889290816)     (4)< MQTTStrncpy:943
[1]:20210728 153314.436 Freeing 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/SSLSocket.c line 747, heap use now 1680 bytes

[3]:20210728 153314.436 (2889290816)    (3)< SSLSocket_setSocketForSSL:753 (1)
[3]:20210728 153314.436 (2889290816)    (3)> SSLSocket_connect:764
[4]:20210728 153314.436 SSL handshake started write:unknown:unknown
[4]:20210728 153314.436 SSL state connect:before SSL initialization:(NONE)
[3]:20210728 153314.436 sent 0 256 buflen 5
[3]:20210728 153314.769 sent 772 22 buflen 311
[4]:20210728 153314.769 SSL state connect:SSLv3/TLS write client hello:(NONE)
[4]:20210728 153314.769 SSL connect:SSLv3/TLS write client hello
[3]:20210728 153314.769 (2889290816)     (4)> SSLSocket_error:101
[3]:20210728 153314.769 SSLSocket error WANT_READ/WANT_WRITE
[3]:20210728 153314.769 (2889290816)     (4)< SSLSocket_error:121 (2)
[3]:20210728 153314.769 (2889290816)    (3)< SSLSocket_connect:822 (-22)
[3]:20210728 153314.769 (2889290816)   (2)< MQTTAsync_connecting:2866 (0)
[3]:20210728 153314.769 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153314.769 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153314.769 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153314.769 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153314.769 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153314.769 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153314.769 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153314.769 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153314.769 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153314.769 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153314.769 (2961851456) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153314.769 (2961851456) (0)< MQTTAsync_isConnected:1321 (0)
[1]:20210728 153314.862 Return code 1 from read select
[3]:20210728 153314.862 (2889290816)    (3)> Socket_continueWrites:995
[3]:20210728 153314.862 (2889290816)    (3)< Socket_continueWrites:1025 (0)
[1]:20210728 153314.862 Return code 1 from write select
[3]:20210728 153314.862 (2889290816)    (3)> isReady:226
[3]:20210728 153314.862 (2889290816)    (3)< isReady:231 (1)
[3]:20210728 153314.862 (2889290816)   (2)< Socket_getReadySocket:328 (19)
[3]:20210728 153314.862 m->c->connect_state = 2
[3]:20210728 153314.862 (2889290816)   (2)> MQTTAsync_connecting:2675
[3]:20210728 153314.862 (2889290816)    (3)> SSLSocket_connect:764
[3]:20210728 153314.862 received 0 256 buflen 5
[4]:20210728 153314.862 SSL state connect:SSLv3/TLS write client hello:(NONE)
[3]:20210728 153314.862 received 772 22 buflen 61
[3]:20210728 153314.862 received 0 256 buflen 5
[4]:20210728 153314.862 SSL connect:SSLv3/TLS read server hello
[3]:20210728 153314.862 (2889290816)     (4)> SSLSocket_error:101
[3]:20210728 153314.862 SSLSocket error WANT_READ/WANT_WRITE
[3]:20210728 153314.862 (2889290816)     (4)< SSLSocket_error:121 (2)
[3]:20210728 153314.862 (2889290816)    (3)< SSLSocket_connect:822 (-22)
[3]:20210728 153314.862 (2889290816)   (2)< MQTTAsync_connecting:2866 (-22)
[3]:20210728 153314.864 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153314.864 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153314.864 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153314.864 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153314.864 (2889290816)  (1)< MQTTAsync_cycle:3029 (-22)
[3]:20210728 153314.864 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153314.864 (2889290816)   (2)> Socket_getReadySocket:252
[1]:20210728 153314.864 Return code 1 from read select
[3]:20210728 153314.864 (2889290816)    (3)> Socket_continueWrites:995
[3]:20210728 153314.864 (2889290816)    (3)< Socket_continueWrites:1025 (0)
[1]:20210728 153314.864 Return code 1 from write select
[3]:20210728 153314.864 (2889290816)    (3)> isReady:226
[3]:20210728 153314.864 (2889290816)    (3)< isReady:231 (1)
[3]:20210728 153314.864 (2889290816)   (2)< Socket_getReadySocket:328 (19)
[3]:20210728 153314.864 m->c->connect_state = 2
[3]:20210728 153314.864 (2889290816)   (2)> MQTTAsync_connecting:2675
[3]:20210728 153314.864 (2889290816)    (3)> SSLSocket_connect:764
[4]:20210728 153314.864 SSL state connect:SSLv3/TLS read server hello:(NONE)
[3]:20210728 153314.864 received 771 22 buflen 1704
[3]:20210728 153314.864 received 0 256 buflen 5
[4]:20210728 153314.866 SSL state connect:SSLv3/TLS read server certificate:(NONE)
[3]:20210728 153314.866 received 771 22 buflen 333
[3]:20210728 153314.866 received 0 256 buflen 5
[4]:20210728 153314.866 SSL state connect:SSLv3/TLS read server key exchange:(NONE)
[3]:20210728 153314.866 received 771 22 buflen 42
[4]:20210728 153314.866 SSL state connect:SSLv3/TLS read server certificate request:(NONE)
[3]:20210728 153314.866 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153314.866 (2923426880)  (1)< Thread_wait_cond:429 (110)
[3]:20210728 153314.866 received 771 22 buflen 4
[3]:20210728 153314.866 (2923426880)  (1)> MQTTAsync_checkTimeouts:1659
[4]:20210728 153314.866 SSL state connect:SSLv3/TLS read server done:(NONE)
[3]:20210728 153314.866 sent 0 256 buflen 5
[3]:20210728 153314.866 sent 771 22 buflen 1693
[4]:20210728 153314.866 SSL state connect:SSLv3/TLS write client certificate:(NONE)
[3]:20210728 153314.866 sent 0 256 buflen 5
[3]:20210728 153314.866 sent 771 22 buflen 70
[4]:20210728 153314.866 SSL state connect:SSLv3/TLS write client key exchange:(NONE)
[3]:20210728 153314.866 sent 0 256 buflen 5
[3]:20210728 153314.866 sent 771 22 buflen 264
[4]:20210728 153314.866 SSL state connect:SSLv3/TLS write certificate verify:(NONE)
[3]:20210728 153315.240 sent 0 256 buflen 5
[3]:20210728 153315.240 sent 771 20 buflen 1
[4]:20210728 153315.240 SSL state connect:SSLv3/TLS write change cipher spec:ECDHE-RSA-AES256-GCM-SHA384
[3]:20210728 153315.240 sent 0 256 buflen 5
[3]:20210728 153315.240 sent 771 22 buflen 16
[4]:20210728 153315.240 SSL state connect:SSLv3/TLS write finished:ECDHE-RSA-AES256-GCM-SHA384
[4]:20210728 153315.240 SSL connect:SSLv3/TLS write finished
[3]:20210728 153315.240 (2889290816)     (4)> SSLSocket_error:101
[3]:20210728 153315.240 SSLSocket error WANT_READ/WANT_WRITE
[3]:20210728 153315.240 (2889290816)     (4)< SSLSocket_error:121 (2)
[3]:20210728 153315.240 (2889290816)    (3)< SSLSocket_connect:822 (-22)
[3]:20210728 153315.240 (2889290816)   (2)< MQTTAsync_connecting:2866 (-22)
[3]:20210728 153315.240 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153315.240 (2889290816)    (3)> MQTTProtocol_keepalive:664
[3]:20210728 153315.240 (2889290816)    (3)< MQTTProtocol_keepalive:716
[3]:20210728 153315.240 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153315.240 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153315.240 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153315.240 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153315.240 (2923426880)  (1)< MQTTAsync_checkTimeouts:1747
[3]:20210728 153315.242 (2923426880)  (1)> Thread_wait_cond:414
[3]:20210728 153315.242 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153315.242 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153315.242 (2889290816)  (1)< MQTTAsync_cycle:3029 (-22)
[3]:20210728 153315.242 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153315.242 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153315.242 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153315.242 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[1]:20210728 153315.242 Return code 1 from read select
[3]:20210728 153315.242 (2889290816)    (3)> Socket_continueWrites:995
[3]:20210728 153315.242 (2889290816)    (3)< Socket_continueWrites:1025 (0)
[1]:20210728 153315.242 Return code 1 from write select
[3]:20210728 153315.242 (2889290816)    (3)> isReady:226
[3]:20210728 153315.242 (2889290816)    (3)< isReady:231 (1)
[3]:20210728 153315.242 (2889290816)   (2)< Socket_getReadySocket:328 (19)
[3]:20210728 153315.242 m->c->connect_state = 2
[3]:20210728 153315.242 (2889290816)   (2)> MQTTAsync_connecting:2675
[3]:20210728 153315.242 (2889290816)    (3)> SSLSocket_connect:764
[3]:20210728 153315.242 received 0 256 buflen 5
[4]:20210728 153315.242 SSL state connect:SSLv3/TLS write finished:ECDHE-RSA-AES256-GCM-SHA384
[3]:20210728 153315.330 received 771 22 buflen 1066
[3]:20210728 153315.330 received 0 256 buflen 5
[4]:20210728 153315.330 SSL state connect:SSLv3/TLS read server session ticket:ECDHE-RSA-AES256-GCM-SHA384
[3]:20210728 153315.330 received 0 256 buflen 5
[4]:20210728 153315.330 SSL state connect:SSLv3/TLS read change cipher spec:ECDHE-RSA-AES256-GCM-SHA384
[3]:20210728 153315.330 received 771 22 buflen 16
[4]:20210728 153315.330 SSL state connect:SSLv3/TLS read finished:ECDHE-RSA-AES256-GCM-SHA384
[4]:20210728 153315.330 SSL handshake done write:unknown:unknown
[4]:20210728 153315.330 SSL certificate verification: X509_V_OK
[4]:20210728 153315.330 SSL connect:SSL negotiation finished successfully
[3]:20210728 153315.330 (2889290816)    (3)< SSLSocket_connect:822 (1)
[3]:20210728 153315.330 (2889290816)    (3)> MQTTPacket_send_connect:55
[1]:20210728 153315.330 Allocating 160 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/MQTTPacketOut.c line 73 ptr 0xaf501480

[3]:20210728 153315.330 (2889290816)     (4)> MQTTPacket_send:198
[3]:20210728 153315.330 (2889290816)      (5)> MQTTPacket_encode:301
[3]:20210728 153315.330 (2889290816)      (5)< MQTTPacket_encode:314 (2)
[1]:20210728 153315.330 Allocating 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/MQTTPacket.c line 200 ptr 0xaf507d50

[3]:20210728 153315.330 (2889290816)      (5)> MQTTPacket_encode:301
[3]:20210728 153315.330 (2889290816)      (5)< MQTTPacket_encode:314 (2)
[3]:20210728 153315.330 (2889290816)      (5)> WebSocket_putdatas:949
[3]:20210728 153315.331 (2889290816)       (6)> SSLSocket_putdatas:967
[1]:20210728 153315.331 Allocating 160 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/SSLSocket.c line 972 ptr 0xaf51e608

[3]:20210728 153315.331 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153315.331 sent 0 256 buflen 5
[1]:20210728 153315.331 Freeing 160 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/SSLSocket.c line 1022, heap use now 2000 bytes

[3]:20210728 153315.331 (2889290816)       (6)< SSLSocket_putdatas:1037 (0)
[3]:20210728 153315.331 (2889290816)      (5)< WebSocket_putdatas:980 (0)
[1]:20210728 153315.331 Freeing 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/MQTTPacket.c line 230, heap use now 1840 bytes

[3]:20210728 153315.331 (2889290816)     (4)< MQTTPacket_send:233 (0)
[4]:20210728 153315.331 19 d1cd25a8b3951e16 -> CONNECT version 4 clean: 0 (0)
[1]:20210728 153315.331 Freeing 160 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/MQTTPacketOut.c line 127, heap use now 1824 bytes

[3]:20210728 153315.331 (2889290816)    (3)< MQTTPacket_send_connect:129 (0)
[3]:20210728 153315.331 (2889290816)   (2)< MQTTAsync_connecting:2866 (0)
[3]:20210728 153315.331 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153315.331 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153315.331 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153315.331 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153315.331 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153315.331 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153315.331 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153315.663 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153315.663 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153315.663 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153315.663 (2961851456) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153315.663 (2961851456) (0)< MQTTAsync_isConnected:1321 (0)
[1]:20210728 153315.663 Return code 1 from read select
[3]:20210728 153315.663 (2889290816)    (3)> Socket_continueWrites:995
[3]:20210728 153315.663 (2889290816)    (3)< Socket_continueWrites:1025 (0)
[1]:20210728 153315.663 Return code 1 from write select
[3]:20210728 153315.663 (2889290816)    (3)> isReady:226
[3]:20210728 153315.663 (2889290816)    (3)< isReady:231 (1)
[3]:20210728 153315.663 (2889290816)   (2)< Socket_getReadySocket:328 (19)
[3]:20210728 153315.663 m->c->connect_state = 4
[3]:20210728 153315.663 (2889290816)   (2)> MQTTPacket_Factory:112
[3]:20210728 153315.663 (2889290816)    (3)> WebSocket_getch:596
[3]:20210728 153315.663 (2889290816)     (4)> SSLSocket_getch:838
[3]:20210728 153315.663 (2889290816)      (5)> SocketBuffer_getQueuedChar:223
[3]:20210728 153315.663 (2889290816)      (5)< SocketBuffer_getQueuedChar:242 (-22)
[3]:20210728 153315.663 received 0 256 buflen 5
[3]:20210728 153315.663 (2889290816)      (5)> SocketBuffer_queueChar:308
[1]:20210728 153315.759 queueChar: index is now 1, headerlen 1
[3]:20210728 153315.759 (2889290816)      (5)< SocketBuffer_queueChar:333
[3]:20210728 153315.759 (2889290816)     (4)< SSLSocket_getch:860 (0)
[3]:20210728 153315.759 (2889290816)    (3)< WebSocket_getch:633 (0)
[3]:20210728 153315.759 (2889290816)    (3)> MQTTPacket_decode:333
[3]:20210728 153315.759 (2889290816)     (4)> WebSocket_getch:596
[3]:20210728 153315.759 (2889290816)      (5)> SSLSocket_getch:838
[3]:20210728 153315.759 (2889290816)       (6)> SocketBuffer_getQueuedChar:223
[3]:20210728 153315.759 (2889290816)       (6)< SocketBuffer_getQueuedChar:242 (-22)
[3]:20210728 153315.759 (2889290816)       (6)> SocketBuffer_queueChar:308
[1]:20210728 153315.759 queueChar: index is now 2, headerlen 2
[3]:20210728 153315.759 (2889290816)       (6)< SocketBuffer_queueChar:333
[3]:20210728 153315.759 (2889290816)      (5)< SSLSocket_getch:860 (0)
[3]:20210728 153315.759 (2889290816)     (4)< WebSocket_getch:633 (0)
[3]:20210728 153315.759 (2889290816)    (3)< MQTTPacket_decode:349 (0)
[3]:20210728 153315.759 (2889290816)    (3)> WebSocket_getdata:677
[3]:20210728 153315.759 (2889290816)     (4)> SSLSocket_getdata:878
[3]:20210728 153315.759 (2889290816)      (5)> SocketBuffer_getQueuedData:180
[3]:20210728 153315.759 (2889290816)      (5)< SocketBuffer_getQueuedData:208
[3]:20210728 153315.759 (2889290816)      (5)> SocketBuffer_complete:283
[3]:20210728 153315.759 (2889290816)      (5)< SocketBuffer_complete:293
[3]:20210728 153315.759 (2889290816)     (4)< SSLSocket_getdata:921
[3]:20210728 153315.759 (2889290816)    (3)< WebSocket_getdata:756 (-1320758320)
[3]:20210728 153315.759 (2889290816)    (3)> MQTTPacket_connack:148
[1]:20210728 153315.759 Allocating 32 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/MQTTPacketOut.c line 149 ptr 0xaf51ebb0

[3]:20210728 153315.759 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153315.759 (2889290816)    (3)< MQTTPacket_connack:176
[3]:20210728 153315.759 (2889290816)   (2)< MQTTPacket_Factory:176 (0)
[3]:20210728 153315.759 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153315.759 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153315.759 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153315.759 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153315.759 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153315.759 (2889290816)  (1)> MQTTAsync_completeConnection:1916
[4]:20210728 153315.759 19 d1cd25a8b3951e16 <- CONNACK rc: 0
[3]:20210728 153315.759 (2889290816)   (2)> Thread_signal_cond:396
[3]:20210728 153315.759 (2889290816)   (2)< Thread_signal_cond:401 (0)
[3]:20210728 153315.759 (2889290816)  (1)< MQTTAsync_completeConnection:1955 (0)
[3]:20210728 153315.759 Calling connected for client d1cd25a8b3951e16
>>>>>>>>void onMqttConnected(void * context, char * cause)
>>>>>>>>int CWeconMqttClient::onConnectCallback(int nToken, int nCode, const string & sMessage) 1111
[3]:20210728 153315.759 (2889290816)  (1)> MQTTPacket_freeConnack:187
int CWeconMqttClient::_reconnect() 5555
[1]:20210728 153316.091 Freeing 32 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/MQTTPacketOut.c line 190, heap use now 1696 bytes

[3]:20210728 153316.091 (2880902208) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153316.091 (2923426880)  (1)< Thread_wait_cond:429 (0)
[3]:20210728 153316.091 (2923426880)  (1)> MQTTAsync_checkTimeouts:1659
[3]:20210728 153316.091 (2889290816)  (1)< MQTTPacket_freeConnack:191
[3]:20210728 153316.091 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153316.091 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153316.091 (2984244288) (0)< MQTTAsync_isConnected:1321 (1)
[3]:20210728 153316.091 (2880902208) (0)< MQTTAsync_isConnected:1321 (1)
int CWeconMqttClient::_reconnect() 6666

the 4th one full log.

int CWeconMqttClient::_reconnect() 1111
int CWeconMqttClient::_reconnect() 2222
[3]:20210728 153346.505 (2880902208) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153346.505 (2880902208) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153346.668 (2880902208) (0)> MQTTAsync_reconnect:918
[1]:20210728 153346.668 Allocating 96 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/MQTTAsync.c line 938 ptr 0xb3900ce8

[3]:20210728 153346.668 (2880902208)  (1)> MQTTAsync_addCommand:791
[1]:20210728 153346.668 Allocating 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/LinkedList.c line 109 ptr 0xb3900cc0

[3]:20210728 153346.668 (2880902208)   (2)> Thread_signal_cond:396
[3]:20210728 153346.668 (2880902208)   (2)< Thread_signal_cond:401 (0)
[3]:20210728 153346.668 (2880902208)  (1)< MQTTAsync_addCommand:912 (0)
[3]:20210728 153346.668 (2880902208) (0)< MQTTAsync_reconnect:955 (0)
int CWeconMqttClient::_reconnect() 4444
[3]:20210728 153346.668 (2923426880)  (1)< Thread_wait_cond:429 (0)
[3]:20210728 153346.668 (2923426880)  (1)> MQTTAsync_checkTimeouts:1659
[3]:20210728 153346.668 (2923426880)  (1)< MQTTAsync_checkTimeouts:1747
[3]:20210728 153346.668 (2923426880)  (1)> MQTTAsync_processCommand:1180
[1]:20210728 153346.668 Allocating 32 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/LinkedList.c line 54 ptr 0xe8e0e0

[1]:20210728 153346.668 Freeing 32 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/LinkedList.c line 400, heap use now 1776 bytes

[1]:20210728 153346.668 Freeing 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/LinkedList.c line 229, heap use now 1744 bytes

[4]:20210728 153346.668 Connecting to serverURI mqtt.v-box.net:9883 with MQTT version 4
[3]:20210728 153346.668 (2923426880)   (2)> MQTTProtocol_connect:218
[3]:20210728 153346.668 (2923426880)    (3)> MQTTProtocol_addressPort:60
[3]:20210728 153346.668 (2923426880)    (3)< MQTTProtocol_addressPort:92
[3]:20210728 153346.668 (2923426880)    (3)> Socket_new:696
[1]:20210728 153346.669 Allocating 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/Socket.c line 706 ptr 0xe74e58

[3]:20210728 153346.669 (2961851456) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153346.669 (2889290816)   (2)< MQTTAsync_sleep:110
udhcpc: started, v1.29.3
udhcpc: sending discover
{"Type":"Pong","Payload":{}}
udhcpc: sending discover
udhcpc: sending discover
[3]:20210728 153346.669 New socket 19 for mqtt.v-box.net:9883, port 9883
[3]:20210728 153346.669 (2923426880)     (4)> Socket_addSocket:174
[1]:20210728 153346.669 Allocating 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/Socket.c line 184 ptr 0xe90d68

[1]:20210728 153346.669 Allocating 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/LinkedList.c line 92 ptr 0xe8ee58

[3]:20210728 153346.669 (2923426880)      (5)> Socket_setnonblocking:86
[3]:20210728 153346.669 (2923426880)      (5)< Socket_setnonblocking:91 (0)
[3]:20210728 153346.669 (2923426880)     (4)< Socket_addSocket:209 (0)
[1]:20210728 153346.669 Allocating 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/Socket.c line 821 ptr 0xede2d0

[1]:20210728 153346.669 Allocating 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/LinkedList.c line 92 ptr 0xe4c7c0

[3]:20210728 153346.669 Connect pending
[1]:20210728 153346.669 Freeing 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/Socket.c line 850, heap use now 1808 bytes

[3]:20210728 153346.669 (2923426880)    (3)< Socket_new:852 (115)
[3]:20210728 153346.669 (2923426880)   (2)< MQTTProtocol_connect:340 (115)
[3]:20210728 153346.669 (2923426880)   (2)> MQTTProperties_free:401
[3]:20210728 153346.669 (2923426880)   (2)< MQTTProperties_free:424
[1]:20210728 153346.669 Freeing 96 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/MQTTAsyncUtils.c line 1058, heap use now 1792 bytes

[3]:20210728 153346.669 (2923426880)  (1)< MQTTAsync_processCommand:1571 (1)
[3]:20210728 153357.679 (2923426880)  (1)> Thread_wait_cond:414
[3]:20210728 153357.679 (2961851456) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153357.679 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153357.679 (2889290816)    (3)> MQTTProtocol_keepalive:664
[3]:20210728 153357.679 (2889290816)    (3)< MQTTProtocol_keepalive:716
[3]:20210728 153357.679 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153357.679 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153357.679 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153357.679 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153357.679 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153357.679 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153357.679 (2961851456) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153357.679 (2961851456) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153357.679 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153357.679 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[1]:20210728 153357.679 Return code 1 from read select
[3]:20210728 153357.679 (2889290816)    (3)> Socket_continueWrites:995
[3]:20210728 153357.679 (2889290816)    (3)< Socket_continueWrites:1025 (0)
[1]:20210728 153357.679 Return code 1 from write select
[3]:20210728 153357.679 (2889290816)    (3)> isReady:226
[1]:20210728 153357.758 Freeing 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/LinkedList.c line 224, heap use now 1696 bytes

[1]:20210728 153357.758 Freeing 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/LinkedList.c line 229, heap use now 1680 bytes

[3]:20210728 153357.758 (2889290816)    (3)< isReady:231 (1)
[3]:20210728 153357.758 (2889290816)   (2)< Socket_getReadySocket:328 (19)
[3]:20210728 153357.758 m->c->connect_state = 1
[3]:20210728 153357.758 (2889290816)   (2)> MQTTAsync_connecting:2675
[3]:20210728 153357.758 (2889290816)    (3)> MQTTProtocol_addressPort:60
[3]:20210728 153357.758 (2889290816)    (3)< MQTTProtocol_addressPort:92
[3]:20210728 153357.758 (2889290816)    (3)> SSLSocket_setSocketForSSL:709
[3]:20210728 153357.758 (2889290816)     (4)> SSLSocket_createContext:550
[3]:20210728 153357.758 (2889290816)     (4)< SSLSocket_createContext:699 (1)
[4]:20210728 153357.758 SSL cipher available: 0:TLS_AES_256_GCM_SHA384
[4]:20210728 153357.758 SSL cipher available: 1:TLS_CHACHA20_POLY1305_SHA256
[4]:20210728 153357.758 SSL cipher available: 2:TLS_AES_128_GCM_SHA256
[4]:20210728 153357.758 SSL cipher available: 3:ECDHE-ECDSA-AES256-GCM-SHA384
[4]:20210728 153357.758 SSL cipher available: 4:ECDHE-RSA-AES256-GCM-SHA384
[4]:20210728 153357.758 SSL cipher available: 5:DHE-RSA-AES256-GCM-SHA384
[4]:20210728 153357.758 SSL cipher available: 6:ECDHE-ECDSA-CHACHA20-POLY1305
[4]:20210728 153357.758 SSL cipher available: 7:ECDHE-RSA-CHACHA20-POLY1305
[4]:20210728 153357.758 SSL cipher available: 8:DHE-RSA-CHACHA20-POLY1305
[4]:20210728 153357.761 SSL cipher available: 9:ECDHE-ECDSA-AES128-GCM-SHA256
[4]:20210728 153357.761 SSL cipher available: 10:ECDHE-RSA-AES128-GCM-SHA256
[4]:20210728 153357.761 SSL cipher available: 11:DHE-RSA-AES128-GCM-SHA256
[4]:20210728 153357.761 SSL cipher available: 12:ECDHE-ECDSA-AES256-SHA384
[4]:20210728 153357.761 SSL cipher available: 13:ECDHE-RSA-AES256-SHA384
[4]:20210728 153357.761 SSL cipher available: 14:DHE-RSA-AES256-SHA256
[4]:20210728 153357.761 SSL cipher available: 15:ECDHE-ECDSA-AES128-SHA256
[4]:20210728 153357.761 SSL cipher available: 16:ECDHE-RSA-AES128-SHA256
[4]:20210728 153357.761 SSL cipher available: 17:DHE-RSA-AES128-SHA256
[4]:20210728 153357.761 SSL cipher available: 18:ECDHE-ECDSA-AES256-SHA
[4]:20210728 153357.761 SSL cipher available: 19:ECDHE-RSA-AES256-SHA
[4]:20210728 153357.761 SSL cipher available: 20:DHE-RSA-AES256-SHA
[4]:20210728 153357.761 SSL cipher available: 21:ECDHE-ECDSA-AES128-SHA
[4]:20210728 153357.761 SSL cipher available: 22:ECDHE-RSA-AES128-SHA
[4]:20210728 153357.761 SSL cipher available: 23:DHE-RSA-AES128-SHA
[4]:20210728 153357.761 SSL cipher available: 24:RSA-PSK-AES256-GCM-SHA384
[4]:20210728 153357.761 SSL cipher available: 25:DHE-PSK-AES256-GCM-SHA384
[4]:20210728 153357.761 SSL cipher available: 26:RSA-PSK-CHACHA20-POLY1305
[4]:20210728 153357.761 SSL cipher available: 27:DHE-PSK-CHACHA20-POLY1305
[4]:20210728 153357.761 SSL cipher available: 28:ECDHE-PSK-CHACHA20-POLY1305
[4]:20210728 153358.094 SSL cipher available: 29:AES256-GCM-SHA384
[4]:20210728 153358.094 SSL cipher available: 30:PSK-AES256-GCM-SHA384
[4]:20210728 153358.094 SSL cipher available: 31:PSK-CHACHA20-POLY1305
[4]:20210728 153358.094 SSL cipher available: 32:RSA-PSK-AES128-GCM-SHA256
[4]:20210728 153358.094 SSL cipher available: 33:DHE-PSK-AES128-GCM-SHA256
[4]:20210728 153358.094 SSL cipher available: 34:AES128-GCM-SHA256
[4]:20210728 153358.094 SSL cipher available: 35:PSK-AES128-GCM-SHA256
[4]:20210728 153358.094 SSL cipher available: 36:AES256-SHA256
[4]:20210728 153358.094 SSL cipher available: 37:AES128-SHA256
[4]:20210728 153358.094 SSL cipher available: 38:ECDHE-PSK-AES256-CBC-SHA384
[4]:20210728 153358.094 SSL cipher available: 39:ECDHE-PSK-AES256-CBC-SHA
[4]:20210728 153358.094 SSL cipher available: 40:SRP-RSA-AES-256-CBC-SHA
[4]:20210728 153358.094 SSL cipher available: 41:SRP-AES-256-CBC-SHA
[4]:20210728 153358.094 SSL cipher available: 42:RSA-PSK-AES256-CBC-SHA384
[4]:20210728 153358.094 SSL cipher available: 43:DHE-PSK-AES256-CBC-SHA384
[4]:20210728 153358.094 SSL cipher available: 44:RSA-PSK-AES256-CBC-SHA
[4]:20210728 153358.094 SSL cipher available: 45:DHE-PSK-AES256-CBC-SHA
[4]:20210728 153358.094 SSL cipher available: 46:AES256-SHA
[4]:20210728 153358.094 SSL cipher available: 47:PSK-AES256-CBC-SHA384
[4]:20210728 153358.094 SSL cipher available: 48:PSK-AES256-CBC-SHA
[4]:20210728 153358.094 SSL cipher available: 49:ECDHE-PSK-AES128-CBC-SHA256
[4]:20210728 153358.094 SSL cipher available: 50:ECDHE-PSK-AES128-CBC-SHA
[4]:20210728 153358.094 SSL cipher available: 51:SRP-RSA-AES-128-CBC-SHA
[4]:20210728 153358.094 SSL cipher available: 52:SRP-AES-128-CBC-SHA
[4]:20210728 153358.094 SSL cipher available: 53:RSA-PSK-AES128-CBC-SHA256
[4]:20210728 153358.094 SSL cipher available: 54:DHE-PSK-AES128-CBC-SHA256
[4]:20210728 153358.094 SSL cipher available: 55:RSA-PSK-AES128-CBC-SHA
[4]:20210728 153358.094 SSL cipher available: 56:DHE-PSK-AES128-CBC-SHA
[4]:20210728 153358.094 SSL cipher available: 57:AES128-SHA
[4]:20210728 153358.094 SSL cipher available: 58:PSK-AES128-CBC-SHA256
[4]:20210728 153358.094 SSL cipher available: 59:PSK-AES128-CBC-SHA
[3]:20210728 153358.094 (3033527360) (0)> MQTTAsync_isConnected:1316
[1]:20210728 153358.094 Allocating 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/SSLSocket.c line 737 ptr 0xaf508a00

[3]:20210728 153358.094 (2889290816)     (4)> MQTTStrncpy:933
[3]:20210728 153358.094 the src string is truncated
[3]:20210728 153358.094 (2889290816)     (4)< MQTTStrncpy:943
[1]:20210728 153358.094 Freeing 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/SSLSocket.c line 747, heap use now 1680 bytes

[3]:20210728 153358.094 (2889290816)    (3)< SSLSocket_setSocketForSSL:753 (1)
[3]:20210728 153358.094 (2889290816)    (3)> SSLSocket_connect:764
[4]:20210728 153358.094 SSL handshake started write:unknown:unknown
[4]:20210728 153358.427 SSL state connect:before SSL initialization:(NONE)
[3]:20210728 153358.427 sent 0 256 buflen 5
[3]:20210728 153358.427 sent 772 22 buflen 311
[4]:20210728 153358.427 SSL state connect:SSLv3/TLS write client hello:(NONE)
[4]:20210728 153358.427 SSL connect:SSLv3/TLS write client hello
[3]:20210728 153358.427 (2889290816)     (4)> SSLSocket_error:101
[3]:20210728 153358.427 SSLSocket error WANT_READ/WANT_WRITE
[3]:20210728 153358.427 (2889290816)     (4)< SSLSocket_error:121 (2)
[3]:20210728 153358.427 (2889290816)    (3)< SSLSocket_connect:822 (-22)
[3]:20210728 153358.427 (2889290816)   (2)< MQTTAsync_connecting:2866 (0)
[3]:20210728 153358.427 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153358.427 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153358.427 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153358.427 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153358.427 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153358.427 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153358.427 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153358.427 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153358.427 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153358.427 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153358.478 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153358.478 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[1]:20210728 153358.478 Return code 1 from read select
[3]:20210728 153358.478 (2889290816)    (3)> Socket_continueWrites:995
[3]:20210728 153358.478 (2889290816)    (3)< Socket_continueWrites:1025 (0)
[1]:20210728 153358.478 Return code 1 from write select
[3]:20210728 153358.478 (2889290816)    (3)> isReady:226
[3]:20210728 153358.478 (2889290816)    (3)< isReady:231 (1)
[3]:20210728 153358.478 (2889290816)   (2)< Socket_getReadySocket:328 (19)
[3]:20210728 153358.478 m->c->connect_state = 2
[3]:20210728 153358.478 (2889290816)   (2)> MQTTAsync_connecting:2675
[3]:20210728 153358.478 (2889290816)    (3)> SSLSocket_connect:764
[3]:20210728 153358.478 received 0 256 buflen 5
[4]:20210728 153358.478 SSL state connect:SSLv3/TLS write client hello:(NONE)
[3]:20210728 153358.478 received 772 22 buflen 61
[3]:20210728 153358.478 received 0 256 buflen 5
[4]:20210728 153358.478 SSL connect:SSLv3/TLS read server hello
[3]:20210728 153358.478 (2889290816)     (4)> SSLSocket_error:101
[3]:20210728 153358.478 SSLSocket error WANT_READ/WANT_WRITE
[3]:20210728 153358.478 (2889290816)     (4)< SSLSocket_error:121 (2)
[3]:20210728 153358.519 (2889290816)    (3)< SSLSocket_connect:822 (-22)
[3]:20210728 153358.519 (2889290816)   (2)< MQTTAsync_connecting:2866 (-22)
[3]:20210728 153358.519 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153358.519 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153358.519 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153358.519 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153358.519 (2889290816)  (1)< MQTTAsync_cycle:3029 (-22)
[3]:20210728 153358.519 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153358.519 (2889290816)   (2)> Socket_getReadySocket:252
[1]:20210728 153358.519 Return code 1 from read select
[3]:20210728 153358.519 (2889290816)    (3)> Socket_continueWrites:995
[3]:20210728 153358.519 (2889290816)    (3)< Socket_continueWrites:1025 (0)
[1]:20210728 153358.519 Return code 1 from write select
[3]:20210728 153358.519 (2889290816)    (3)> isReady:226
[3]:20210728 153358.519 (2889290816)    (3)< isReady:231 (1)
[3]:20210728 153358.519 (2889290816)   (2)< Socket_getReadySocket:328 (19)
[3]:20210728 153358.519 m->c->connect_state = 2
[3]:20210728 153358.519 (2889290816)   (2)> MQTTAsync_connecting:2675
[3]:20210728 153358.519 (2889290816)    (3)> SSLSocket_connect:764
[4]:20210728 153358.519 SSL connect:SSLv3/TLS read server hello
[3]:20210728 153358.520 (2889290816)     (4)> SSLSocket_error:101
[3]:20210728 153358.520 SSLSocket error WANT_READ/WANT_WRITE
[3]:20210728 153358.520 (2889290816)     (4)< SSLSocket_error:121 (2)
[3]:20210728 153358.520 (2889290816)    (3)< SSLSocket_connect:822 (-22)
[3]:20210728 153358.520 (2889290816)   (2)< MQTTAsync_connecting:2866 (-22)
[3]:20210728 153358.520 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153358.520 (2923426880)  (1)< Thread_wait_cond:429 (110)
[3]:20210728 153358.520 (2923426880)  (1)> MQTTAsync_checkTimeouts:1659
[3]:20210728 153358.520 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153358.520 (2889290816)    (3)> MQTTProtocol_keepalive:664
[3]:20210728 153358.520 (2889290816)    (3)< MQTTProtocol_keepalive:716
[3]:20210728 153358.520 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153358.520 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153358.520 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153358.520 (2923426880)   (2)> nextOrClose:1579
[3]:20210728 153358.520 (2923426880)    (3)> MQTTAsync_checkConn:189
[3]:20210728 153358.520 (2923426880)    (3)< MQTTAsync_checkConn:192 (0)
[3]:20210728 153358.520 (2923426880)    (3)> MQTTAsync_closeSession:2362
[3]:20210728 153358.520 (2923426880)     (4)> MQTTAsync_closeOnly:2331
[3]:20210728 153358.520 (2923426880)      (5)> MQTTProtocol_checkPendingWrites:995
[3]:20210728 153358.851 (2923426880)      (5)< MQTTProtocol_checkPendingWrites:1012
[3]:20210728 153358.851 (2923426880)      (5)> WebSocket_close:529
[3]:20210728 153358.851 (2923426880)      (5)< WebSocket_close:577
[3]:20210728 153358.851 (2923426880)      (5)> SSLSocket_close:940
[3]:20210728 153358.851 (2923426880)       (6)> SSLSocket_destroyContext:927
[3]:20210728 153358.851 (2923426880)       (6)< SSLSocket_destroyContext:931
[3]:20210728 153358.851 (2923426880)      (5)< SSLSocket_close:953 (-1)
[3]:20210728 153358.851 (2923426880)      (5)> Socket_close:636
[3]:20210728 153358.851 (2923426880)       (6)> Socket_close_only:610
[3]:20210728 153358.851 (2923426880)       (6)< Socket_close_only:624 (0)
[3]:20210728 153358.851 (2923426880)       (6)> Socket_abortWrite:961
[3]:20210728 153358.851 (2923426880)       (6)< Socket_abortWrite:979 (0)
[3]:20210728 153358.851 (2923426880)       (6)> SocketBuffer_cleanup:153
[3]:20210728 153358.851 (2923426880)       (6)< SocketBuffer_cleanup:165
[1]:20210728 153358.851 Freeing 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/LinkedList.c line 224, heap use now 1664 bytes

[1]:20210728 153358.851 Freeing 16 bytes in heap at file /opt/linxy/____applications/r5-01-2021-07-15-wmqtt/client/trunk/src/paho.mqtt.c-1.3.9/src/LinkedList.c line 229, heap use now 1648 bytes

[3]:20210728 153358.851 Removed socket 19
[1]:20210728 153358.851 Reset max fdp1 to 1
[3]:20210728 153358.851 (2923426880)      (5)< Socket_close:663
[3]:20210728 153358.851 (2923426880)     (4)< MQTTAsync_closeOnly:2356
[3]:20210728 153358.852 (2923426880)    (3)< MQTTAsync_closeSession:2369
[3]:20210728 153358.852 (2923426880)   (2)< nextOrClose:1649
[3]:20210728 153358.852 (2923426880)  (1)< MQTTAsync_checkTimeouts:1747
[3]:20210728 153358.852 (2923426880)  (1)> Thread_wait_cond:414
[3]:20210728 153358.852 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153358.852 (2889290816)  (1)< MQTTAsync_cycle:3029 (-22)
[3]:20210728 153358.852 Could not find client corresponding to socket 19
[3]:20210728 153358.852 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153358.852 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153358.852 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153358.852 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153358.852 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153358.852 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153358.852 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153358.852 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153358.852 (2961851456) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153358.852 (2961851456) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153358.852 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153358.852 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153358.852 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153358.953 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153358.953 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153358.953 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153358.953 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153358.953 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153358.953 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153358.953 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153358.953 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153358.953 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153358.953 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153358.953 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153358.953 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153358.953 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153358.953 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153358.953 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153358.953 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153358.953 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153358.953 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153358.953 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153358.953 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153359.053 (2889290816)   (2)> MQTTAsync_sleep:104
udhcpc: no lease, failing
[3]:20210728 153359.053 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.053 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
{"Type":"Pong","Payload":{}}
[3]:20210728 153359.053 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.053 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.053 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153359.053 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153359.053 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153359.053 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153359.053 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153359.053 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153359.053 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153359.053 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153359.053 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153359.053 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153359.053 (2961851456) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.053 (2961851456) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.053 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.053 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.053 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153359.254 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153359.254 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153359.254 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153359.254 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153359.254 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153359.254 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153359.254 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153359.254 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153359.254 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153359.254 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.254 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.254 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.254 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.254 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153359.254 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153359.254 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153359.254 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153359.254 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153359.254 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153359.254 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153359.354 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153359.354 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153359.354 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153359.354 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.354 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.354 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.354 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.354 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153359.354 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153359.354 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153359.354 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153359.354 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153359.354 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153359.354 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153359.354 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153359.354 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153359.354 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153359.354 (2961851456) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.354 (2961851456) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.354 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.552 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.552 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153359.552 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153359.552 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153359.552 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153359.552 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153359.552 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153359.552 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153359.552 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153359.552 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153359.552 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153359.552 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.552 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.552 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.552 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.552 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153359.552 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153359.552 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153359.552 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153359.552 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153359.655 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153359.655 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153359.655 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153359.655 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153359.655 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153359.655 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.655 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.655 (2961851456) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.655 (2961851456) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.655 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153359.655 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153359.655 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153359.655 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153359.655 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153359.655 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153359.655 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153359.655 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153359.655 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153359.655 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153359.655 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.801 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.801 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.801 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.801 (2923426880)  (1)< Thread_wait_cond:429 (110)
[3]:20210728 153359.801 (2923426880)  (1)> MQTTAsync_checkTimeouts:1659
[3]:20210728 153359.801 (2923426880)  (1)< MQTTAsync_checkTimeouts:1747
[3]:20210728 153359.801 (2923426880)  (1)> Thread_wait_cond:414
[3]:20210728 153359.801 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153359.801 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153359.801 (2889290816)    (3)> MQTTProtocol_keepalive:664
[3]:20210728 153359.801 (2889290816)    (3)< MQTTProtocol_keepalive:716
[3]:20210728 153359.801 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153359.801 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153359.801 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153359.801 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153359.801 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153359.801 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153359.801 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153359.801 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153359.801 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.901 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.901 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.901 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.901 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153359.901 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153359.901 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153359.901 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153359.901 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153359.901 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153359.901 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153359.901 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153359.901 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153359.901 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153359.901 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.901 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.901 (2961851456) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153359.901 (2961851456) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153359.901 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153359.901 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153359.901 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153400.057 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153400.057 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153400.057 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153400.057 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153400.057 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153400.057 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153400.057 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153400.057 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153400.057 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153400.057 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153400.057 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153400.057 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153400.057 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153400.057 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153400.057 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153400.057 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153400.057 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153400.057 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153400.057 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153400.057 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153400.157 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153400.157 (2961851456) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153400.157 (2961851456) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153400.157 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153400.157 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153400.157 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153400.157 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153400.157 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153400.157 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153400.157 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153400.157 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153400.157 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153400.157 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153400.157 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153400.157 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153400.157 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153400.157 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153400.157 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153400.157 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153400.157 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153400.358 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153400.358 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153400.358 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153400.358 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153400.358 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153400.358 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153400.358 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153400.358 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153400.358 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153400.358 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153400.358 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153400.358 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153400.358 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153400.358 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153400.358 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153400.358 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153400.358 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153400.358 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153400.358 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153400.358 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153400.459 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153400.459 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153400.459 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153400.459 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153400.459 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153400.459 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153400.459 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153400.459 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153400.459 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153400.459 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153400.459 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153400.459 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153400.459 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153400.459 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153400.459 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153400.459 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153400.459 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153400.459 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153400.459 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153400.459 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153400.652 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153400.652 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153400.652 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153400.652 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153400.652 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153400.652 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153400.652 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153400.652 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153400.652 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153400.652 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153400.652 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153400.652 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153400.652 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153400.652 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153400.652 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153400.652 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153400.652 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153400.652 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153400.652 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153400.652 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153400.760 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153400.760 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153400.760 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153400.760 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153400.760 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153400.760 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153400.760 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153400.760 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153400.760 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153400.760 (2923426880)  (1)< Thread_wait_cond:429 (110)
[3]:20210728 153400.760 (2923426880)  (1)> MQTTAsync_checkTimeouts:1659
[3]:20210728 153400.760 (2923426880)  (1)< MQTTAsync_checkTimeouts:1747
[3]:20210728 153400.760 (2923426880)  (1)> Thread_wait_cond:414
[3]:20210728 153400.760 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153400.760 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153400.760 (2889290816)    (3)> MQTTProtocol_keepalive:664
[3]:20210728 153400.760 (2889290816)    (3)< MQTTProtocol_keepalive:716
[3]:20210728 153400.760 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153400.760 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153400.760 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153400.860 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153400.860 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153400.860 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153400.860 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153400.860 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153400.860 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153400.860 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153400.860 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153400.860 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153400.860 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153400.860 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153400.860 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153400.860 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153400.860 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153400.860 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153400.860 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153400.860 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153400.860 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153400.860 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153400.860 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.001 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.001 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.001 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.001 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153401.001 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153401.001 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153401.001 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153401.001 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153401.001 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153401.001 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153401.001 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153401.001 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153401.001 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153401.001 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.001 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.001 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.001 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.001 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153401.001 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153401.001 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153401.161 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153401.161 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153401.161 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153401.161 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153401.161 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153401.161 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153401.161 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153401.161 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.161 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.161 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.161 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.161 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153401.161 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153401.161 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153401.161 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153401.161 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153401.161 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153401.161 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153401.161 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153401.161 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153401.262 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153401.262 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.262 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.262 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.262 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.262 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153401.262 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153401.262 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153401.262 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153401.262 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153401.262 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153401.262 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153401.262 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153401.262 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153401.262 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153401.262 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.262 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.262 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.262 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.262 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153401.463 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153401.463 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153401.463 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153401.463 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153401.463 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153401.463 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153401.463 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153401.463 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153401.463 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153401.463 (2961851456) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.463 (2961851456) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.463 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.463 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.463 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153401.463 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153401.463 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153401.463 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153401.463 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153401.463 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153401.463 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153401.563 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153401.563 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153401.563 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153401.563 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.563 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.563 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.563 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.563 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153401.563 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153401.563 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153401.563 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153401.563 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153401.563 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153401.563 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153401.563 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153401.563 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153401.563 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153401.563 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.563 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.563 (2961851456) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.752 (2961851456) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.752 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153401.752 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153401.752 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153401.752 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153401.752 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153401.752 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153401.752 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153401.752 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153401.752 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153401.752 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153401.752 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.752 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.752 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.752 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.752 (2923426880)  (1)< Thread_wait_cond:429 (110)
[3]:20210728 153401.752 (2923426880)  (1)> MQTTAsync_checkTimeouts:1659
[3]:20210728 153401.752 (2923426880)  (1)< MQTTAsync_checkTimeouts:1747
[3]:20210728 153401.752 (2923426880)  (1)> Thread_wait_cond:414
[3]:20210728 153401.752 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153401.865 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153401.865 (2889290816)    (3)> MQTTProtocol_keepalive:664
[3]:20210728 153401.865 (2889290816)    (3)< MQTTProtocol_keepalive:716
[3]:20210728 153401.865 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153401.865 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153401.865 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153401.865 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153401.865 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153401.865 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153401.865 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153401.865 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153401.865 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.865 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.865 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.865 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.865 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153401.865 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153401.865 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153401.865 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153401.865 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153401.965 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153401.965 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153401.965 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153401.965 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153401.965 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153401.965 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.965 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.965 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153401.965 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153401.965 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153401.965 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153401.965 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153401.965 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153401.965 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153401.965 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153401.965 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153401.965 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153401.965 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153401.965 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153401.965 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.101 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.101 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.101 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.101 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153402.101 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153402.101 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153402.101 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153402.101 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153402.101 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153402.101 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153402.101 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153402.101 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153402.101 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153402.101 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.101 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.101 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.101 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.101 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153402.101 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153402.101 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153402.266 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153402.266 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153402.266 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153402.266 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153402.266 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153402.266 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153402.266 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153402.266 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.266 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.266 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.266 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.266 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153402.266 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153402.266 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153402.266 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153402.266 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153402.266 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153402.266 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153402.266 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153402.266 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153402.367 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153402.367 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.367 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.367 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.367 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.367 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153402.367 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153402.367 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153402.367 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153402.367 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153402.367 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153402.367 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153402.367 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153402.367 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153402.367 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153402.367 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.367 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.367 (2961851456) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.367 (2961851456) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.367 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153402.568 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153402.568 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153402.568 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153402.568 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153402.568 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153402.568 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153402.568 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153402.568 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153402.568 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153402.568 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.568 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.568 (2961851456) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.568 (2961851456) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.568 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153402.568 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153402.568 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153402.568 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153402.568 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153402.568 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153402.568 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153402.668 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153402.668 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153402.668 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153402.668 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.668 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.668 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.668 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.668 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153402.668 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153402.668 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153402.668 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153402.668 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153402.668 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153402.668 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153402.668 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153402.668 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153402.668 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153402.668 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.668 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.668 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.851 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.851 (2923426880)  (1)< Thread_wait_cond:429 (110)
[3]:20210728 153402.851 (2923426880)  (1)> MQTTAsync_checkTimeouts:1659
[3]:20210728 153402.851 (2923426880)  (1)< MQTTAsync_checkTimeouts:1747
[3]:20210728 153402.851 (2923426880)  (1)> Thread_wait_cond:414
[3]:20210728 153402.851 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153402.851 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153402.851 (2889290816)    (3)> MQTTProtocol_keepalive:664
[3]:20210728 153402.851 (2889290816)    (3)< MQTTProtocol_keepalive:716
[3]:20210728 153402.851 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153402.851 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153402.851 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153402.851 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153402.851 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153402.851 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153402.851 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153402.851 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153402.851 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.851 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.851 (2961851456) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.951 (2961851456) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.951 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153402.951 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153402.951 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153402.951 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153402.951 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153402.951 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153402.951 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153402.951 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153402.951 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153402.951 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153402.951 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.951 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.951 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153402.951 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153402.951 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153402.951 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153402.951 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153402.951 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153402.951 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153403.070 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153403.070 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153403.070 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153403.070 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153403.070 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153403.070 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153403.070 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153403.070 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153403.070 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153403.070 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153403.070 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153403.070 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153403.070 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153403.070 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153403.070 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153403.070 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153403.070 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153403.070 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153403.070 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153403.070 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153403.202 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153403.202 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153403.202 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153403.202 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153403.202 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153403.202 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153403.202 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153403.202 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153403.202 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153403.202 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153403.202 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153403.202 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153403.202 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153403.202 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153403.202 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153403.202 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153403.202 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153403.202 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153403.202 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153403.202 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153403.371 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153403.371 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153403.371 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153403.371 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153403.371 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153403.371 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153403.371 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153403.371 (2984244288) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153403.371 (2984244288) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153403.371 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153403.371 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153403.371 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153403.371 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153403.371 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153403.371 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153403.371 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153403.371 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153403.371 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153403.371 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153403.371 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153403.472 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153403.472 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153403.472 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153403.472 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153403.472 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153403.472 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153403.472 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153403.472 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153403.472 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153403.472 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153403.472 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153403.472 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153403.472 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153403.472 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153403.472 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153403.472 (3012555840) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153403.472 (3012555840) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153403.472 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153403.472 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153403.472 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153403.673 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153403.673 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153403.673 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153403.673 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153403.673 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153403.673 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153403.673 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153403.673 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153403.673 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153403.673 (2961851456) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153403.673 (2961851456) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153403.673 (3033527360) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153403.673 (3033527360) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153403.673 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153403.673 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153403.673 (2889290816)    (3)> MQTTProtocol_retry:804
[3]:20210728 153403.673 (2889290816)    (3)< MQTTProtocol_retry:823
[3]:20210728 153403.673 (2889290816)   (2)< MQTTAsync_retry:2663
[3]:20210728 153403.673 (2889290816)  (1)< MQTTAsync_cycle:3029 (0)
[3]:20210728 153403.673 (2889290816)  (1)> MQTTAsync_cycle:2876
[3]:20210728 153403.773 (2889290816)   (2)> Socket_getReadySocket:252
[3]:20210728 153403.773 (2889290816)   (2)< Socket_getReadySocket:328 (0)
[3]:20210728 153403.773 (2889290816)   (2)> MQTTAsync_sleep:104
[3]:20210728 153403.773 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153403.773 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153403.773 (2953462848) (0)> MQTTAsync_isConnected:1316
[3]:20210728 153403.773 (2953462848) (0)< MQTTAsync_isConnected:1321 (0)
[3]:20210728 153403.773 (2923426880)  (1)< Thread_wait_cond:429 (110)
[3]:20210728 153403.773 (2923426880)  (1)> MQTTAsync_checkTimeouts:1659
[3]:20210728 153403.773 (2923426880)  (1)< MQTTAsync_checkTimeouts:1747
[3]:20210728 153403.773 (2923426880)  (1)> Thread_wait_cond:414
[3]:20210728 153403.773 (2889290816)   (2)< MQTTAsync_sleep:110
[3]:20210728 153403.773 (2889290816)   (2)> MQTTAsync_retry:2653
[3]:20210728 153403.773 (2889290816)    (3)> MQTTProtocol_keepalive:66

Thanks. linxy.

icraggs commented 3 years ago

Please don't paste long log contents into issues - attach them as files - it makes the issues hard to read. The issue template requests this.

You don't show where you set the callbacks. There isn't a reconnect callback is there? There is connectionLost() and connected() - do you mean one of those? Are you using automatic reconnect?

linxingyang commented 3 years ago

Thank you for reply and sorry for that.

reconnect-3.log reconnect-4.log


You don't show where you set the callbacks. There isn't a reconnect callback is there? There is connectionLost() and connected() - do you mean one of those?

i set the callbacks in the connect function, is there a way to set reconnect callback in MQTTAsyc_* APIs ? i think reconnect will trigger onMqttConnected like connect. as the MQTTAsync_reconnect() said

int MQTTAsync_reconnect ( MQTTAsync handle) Reconnects a client with the previously used connect options. Connect must have previously been called for this to work.

and here is the connect code.

int CWeconMqttClient::_connect(const CConnectOptions & connOpts, const CAuthenticationOptions & authOpts)
{
    int nRtn = 0;
    string sServerAddress, sClientId;
    MQTTAsync_connectOptions mqttConnOpts = MQTTAsync_connectOptions_initializer;
    MQTTAsync_SSLOptions mqttSslOpts = MQTTAsync_SSLOptions_initializer;
    MQTTAsync_willOptions willOpts = MQTTAsync_willOptions_initializer; 

    if (existsInstance()) 
    {
        return -1; 
    }
    m_connOpts = connOpts;
    m_authOpts = authOpts;
    mqttConnOpts.ssl = &mqttSslOpts;
    mqttConnOpts.will = &willOpts;
    if (0 > (nRtn = parseOptions(mqttConnOpts, sServerAddress, sClientId)))
    {
        return -2;
    }
    if (MQTTASYNC_SUCCESS != (nRtn = MQTTAsync_create(&m_mqtt, 
                                                      sServerAddress.c_str(), 
                                                      sClientId.c_str(), 
                                                      MQTTCLIENT_PERSISTENCE_NONE, 
                                                      NULL)))
    {
        printf("%s > CWeconMqttClient::_connect() -3, return code %d\n", FORMAT_DATETIME, nRtn);
        return -3;
    }
    if (MQTTASYNC_SUCCESS != (nRtn = MQTTAsync_setConnected(m_mqtt, this, onMqttConnected)))
    {
        printf("%s > CWeconMqttClient::_connect() -4, return code %d\n", FORMAT_DATETIME, nRtn);
        nRtn = -4;
        goto _connectExit;
    }
    if (MQTTASYNC_SUCCESS != (nRtn = MQTTAsync_setDisconnected(m_mqtt, this, onMqttDisconnected)))
    {
        printf("%s > CWeconMqttClient::_connect() -5, return code %d\n", FORMAT_DATETIME, nRtn);
        nRtn = -5;
        goto _connectExit;
    }
    if (MQTTASYNC_SUCCESS != (nRtn = MQTTAsync_setCallbacks(m_mqtt, this, onMqttConnectLost, onMqttMessageArrived, onMqttMessageDelivered)))
    {
        printf("%s > CWeconMqttClient::_connect() -6, return code %d\n", FORMAT_DATETIME, nRtn);
        nRtn = -6;
        goto _connectExit;
    }
    if (MQTTASYNC_SUCCESS != (nRtn = MQTTAsync_connect(m_mqtt, &mqttConnOpts)))
    {
        printf("%s > CWeconMqttClient::_connect() -7, return code %d\n", FORMAT_DATETIME, nRtn);
        nRtn = -7;
        goto _connectExit;
    }
_connectExit:
    if (nRtn < 0) 
    {
        _destory();
    }
    return nRtn;
}

Are you using automatic reconnect?

no, as Automatic Reconnect said

To switch on automatic reconnect, the connect options field automaticReconnect should be set to non-zero. The minimum and maximum times before the next connection attempt can also be set, the defaults being 1 and 60 seconds. At each failure to reconnect, the retry interval is doubled until the maximum value is reached, and there it stays until the connection is successfully re-established whereupon it is reset.

in my code, using default MQTTAsync_connectOptions_initializer, and the automaticReconnect is set to 0.

MQTTAsync_connectOptions mqttConnOpts = MQTTAsync_connectOptions_initializer;

linxy.

icraggs commented 3 years ago

Well that SSLSocket error is what you could get if the TCP connection isn't available yet. How long do you wait, how many times to do attempt a reconnect? Why don't you use automatic reconnect which would be simpler?

linxingyang commented 3 years ago

thank you for reply.

Well that SSLSocket error is what you could get if the TCP connection isn't available yet

so at this point, will the reconnect callback be triggered if call MQTTAsync_reconnect again ? i will try this later.

How long do you wait

wait more than 10 minutes after the 4th reconnect called. And the longest i wait is few hours in the previous tests.

how many times to do attempt a reconnect?

as you can see the CWeconMqttClient::reconnect() is block by semaphore, once connection is lost, invoker will call reconnect() repeatly until connection connected.

/**
 * @details reconnect sync.
 * @return 
 *       0 if reconnect success. \n
 *      -1 if instance not exists or already connected. \n
 *      -2 if call reconnect fail. \n
 *      -3 if not connected. \n */
int CWeconMqttClient::reconnect()
{
    int nRtn = 0;
    printf("int CWeconMqttClient::_reconnect() 1111\n");
    CAutoLock autolock(m_connectionLock);
    printf("int CWeconMqttClient::_reconnect() 2222\n");

    if (0 > (nRtn = _reconnect()))
    {
        printf("int CWeconMqttClient::_reconnect() 3333\n");
        return nRtn;
    }
    printf("int CWeconMqttClient::_reconnect() 4444\n");
    m_connectionActionType = WMQTT_CONNECTION_ACTION_RECONNECT;
    m_connectionSemaphore.create(1); 
    m_connectionSemaphore.wait();               /* block here wait for semaphore.  */
    printf("int CWeconMqttClient::_reconnect() 5555\n");
    if (!MQTTAsync_isConnected(m_mqtt)) 
    {
        return -3;
    }
    printf("int CWeconMqttClient::_reconnect() 6666\n");
    return 0;
}

Why don't you use automatic reconnect which would be simpler?

i want more flexible for the invoker that they can decide to reconnect after connection lost or just close the connection. if it's the problem because of the reconnect callback is not triggered, i think the automatic reconnect don't work too, right?

linxy.

icraggs commented 3 years ago

As I said before, there is no reconnect callback, so I'm not sure what you are referring to here.

The callbacks are connected() and connectionLost(),

Calling reconnect() will only trigger the connected() callback if there is a successful connection, otherwise no callback will be invoked.

linxingyang commented 3 years ago

Calling reconnect() will only trigger the connected() callback if there is a successful connection, otherwise no callback will be invoked.

Calling reconnect() will only trigger the connected() callback if there is a successful connection, so there is no way to tell invoker when failed?

I'm consider that reconnect fail will trigger onFailure which define in struct MQTTAsync_connectOptions.

/**
 * MQTTAsync_connectOptions defines several settings that control the way the
 * client connects to an MQTT server.  Default values are set in
 * MQTTAsync_connectOptions_initializer.
 */
typedef struct
{
    /**
      * A pointer to a callback function to be called if the connect successfully
      * completes.  Can be set to NULL, in which case no indication of successful
      * completion will be received.
      */
    MQTTAsync_onSuccess* onSuccess;
    /**
      * A pointer to a callback function to be called if the connect fails.
      * Can be set to NULL, in which case no indication of unsuccessful
      * completion will be received.
      */
    MQTTAsync_onFailure* onFailure;

} MQTTAsync_connectOptions;

linxy.

icraggs commented 3 years ago

Either onSuccess or onFailure will be called once as a result of calling any command, including connect.

If you call reconnect there will only be a callback called if the connect was successful - connected(). Reconnect was mainly intended to be used with automatic reconnect. If you're not using automatic reconnect you can just call connect again - which will cause onSuccess/onFailure to be called again.

linxingyang commented 3 years ago

Oh, I get it, ( but the MQTTAsync_reconnect doc don't mention that it only used by automatic reconnect), i will try it and post the result later, thank you.

linxy.

icraggs commented 3 years ago

Reconnect() can be used outside of automatic reconnect, but that was the main reason for it. Outside automatic reconnect, it is like a connect(), but there are no onSuccess/onFailure callbacks - just connected(). If you want onSuccess/onFailure again, use connect().

icraggs commented 3 years ago

I think this is resolved.

linxingyang commented 3 years ago

Thank you. yes, it's resolved, after few days automatic tests, this problem never appeared.

linxy.