Hi,
I need to run Shadow demo and OTA demo simultaneously in the STM32CubeExpansion_Cloud_AWS_V2.0.0 project. I have read similar posts on running MQTT demo and OTA simultaneously but I have issues running the same still.
In an attempt to run the shadow demo and the OTA demo simultaneously I have created another detached thread in iot_demo_runner.c as seen below:
void DEMO_RUNNER_RunDemos( void )
{
/* These demos are shared with the C SDK and perform their own initialization and cleanup. */
static demoContext_t mqttDemoContext2 =
{
.networkTypes = democonfigNETWORK_TYPES,
.demoFunction = DEMO_entryFUNCTION2,
.networkConnectedCallback = DEMO_networkConnectedCallback,
.networkDisconnectedCallback = DEMO_networkDisconnectedCallback
};
Iot_CreateDetachedThread( runDemoTask,
&mqttDemoContext2,
democonfigDEMO_PRIORITY,
democonfigDEMO_STACKSIZE );
static demoContext_t mqttDemoContext =
{
.networkTypes = democonfigNETWORK_TYPES,
.demoFunction = DEMO_entryFUNCTION,
.networkConnectedCallback = DEMO_networkConnectedCallback,
.networkDisconnectedCallback = DEMO_networkDisconnectedCallback
};
Iot_CreateDetachedThread( runDemoTask,
&mqttDemoContext,
democonfigDEMO_PRIORITY,
democonfigDEMO_STACKSIZE );
}
In aws_demo_config.h I have created my own demo define as follows:
#if (defined( CONFIG_COMBINED_SHADOW_OTA_ENABLED ))
#define CONFIG_SHADOW_DEMO_ENABLED
#define CONFIG_OTA_UPDATE_DEMO_ENABLED
#endif
And in iot_demo_runner .h I added this as well to define my second demo entry function
#if defined( CONFIG_COMBINED_SHADOW_OTA_ENABLED )
#define DEMO_entryFUNCTION RunShadowDemo
#if defined( democonfigSHADOW_DEMO_TASK_STACK_SIZE )
#undef democonfigDEMO_STACKSIZE
#define democonfigDEMO_STACKSIZE democonfigSHADOW_DEMO_TASK_STACK_SIZE
#endif
#if defined( democonfigSHADOW_DEMO_TASK_PRIORITY )
#undef democonfigDEMO_PRIORITY
#define democonfigDEMO_PRIORITY democonfigSHADOW_DEMO_TASK_PRIORITY
#endif
#define DEMO_entryFUNCTION2 vStartOTAUpdateDemoTask
#elif defined( CONFIG_MQTT_DEMO_ENABLED )
If I leave the priorities as show in the code above then the Shadow Demo is the only thing that runs (Terminal Output):
6 20678 [iot_thread] [INFO ][DEMO][20678] ---------STARTING DEMO---------
7 20685 [iot_thread] [INFO ][DEMO][20685] ---------STARTING DEMO---------
8 20692 [iot_thread] [INFO ][INIT][20692] SDK successfully initialized.
...9 27115 [iot_thread] [INFO ][DEMO][27114] Successfully initialized the demo. Network type for the demo: 1
10 27124 [iot_thread] Shadow Demo
11 27127 [iot_thread] [INFO ][MQTT][27127] MQTT library successfully initialized.
12 27135 [iot_thread] [INFO ][Shadow][27135] Shadow library successfully initialized.
13 27143 [iot_thread] [INFO ][DEMO][27142] Shadow Thing Name is C201300004 (length 10).
.14 31025 [iot_thread] [INFO ][MQTT][31025] Establishing new MQTT connection.
15 31033 [iot_thread] [INFO ][MQTT][31033] Anonymous metrics (SDK language, SDK version) will be provided to AWS IoT. Recompile with AWS_IOT_MQTT_ENABLE_METRICS set to 0 to disable.
16 31049 [iot_thread] [INFO ][MQTT][31049] (MQTT connection 200485e0, CONNECT operation 20048768) Waiting for operation completion.
17 31197 [iot_thread] [INFO ][MQTT][31197] (MQTT connection 200485e0, CONNECT operation 20048768) Wait complete with result SUCCESS.
18 31209 [iot_thread] [INFO ][MQTT][31209] New MQTT connection 20045c08 established.
19 31217 [iot_thread] [INFO ][Shadow][31217] (C201300004) Modifying Shadow DELTA callback.
20 31225 [iot_thread] [INFO ][Shadow][31225] (C201300004) Adding new DELTA callback.
21 31233 [iot_thread] [INFO ][MQTT][31233] (MQTT connection 200485e0) SUBSCRIBE operation scheduled.
22 31244 [iot_thread] [INFO ][MQTT][31243] (MQTT connection 200485e0, SUBSCRIBE operation 20049090) Waiting for operation completion.
23 31370 [iot_thread] [INFO ][MQTT][31370] (MQTT connection 200485e0, SUBSCRIBE operation 20049090) Wait complete with result SUCCESS.
24 31383 [iot_thread] [INFO ][Shadow][31382] (C201300004) Shadow DELTA callback operation complete with result SUCCESS.
25 31393 [iot_thread] [INFO ][Shadow][31393] (C201300004) Modifying Shadow UPDATED callback.
26 31402 [iot_thread] [INFO ][Shadow][31402] (C201300004) Adding new UPDATED callback.
27 31410 [iot_thread] [INFO ][MQTT][31410] (MQTT connection 200485e0) SUBSCRIBE operation scheduled.
28 31421 [iot_thread] [INFO ][MQTT][31420] (MQTT connection 200485e0, SUBSCRIBE operation 20049090) Waiting for operation completion.
29 31583 [iot_thread] [INFO ][MQTT][31583] (MQTT connection 200485e0, SUBSCRIBE operation 20049090) Wait complete with result SUCCESS.
30 31596 [iot_thread] [INFO ][Shadow][31595] (C201300004) Shadow UPDATED callback operation complete with result SUCCESS.
31 31607 [iot_thread] [INFO ][MQTT][31607] (MQTT connection 200485e0) SUBSCRIBE operation scheduled.
32 31618 [iot_thread] [INFO ][MQTT][31617] (MQTT connection 200485e0, SUBSCRIBE operation 200491a0) Waiting for operation completion.
.33 31751 [iot_thread] [INFO ][MQTT][31751] (MQTT connection 200485e0, SUBSCRIBE operation 200491a0) Wait complete with result SUCCESS.
34 31764 [iot_thread] [INFO ][MQTT][31764] (MQTT connection 200485e0) SUBSCRIBE operation scheduled.
35 31775 [iot_thread] [INFO ][MQTT][31774] (MQTT connection 200485e0, SUBSCRIBE operation 200491a0) Waiting for operation completion.
36 31900 [iot_thread] [INFO ][MQTT][31900] (MQTT connection 200485e0, SUBSCRIBE operation 200491a0) Wait complete with result SUCCESS.
37 31913 [iot_thread] [INFO ][MQTT][31913] (MQTT connection 200485e0) MQTT PUBLISH operation queued.
38 32062 [iot_thread] [WARN ][Shadow][32062] Shadow DELETE of C201300004 was REJECTED.
39 32070 [iot_thread] [WARN ][Shadow][32070] Code 404: "No shadow exists with name: 'C201300004'".
40 32079 [iot_thread] [INFO ][MQTT][32079] (MQTT connection 200485e0) UNSUBSCRIBE operation scheduled.
41 32089 [iot_thread] [INFO ][MQTT][32089] (MQTT connection 200485e0, UNSUBSCRIBE operation 200491a0) Waiting for operation completion.
42 32215 [iot_thread] [INFO ][MQTT][32215] (MQTT connection 200485e0, UNSUBSCRIBE operation 200491a0) Wait complete with result SUCCESS.
43 32228 [iot_thread] [INFO ][MQTT][32228] (MQTT connection 200485e0) UNSUBSCRIBE operation scheduled.
44 32238 [iot_thread] [INFO ][MQTT][32238] (MQTT connection 200485e0, UNSUBSCRIBE operation 200491a0) Waiting for operation completion.
45 32384 [iot_thread] [INFO ][MQTT][32384] (MQTT connection 200485e0, UNSUBSCRIBE operation 200491a0) Wait complete with result SUCCESS.
46 32397 [iot_thread] [INFO ][DEMO][32397] Successfully cleared Shadow of C201300004.
47 32504 [iot_thread] [INFO ][DEMO][32504] Sending Shadow update 1 of 20: {"state":{"desired":{"powerOn":1}},"clientToken":"032504"}
48 32516 [iot_thread] [INFO ][MQTT][32516] (MQTT connection 200485e0) SUBSCRIBE operation scheduled.
49 32526 [iot_thread] [INFO ][MQTT][32526] (MQTT connection 200485e0, SUBSCRIBE operation 200491a0) Waiting for operation completion.
50 32664 [iot_thread] [INFO ][MQTT][32664] (MQTT connection 200485e0, SUBSCRIBE operation 200491a0) Wait complete with result SUCCESS.
51 32677 [iot_thread] [INFO ][MQTT][32677] (MQTT connection 200485e0) SUBSCRIBE operation scheduled.
52 32687 [iot_thread] [INFO ][MQTT][32687] (MQTT connection 200485e0, SUBSCRIBE operation 200491a0) Waiting for operation completion.
53 32821 [iot_thread] [INFO ][MQTT][32821] (MQTT connection 200485e0, SUBSCRIBE operation 200491a0) Wait complete with result SUCCESS.
54 32834 [iot_thread] [INFO ][MQTT][32834] (MQTT connection 200485e0) MQTT PUBLISH operation queued.
55 32985 [iot_thread] [INFO ][DEMO][32985] Shadow was updated!
Previous: {"state":{}}
Current: {"state":{"desired":{"powerOn":1}}}
56 33011 [iot_thread] [INFO ][DEMO][33011] C201300004 changing state from 0 to 1.
57 33020 [iot_thread] [INFO ][MQTT][33020] (MQTT connection 200485e0) MQTT PUBLISH operation queued.
58 33030 [iot_thread] [INFO ][DEMO][33030] C201300004 sent new state report.
59 33056 [iot_thread] [INFO ][Shadow][33056] Shadow UPDATE of C201300004 was ACCEPTED.
60 33064 [iot_thread] [INFO ][DEMO][33064] Successfully sent Shadow update 1 of 20.
61 33221 [iot_thread] [INFO ][DEMO][33221] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
62 33253 [iot_thread] [INFO ][Shadow][33253] Shadow UPDATE of C201300004 was ACCEPTED.
..63 36171 [iot_thread] [INFO ][DEMO][36171] Sending Shadow update 2 of 20: {"state":{"desired":{"powerOn":0}},"clientToken":"036171"}
64 36183 [iot_thread] [INFO ][MQTT][36183] (MQTT connection 200485e0) MQTT PUBLISH operation queued.
65 36339 [iot_thread] [INFO ][DEMO][36339] C201300004 changing state from 1 to 0.
66 36349 [iot_thread] [INFO ][MQTT][36346] (MQTT connection 200485e0) MQTT PUBLISH operation queued.
67 36358 [iot_thread] [INFO ][DEMO][36358] C201300004 sent new state report.
68 36385 [iot_thread] [INFO ][DEMO][36385] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
69 36418 [iot_thread] [INFO ][Shadow][36418] Shadow UPDATE of C201300004 was ACCEPTED.
70 36426 [iot_thread] [INFO ][DEMO][36426] Successfully sent Shadow update 2 of 20.
71 36478 [iot_thread] [INFO ][DEMO][36478] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
72 36511 [iot_thread] [INFO ][Shadow][36511] Shadow UPDATE of C201300004 was ACCEPTED.
.73 39533 [iot_thread] [INFO ][DEMO][39533] Sending Shadow update 3 of 20: {"state":{"desired":{"powerOn":1}},"clientToken":"039533"}
74 39545 [iot_thread] [INFO ][MQTT][39545] (MQTT connection 200485e0) MQTT PUBLISH operation queued.
.75 39793 [iot_thread] [INFO ][DEMO][39793] C201300004 changing state from 0 to 1.
76 39802 [iot_thread] [INFO ][MQTT][39800] (MQTT connection 200485e0) MQTT PUBLISH operation queued.
77 39811 [iot_thread] [INFO ][DEMO][39811] C201300004 sent new state report.
78 39839 [iot_thread] [INFO ][DEMO][39839] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
79 39872 [iot_thread] [INFO ][Shadow][39872] Shadow UPDATE of C201300004 was ACCEPTED.
80 39880 [iot_thread] [INFO ][DEMO][39880] Successfully sent Shadow update 3 of 20.
81 40036 [iot_thread] [INFO ][DEMO][40036] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
82 40069 [iot_thread] [INFO ][Shadow][40069] Shadow UPDATE of C201300004 was ACCEPTED.
If I modify the ota priority to: democonfigDEMO_PRIORITY - 1 both demos seem to begin but then the execution fails (Terminal Output):
6 3623 [iot_thread] [INFO ][DEMO][3623] ---------STARTING DEMO---------
7 3630 [iot_thread] [INFO ][INIT][3630] SDK successfully initialized.
8 4148 [iot_thread] [INFO ][DEMO][4148] ---------STARTING DEMO---------
9 4155 [iot_thread] [INFO ][INIT][4155] SDK successfully initialized.
.................10 38669 [iot_thread] [INFO ][DEMO][38668] Successfully initialized the demo. Network type for the demo: 1
11 38678 [iot_thread] Shadow Demo
12 38681 [iot_thread] [INFO ][MQTT][38681] MQTT library successfully initialized.
13 38689 [iot_thread] [INFO ][Shadow][38689] Shadow library successfully initialized.
14 38697 [iot_thread] [INFO ][DEMO][38697] Shadow Thing Name is C201300004 (length 10).
......15 48884 [iot_thread] ERROR: Handshake failed with error code -29312
16 48891 [iot_thread] [ERROR][NET][48891] Failed to establish new connection. Socket status: -1002.
17 48931 [iot_thread] [INFO ][DEMO][48931] Successfully initialized the demo. Network type for the demo: 1
18 48940 [iot_thread] OTA Demo
19 48943 [iot_thread] [ERROR][MQTT][48943] Failed to establish new MQTT connection, error NETWORK ERROR.
20 48953 [iot_thread] [ERROR][DEMO][48953] MQTT CONNECT returned error NETWORK ERROR.
21 48961 [iot_thread] [INFO ][Shadow][48961] Shadow library cleanup done.
22 48967 [iot_thread] [INFO ][MQTT][48967] MQTT library cleanup done.
23 48974 [iot_thread] [INFO ][DEMO][48974] memory_metrics::freertos_heap::before::bytes::193000
24 48983 [iot_thread] [INFO ][DEMO][48982] memory_metrics::freertos_heap::after::bytes::157176
25 48991 [iot_thread] [INFO ][DEMO][48991] memory_metrics::demo_task_stack::before::bytes::0
26 49000 [iot_thread] [INFO ][DEMO][49000] memory_metrics::demo_task_stack::after::bytes::0
27 49008 [iot_thread] [ERROR][DEMO][49008] Error running demo.
28 49020 [iot_thread] [INFO ][INIT][49020] SDK cleanup done.
29 49026 [iot_thread] [INFO ][DEMO][49026] -------DEMO FINISHED-------
30 49114 [iot_thread] [INFO ][MQTT][49114] MQTT library successfully initialized.
31 49121 [iot_thread] OTA demo version 0.9.2
32 49125 [iot_thread] Creating MQTT Client...
33 49129 [iot_thread] Waiting for a network connection.
.........................................
Please let me know if you have any incite as for how I should continue.
Edit:
So there seems to be a little inconsistency with the output in our trials. Without modifying the firmware There are different outcomes seeming to happen randomly. In some cases we are able to get a successful execution of both demos, and in others it fails as shown above.
Successful terminal output:
6 5602 [iot_thread] [INFO ][DEMO][5602] ---------STARTING DEMO---------
7 5609 [iot_thread] [INFO ][INIT][5609] SDK successfully initialized.
8 6127 [iot_thread] [INFO ][DEMO][6127] ---------STARTING DEMO---------
9 6134 [iot_thread] [INFO ][INIT][6134] SDK successfully initialized.
.....................10 46871 [iot_thread] [INFO ][DEMO][46870] Successfully ini
11 46880 [iot_thread] Shadow Demo
12 46883 [iot_thread] [INFO ][MQTT][46883] MQTT library successfully initialized
13 46891 [iot_thread] [INFO ][Shadow][46891] Shadow library successfully initial
14 46899 [iot_thread] [INFO ][DEMO][46899] Shadow Thing Name is C201300004 (leng
..15 52167 [iot_thread] [INFO ][DEMO][52167] Successfully initialized the demo.
16 52176 [iot_thread] OTA Demo
17 52279 [iot_thread] [INFO ][MQTT][52279] MQTT library successfully initialized
18 52286 [iot_thread] OTA demo version 0.9.2
19 52290 [iot_thread] Creating MQTT Client...
.20 56169 [iot_thread] Connecting to broker...
21 56173 [iot_thread] [INFO ][MQTT][56173] Establishing new MQTT connection.
22 56181 [iot_thread] [INFO ][MQTT][56180] Anonymous metrics (SDK language, SDK ICS set to 0 to disable.
23 56199 [iot_thread] [INFO ][MQTT][56198] (MQTT connection 2004ef00, CONNECT op
24 56341 [iot_thread] [INFO ][MQTT][56341] (MQTT connection 2004ef00, CONNECT op
25 56354 [iot_thread] [INFO ][MQTT][56354] New MQTT connection 20042bdc establis
26 56361 [iot_thread] Connected to broker.
27 56367 [iot_thread] [OTA_AgentInit_internal] OTA Task is Ready.
28 56373 [OTA Agent Task] [prvOTAAgentTask] Called handler. Current State [Ready
29 56386 [OTA Agent Task] [INFO ][MQTT][56385] (MQTT connection 2004ef00) SUBSCR
30 56395 [OTA Agent Task] [INFO ][MQTT][56395] (MQTT connection 2004ef00, SUBSCR
.31 56535 [OTA Agent Task] [INFO ][MQTT][56535] (MQTT connection 2004ef00, SUBSC
32 56547 [OTA Agent Task] [prvSubscribeToJobNotificationTopics] OK: $aws/things/
33 56559 [OTA Agent Task] [INFO ][MQTT][56559] (MQTT connection 2004ef00) SUBSCR
34 56569 [OTA Agent Task] [INFO ][MQTT][56569] (MQTT connection 2004ef00, SUBSCR
35 56694 [OTA Agent Task] [INFO ][MQTT][56694] (MQTT connection 2004ef00, SUBSCR
36 56706 [OTA Agent Task] [prvSubscribeToJobNotificationTopics] OK: $aws/things/
37 56716 [OTA Agent Task] [prvRequestJob_Mqtt] Request #0
38 56723 [OTA Agent Task] [INFO ][MQTT][56723] (MQTT connection 2004ef00) MQTT P
39 56733 [OTA Agent Task] [INFO ][MQTT][56733] (MQTT connection 2004ef00, PUBLIS
40 58580 [iot_thread] State: Ready Received: 0 Queued: 0 Processed: 0 Dro
.41 59855 [iot_thread] State: RequestingJob Received: 0 Queued: 0 Processed
42 59950 [OTA Agent Task] [INFO ][MQTT][59950] (MQTT connection 2004ef00, PUBLIS
43 59962 [OTA Agent Task] [prvOTAAgentTask] Called handler. Current State [Reque
44 59975 [OTA Agent Task] [prvParseJobDoc] Size of OTA_FileContext_t [64]
45 59982 [OTA Agent Task] [prvParseJSONbyModel] Extracted parameter [ clientToke
46 59991 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: execution
47 59999 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: jobId
48 60006 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: jobDocume
49 60014 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: afr_ota
50 60021 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: protocols
51 60028 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: files
52 60035 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: filepath
53 60043 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: filesize
54 60050 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: fileid
55 60057 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: certfile
56 60066 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: sig-sha25
57 60074 [OTA Agent Task] [prvDefaultCustomJobCallback] Received Custom Job insi
58 60085 [OTA Agent Task] [prvParseJobDoc] Ignoring job without ID.
59 60091 [OTA Agent Task] [prvOTA_Close] Context->0x20058888
60 60097 [OTA Agent Task] [prvPAL_SetPlatformImageState] Current state (SE_FwSta
61 60105 [OTA Agent Task] [prvPAL_SetPlatformImageState] Request to change state
62 60115 [OTA Agent Task] [prvOTAAgentTask] Handler failed. Current State [Waiti
63 60128 [OTA Agent Task] [prvParseJobDoc] Size of OTA_FileContext_t [64]
64 60136 [iot_thread] [INFO ][MQTT][60136] Establishing new MQTT connection.
65 60144 [iot_thread] [INFO ][MQTT][60143] Anonymous metrics (SDK language, SDK ICS set to 0 to disable.
66 60160 [iot_thread] [INFO ][MQTT][60160] (MQTT connection 200487a8, CONNECT op
67 60174 [OTA Agent Task] [prvParseJSONbyModel] Extracted parameter [ clientToke
68 60183 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: execution
69 60190 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: jobId
70 60198 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: jobDocume
71 60205 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: afr_ota
72 60213 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: protocols
73 60220 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: files
74 60227 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: filepath
75 60235 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: filesize
76 60243 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: fileid
77 60250 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: certfile
78 60258 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: sig-sha25
79 60266 [OTA Agent Task] [prvDefaultCustomJobCallback] Received Custom Job insi
80 60276 [OTA Agent Task] [prvParseJobDoc] Ignoring job without ID.
81 60282 [OTA Agent Task] [prvOTA_Close] Context->0x20058888
82 60288 [OTA Agent Task] [prvPAL_SetPlatformImageState] Current state (SE_FwSta
83 60297 [OTA Agent Task] [prvPAL_SetPlatformImageState] Request to change state
84 60306 [OTA Agent Task] [prvOTAAgentTask] Handler failed. Current State [Waiti
85 60319 [OTA Agent Task] [prvParseJobDoc] Size of OTA_FileContext_t [64]
86 60326 [OTA Agent Task] [prvParseJSONbyModel] Extracted parameter [ clientToke
87 60335 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: execution
88 60342 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: jobId
89 60350 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: jobDocume
90 60357 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: afr_ota
91 60364 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: protocols
92 60373 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: files
93 60380 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: filepath
94 60387 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: filesize
95 60395 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: fileid
96 60402 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: certfile
97 60409 [OTA Agent Task] [prvParseJSONbyModel] parameter not present: sig-sha25
98 60417 [OTA Agent Task] [prvDefaultCustomJobCallback] Received Custom Job insi
99 60428 [OTA Agent Task] [prvParseJobDoc] Ignoring job without ID.
100 60434 [OTA Agent Task] [prvOTA_Close] Context->0x20058888
101 60440 [OTA Agent Task] [prvPAL_SetPlatformImageState] Current state (SE_FwSt
102 60449 [OTA Agent Task] [prvPAL_SetPlatformImageState] Request to change stat
103 60459 [OTA Agent Task] [prvOTAAgentTask] Handler failed. Current State [Wait
104 60472 [iot_thread] [INFO ][MQTT][60472] (MQTT connection 200487a8, CONNECT o
105 60484 [iot_thread] [INFO ][MQTT][60484] New MQTT connection 20045c08 establi
106 60492 [iot_thread] [INFO ][Shadow][60491] (C201300004) Modifying Shadow DELT
107 60500 [iot_thread] [INFO ][Shadow][60500] (C201300004) Adding new DELTA call
108 60508 [iot_thread] [INFO ][MQTT][60508] (MQTT connection 200487a8) SUBSCRIBE
109 60518 [iot_thread] [INFO ][MQTT][60518] (MQTT connection 200487a8, SUBSCRIBE
.110 60655 [iot_thread] [INFO ][MQTT][60655] (MQTT connection 200487a8, SUBSCRIB
111 60668 [iot_thread] [INFO ][Shadow][60668] (C201300004) Shadow DELTA callback
112 60679 [iot_thread] [INFO ][Shadow][60679] (C201300004) Modifying Shadow UPDA
113 60687 [iot_thread] [INFO ][Shadow][60687] (C201300004) Adding new UPDATED ca
114 60695 [iot_thread] [INFO ][MQTT][60695] (MQTT connection 200487a8) SUBSCRIBE
115 60705 [iot_thread] [INFO ][MQTT][60705] (MQTT connection 200487a8, SUBSCRIBE
116 60842 [iot_thread] [INFO ][MQTT][60842] (MQTT connection 200487a8, SUBSCRIBE
117 60855 [iot_thread] [INFO ][Shadow][60855] (C201300004) Shadow UPDATED callba
118 60866 [iot_thread] [INFO ][MQTT][60866] (MQTT connection 200487a8) SUBSCRIBE
119 60876 [iot_thread] [INFO ][MQTT][60876] (MQTT connection 200487a8, SUBSCRIBE
120 60888 [iot_thread] State: RequestingJob Received: 3 Queued: 0 Processed
121 61019 [iot_thread] [INFO ][MQTT][61019] (MQTT connection 200487a8, SUBSCRIBE
122 61032 [iot_thread] [INFO ][MQTT][61032] (MQTT connection 200487a8) SUBSCRIBE
123 61042 [iot_thread] [INFO ][MQTT][61042] (MQTT connection 200487a8, SUBSCRIBE
124 61179 [iot_thread] [INFO ][MQTT][61179] (MQTT connection 200487a8, SUBSCRIBE
125 61192 [iot_thread] [INFO ][MQTT][61192] (MQTT connection 200487a8) MQTT PUBL
126 61341 [iot_thread] [WARN ][Shadow][61341] Shadow DELETE of C201300004 was RE
127 61349 [iot_thread] [WARN ][Shadow][61349] Code 404: "No shadow exists with n
128 61358 [iot_thread] [INFO ][MQTT][61358] (MQTT connection 200487a8) UNSUBSCRI
129 61368 [iot_thread] [INFO ][MQTT][61368] (MQTT connection 200487a8, UNSUBSCRI
130 61493 [iot_thread] [INFO ][MQTT][61493] (MQTT connection 200487a8, UNSUBSCRI
131 61506 [iot_thread] [INFO ][MQTT][61506] (MQTT connection 200487a8) UNSUBSCRI
132 61516 [iot_thread] [INFO ][MQTT][61516] (MQTT connection 200487a8, UNSUBSCRI
133 61644 [iot_thread] [INFO ][MQTT][61644] (MQTT connection 200487a8, UNSUBSCRI
134 61657 [iot_thread] [INFO ][DEMO][61657] Successfully cleared Shadow of C2013
135 61765 [iot_thread] [INFO ][DEMO][61765] Sending Shadow update 1 of 20: {"sta
136 61777 [iot_thread] [INFO ][MQTT][61777] (MQTT connection 200487a8) SUBSCRIBE
137 61787 [iot_thread] [INFO ][MQTT][61787] (MQTT connection 200487a8, SUBSCRIBE
138 61897 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
139 61923 [iot_thread] [INFO ][MQTT][61923] (MQTT connection 200487a8, SUBSCRIBE
140 61936 [iot_thread] [INFO ][MQTT][61936] (MQTT connection 200487a8) SUBSCRIBE
141 61946 [iot_thread] [INFO ][MQTT][61946] (MQTT connection 200487a8, SUBSCRIBE
142 62073 [iot_thread] [INFO ][MQTT][62073] (MQTT connection 200487a8, SUBSCRIBE
143 62086 [iot_thread] [INFO ][MQTT][62086] (MQTT connection 200487a8) MQTT PUBL
144 62248 [iot_thread] [INFO ][DEMO][62248] Shadow was updated!
Previous: {"state":{}}
Current: {"state":{"desired":{"powerOn":1}}}
145 62275 [iot_thread] [INFO ][DEMO][62275] C201300004 changing state from 0 to
146 62283 [iot_thread] [INFO ][MQTT][62283] (MQTT connection 200487a8) MQTT PUBL
147 62294 [iot_thread] [INFO ][DEMO][62293] C201300004 sent new state report.
148 62318 [iot_thread] [INFO ][Shadow][62318] Shadow UPDATE of C201300004 was AC
149 62326 [iot_thread] [INFO ][DEMO][62326] Successfully sent Shadow update 1 of
150 62442 [iot_thread] [INFO ][DEMO][62442] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
151 62473 [iot_thread] [INFO ][Shadow][62473] Shadow UPDATE of C201300004 was AC
.152 62905 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
153 63913 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
.154 64921 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
155 65434 [iot_thread] [INFO ][DEMO][65434] Sending Shadow update 2 of 20: {"sta
156 65446 [iot_thread] [INFO ][MQTT][65446] (MQTT connection 200487a8) MQTT PUBL
157 65605 [iot_thread] [INFO ][DEMO][65605] C201300004 changing state from 1 to
158 65613 [iot_thread] [INFO ][MQTT][65613] (MQTT connection 200487a8) MQTT PUBL
159 65623 [iot_thread] [INFO ][DEMO][65623] C201300004 sent new state report.
160 65648 [iot_thread] [INFO ][Shadow][65648] Shadow UPDATE of C201300004 was AC
161 65656 [iot_thread] [INFO ][DEMO][65656] Successfully sent Shadow update 2 of
162 65681 [iot_thread] [INFO ][DEMO][65681] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
163 65744 [iot_thread] [INFO ][DEMO][65744] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
164 65787 [iot_thread] [INFO ][Shadow][65787] Shadow UPDATE of C201300004 was AC
165 65929 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
.166 66937 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
167 67945 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
.168 68764 [iot_thread] [INFO ][DEMO][68764] Sending Shadow update 3 of 20: {"st
169 68776 [iot_thread] [INFO ][MQTT][68776] (MQTT connection 200487a8) MQTT PUBL
170 68932 [iot_thread] [INFO ][DEMO][68932] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
171 68953 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
172 68974 [iot_thread] [INFO ][DEMO][68974] C201300004 changing state from 0 to
173 68982 [iot_thread] [INFO ][MQTT][68982] (MQTT connection 200487a8) MQTT PUBL
174 68992 [iot_thread] [INFO ][DEMO][68992] C201300004 sent new state report.
175 69017 [iot_thread] [INFO ][Shadow][69017] Shadow UPDATE of C201300004 was AC
176 69025 [iot_thread] [INFO ][DEMO][69025] Successfully sent Shadow update 3 of
177 69145 [iot_thread] [INFO ][DEMO][69145] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
178 69178 [iot_thread] [INFO ][Shadow][69178] Shadow UPDATE of C201300004 was AC
179 69961 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
.180 70969 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
181 71977 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
182 72133 [iot_thread] [INFO ][DEMO][72133] Sending Shadow update 4 of 20: {"sta
183 72145 [iot_thread] [INFO ][MQTT][72145] (MQTT connection 200487a8) MQTT PUBL
184 72312 [iot_thread] [INFO ][DEMO][72312] C201300004 changing state from 1 to
185 72320 [iot_thread] [INFO ][MQTT][72320] (MQTT connection 200487a8) MQTT PUBL
186 72330 [iot_thread] [INFO ][DEMO][72330] C201300004 sent new state report.
187 72358 [iot_thread] [INFO ][DEMO][72358] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
188 72391 [iot_thread] [INFO ][Shadow][72391] Shadow UPDATE of C201300004 was AC
189 72399 [iot_thread] [INFO ][DEMO][72399] Successfully sent Shadow update 4 of
190 72452 [iot_thread] [INFO ][DEMO][72452] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
191 72485 [iot_thread] [INFO ][Shadow][72485] Shadow UPDATE of C201300004 was AC
.192 72985 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
193 73993 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
.194 75001 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
195 75507 [iot_thread] [INFO ][DEMO][75507] Sending Shadow update 5 of 20: {"sta
196 75519 [iot_thread] [INFO ][MQTT][75519] (MQTT connection 200487a8) MQTT PUBL
197 75673 [iot_thread] [INFO ][DEMO][75673] C201300004 changing state from 0 to
198 75681 [iot_thread] [INFO ][MQTT][75681] (MQTT connection 200487a8) MQTT PUBL
199 75691 [iot_thread] [INFO ][DEMO][75691] C201300004 sent new state report.
200 75719 [iot_thread] [INFO ][DEMO][75719] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
201 75752 [iot_thread] [INFO ][Shadow][75752] Shadow UPDATE of C201300004 was AC
202 75760 [iot_thread] [INFO ][DEMO][75760] Successfully sent Shadow update 5 of
203 75824 [iot_thread] [INFO ][DEMO][75824] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
204 75857 [iot_thread] [INFO ][Shadow][75857] Shadow UPDATE of C201300004 was AC
205 76009 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
.206 77017 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
207 78025 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
.208 78868 [iot_thread] [INFO ][DEMO][78868] Sending Shadow update 6 of 20: {"st
209 78880 [iot_thread] [INFO ][MQTT][78880] (MQTT connection 200487a8) MQTT PUBL
210 79033 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
211 79044 [iot_thread] [INFO ][Shadow][79044] Shadow UPDATE of C201300004 was AC
212 79052 [iot_thread] [INFO ][DEMO][79052] Successfully sent Shadow update 6 of
213 79074 [iot_thread] [INFO ][DEMO][79074] C201300004 changing state from 1 to
214 79082 [iot_thread] [INFO ][MQTT][79082] (MQTT connection 200487a8) MQTT PUBL
215 79092 [iot_thread] [INFO ][DEMO][79092] C201300004 sent new state report.
216 79120 [iot_thread] [INFO ][DEMO][79120] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
217 79233 [iot_thread] [INFO ][DEMO][79233] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
218 79266 [iot_thread] [INFO ][Shadow][79266] Shadow UPDATE of C201300004 was AC
219 80041 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
.220 81049 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
221 82057 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
222 82199 [iot_thread] [INFO ][DEMO][82199] Sending Shadow update 7 of 20: {"sta
223 82211 [iot_thread] [INFO ][MQTT][82211] (MQTT connection 200487a8) MQTT PUBL
224 82368 [iot_thread] [INFO ][DEMO][82368] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
225 82401 [iot_thread] [INFO ][DEMO][82401] C201300004 changing state from 0 to
226 82409 [iot_thread] [INFO ][MQTT][82409] (MQTT connection 200487a8) MQTT PUBL
227 82419 [iot_thread] [INFO ][DEMO][82419] C201300004 sent new state report.
228 82444 [iot_thread] [INFO ][Shadow][82444] Shadow UPDATE of C201300004 was AC
229 82452 [iot_thread] [INFO ][DEMO][82452] Successfully sent Shadow update 7 of
230 82562 [iot_thread] [INFO ][DEMO][82562] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
231 82595 [iot_thread] [INFO ][Shadow][82595] Shadow UPDATE of C201300004 was AC
.232 83065 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
233 84073 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
.234 85081 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
235 85560 [iot_thread] [INFO ][DEMO][85560] Sending Shadow update 8 of 20: {"sta
236 85572 [iot_thread] [INFO ][MQTT][85572] (MQTT connection 200487a8) MQTT PUBL
237 85727 [iot_thread] [INFO ][DEMO][85727] C201300004 changing state from 1 to
238 85735 [iot_thread] [INFO ][MQTT][85735] (MQTT connection 200487a8) MQTT PUBL
239 85745 [iot_thread] [INFO ][DEMO][85745] C201300004 sent new state report.
240 85773 [iot_thread] [INFO ][DEMO][85773] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
241 85806 [iot_thread] [INFO ][Shadow][85806] Shadow UPDATE of C201300004 was AC
242 85814 [iot_thread] [INFO ][DEMO][85814] Successfully sent Shadow update 8 of
243 86089 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
.244 86921 [iot_thread] [INFO ][DEMO][86921] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
245 86955 [iot_thread] [INFO ][Shadow][86955] Shadow UPDATE of C201300004 was AC
246 87097 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
247 88105 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
.248 88922 [iot_thread] [INFO ][DEMO][88922] Sending Shadow update 9 of 20: {"st
249 88934 [iot_thread] [INFO ][MQTT][88934] (MQTT connection 200487a8) MQTT PUBL
250 89098 [iot_thread] [INFO ][DEMO][89098] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
251 89117 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
252 89141 [iot_thread] [INFO ][DEMO][89141] C201300004 changing state from 0 to
253 89149 [iot_thread] [INFO ][MQTT][89149] (MQTT connection 200487a8) MQTT PUBL
254 89160 [iot_thread] [INFO ][DEMO][89159] C201300004 sent new state report.
255 89184 [iot_thread] [INFO ][Shadow][89184] Shadow UPDATE of C201300004 was AC
256 89192 [iot_thread] [INFO ][DEMO][89192] Successfully sent Shadow update 9 of
257 89292 [iot_thread] [INFO ][DEMO][89292] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
258 89325 [iot_thread] [INFO ][Shadow][89325] Shadow UPDATE of C201300004 was AC
259 90125 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
.260 91133 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
261 92141 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
262 92300 [iot_thread] [INFO ][DEMO][92300] Sending Shadow update 10 of 20: {"st
263 92312 [iot_thread] [INFO ][MQTT][92312] (MQTT connection 200487a8) MQTT PUBL
264 92473 [iot_thread] [INFO ][DEMO][92473] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
265 92506 [iot_thread] [INFO ][DEMO][92506] C201300004 changing state from 1 to
266 92514 [iot_thread] [INFO ][MQTT][92514] (MQTT connection 200487a8) MQTT PUBL
267 92524 [iot_thread] [INFO ][DEMO][92524] C201300004 sent new state report.
268 92549 [iot_thread] [INFO ][Shadow][92549] Shadow UPDATE of C201300004 was AC
269 92557 [iot_thread] [INFO ][DEMO][92557] Successfully sent Shadow update 10 o
.270 92664 [iot_thread] [INFO ][Shadow][92664] Shadow UPDATE of C201300004 was A
271 92689 [iot_thread] [INFO ][DEMO][92689] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
272 93149 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
273 94157 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
.274 95165 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
275 95665 [iot_thread] [INFO ][DEMO][95665] Sending Shadow update 11 of 20: {"st
276 95677 [iot_thread] [INFO ][MQTT][95677] (MQTT connection 200487a8) MQTT PUBL
277 95835 [iot_thread] [INFO ][DEMO][95835] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
278 95868 [iot_thread] [INFO ][DEMO][95868] C201300004 changing state from 0 to
279 95876 [iot_thread] [INFO ][MQTT][95876] (MQTT connection 200487a8) MQTT PUBL
280 95886 [iot_thread] [INFO ][DEMO][95886] C201300004 sent new state report.
281 95911 [iot_thread] [INFO ][Shadow][95911] Shadow UPDATE of C201300004 was AC
282 95919 [iot_thread] [INFO ][DEMO][95919] Successfully sent Shadow update 11 o
283 96021 [iot_thread] [INFO ][DEMO][96021] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
284 96054 [iot_thread] [INFO ][Shadow][96054] Shadow UPDATE of C201300004 was AC
285 96173 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
.286 97181 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
287 98189 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
.288 99027 [iot_thread] [INFO ][DEMO][99027] Sending Shadow update 12 of 20: {"s
289 99039 [iot_thread] [INFO ][MQTT][99039] (MQTT connection 200487a8) MQTT PUBL
290 99194 [iot_thread] [INFO ][DEMO][99194] C201300004 changing state from 1 to
291 99202 [iot_thread] [INFO ][MQTT][99202] (MQTT connection 200487a8) MQTT PUBL
292 99212 [iot_thread] [INFO ][DEMO][99212] C201300004 sent new state report.
293 99219 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processed
294 99248 [iot_thread] [INFO ][DEMO][99248] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
295 99281 [iot_thread] [INFO ][Shadow][99281] Shadow UPDATE of C201300004 was AC
296 99289 [iot_thread] [INFO ][DEMO][99289] Successfully sent Shadow update 12 o
297 99341 [iot_thread] [INFO ][DEMO][99341] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
298 99374 [iot_thread] [INFO ][Shadow][99374] Shadow UPDATE of C201300004 was AC
299 100228 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
.300 101236 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Process
301 102244 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
302 102397 [iot_thread] [INFO ][DEMO][102397] Sending Shadow update 13 of 20: {"
303 102409 [iot_thread] [INFO ][MQTT][102409] (MQTT connection 200487a8) MQTT PU
304 102575 [iot_thread] [INFO ][DEMO][102575] C201300004 changing state from 0 t
305 102583 [iot_thread] [INFO ][MQTT][102583] (MQTT connection 200487a8) MQTT PU
306 102593 [iot_thread] [INFO ][DEMO][102593] C201300004 sent new state report.
.307 102619 [iot_thread] [INFO ][Shadow][102619] Shadow UPDATE of C201300004 was
308 102627 [iot_thread] [INFO ][DEMO][102627] Successfully sent Shadow update 13
309 102652 [iot_thread] [INFO ][DEMO][102652] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
310 102709 [iot_thread] [INFO ][DEMO][102709] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
311 102742 [iot_thread] [INFO ][Shadow][102742] Shadow UPDATE of C201300004 was
312 103252 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
313 104260 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
.314 105268 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Process
315 105735 [iot_thread] [INFO ][DEMO][105735] Sending Shadow update 14 of 20: {"
316 105747 [iot_thread] [INFO ][MQTT][105747] (MQTT connection 200487a8) MQTT PU
317 105913 [iot_thread] [INFO ][DEMO][105913] C201300004 changing state from 1 t
318 105921 [iot_thread] [INFO ][MQTT][105921] (MQTT connection 200487a8) MQTT PU
319 105931 [iot_thread] [INFO ][DEMO][105931] C201300004 sent new state report.
320 105957 [iot_thread] [INFO ][Shadow][105957] Shadow UPDATE of C201300004 was
321 105965 [iot_thread] [INFO ][DEMO][105965] Successfully sent Shadow update 14
322 105990 [iot_thread] [INFO ][DEMO][105990] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
323 106053 [iot_thread] [INFO ][DEMO][106053] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
324 106086 [iot_thread] [INFO ][Shadow][106086] Shadow UPDATE of C201300004 was
325 106276 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
.326 107284 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Process
327 108292 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
.328 109073 [iot_thread] [INFO ][DEMO][109073] Sending Shadow update 15 of 20: {
329 109085 [iot_thread] [INFO ][MQTT][109085] (MQTT connection 200487a8) MQTT PU
330 109245 [iot_thread] [INFO ][DEMO][109245] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
331 109279 [iot_thread] [INFO ][DEMO][109279] C201300004 changing state from 0 t
332 109287 [iot_thread] [INFO ][MQTT][109287] (MQTT connection 200487a8) MQTT PU
333 109297 [iot_thread] [INFO ][DEMO][109297] C201300004 sent new state report.
334 109304 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
335 109331 [iot_thread] [INFO ][Shadow][109331] Shadow UPDATE of C201300004 was
336 109339 [iot_thread] [INFO ][DEMO][109339] Successfully sent Shadow update 15
337 109450 [iot_thread] [INFO ][DEMO][109450] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
338 109483 [iot_thread] [INFO ][Shadow][109483] Shadow UPDATE of C201300004 was
339 110313 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
.340 111321 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Process
341 112329 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
342 112447 [iot_thread] [INFO ][DEMO][112447] Sending Shadow update 16 of 20: {"
343 112459 [iot_thread] [INFO ][MQTT][112459] (MQTT connection 200487a8) MQTT PU
344 112616 [iot_thread] [INFO ][DEMO][112616] C201300004 changing state from 1 t
345 112624 [iot_thread] [INFO ][MQTT][112624] (MQTT connection 200487a8) MQTT PU
346 112634 [iot_thread] [INFO ][DEMO][112634] C201300004 sent new state report.
.347 112663 [iot_thread] [INFO ][DEMO][112663] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
348 112696 [iot_thread] [INFO ][Shadow][112696] Shadow UPDATE of C201300004 was
349 112704 [iot_thread] [INFO ][DEMO][112704] Successfully sent Shadow update 16
350 112757 [iot_thread] [INFO ][DEMO][112757] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
351 112790 [iot_thread] [INFO ][Shadow][112790] Shadow UPDATE of C201300004 was
352 113337 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
353 114345 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
.354 115353 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Process
355 115812 [iot_thread] [INFO ][DEMO][115812] Sending Shadow update 17 of 20: {"
356 115824 [iot_thread] [INFO ][MQTT][115824] (MQTT connection 200487a8) MQTT PU
357 115982 [iot_thread] [INFO ][DEMO][115982] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
358 116016 [iot_thread] [INFO ][DEMO][116016] C201300004 changing state from 0 t
359 116024 [iot_thread] [INFO ][MQTT][116024] (MQTT connection 200487a8) MQTT PU
360 116034 [iot_thread] [INFO ][DEMO][116034] C201300004 sent new state report.
361 116060 [iot_thread] [INFO ][Shadow][116060] Shadow UPDATE of C201300004 was
362 116068 [iot_thread] [INFO ][DEMO][116068] Successfully sent Shadow update 17
363 116179 [iot_thread] [INFO ][DEMO][116179] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
364 116212 [iot_thread] [INFO ][Shadow][116212] Shadow UPDATE of C201300004 was
365 116361 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
.366 117369 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Process
367 118377 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
.368 119176 [iot_thread] [INFO ][DEMO][119176] Sending Shadow update 18 of 20: {
369 119188 [iot_thread] [INFO ][MQTT][119188] (MQTT connection 200487a8) MQTT PU
370 119353 [iot_thread] [INFO ][DEMO][119353] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
371 119385 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
372 119396 [iot_thread] [INFO ][DEMO][119396] C201300004 changing state from 1 t
373 119404 [iot_thread] [INFO ][MQTT][119404] (MQTT connection 200487a8) MQTT PU
374 119414 [iot_thread] [INFO ][DEMO][119414] C201300004 sent new state report.
375 119440 [iot_thread] [INFO ][Shadow][119440] Shadow UPDATE of C201300004 was
376 119448 [iot_thread] [INFO ][DEMO][119448] Successfully sent Shadow update 18
377 119551 [iot_thread] [INFO ][DEMO][119551] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
378 119584 [iot_thread] [INFO ][Shadow][119584] Shadow UPDATE of C201300004 was
379 120393 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
.380 121401 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Process
381 122409 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
382 122556 [iot_thread] [INFO ][DEMO][122556] Sending Shadow update 19 of 20: {"
383 122568 [iot_thread] [INFO ][MQTT][122568] (MQTT connection 200487a8) MQTT PU
.384 122725 [iot_thread] [INFO ][DEMO][122725] C201300004 changing state from 0
385 122733 [iot_thread] [INFO ][MQTT][122733] (MQTT connection 200487a8) MQTT PU
386 122744 [iot_thread] [INFO ][DEMO][122743] C201300004 sent new state report.
387 122773 [iot_thread] [INFO ][DEMO][122773] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
388 122806 [iot_thread] [INFO ][Shadow][122806] Shadow UPDATE of C201300004 was
389 122814 [iot_thread] [INFO ][DEMO][122814] Successfully sent Shadow update 19
390 122866 [iot_thread] [INFO ][DEMO][122866] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":0}}}
Current: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
391 122899 [iot_thread] [INFO ][Shadow][122899] Shadow UPDATE of C201300004 was
392 123417 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
393 124425 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
.394 125433 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Process
395 125922 [iot_thread] [INFO ][DEMO][125922] Sending Shadow update 20 of 20: {"
396 125934 [iot_thread] [INFO ][MQTT][125934] (MQTT connection 200487a8) MQTT PU
397 126087 [iot_thread] [INFO ][DEMO][126087] C201300004 changing state from 1 t
398 126095 [iot_thread] [INFO ][MQTT][126095] (MQTT connection 200487a8) MQTT PU
399 126105 [iot_thread] [INFO ][DEMO][126105] C201300004 sent new state report.
400 126134 [iot_thread] [INFO ][DEMO][126134] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":1},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
401 126167 [iot_thread] [INFO ][Shadow][126167] Shadow UPDATE of C201300004 was
402 126175 [iot_thread] [INFO ][DEMO][126175] Successfully sent Shadow update 20
403 126227 [iot_thread] [INFO ][DEMO][126227] Shadow was updated!
Previous: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":1}}}
Current: {"state":{"desired":{"powerOn":0},"reported":{"powerOn":0}}}
404 126260 [iot_thread] [INFO ][Shadow][126260] Shadow UPDATE of C201300004 was
405 126441 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
.406 127449 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Process
407 128457 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
.408 129183 [iot_thread] [INFO ][MQTT][129183] (MQTT connection 200487a8) SUBSCR
409 129193 [iot_thread] [INFO ][MQTT][129193] (MQTT connection 200487a8, SUBSCRI
410 129328 [iot_thread] [INFO ][MQTT][129328] (MQTT connection 200487a8, SUBSCRI
411 129341 [iot_thread] [INFO ][MQTT][129341] (MQTT connection 200487a8) SUBSCRI
412 129351 [iot_thread] [INFO ][MQTT][129351] (MQTT connection 200487a8, SUBSCRI
413 129465 [iot_thread] State: WaitingForJob Received: 3 Queued: 0 Processe
414 129483 [iot_thread] [INFO ][MQTT][129483] (MQTT connection 200487a8, SUBSCRI
415 129496 [iot_thread] [INFO ][MQTT][129496] (MQTT connection 200487a8) MQTT PU
416 129643 [iot_thread] [INFO ][Shadow][129643] Shadow DELETE of C201300004 was
417 129651 [iot_thread] [INFO ][MQTT][129651] (MQTT connection 200487a8) UNSUBSC
418 129662 [iot_thread] [INFO ][MQTT][129662] (MQTT connection 200487a8, UNSUBSC
419 129790 [iot_thread] [INFO ][MQTT][129790] (MQTT connection 200487a8, UNSUBSC
420 129803 [iot_thread] [INFO ][MQTT][129803] (MQTT connection 200487a8) UNSUBSC
421 129814 [iot_thread] [INFO ][MQTT][129814] (MQTT connection 200487a8, UNSUBSC
422 129949 [iot_thread] [INFO ][MQTT][129949] (MQTT connection 200487a8, UNSUBSC
423 129962 [iot_thread] [INFO ][DEMO][129962] Successfully cleared Shadow of C20
424 129970 [iot_thread] [INFO ][MQTT][129970] (MQTT connection 200487a8) Disconn
425 129979 [iot_thread] [INFO ][MQTT][129979] (MQTT connection 200487a8, DISCONN
426 129997 [iot_thread] [INFO ][MQTT][129997] (MQTT connection 200487a8, DISCONN
427 130009 [iot_thread] [INFO ][MQTT][130009] (MQTT connection 200487a8) Connect
428 130018 [iot_thread] [INFO ][MQTT][130018] (MQTT connection 200487a8) Network
429 130112 [iot_thread] [INFO ][MQTT][130112] (MQTT connection 200487a8) Network
430 130121 [iot_thread] [INFO ][Shadow][130121] Shadow library cleanup done.
431 130128 [iot_thread] [INFO ][MQTT][130128] MQTT library cleanup done.
432 130135 [iot_thread] [INFO ][DEMO][130135] memory_metrics::freertos_heap::bef
433 130144 [iot_thread] [INFO ][DEMO][130144] memory_metrics::freertos_heap::aft
434 130153 [iot_thread] [INFO ][DEMO][130152] memory_metrics::demo_task_stack::b
435 130161 [iot_thread] [INFO ][DEMO][130161] memory_metrics::demo_task_stack::a
436 130170 [iot_thread] [INFO ][DEMO][130170] Demo completed successfully.