FullLogsErrorAWSIO

[INFO ] [2020-04-28T20:23:07Z] [000000010d059dc0] [event-loop] - id=0x7fbfc0d23ab0: Initializing edge-triggered kqueue
[INFO ] [2020-04-28T20:23:07Z] [000000010d059dc0] [event-loop] - id=0x7fbfc0d23ab0: starting event-loop thread.
[INFO ] [2020-04-28T20:23:07Z] [000000010d059dc0] [dns] - id=0x7fbfc0c31920: Initializing default host resolver with 16 max host entries.
[INFO ] [2020-04-28T20:23:07Z] [0000700001cea000] [event-loop] - id=0x7fbfc0d23ab0: main loop started
[INFO ] [2020-04-28T20:23:07Z] [0000700001cea000] [event-loop] - id=0x7fbfc0d23ab0: default timeout 100s, and max events to process per tick 100
[INFO ] [2020-04-28T20:23:07Z] [000000010d059dc0] [channel-bootstrap] - id=0x7fbfc0c07230: Initializing client bootstrap with event-loop group 0x7fbfc0d2cb60
[WARN ] [2020-04-28T20:23:07Z] [000000010d059dc0] [AuthProfile] - property "aws_access_key_id" has value "" replaced during merge
[WARN ] [2020-04-28T20:23:07Z] [000000010d059dc0] [AuthProfile] - property "aws_secret_access_key" has value "" replaced during merge
[INFO ] [2020-04-28T20:23:07Z] [000000010d059dc0] [connection-manager] - id=0x7fbfc0c342e0: Successfully created
[DEBUG] [2020-04-28T20:23:08Z] [000000010d059dc0] [mqtt-client] - client=0x7fbfc0c4d2f0: Initalizing MQTT client
[DEBUG] [2020-04-28T20:23:08Z] [000000010d059dc0] [mqtt-client] - id=0x7fbfc0ca32e0: Creating new connection
[DEBUG] [2020-04-28T20:23:08Z] [000000010d059dc0] [mqtt-topic-tree] - tree=0x7fbfc0ca33c8: Creating new topic tree
Connecting to XXXXXXXXXXXX-ats.iot.us-east-2.amazonaws.com with client ID 'samples-client-id'...
[INFO ] [2020-04-28T20:23:08Z] [000000010d059dc0] [mqtt-client] - id=0x7fbfc0ca32e0: using ping timeout of 3000000000 ns
[INFO ] [2020-04-28T20:23:08Z] [000000010d059dc0] [AuthCredentialsProvider] - (id=0x7fbfc0c323e0) Cached credentials provider has expired credentials.  Requerying.
[DEBUG] [2020-04-28T20:23:08Z] [000000010d059dc0] [AuthCredentialsProvider] - (id=0x7fbfc0c33cc0) Credentials provider chain get credentials dispatch
[INFO ] [2020-04-28T20:23:08Z] [000000010d059dc0] [AuthCredentialsProvider] - (id=0x7fbfc0c1ef40) Environment credentials provider successfully sourced credentials
[INFO ] [2020-04-28T20:23:08Z] [000000010d059dc0] [AuthCredentialsProvider] - (id=0x7fbfc0c33cc0) Credentials provider chain ending query on chain member 1 with valid credentials
[DEBUG] [2020-04-28T20:23:08Z] [000000010d059dc0] [AuthCredentialsProvider] - (id=0x7fbfc0c323e0) Cached credentials provider next refresh time set to 106374006210801
[DEBUG] [2020-04-28T20:23:08Z] [000000010d059dc0] [AuthCredentialsProvider] - (id=0x7fbfc0c323e0) Cached credentials provider successfully sourced credentials on refresh
[DEBUG] [2020-04-28T20:23:08Z] [000000010d059dc0] [AuthCredentialsProvider] - (id=0x7fbfc0c323e0) Cached credentials provider notifying pending queries of new credentials
[INFO ] [2020-04-28T20:23:08Z] [000000010d059dc0] [AuthSigning] - (id=0x7fbfc0c34010) Http request successfully built canonical request for algorithm (null), with contents "GET
/mqtt
X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=XXXXXXXXXXX%2F20200428%2Fus-east-2%2Fiotdevicegateway%2Faws4_request&X-Amz-Date=20200428T202308Z&X-Amz-SignedHeaders=host
host:xxxxxxxxxxxxx-ats.iot.us-east-2.amazonaws.com

