[INFO] [2023-08-08T17:35:38Z] [00007fdbc0512980] [event-loop] - id=0x5623e333b540: Initializing edge-triggered epoll [INFO] [2023-08-08T17:35:38Z] [00007fdbc0512980] [event-loop] - id=0x5623e333b540: Using eventfd for cross-thread notifications. [TRACE] [2023-08-08T17:35:38Z] [00007fdbc0512980] [event-loop] - id=0x5623e333b540: eventfd descriptor 6. [INFO] [2023-08-08T17:35:38Z] [00007fdbc0512980] [event-loop] - id=0x5623e333b540: Starting event-loop thread. [INFO] [2023-08-08T17:35:38Z] [00007fdbc0512980] [thread] - id=0x5623e3420e50: cpu affinity of cpu_id 0 was specified, attempting to honor the value. [INFO] [2023-08-08T17:35:38Z] [00007fdbc0512980] [event-loop] - id=0x5623e333e5e0: Initializing edge-triggered epoll [INFO] [2023-08-08T17:35:38Z] [00007fdbc0512980] [event-loop] - id=0x5623e333e5e0: Using eventfd for cross-thread notifications. [TRACE] [2023-08-08T17:35:38Z] [00007fdbc0512980] [event-loop] - id=0x5623e333e5e0: eventfd descriptor 8. [INFO] [2023-08-08T17:35:38Z] [00007fdbc0512980] [event-loop] - id=0x5623e333e5e0: Starting event-loop thread. [INFO] [2023-08-08T17:35:38Z] [00007fdbc0512980] [thread] - id=0x5623e34199d0: cpu affinity of cpu_id 1 was specified, attempting to honor the value. [INFO] [2023-08-08T17:35:38Z] [00007fdbba874700] [event-loop] - id=0x5623e333b540: main loop started [TRACE] [2023-08-08T17:35:38Z] [00007fdbba874700] [event-loop] - id=0x5623e333b540: subscribing to events on fd 6 [INFO] [2023-08-08T17:35:38Z] [00007fdbba874700] [event-loop] - id=0x5623e333b540: default timeout 100000, and max events to process per tick 100 [TRACE] [2023-08-08T17:35:38Z] [00007fdbba874700] [event-loop] - id=0x5623e333b540: waiting for a maximum of 100000 ms [INFO] [2023-08-08T17:35:38Z] [00007fdbc0512980] [dns] - id=0x5623e34192f0: Initializing default host resolver with 1 max host entries. [INFO] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: main loop started [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: subscribing to events on fd 8 [INFO] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: default timeout 100000, and max events to process per tick 100 [INFO] [2023-08-08T17:35:38Z] [00007fdbc0512980] [channel-bootstrap] - id=0x5623e33d1210: Initializing client bootstrap with event-loop group 0x5623e33bff50 [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: waiting for a maximum of 100000 ms [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [tls-handler] - ctx: Certificate and key have been set, setting them up now. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [channel-bootstrap] - id=0x5623e33d1210: acquiring bootstrap reference [INFO] [2023-08-08T17:35:38Z] [00007fdbc0512980] [connection-manager] - id=0x5623e34d1270: Successfully created [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [mqtt-client] - client=0x5623e364a9e0: Initalizing MQTT client [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [channel-bootstrap] - id=0x5623e33d1210: acquiring bootstrap reference [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [mqtt-client] - id=0x5623e364ab50: Creating new mqtt 311 connection [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [mqtt-topic-tree] - tree=0x5623e364ae58: Creating new topic tree [TRACE] [2023-08-08T17:35:38Z] [00007fdbc0512980] [mqtt-client] - id=0x5623e364ab50: Setting connection success and failure handlers [TRACE] [2023-08-08T17:35:38Z] [00007fdbc0512980] [mqtt-client] - id=0x5623e364ab50: Setting connection interrupted and resumed handlers [TRACE] [2023-08-08T17:35:38Z] [00007fdbc0512980] [mqtt-client] - id=0x5623e364ab50: Setting connection closed handler [TRACE] [2023-08-08T17:35:38Z] [00007fdbc0512980] [mqtt-client] - id=0x5623e364ab50: Setting username and password [TRACE] [2023-08-08T17:35:38Z] [00007fdbc0512980] [mqtt-client] - id=0x5623e364ab50: Using websockets [TRACE] [2023-08-08T17:35:38Z] [00007fdbc0512980] [mqtt-client] - id=0x5623e364ab50: Opening connection [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [mqtt-client] - id=0x5623e364ab50: Begin connecting process, switch state to CONNECTING. [INFO] [2023-08-08T17:35:38Z] [00007fdbc0512980] [mqtt-client] - id=0x5623e364ab50: using ping timeout of 10000000000 ns [TRACE] [2023-08-08T17:35:38Z] [00007fdbc0512980] [mqtt-client] - id=0x5623e364ab50: Transforming websocket handshake request. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [connection-manager] - id=0x5623e34d1270: Acquire connection [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [connection-manager] - id=0x5623e34d1270: snapshot - state=1, idle_connection_count=0, pending_acquire_count=1, pending_settings_count=0, pending_connect_count=1, vended_connection_count=0, open_connection_count=0, ref_count=1 [INFO] [2023-08-08T17:35:38Z] [00007fdbc0512980] [connection-manager] - id=0x5623e34d1270: Requesting 1 new connections from http [TRACE] [2023-08-08T17:35:38Z] [00007fdbc0512980] [http-connection] - static: attempting to initialize a new client channel to c1nziiusqalu78.credentials.iot.us-east-1.amazonaws.com:443 [TRACE] [2023-08-08T17:35:38Z] [00007fdbc0512980] [channel-bootstrap] - id=0x5623e33d1210: attempting to initialize a new client channel to c1nziiusqalu78.credentials.iot.us-east-1.amazonaws.com:443 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [channel-bootstrap] - id=0x5623e33d1210: acquiring bootstrap reference [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [dns] - id=0x5623e34192f0: Host resolution requested for c1nziiusqalu78.credentials.iot.us-east-1.amazonaws.com [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [dns] - id=0x5623e34192f0: No cached entries found for c1nziiusqalu78.credentials.iot.us-east-1.amazonaws.com starting new resolver thread. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbb9872700] [dns] - static: resolving host c1nziiusqalu78.credentials.iot.us-east-1.amazonaws.com [DEBUG] [2023-08-08T17:35:38Z] [00007fdbb9872700] [dns] - static: resolved record: 52.70.54.240 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbb9872700] [dns] - static: resolved record: 52.202.70.64 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbb9872700] [dns] - static: resolved record: 54.160.21.133 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbb9872700] [dns] - static, resolving host c1nziiusqalu78.credentials.iot.us-east-1.amazonaws.com successful, returned 3 addresses [DEBUG] [2023-08-08T17:35:38Z] [00007fdbb9872700] [dns] - static: new address resolved 52.70.54.240 for host c1nziiusqalu78.credentials.iot.us-east-1.amazonaws.com caching [DEBUG] [2023-08-08T17:35:38Z] [00007fdbb9872700] [dns] - static: new address resolved 52.202.70.64 for host c1nziiusqalu78.credentials.iot.us-east-1.amazonaws.com caching [DEBUG] [2023-08-08T17:35:38Z] [00007fdbb9872700] [dns] - static: new address resolved 54.160.21.133 for host c1nziiusqalu78.credentials.iot.us-east-1.amazonaws.com caching [TRACE] [2023-08-08T17:35:38Z] [00007fdbb9872700] [dns] - static: remaining record count for host 0 [TRACE] [2023-08-08T17:35:38Z] [00007fdbb9872700] [dns] - static: remaining record count for host 3 [TRACE] [2023-08-08T17:35:38Z] [00007fdbb9872700] [dns] - static: vending address 52.70.54.240 for host c1nziiusqalu78.credentials.iot.us-east-1.amazonaws.com to caller [DEBUG] [2023-08-08T17:35:38Z] [00007fdbb9872700] [dns] - static, invoking resolution callback for host c1nziiusqalu78.credentials.iot.us-east-1.amazonaws.com with 1 addresses [TRACE] [2023-08-08T17:35:38Z] [00007fdbb9872700] [channel-bootstrap] - id=0x5623e33d1210: dns resolution completed. Kicking off connections on 1 addresses. First one back wins. [TRACE] [2023-08-08T17:35:38Z] [00007fdbb9872700] [channel-bootstrap] - acquiring client connection args, args=0x5623e3659cb0 [TRACE] [2023-08-08T17:35:38Z] [00007fdbb9872700] [event-loop] - id=0x5623e333e5e0: Scheduling task 0x7fdba4002570 cross-thread for timestamp 0 [TRACE] [2023-08-08T17:35:38Z] [00007fdbb9872700] [event-loop] - id=0x5623e333e5e0: Waking up event-loop thread [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: wake up with 1 events to process. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: activity on fd 8, invoking handler. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: notified of cross-thread tasks to schedule [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: processing cross-thread tasks [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: task 0x7fdba4002570 pulled to event-loop, scheduling now. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x7fdba4002570: Scheduling attempt_connection task for immediate execution [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: running scheduled tasks. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x7fdba4002570: Running attempt_connection task with status [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [socket] - id=0x7fdbb00010a0 fd=10: initializing with domain 0 and type 0 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [socket] - id=0x7fdbb00010a0 fd=10: setting socket options to: keep-alive 0, keep idle 0, keep-alive interval 0, keep-alive probe count 0. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [socket] - id=0x7fdbb00010a0 fd=10: beginning connect. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [socket] - id=0x7fdbb00010a0 fd=10: connecting to endpoint 52.70.54.240:443. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [socket] - id=0x7fdbb00010a0 fd=10: connection pending waiting on event-loop notification or timeout. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: subscribing to events on fd 10 [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [socket] - id=0x7fdbb00010a0 fd=10: scheduling timeout task for 574648157853398. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: scheduling task 0x7fdbb0000c40 in-thread for timestamp 574648157853398 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x7fdbb0000c40: Scheduling (null) task for future execution at time 574648157853398 [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: detected more scheduled tasks with the next occurring at 1999986917, using timeout of 1999. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: waiting for a maximum of 1999 ms [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: wake up with 1 events to process. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: activity on fd 10, invoking handler. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [socket] - fd=10: connection activity handler triggered [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [socket] - id=0x7fdbb00010a0 fd=10: has not timed out yet proceeding with connection. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: un-subscribing from events on fd 10 [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: scheduling task 0x7fdbb0000de0 in-thread for timestamp 0 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x7fdbb0000de0: Scheduling epoll_event_loop_unsubscribe_cleanup task for immediate execution [INFO] [2023-08-08T17:35:38Z] [00007fdbba073700] [socket] - id=0x7fdbb00010a0 fd=10: connection success [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [socket] - id=0x7fdbb00010a0 fd=10: assigning to event loop 0x5623e333e5e0 [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: subscribing to events on fd 10 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel-bootstrap] - id=0x5623e33d1210: client connection on socket 0x7fdbb00010a0 completed with error 0. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel-bootstrap] - id=0x5623e33d1210: Successful connection, creating a new channel using socket 0x7fdbb00010a0. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel] - id=0x7fdbb00018d0: Beginning creation and setup of new channel. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: scheduling task 0x7fdbb0000e50 in-thread for timestamp 0 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x7fdbb0000e50: Scheduling on_channel_setup_complete task for immediate execution [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: running scheduled tasks. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x7fdbb0000de0: Running epoll_event_loop_unsubscribe_cleanup task with status [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x7fdbb0000e50: Running on_channel_setup_complete task with status [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel] - id=0x7fdbb00018d0: setup complete, notifying caller. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel] - id=0x7fdbb00018d0: no message pool is currently stored in the event-loop local storage, adding 0x7fdbb0001de0 with max message size 16384, message count 4, with 4 small blocks of 128 bytes. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel-bootstrap] - id=0x5623e33d1210: channel 0x7fdbb00018d0 setup succeeded: bootstrapping. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel] - id=0x7fdbb00018d0: creating new slot 0x7fdbb00122e0. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [socket-handler] - id=0x7fdbb0012890: Socket handler created with max_read_size of 16384 [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [socket] - id=0x7fdbb00010a0 fd=10: subscribing to readable events [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel-bootstrap] - id=0x5623e33d1210: Setting up socket handler on channel 0x7fdbb00018d0 with handler 0x7fdbb0012890 on slot 0x7fdbb00122e0. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel] - id=0x7fdbb00018d0: creating new slot 0x7fdbb0000fc0. [ERROR] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel-bootstrap] - id=0x5623e33d1210: channel 0x7fdbb00018d0 setup failed with error 30. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel] - id=0x7fdbb00018d0: channel shutdown task is scheduled [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel] - id=0x7fdbb00018d0: scheduling task with wrapper task id 0x7fdbb0001ad0. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: scheduling task 0x7fdbb0001ad0 in-thread for timestamp 0 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x7fdbb0001ad0: Scheduling channel_shutdown task for immediate execution [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: detected more scheduled tasks with the next occurring at 0, using timeout of 0. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: waiting for a maximum of 0 ms [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: wake up with 1 events to process. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: activity on fd 10, invoking handler. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [socket] - id=0x7fdbb00010a0 fd=10: is writable [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [socket] - id=0x7fdbb00010a0 fd=10: processing write requests, invoked by the event-loop [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: running scheduled tasks. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x7fdbb0001ad0: Running channel_shutdown task with status [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel] - id=0x7fdbb00018d0: beginning shutdown process [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel] - id=0x7fdbb00018d0: shutting down slot 0x7fdbb00122e0 (the first one) in the read direction [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel] - id=0x7fdbb00018d0: shutting down slot 0x7fdbb00122e0, with handler 0x7fdbb0012890 in read direction with error code 30 [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [socket-handler] - id=0x7fdbb0012890: shutting down read direction with error_code 30 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel] - id=0x7fdbb00018d0: handler 0x7fdbb0012890 shutdown in read dir completed. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: scheduling task 0x7fdbb00018f8 in-thread for timestamp 0 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x7fdbb00018f8: Scheduling (null) task for immediate execution [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: detected more scheduled tasks with the next occurring at 0, using timeout of 0. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: waiting for a maximum of 0 ms [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: wake up with 0 events to process. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: running scheduled tasks. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x7fdbb00018f8: Running (null) task with status [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [socket-handler] - id=0x7fdbb0012890: shutting down write direction with error_code 30 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [socket] - id=0x7fdbb00010a0 fd=10: closing [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: un-subscribing from events on fd 10 [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: scheduling task 0x7fdbb0001640 in-thread for timestamp 0 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x7fdbb0001640: Scheduling epoll_event_loop_unsubscribe_cleanup task for immediate execution [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel] - id=0x7fdbb00018d0: scheduling task with wrapper task id 0x7fdbb0012930. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: scheduling task 0x7fdbb0012930 in-thread for timestamp 0 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x7fdbb0012930: Scheduling socket_handler_close task for immediate execution [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: detected more scheduled tasks with the next occurring at 0, using timeout of 0. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: waiting for a maximum of 0 ms [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: wake up with 0 events to process. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: running scheduled tasks. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x7fdbb0001640: Running epoll_event_loop_unsubscribe_cleanup task with status [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x7fdbb0012930: Running socket_handler_close task with status [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel] - id=0x7fdbb00018d0: handler 0x7fdbb0012890 shutdown in write dir completed. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: scheduling task 0x7fdbb00018f8 in-thread for timestamp 0 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x7fdbb00018f8: Scheduling (null) task for immediate execution [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: detected more scheduled tasks with the next occurring at 0, using timeout of 0. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: waiting for a maximum of 0 ms [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: wake up with 0 events to process. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: running scheduled tasks. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x7fdbb00018f8: Running (null) task with status [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel-bootstrap] - id=0x5623e33d1210: channel 0x7fdbb00018d0 shutdown with error 30. [ERROR] [2023-08-08T17:35:38Z] [00007fdbba073700] [http-connection] - static: Client connection failed with error 30 (AWS_ERROR_PRIORITY_QUEUE_EMPTY). [WARN] [2023-08-08T17:35:38Z] [00007fdbba073700] [connection-manager] - id=0x5623e34d1270: Failed to obtain new connection from http layer, error 30(Attempt to pop an item from an empty queue.) [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [connection-manager] - id=0x5623e34d1270: Failing excess connection acquisition with error code 30 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [connection-manager] - id=0x5623e34d1270: snapshot - state=1, idle_connection_count=0, pending_acquire_count=0, pending_settings_count=0, pending_connect_count=0, vended_connection_count=0, open_connection_count=0, ref_count=1 [WARN] [2023-08-08T17:35:38Z] [00007fdbba073700] [connection-manager] - id=0x5623e34d1270: Failed to complete connection acquisition with error_code 30(Attempt to pop an item from an empty queue.) [WARN] [2023-08-08T17:35:38Z] [00007fdbba073700] [AuthCredentialsProvider] - id=0x5623e3515970: X509 provider failed to acquire a connection, error code 30(Attempt to pop an item from an empty queue.) [ERROR] [2023-08-08T17:35:38Z] [00007fdbba073700] [AuthCredentialsProvider] - Failed to parse IoT Core response as Json document. [WARN] [2023-08-08T17:35:38Z] [00007fdbba073700] [AuthCredentialsProvider] - (id=0x5623e3515970) X509 credentials provider failed to query credentials with error 30(Attempt to pop an item from an empty queue.) [ERROR] [2023-08-08T17:35:38Z] [00007fdbba073700] [AuthSigning] - (id=0x5623e3652c00) Credentials Provider failed to source credentials with error 30(aws-c-common: AWS_ERROR_PRIORITY_QUEUE_EMPTY, Attempt to pop an item from an empty queue.) [ERROR] [2023-08-08T17:35:38Z] [00007fdbba073700] [mqtt-client] - id=0x5623e364ab50: Failure reported by websocket handshake transform callback. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [mqtt-client] - id=0x5623e364ab50: Channel has been shutdown with error code 6146 [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [mqtt-client] - id=0x5623e364ab50: All subscribe/unsubscribe and publish QoS>0 have been move to pending list [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [mqtt-client] - id=0x5623e364ab50: current state is 0 [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [mqtt-client] - id=0x5623e364ab50: Initial connection attempt failed, calling callback (F) 2023-Aug-08 17:35:38 [MQTT] Connection failed with error aws-c-auth: AWS_AUTH_SIGNING_NO_CREDENTIALS, Attempt to sign an http request without credentials [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel-bootstrap] - releasing client connection args, args=0x5623e3659cb0 [TRACE] [2023-08-08T17:35:38Z] [00007fdbc0512980] [mqtt-client] - id=0x5623e364ab50: Setting connection closed handler [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel] - id=0x7fdbb00018d0: destroying channel. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [mqtt-client] - id=0x5623e364ab50: Last refcount on connection has been released, start destroying the connection. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel-bootstrap] - releasing client connection args, args=0x5623e3659cb0 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [mqtt-client] - id=0x5623e364ab50: Destroying connection [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel-bootstrap] - destroying client connection args, args=0x5623e3659cb0 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [mqtt-topic-tree] - tree=0x5623e364ae58: Cleaning up topic tree [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [channel-bootstrap] - id=0x5623e33d1210: releasing bootstrap reference [TRACE] [2023-08-08T17:35:38Z] [00007fdbc0512980] [mqtt-topic-tree] - node=0x5623e3657e70: Destroying topic tree node [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: detected more scheduled tasks with the next occurring at 1996698762, using timeout of 1996. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: waiting for a maximum of 1996 ms [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [mqtt-client] - client=0x5623e364a9e0: Cleaning up MQTT client [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [channel-bootstrap] - id=0x5623e33d1210: releasing bootstrap reference [INFO] [2023-08-08T17:35:38Z] [00007fdbc0512980] [connection-manager] - id=0x5623e34d1270: release [INFO] [2023-08-08T17:35:38Z] [00007fdbc0512980] [connection-manager] - id=0x5623e34d1270: ref count now zero, starting shut down process [INFO] [2023-08-08T17:35:38Z] [00007fdbc0512980] [connection-manager] - id=0x5623e34d1270: manager release, failing 0 pending acquisitions [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [connection-manager] - id=0x5623e34d1270: snapshot - state=2, idle_connection_count=0, pending_acquire_count=0, pending_settings_count=0, pending_connect_count=0, vended_connection_count=0, open_connection_count=0, ref_count=0 [INFO] [2023-08-08T17:35:38Z] [00007fdbc0512980] [connection-manager] - id=0x5623e34d1270: Destroying self [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [channel-bootstrap] - id=0x5623e33d1210: releasing bootstrap reference [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [channel-bootstrap] - id=0x5623e33d1210: releasing bootstrap reference [DEBUG] [2023-08-08T17:35:38Z] [00007fdbc0512980] [channel-bootstrap] - id=0x5623e33d1210: bootstrap destroying [DEBUG] [2023-08-08T17:35:38Z] [00007fdbb9872700] [dns] - static: Either no requests have been made for an address for c1nziiusqalu78.credentials.iot.us-east-1.amazonaws.com for the duration of the ttl, or this thread is being forcibly shutdown. Killing thread. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbb9872700] [dns] - static: purging address 54.160.21.133 for host c1nziiusqalu78.credentials.iot.us-east-1.amazonaws.com from the cache due to cache eviction or shutdown [DEBUG] [2023-08-08T17:35:38Z] [00007fdbb9872700] [dns] - static: purging address 52.202.70.64 for host c1nziiusqalu78.credentials.iot.us-east-1.amazonaws.com from the cache due to cache eviction or shutdown [DEBUG] [2023-08-08T17:35:38Z] [00007fdbb9872700] [dns] - static: purging address 52.70.54.240 for host c1nziiusqalu78.credentials.iot.us-east-1.amazonaws.com from the cache due to cache eviction or shutdown [INFO] [2023-08-08T17:35:38Z] [00007fdbb8e6a700] [event-loop] - id=0x5623e333e5e0: Destroying event_loop [INFO] [2023-08-08T17:35:38Z] [00007fdbb8e6a700] [event-loop] - id=0x5623e333e5e0: Stopping event-loop thread. [TRACE] [2023-08-08T17:35:38Z] [00007fdbb8e6a700] [event-loop] - id=0x5623e333e5e0: Scheduling task 0x5623e3419a88 cross-thread for timestamp 0 [TRACE] [2023-08-08T17:35:38Z] [00007fdbb8e6a700] [event-loop] - id=0x5623e333e5e0: Waking up event-loop thread [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: wake up with 1 events to process. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: activity on fd 8, invoking handler. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: notified of cross-thread tasks to schedule [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: processing cross-thread tasks [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: task 0x5623e3419a88 pulled to event-loop, scheduling now. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x5623e3419a88: Scheduling epoll_event_loop_stop task for immediate execution [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: running scheduled tasks. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x5623e3419a88: Running epoll_event_loop_stop task with status [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: detected more scheduled tasks with the next occurring at 1994195317, using timeout of 1994. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: exiting main loop [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: un-subscribing from events on fd 8 [TRACE] [2023-08-08T17:35:38Z] [00007fdbba073700] [event-loop] - id=0x5623e333e5e0: scheduling task 0x7fdbb0000940 in-thread for timestamp 0 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba073700] [task-scheduler] - id=0x7fdbb0000940: Scheduling epoll_event_loop_unsubscribe_cleanup task for immediate execution [DEBUG] [2023-08-08T17:35:38Z] [00007fdbb8e6a700] [task-scheduler] - id=0x7fdbb0000940: Running epoll_event_loop_unsubscribe_cleanup task with status [DEBUG] [2023-08-08T17:35:38Z] [00007fdbb8e6a700] [task-scheduler] - id=0x7fdbb0000c40: Running (null) task with status [TRACE] [2023-08-08T17:35:38Z] [00007fdbb8e6a700] [socket] - task_id=0x7fdbb0000c40: timeout task triggered, evaluating timeouts. [TRACE] [2023-08-08T17:35:38Z] [00007fdbb8e6a700] [channel] - static: message pool 0x7fdbb0001de0 has been purged from the event-loop: likely because of shutdown [INFO] [2023-08-08T17:35:38Z] [00007fdbb8e6a700] [event-loop] - id=0x5623e333b540: Destroying event_loop [INFO] [2023-08-08T17:35:38Z] [00007fdbb8e6a700] [event-loop] - id=0x5623e333b540: Stopping event-loop thread. [TRACE] [2023-08-08T17:35:38Z] [00007fdbb8e6a700] [event-loop] - id=0x5623e333b540: Scheduling task 0x5623e3420f08 cross-thread for timestamp 0 [TRACE] [2023-08-08T17:35:38Z] [00007fdbb8e6a700] [event-loop] - id=0x5623e333b540: Waking up event-loop thread [TRACE] [2023-08-08T17:35:38Z] [00007fdbba874700] [event-loop] - id=0x5623e333b540: wake up with 1 events to process. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba874700] [event-loop] - id=0x5623e333b540: activity on fd 6, invoking handler. [TRACE] [2023-08-08T17:35:38Z] [00007fdbba874700] [event-loop] - id=0x5623e333b540: notified of cross-thread tasks to schedule [TRACE] [2023-08-08T17:35:38Z] [00007fdbba874700] [event-loop] - id=0x5623e333b540: processing cross-thread tasks [TRACE] [2023-08-08T17:35:38Z] [00007fdbba874700] [event-loop] - id=0x5623e333b540: task 0x5623e3420f08 pulled to event-loop, scheduling now. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba874700] [task-scheduler] - id=0x5623e3420f08: Scheduling epoll_event_loop_stop task for immediate execution [TRACE] [2023-08-08T17:35:38Z] [00007fdbba874700] [event-loop] - id=0x5623e333b540: running scheduled tasks. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba874700] [task-scheduler] - id=0x5623e3420f08: Running epoll_event_loop_stop task with status [TRACE] [2023-08-08T17:35:38Z] [00007fdbba874700] [event-loop] - id=0x5623e333b540: no more scheduled tasks using default timeout. [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba874700] [event-loop] - id=0x5623e333b540: exiting main loop [TRACE] [2023-08-08T17:35:38Z] [00007fdbba874700] [event-loop] - id=0x5623e333b540: un-subscribing from events on fd 6 [TRACE] [2023-08-08T17:35:38Z] [00007fdbba874700] [event-loop] - id=0x5623e333b540: scheduling task 0x7fdbac001180 in-thread for timestamp 0 [DEBUG] [2023-08-08T17:35:38Z] [00007fdbba874700] [task-scheduler] - id=0x7fdbac001180: Scheduling epoll_event_loop_unsubscribe_cleanup task for immediate execution [DEBUG] [2023-08-08T17:35:38Z] [00007fdbb8e6a700] [task-scheduler] - id=0x7fdbac001180: Running epoll_event_loop_unsubscribe_cleanup task with status