Attempting to run Shadow demo and OTA demo simultaneously

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.

Hi msukanya,

What device are you running this demo on? Could you check to see if you are running out of memory when you try to use both shadow and OTA updates at the same time? If this was the case, you could consider shutting down OTA when you aren’t using it and starting it up again when you want to check for an update.

Hi,

Thanks a lot for your response !
I am running the demo on the B-L4S5I-IOT01A STM32L4+ board. I am not sure how to confirm that I am running out of memory. The IDE environment that I am using is IAR - would you know how I can do it ? I am not sure implementing what you are suggest would help us since we are trying to run OTA and Shadow concurrently.

Hi,

I dont think that I am exceeding the memory limitation- just checked.

Do you have any direction that you could point me in please?Thanks for your help !

Hi @msukanya

It seems some of the logs are truncated from your last logs posted so its not clear what error logs are emitted from the OTA demo.

Nevertheless the setup as described above creates two simultaneous MQTT connections, which creates two TLS over TCP connections under the hood and additional tasks on the network interface.

Mqtt connection can be shared among shadow update and OTA demo, so you can run a single demo with one mqtt connection shared among OTA and shadow demo tasks. This will be more efficient in terms of resources consumed.

You can take a look at a similar post where MQTT hello world task and OTA demo shares the same MQTT connection here.