host
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855"
[INFO ] [2020-04-28T20:23:08Z] [000000010d059dc0] [AuthSigning] - (id=0x7fbfc0c34010) Http request successfully built string-to-sign via algorithm (null), with contents "AWS4-HMAC-SHA256
20200428T202308Z
20200428/us-east-2/iotdevicegateway/aws4_request
12fd54faac3c56a330295368e74a2d52256ceeb733909739fc92fd42c727e32c"
[INFO ] [2020-04-28T20:23:08Z] [000000010d059dc0] [AuthSigning] - (id=0x7fbfc0c34010) Http request successfully built final authorization value via algorithm (null), with contents "47cd1119d0ff1ff58bb5b961eb4601618a464945f8462771d04a87c80254f298"
[DEBUG] [2020-04-28T20:23:08Z] [000000010d059dc0] [dns] - id=0x7fbfc0c31920: Host resolution requested for XXXXXXXXXXXX-ats.iot.us-east-2.amazonaws.com
[DEBUG] [2020-04-28T20:23:08Z] [000000010d059dc0] [dns] - id=0x7fbfc0c31920: No cached entries found for XXXXXXXXXXXX-ats.iot.us-east-2.amazonaws.com starting new resolver thread.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001d6d000] [dns] - static: resolving host XXXXXXXXXXXX-ats.iot.us-east-2.amazonaws.com
[DEBUG] [2020-04-28T20:23:08Z] [0000700001d6d000] [dns] - static: resolved record: 2600:1f00:6000::314:c03b
[DEBUG] [2020-04-28T20:23:08Z] [0000700001d6d000] [dns] - static: resolved record: 2600:1f00:6000::12dc:f08d
[DEBUG] [2020-04-28T20:23:08Z] [0000700001d6d000] [dns] - static: resolved record: 2600:1f00:6000::30f:5c13
[DEBUG] [2020-04-28T20:23:08Z] [0000700001d6d000] [dns] - static: resolved record: 13.58.156.138
[DEBUG] [2020-04-28T20:23:08Z] [0000700001d6d000] [dns] - static: resolved record: 3.136.140.196
[DEBUG] [2020-04-28T20:23:08Z] [0000700001d6d000] [dns] - static: resolved record: 3.133.43.205
[DEBUG] [2020-04-28T20:23:08Z] [0000700001d6d000] [dns] - static: new address resolved 2600:1f00:6000::314:c03b for host XXXXXXXXXXXX-ats.iot.us-east-2.amazonaws.com caching
[DEBUG] [2020-04-28T20:23:08Z] [0000700001d6d000] [dns] - static: new address resolved 2600:1f00:6000::12dc:f08d for host XXXXXXXXXXXX-ats.iot.us-east-2.amazonaws.com caching
[DEBUG] [2020-04-28T20:23:08Z] [0000700001d6d000] [dns] - static: new address resolved 2600:1f00:6000::30f:5c13 for host XXXXXXXXXXXX-ats.iot.us-east-2.amazonaws.com caching
[DEBUG] [2020-04-28T20:23:08Z] [0000700001d6d000] [dns] - static: new address resolved 13.58.156.138 for host XXXXXXXXXXXX-ats.iot.us-east-2.amazonaws.com caching
[DEBUG] [2020-04-28T20:23:08Z] [0000700001d6d000] [dns] - static: new address resolved 3.136.140.196 for host XXXXXXXXXXXX-ats.iot.us-east-2.amazonaws.com caching
[DEBUG] [2020-04-28T20:23:08Z] [0000700001d6d000] [dns] - static: new address resolved 3.133.43.205 for host XXXXXXXXXXXX-ats.iot.us-east-2.amazonaws.com caching
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f18d20: Scheduling attempt_connection task for immediate execution
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f18ec0: Scheduling attempt_connection task for immediate execution
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f18d20: Running attempt_connection task with <Running> status
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [socket] - id=0x7fbfc0d3bbf0 fd=10: initializing with domain 1 and type 0
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [socket] - id=0x7fbfc0d3bbf0 fd=10: setting socket options to: keep-alive 0, keep idle 0, keep-alive interval 0, keep-alive probe count 0.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [socket] - id=0x7fbfc0d3bbf0 fd=10: beginning connect.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [socket] - id=0x7fbfc0d3bbf0 fd=10: connecting to endpoint 2600:1f00:6000::314:c03b:443.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0d39f00: Scheduling kqueue_event_loop_subscribe task for immediate execution
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0d32460: Scheduling (null) task for future execution at time 105479007918993
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f18ec0: Running attempt_connection task with <Running> status
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [socket] - id=0x7fbfc0d349d0 fd=11: initializing with domain 0 and type 0
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [socket] - id=0x7fbfc0d349d0 fd=11: setting socket options to: keep-alive 0, keep idle 0, keep-alive interval 0, keep-alive probe count 0.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [socket] - id=0x7fbfc0d349d0 fd=11: beginning connect.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [socket] - id=0x7fbfc0d349d0 fd=11: connecting to endpoint 13.58.156.138:443.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0d33db0: Scheduling kqueue_event_loop_subscribe task for immediate execution
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0d1e800: Scheduling (null) task for future execution at time 105479008012821
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0d39f00: Running kqueue_event_loop_subscribe task with <Running> status
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0d33db0: Running kqueue_event_loop_subscribe task with <Running> status
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0d39f40: Scheduling kqueue_event_loop_clean_up_handle_data task for immediate execution
[INFO ] [2020-04-28T20:23:08Z] [0000700001cea000] [socket] - id=0x7fbfc0d3bbf0 fd=10: connection success
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [socket] - id=0x7fbfc0d3bbf0 fd 10: local endpoint 2806:261:40b:94e5:1cb:6140:7075:ad43:61160
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [socket] - id=0x7fbfc0d3bbf0 fd=10: assigning to event loop 0x7fbfc0d23ab0
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f07340: Scheduling kqueue_event_loop_subscribe task for immediate execution
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel-bootstrap] - id=0x7fbfc0c07230: client connection on socket 0x7fbfc0d3bbf0 completed with error 0.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: Beginning creation and setup of new channel.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f0fd20: Scheduling on_channel_setup_complete task for immediate execution
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0d39f40: Running kqueue_event_loop_clean_up_handle_data task with <Running> status
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f07340: Running kqueue_event_loop_subscribe task with <Running> status
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f0fd20: Running on_channel_setup_complete task with <Running> status
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: setup complete, notifying caller.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: no message pool is currently stored in the event-loop local storage, adding 0x7fbfc0f0fbd0 with max message size 16384, message count 4, with 4 small blocks of 128 bytes.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel-bootstrap] - id=0x7fbfc0c07230: channel 0x7fbfc0f18340 setup succeeded: bootstrapping.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [socket-handler] - id=0x7fbfc0f186e0: Socket handler created with max_read_size of 16384
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f192a0: Scheduling tls_timeout task for future execution at time 105478105604347
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [tls-handler] - id=0x7fbfc0f19270: negotiation succeeded
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [tls-handler] - id=0x7fbfc0f19270: negotiated protocol: http/1.1
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [tls-handler] - id=0x7fbfc0f19270: Remote Server Name:
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel-bootstrap] - id=0x7fbfc0c07230: tls negotiation result 0 on channel 0x7fbfc0f18340
[INFO ] [2020-04-28T20:23:08Z] [0000700001cea000] [http-connection] - id=0x7fbfc0d4b640: HTTP/1.1 client connection established.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [http-stream] - id=0x7fbfc0d4be10: Created client request on connection=0x7fbfc0d4b640: GET /mqtt?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=XXXXXXXXX%2F20200428%2Fus-east-2%2Fiotdevicegateway%2Faws4_request&X-Amz-Date=20200428T202308Z&X-Amz-SignedHeaders=host&X-Amz-Signature=47cd1119d0ff1ff58bb5b961eb4601618a464945f8462771d04a87c80254f298 HTTP/1.1
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0d4b6d0: Scheduling http1_outgoing_stream task for immediate execution
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0d4b6d0: Running http1_outgoing_stream task with <Running> status
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0d4b6d0: Scheduling http1_outgoing_stream task for immediate execution
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0d4b6d0: Running http1_outgoing_stream task with <Running> status
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [websocket] - id=0x7fbfc0e6ead0: Websocket client connection established.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [mqtt-client] - id=0x7fbfc0ca32e0: Connection successfully opened, sending CONNECT packet
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0e30930: Scheduling mqtt_connack_timeout task for future execution at time 105477551081615
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [mqtt-client] - id=0x7fbfc0ca32e0: Adding username ?SDK=PythonV2&Version=0.5.13 to connection
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [websocket] - id=0x7fbfc0e6ead0: Enqueuing outgoing frame with opcode=2(binary) length=61 fin=T priority=normal
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0e6eb30: Scheduling websocket_move_synced_data_to_thread task for immediate execution
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0e6eb30: Running websocket_move_synced_data_to_thread task with <Running> status
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [websocket] - id=0x7fbfc0e6ead0: Close frame received, any further data received will be ignored.
[ERROR] [2020-04-28T20:23:08Z] [0000700001cea000] [tls-handler] - id=0x7fbfc0f19270: error reported during SSLRead. OSStatus code -9805
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f18558: Scheduling channel_shutdown task for immediate execution
[INFO ] [2020-04-28T20:23:08Z] [0000700001cea000] [socket] - id=0x7fbfc0d3bbf0 fd=10: zero read, socket is closed
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: Channel shutdown is already pending, not scheduling another.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: Channel shutdown is already pending, not scheduling another.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f18558: Running channel_shutdown task with <Running> status
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: beginning shutdown process
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: handler 0x7fbfc0f186e0 shutdown in read dir completed.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [tls-handler] - id=0x7fbfc0f19270: shutting down read direction with error 0. Flushing queues.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: handler 0x7fbfc0f19270 shutdown in read dir completed.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: handler 0x7fbfc0d4b648 shutdown in read dir completed.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [websocket] - id=0x7fbfc0e6ead0: Websocket handler shutting down dir=READ error_code=0 immediate=0.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: handler 0x7fbfc0e6ead8 shutdown in read dir completed.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: handler 0x7fbfc0ca33a0 shutdown in read dir completed.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f18368: Scheduling (null) task for immediate execution
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f18368: Running (null) task with <Running> status
[INFO ] [2020-04-28T20:23:08Z] [0000700001cea000] [mqtt-client] - id=0x7fbfc0ca32e0: sending disconnect message as part of graceful shutdown.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [websocket] - id=0x7fbfc0e6ead0: Enqueuing outgoing frame with opcode=2(binary) length=2 fin=T priority=normal
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0e6eb30: Scheduling websocket_move_synced_data_to_thread task for immediate execution
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: handler 0x7fbfc0ca33a0 shutdown in write dir completed.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [websocket] - id=0x7fbfc0e6ead0: Websocket handler shutting down dir=WRITE error_code=0 immediate=0.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [websocket] - id=0x7fbfc0e6ead0: Enqueuing outgoing frame with opcode=8(close) length=0 fin=T priority=normal
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0e6eb30: Running websocket_move_synced_data_to_thread task with <Running> status
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [tls-handler] - id=0x7fbfc0f19270: SSLWrite failed with OSStatus error code -9805.
[ERROR] [2020-04-28T20:23:08Z] [0000700001cea000] [http-connection] - id=0x7fbfc0d4b640: Destroying write message without passing it along, error 1031 (AWS_IO_TLS_ERROR_WRITE_FAILURE)
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: handler 0x7fbfc0e6ead8 shutdown in write dir completed.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [http-stream] - id=0x7fbfc0d4be10: Stream completed with error code 2058 (AWS_ERROR_HTTP_CONNECTION_CLOSED).
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: handler 0x7fbfc0d4b648 shutdown in write dir completed.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: handler 0x7fbfc0f19270 shutdown in write dir completed.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [socket] - id=0x7fbfc0d3bbf0 fd=10: closing
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f07380: Scheduling kqueue_event_loop_clean_up_handle_data task for immediate execution
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f18780: Scheduling socket_handler_close task for immediate execution
[INFO ] [2020-04-28T20:23:08Z] [0000700001cea000] [http-connection] - id=0x7fbfc0d4b640: Shutting down connection with error code 1031 (AWS_IO_TLS_ERROR_WRITE_FAILURE).
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: Channel shutdown is already pending, not scheduling another.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f07380: Running kqueue_event_loop_clean_up_handle_data task with <Running> status
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f18780: Running socket_handler_close task with <Running> status
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: handler 0x7fbfc0f186e0 shutdown in write dir completed.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f18368: Scheduling (null) task for immediate execution
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f18368: Running (null) task with <Running> status
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: during shutdown, canceling task 0x7fbfc0f192a0
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0f192a0: Running tls_timeout task with <Canceled> status
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: during shutdown, canceling task 0x7fbfc0e30930
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [task-scheduler] - id=0x7fbfc0e30930: Running mqtt_connack_timeout task with <Canceled> status
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel-bootstrap] - id=0x7fbfc0c07230: channel 0x7fbfc0f18340 shutdown with error 0.
[INFO ] [2020-04-28T20:23:08Z] [0000700001cea000] [http-connection] - 0x7fbfc0d4b640: Client shutdown completed with error 0 (AWS_ERROR_SUCCESS).
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [websocket] - id=0x7fbfc0e6ead0: Websocket client connection shut down with error 0 (AWS_ERROR_SUCCESS).
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: Running websocket_shutdown_channel channel task immediately as canceled due to shut down channel
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: Channel shutdown is already pending, not scheduling another.
[DEBUG] [2020-04-28T20:23:08Z] [0000700001cea000] [channel] - id=0x7fbfc0f18340: destroying channel.
Traceback (most recent call last):
  File "pubsub.py", line 141, in <module>
    connect_future.result()
  File "/Users/victorct/miniconda3/lib/python3.7/concurrent/futures/_base.py", line 435, in result
    return self.__get_result()
  File "/Users/victorct/miniconda3/lib/python3.7/concurrent/futures/_base.py", line 384, in __get_result
    raise self._exception
