parallels@vm-ubuntu:~/spaces/aws-iot-device-sdk-embedded-C/dev1/build$ ./bin/ota_demo_core_mqtt 
[INFO] [DEMO] [ota_demo_core_mqtt.c:1522] OTA over MQTT demo, Application version 0.9.2
[INFO] [DEMO] [ota_demo_core_mqtt.c:994] Establishing a TLS session to a1orjg3woutzln-ats.iot.us-west-2.amazonaws.com:8883.
[INFO] [DEMO] [ota_demo_core_mqtt.c:1144] Creating an MQTT connection to a1orjg3woutzln-ats.iot.us-west-2.amazonaws.com.
[INFO] [MQTT] [core_mqtt.c:885] Packet received. ReceivedBytes=2.
[INFO] [MQTT] [core_mqtt_serializer.c:970] CONNACK session present bit not set.
[INFO] [MQTT] [core_mqtt_serializer.c:912] Connection accepted.
[INFO] [MQTT] [core_mqtt.c:1565] Received MQTT CONNACK successfully from broker.
[INFO] [MQTT] [core_mqtt.c:1831] MQTT connection established with the broker.
[INFO] [DEMO] [ota_demo_core_mqtt.c:1112] MQTT connection successfully established with broker.


[INFO] [OTA] [ota.c:2892] Current State=[RequestingJob], Event=[Start], New state=[RequestingJob]
[INFO] [DEMO] [ota_demo_core_mqtt.c:1307] SUBSCRIBE topic $aws/things/testclient/jobs/notify-next to broker.


[INFO] [OTA] [ota_mqtt.c:386] Subscribed to MQTT topic: $aws/things/testclient/jobs/notify-next
[INFO] [MQTT] [core_mqtt.c:885] Packet received. ReceivedBytes=2.
[INFO] [MQTT] [core_mqtt.c:1163] Ack packet deserialized with result: MQTTSuccess.
[INFO] [MQTT] [core_mqtt.c:1176] State record updated. New state=MQTTPublishDone.
[INFO] [DEMO] [ota_demo_core_mqtt.c:861] PUBACK received for packet id 2.


[INFO] [OTA] [ota.c:2892] Current State=[WaitingForJob], Event=[RequestJobDocument], New state=[WaitingForJob]
[INFO] [MQTT] [core_mqtt.c:885] Packet received. ReceivedBytes=100.
[INFO] [MQTT] [core_mqtt.c:1046] De-serialized incoming PUBLISH packet: DeserializerResult=MQTTSuccess.
[INFO] [MQTT] [core_mqtt.c:1059] State record updated. New state=MQTTPublishDone.
[INFO] [DEMO] [mqtt_subscription_manager.c:86] Invoking subscription callback of matching topic filter: TopicFilter=$aws/things/+/jobs/#, TopicName=$aws/things/testclient/jobs/$next/get/accepted
[INFO] [OTA] [ota.c:2265] No active job available in received job document: OtaJobParseErr_t=OtaJobParseErrNoActiveJobs
[INFO] [OTA] [ota.c:2892] Current State=[WaitingForJob], Event=[ReceivedJobDocument], New state=[CreatingFile]
[INFO] [MQTT] [core_mqtt.c:885] Packet received. ReceivedBytes=3.
[INFO] [DEMO] [ota_demo_core_mqtt.c:845] Received SUBACK.


[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1608] Int-Test: Calling OTA_Resume()...
[INFO] [OTA] [ota_os_posix.c:290] Int-Test: Created timer[0]
[INFO] [OTA] [ota_os_posix.c:313] Int-Test: Started timer[0]
[ERROR] [OTA] [ota.c:2831] Received unexpected event: Current state=[WaitingForJob], Event received=[Resume]
[INFO] [DEMO] [ota_demo_core_mqtt.c:1610] Int-Test: OTA_Resume() done.
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [OTA] [ota.c:630] Int-Test: Request callback fired
[ERROR] [OTA] [ota.c:2831] Received unexpected event: Current state=[WaitingForJob], Event received=[RequestTimer]
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
[INFO] [DEMO] [ota_demo_core_mqtt.c:1604] Int-Test: OTA Agent nominal
[INFO] [DEMO] [ota_demo_core_mqtt.c:1616]  Received: 0   Queued: 0   Processed: 0   Dropped: 0
^C