awscrt.exceptions.AwsCrtError: AwsCrtError(name='AWS_ERROR_MQTT_UNEXPECTED_HANGUP', message='The connection was closed unexpectedly.', code=5134)
[DEBUG] [2020-04-28T20:23:08Z] [000000010d059dc0] [mqtt-client] - id=0x7fbfc0ca32e0: user called disconnect.
[ERROR] [2020-04-28T20:23:08Z] [000000010d059dc0] [mqtt-client] - id=0x7fbfc0ca32e0: Connection is not open, and may not be closed
[DEBUG] [2020-04-28T20:23:08Z] [000000010d059dc0] [mqtt-client] - id=0x7fbfc0ca32e0: Destroying connection
[DEBUG] [2020-04-28T20:23:08Z] [000000010d059dc0] [mqtt-topic-tree] - tree=0x7fbfc0ca33c8: Cleaning up topic tree
[DEBUG] [2020-04-28T20:23:08Z] [000000010d059dc0] [mqtt-client] - client=0x7fbfc0c4d2f0: Cleaning up MQTT client
[INFO ] [2020-04-28T20:23:08Z] [000000010d059dc0] [connection-manager] - id=0x7fbfc0c342e0: release
[INFO ] [2020-04-28T20:23:08Z] [000000010d059dc0] [connection-manager] - id=0x7fbfc0c342e0: ref count now zero, starting shut down process
[INFO ] [2020-04-28T20:23:08Z] [000000010d059dc0] [connection-manager] - id=0x7fbfc0c342e0: manager release, failing 0 pending acquisitions
[DEBUG] [2020-04-28T20:23:08Z] [000000010d059dc0] [connection-manager] - id=0x7fbfc0c342e0: snapshot - state=2, held_connection_count=0, pending_acquire_count=0, pending_connect_count=0, vended_connection_count=0, open_connection_count=0, ref_count=0
[INFO ] [2020-04-28T20:23:08Z] [000000010d059dc0] [connection-manager] - id=0x7fbfc0c342e0: Destroying self
[DEBUG] [2020-04-28T20:23:08Z] [000000010d059dc0] [channel-bootstrap] - id=0x7fbfc0c07230: releasing bootstrap reference