Azure IoT Hub FOTA

jaredwolff Here you go… let me know if you want more. The device is running FW version 0.1.2, and attempts to upgrade to V0.1.3. It downloads v0.1.3 in 1800 byte fragments, and then automatically reboots the device when complete. On reboot, it should run the new image v0.1.3, but as you can see below, it continues to run v0.1.2.

[00:02:56.111,907] [0m<dbg> azure_iot_hub.on_publish: Device twin data received[0mAZURE_IOT_HUB_EVT_TWIN_RESULT_SUCCESS, ID: 175289RRC mode: Idle*** Booting Zephyr OS version 2.4.99 ***Flash regionsDomainPermissions00 02 0x00000 0x18000 Securerwxl03 31 0x18000 0x100000 Non-SecurerwxlNon-secure callable region 0 placed in flash region 2 with size 32.SRAM regionDomainPermissions00 07 0x00000 0x10000 Securerwxl08 31 0x10000 0x40000 Non-SecurerwxlPeripheralDomainStatus00 NRF_P0 Non-SecureOK01 NRF_CLOCK Non-SecureOK02 NRF_RTC0 Non-SecureOK03 NRF_RTC1 Non-SecureOK04 NRF_NVMC Non-SecureOK05 NRF_UARTE1 Non-SecureOK06 NRF_UARTE2 SecureSKIP07 NRF_TWIM2 Non-SecureOK08 NRF_SPIM3 Non-SecureOK09 NRF_TIMER0 Non-SecureOK10 NRF_TIMER1 Non-SecureOK11 NRF_TIMER2 Non-SecureOK12 NRF_SAADC Non-SecureOK13 NRF_PWM0 Non-SecureOK14 NRF_PWM1 Non-SecureOK15 NRF_PWM2 Non-SecureOK16 NRF_PWM3 Non-SecureOK17 NRF_WDT Non-SecureOK18 NRF_IPC Non-SecureOK19 NRF_VMC Non-SecureOK20 NRF_FPU Non-SecureOK21 NRF_EGU1 Non-SecureOK22 NRF_EGU2 Non-SecureOK23 NRF_DPPIC Non-SecureOK24 NRF_GPIOTE1 Non-SecureOK25 NRF_REGULATORS Non-SecureOKSPM: NS image at 0x1c200SPM: NS MSP at 0x20028c30SPM: NS reset vector at 0x22439SPM: prepare to jump to Non-Secure image.*** Booting Zephyr OS version 2.4.99 ***Azure FOTA v0.1.2 started

jaredwolff Yes. I have been able to connect and publish data. I’ve loaded x509 certs on, configured DPS, etc. I’m pasting more info from the session log below.
2021-02-09T22:23:54.316Z DEBUG modem << *** Booting Zephyr OS version 2.4.99 ***
2021-02-09T22:23:54.324Z DEBUG modem << Azure FOTA v0.1.2 started
2021-02-09T22:23:54.326Z DEBUG modem << Initializing modem library
2021-02-09T22:23:54.328Z DEBUG modem << This may take a while if a modem firmware update is pending
2021-02-09T22:23:54.330Z DEBUG modem << Modem library initialized
2021-02-09T22:23:54.344Z DEBUG modem << [00:00:00.224,090] [0m<inf> azure_iot_hub_dps: Setting DPS registration ID: mycordevice261[0m
2021-02-09T22:23:54.362Z DEBUG modem << [00:00:00.232,482] [0m<dbg> azure_iot_hub_dps.dps_reg_id_set: Saved DPS registration ID: mycordevice261[0m
2021-02-09T22:23:54.370Z DEBUG modem << [00:00:00.243,072] [0m<dbg> azure_iot_hub_dps.dps_settings_handler: Azure IoT Hub hostname found, DPS not needed: lab651hub.azure-devices.net[0m
2021-02-09T22:23:54.379Z DEBUG modem << [00:00:00.256,042] [0m<dbg> azure_iot_hub_dps.dps_on_settings_loaded: Settings fully loaded[0m
2021-02-09T22:23:54.388Z DEBUG modem << [00:00:00.264,556] [0m<dbg> azure_iot_hub.azure_iot_hub_init: DPS initialized[0m
2021-02-09T22:23:54.390Z DEBUG modem << [00:00:00.271,911] [0m<inf> azure_fota: Current firmware version: v0.1.2[0m
2021-02-09T22:23:54.405Z DEBUG modem << [00:00:00.278,778] [0m<dbg> azure_iot_hub.azure_iot_hub_init: Azure FOTA initialized[0m
2021-02-09T22:23:54.407Z DEBUG modem << [00:00:00.286,712] [0m<dbg> azure_iot_hub.connection_state_set: New connection state: STATE_INIT[0m
2021-02-09T22:23:54.409Z DEBUG modem << Connecting to LTE network
2021-02-09T22:24:58.945Z DEBUG modem << LTE cell changed: Cell ID: 5774613, Tracking area: 43524
2021-02-09T22:24:59.007Z DEBUG modem << RRC mode: Connected
2021-02-09T22:25:02.079Z DEBUG modem << Network registration status: Connected - roaming
2021-02-09T22:25:02.128Z DEBUG modem << [00:01:07.972,106] [0m<dbg> azure_iot_hub.connection_state_set: New connection state: STATE_CONNECTING[0m
2021-02-09T22:25:02.131Z DEBUG modem << PSM parameter update: TAU: -1, Active time: -1
2021-02-09T22:25:02.405Z DEBUG modem << [00:01:08.286,895] [0m<dbg> azure_iot_hub.broker_init: IPv4 Address found 20.49.109.129[0m
2021-02-09T22:25:02.407Z DEBUG modem << AZURE_IOT_HUB_EVT_CONNECTING
2021-02-09T22:25:12.105Z DEBUG modem << RRC mode: Idle
2021-02-09T22:25:12.184Z DEBUG modem << RRC mode: Connected
2021-02-09T22:25:13.092Z DEBUG modem << [00:01:18.975,646] [0m<dbg> azure_iot_hub.connection_state_set: New connection state: STATE_CONNECTED[0m
2021-02-09T22:25:13.103Z DEBUG modem << [00:01:18.984,985] [0m<dbg> azure_iot_hub.mqtt_evt_handler: MQTT client connected[0m
2021-02-09T22:25:13.117Z DEBUG modem << [00:01:18.992,706] [0m<dbg> azure_iot_hub.topic_subscribe: Subscribing to: devices/mycordevice261/messages/devicebound/#[0m
2021-02-09T22:25:13.120Z DEBUG modem << [00:01:19.003,692] [0m<dbg> azure_iot_hub.topic_subscribe: Subscribing to: $iothub/twin/PATCH/properties/desired/#[0m
2021-02-09T22:25:13.134Z DEBUG modem << [00:01:19.014,221] [0m<dbg> azure_iot_hub.topic_subscribe: Subscribing to: $iothub/twin/res/#[0m
2021-02-09T22:25:13.143Z DEBUG modem << [00:01:19.022,949] [0m<dbg> azure_iot_hub.topic_subscribe: Subscribing to: $iothub/methods/POST/#[0m
2021-02-09T22:25:13.151Z DEBUG modem << [00:01:19.033,142] [0m<dbg> azure_iot_hub.topic_subscribe: Successfully subscribed to default topics[0m
2021-02-09T22:25:13.152Z DEBUG modem << AZURE_IOT_HUB_EVT_CONNECTED
2021-02-09T22:25:13.471Z DEBUG modem << [00:01:19.354,461] [0m<dbg> azure_iot_hub.mqtt_evt_handler: MQTT_EVT_SUBACK: id = 13456 result = 0[0m
2021-02-09T22:25:13.482Z DEBUG modem << [00:01:19.363,586] [0m<dbg> azure_iot_hub.azure_iot_hub_send: Publishing to topic: $iothub/twin/GET/?$rid=79363[0m
2021-02-09T22:25:13.488Z DEBUG modem << [00:01:19.374,542] [0m<dbg> azure_iot_hub.device_twin_request: Device twin requested[0m
2021-02-09T22:25:13.492Z DEBUG modem << AZURE_IOT_HUB_EVT_READY
2021-02-09T22:25:13.814Z DEBUG modem << [00:01:19.698,028] [0m<dbg> azure_iot_hub.on_publish: MQTT_EVT_PUBLISH: id = 6, len = 297 [0m
2021-02-09T22:25:13.826Z DEBUG modem << [00:01:19.706,542] [0m<dbg> azure_iot_hub_topic.azure_iot_hub_topic_parse: Dynamic value: 200[0m
2021-02-09T22:25:13.834Z DEBUG modem << [00:01:19.715,179] [0m<dbg> azure_iot_hub_topic.get_next_prop_bag: Key: $rid[0m
2021-02-09T22:25:13.837Z DEBUG modem << [00:01:19.722,412] [0m<dbg> azure_iot_hub_topic.get_next_prop_bag: Value: 79363[0m
2021-02-09T22:25:13.851Z DEBUG modem << [00:01:19.729,888] [0m<dbg> azure_iot_hub.on_publish: Device twin data received[0m
2021-02-09T22:25:13.860Z DEBUG modem << [00:01:19.738,250] [0m<dbg> azure_fota.parse_reported_status: Currently reported ‘fwUpdateStatus’ in device twin: current[0m
2021-02-09T22:25:13.871Z DEBUG modem << [00:01:19.749,359] [0m<dbg> azure_fota.parse_reported_status: Currently reported ‘currentFwVersion’ in device twin: v0.1.2[0m
2021-02-09T22:25:13.883Z DEBUG modem << [00:01:19.760,589] [0m<dbg> azure_fota.parse_reported_status: Currently reported ‘pendingFwVersion’ in device twin: (none)[0m
2021-02-09T22:25:13.889Z DEBUG modem << [00:01:19.771,820] [0m<dbg> azure_fota.parse_reported_status: Currently reported version is correct[0m
2021-02-09T22:25:13.902Z DEBUG modem << [00:01:19.782,287] [0m<dbg> azure_fota.extract_fw_details: Incoming firmware version: v0.1.3[0m
2021-02-09T22:25:13.918Z DEBUG modem << [00:01:19.791,229] [0m<inf> azure_fota: Attempting to download firmware (version ‘v0.1.3’) from lab651storageaccount.blob.core.windows.net//mycor/app_update.bin[0m
2021-02-09T22:25:14.292Z DEBUG modem << [00:01:20.175,842] [0m<inf> download_client: Setting up TLS credentials, tag 261[0m
2021-02-09T22:25:14.300Z DEBUG modem << [00:01:20.183,410] [0m<inf> download_client: Configuring socket timeout (30 s)[0m
2021-02-09T22:25:14.307Z DEBUG modem << [00:01:20.190,765] [0m<inf> download_client: Connecting to lab651storageaccount.blob.core.windows.net[0m
2021-02-09T22:25:17.930Z DEBUG modem << [00:01:23.813,842] [0m<inf> download_client: Downloading: /mycor/app_update.bin [0][0m
2021-02-09T22:25:17.946Z DEBUG modem << [00:01:23.821,746] [0m<dbg> azure_fota.create_report: Created FOTA report: {“firmware”:{“fwUpdateStatus”:“downloading”,“currentFwVersion”:“v0.1.2”,“pendingFwVersion”:“v0.1.3”}}[0m
2021-02-09T22:25:17.952Z DEBUG modem << [00:01:23.837,585] [0m<dbg> azure_iot_hub.fota_evt_handler: AZURE_FOTA_EVT_START[0m
2021-02-09T22:25:17.967Z DEBUG modem << [00:01:23.845,245] [0m<dbg> azure_iot_hub.azure_iot_hub_send: Publishing to topic: $iothub/twin/PATCH/properties/reported/?$rid=83845[0m
2021-02-09T22:25:17.976Z DEBUG modem << [00:01:23.859,130] [0m<dbg> azure_iot_hub.fota_report_send: FOTA report sent[0m
2021-02-09T22:25:17.977Z DEBUG modem << AZURE_IOT_HUB_EVT_FOTA_START
2021-02-09T22:25:17.987Z DEBUG modem << [00:01:23.868,927] [0m<dbg> azure_iot_hub.device_twin_result_process: FOTA message handled[0m
2021-02-09T22:25:17.988Z DEBUG modem << AZURE_IOT_HUB_EVT_TWIN_RECEIVED
2021-02-09T22:25:18.762Z DEBUG modem << [00:01:24.648,345] [0m<inf> download_client: Downloaded 1800/194658 bytes (0%)[0m
2021-02-09T22:25:19.711Z DEBUG modem << [00:01:25.597,229] [0m<inf> download_client: Downloaded 3600/194658 bytes (1%)[0m
2021-02-09T22:25:19.767Z DEBUG modem << [00:01:25.651,733] [0m<dbg> azure_iot_hub.on_publish: MQTT_EVT_PUBLISH: id = 6, len = 0 [0m
2021-02-09T22:25:19.783Z DEBUG modem << [00:01:25.659,973] [0m<dbg> azure_iot_hub_topic.azure_iot_hub_topic_parse: Dynamic value: 204[0m
2021-02-09T22:25:19.785Z DEBUG modem << [00:01:25.668,670] [0m<dbg> azure_iot_hub_topic.get_next_prop_bag: Key: $rid[0m
2021-02-09T22:25:19.790Z DEBUG modem << [00:01:25.675,872] [0m<dbg> azure_iot_hub_topic.get_next_prop_bag: Value: 83845[0m
2021-02-09T22:25:19.801Z DEBUG modem << [00:01:25.683,349] [0m<dbg> azure_iot_hub_topic.get_next_prop_bag: Key: $version[0m
2021-02-09T22:25:19.807Z DEBUG modem << [00:01:25.690,917] [0m<dbg> azure_iot_hub_topic.get_next_prop_bag: Value: 19[0m
2021-02-09T22:25:19.812Z DEBUG modem << [00:01:25.698,150] [0m<dbg> azure_iot_hub.on_publish: Device twin data received[0m
2021-02-09T22:25:19.824Z DEBUG modem << AZURE_IOT_HUB_EVT_TWIN_RESULT_SUCCESS, ID: 83845
2021-02-09T22:25:20.512Z DEBUG modem << [00:01:26.397,583] [0m<inf> download_client: Downloaded 5400/194658 bytes (2%)[0m
2021-02-09T22:25:21.288Z DEBUG modem << [00:01:27.174,377] [0m<inf> download_client: Downloaded 7200/194658 bytes (3%)[0m
2021-02-09T22:25:22.214Z DEBUG modem << [00:01:28.100,402] [0m<inf> download_client: Downloaded 9000/194658 bytes (4%)[0m
2021-02-09T22:25:23.157Z DEBUG modem << [00:01:29.043,365] [0m<inf> download_client: Downloaded 10800/194658 bytes (5%)[0m
2021-02-09T22:25:23.795Z DEBUG modem << [00:01:29.681,427] [0m<inf> download_client: Downloaded 12600/194658 bytes (6%)[0m
2021-02-09T22:25:24.318Z DEBUG modem << [00:01:30.203,857] [0m<inf> download_client: Downloaded 14400/194658 bytes (7%)[0m
2021-02-09T22:25:25.472Z DEBUG modem << [00:01:31.357,666] [0m<inf> download_client: Downloaded 16200/194658 bytes (8%)[0m
2021-02-09T22:25:26.203Z DEBUG modem << [00:01:32.088,684] [0m<inf> download_client: Downloaded 18000/194658 bytes (9%)[0m
2021-02-09T22:25:27.157Z DEBUG modem << [00:01:33.043,579] [0m<inf> download_client: Downloaded 19800/194658 bytes (10%)[0m
2021-02-09T22:25:27.722Z DEBUG modem << [00:01:33.608,551] [0m<inf> download_client: Downloaded 21600/194658 bytes (11%)[0m
2021-02-09T22:25:28.602Z DEBUG modem << [00:01:34.488,586] [0m<inf> download_client: Downloaded 23400/194658 bytes (12%)[0m
2021-02-09T22:25:29.379Z DEBUG modem << [00:01:35.265,441] [0m<inf> download_client: Downloaded 25200/194658 bytes (12%)[0m
2021-02-09T22:25:30.259Z DEBUG modem << [00:01:36.145,446] [0m<inf> download_client: Downloaded 27000/194658 bytes (13%)[0m
2021-02-09T22:25:30.751Z DEBUG modem << [00:01:36.637,817] [0m<inf> download_client: Downloaded 28800/194658 bytes (14%)[0m
2021-02-09T22:25:31.311Z DEBUG modem << [00:01:37.197,631] [0m<inf> download_client: Downloaded 30600/194658 bytes (15%)[0m
2021-02-09T22:25:32.292Z DEBUG modem << [00:01:38.178,314] [0m<inf> download_client: Downloaded 32400/194658 bytes (16%)[0m
2021-02-09T22:25:33.139Z DEBUG modem << [00:01:39.025,665] [0m<inf> download_client: Downloaded 34200/194658 bytes (17%)[0m
2021-02-09T22:25:34.122Z DEBUG modem << [00:01:40.009,002] [0m<inf> download_client: Downloaded 36000/194658 bytes (18%)[0m
2021-02-09T22:25:34.968Z DEBUG modem << [00:01:40.854,187] [0m<inf> download_client: Downloaded 37800/194658 bytes (19%)[0m
2021-02-09T22:25:35.792Z DEBUG modem << [00:01:41.678,222] [0m<inf> download_client: Downloaded 39600/194658 bytes (20%)[0m
2021-02-09T22:25:36.671Z DEBUG modem << [00:01:42.558,105] [0m<inf> download_client: Downloaded 41400/194658 bytes (21%)[0m
2021-02-09T22:25:37.678Z DEBUG modem << [00:01:43.564,422] [0m<inf> download_client: Downloaded 43200/194658 bytes (22%)[0m
2021-02-09T22:25:38.648Z DEBUG modem << [00:01:44.535,125] [0m<inf> download_client: Downloaded 45000/194658 bytes (23%)[0m
2021-02-09T22:25:39.279Z DEBUG modem << [00:01:45.165,649] [0m<inf> download_client: Downloaded 46800/194658 bytes (24%)[0m
2021-02-09T22:25:40.099Z DEBUG modem << [00:01:45.985,992] [0m<inf> download_client: Downloaded 48600/194658 bytes (24%)[0m
2021-02-09T22:25:40.831Z DEBUG modem << [00:01:46.718,231] [0m<inf> download_client: Downloaded 50400/194658 bytes (25%)[0m
2021-02-09T22:25:41.722Z DEBUG modem << [00:01:47.608,917] [0m<inf> download_client: Downloaded 52200/194658 bytes (26%)[0m
2021-02-09T22:25:42.214Z DEBUG modem << [00:01:48.101,104] [0m<inf> download_client: Downloaded 54000/194658 bytes (27%)[0m
2021-02-09T22:25:42.854Z DEBUG modem << [00:01:48.741,149] [0m<inf> download_client: Downloaded 55800/194658 bytes (28%)[0m
2021-02-09T22:25:43.459Z DEBUG modem << [00:01:49.345,825] [0m<inf> download_client: Downloaded 57600/194658 bytes (29%)[0m
2021-02-09T22:25:44.122Z DEBUG modem << [00:01:50.009,033] [0m<inf> download_client: Downloaded 59400/194658 bytes (30%)[0m
2021-02-09T22:25:44.797Z DEBUG modem << [00:01:50.684,234] [0m<inf> download_client: Downloaded 61200/194658 bytes (31%)[0m
2021-02-09T22:25:45.368Z DEBUG modem << [00:01:51.255,035] [0m<inf> download_client: Downloaded 63000/194658 bytes (32%)[0m
2021-02-09T22:25:46.077Z DEBUG modem << [00:01:51.964,599] [0m<inf> download_client: Downloaded 64800/194658 bytes (33%)[0m
2021-02-09T22:25:46.808Z DEBUG modem << [00:01:52.695,098] [0m<inf> download_client: Downloaded 66600/194658 bytes (34%)[0m
2021-02-09T22:25:47.539Z DEBUG modem << [00:01:53.425,933] [0m<inf> download_client: Downloaded 68400/194658 bytes (35%)[0m
2021-02-09T22:25:48.054Z DEBUG modem << [00:01:53.941,284] [0m<inf> download_client: Downloaded 70200/194658 bytes (36%)[0m
2021-02-09T22:25:48.682Z DEBUG modem << [00:01:54.568,908] [0m<inf> download_client: Downloaded 72000/194658 bytes (36%)[0m
2021-02-09T22:25:49.334Z DEBUG modem << [00:01:55.221,313] [0m<inf> download_client: Downloaded 73800/194658 bytes (37%)[0m
2021-02-09T22:25:50.077Z DEBUG modem << [00:01:55.964,691] [0m<inf> download_client: Downloaded 75600/194658 bytes (38%)[0m
2021-02-09T22:25:50.934Z DEBUG modem << [00:01:56.821,166] [0m<inf> download_client: Downloaded 77400/194658 bytes (39%)[0m
2021-02-09T22:25:51.677Z DEBUG modem << [00:01:57.564,422] [0m<inf> download_client: Downloaded 79200/194658 bytes (40%)[0m
2021-02-09T22:25:52.511Z DEBUG modem << [00:01:58.398,376] [0m<inf> download_client: Downloaded 81000/194658 bytes (41%)[0m
2021-02-09T22:25:53.414Z DEBUG modem << [00:01:59.301,269] [0m<inf> download_client: Downloaded 82800/194658 bytes (42%)[0m
2021-02-09T22:25:54.431Z DEBUG modem << [00:02:00.318,572] [0m<inf> download_client: Downloaded 84600/194658 bytes (43%)[0m
2021-02-09T22:25:55.365Z DEBUG modem << [00:02:01.253,204] [0m<inf> download_client: Downloaded 86400/194658 bytes (44%)[0m
2021-02-09T22:25:56.271Z DEBUG modem << [00:02:02.158,508] [0m<inf> download_client: Downloaded 88200/194658 bytes (45%)[0m
2021-02-09T22:25:57.254Z DEBUG modem << [00:02:03.141,387] [0m<inf> download_client: Downloaded 90000/194658 bytes (46%)[0m
2021-02-09T22:25:58.238Z DEBUG modem << [00:02:04.125,823] [0m<inf> download_client: Downloaded 91800/194658 bytes (47%)[0m
2021-02-09T22:25:59.138Z DEBUG modem << [00:02:05.026,214] [0m<inf> download_client: Downloaded 93600/194658 bytes (48%)[0m
2021-02-09T22:25:59.917Z DEBUG modem << [00:02:05.804,870] [0m<inf> download_client: Downloaded 95400/194658 bytes (49%)[0m
2021-02-09T22:26:00.877Z DEBUG modem << [00:02:06.764,770] [0m<inf> download_client: Downloaded 97200/194658 bytes (49%)[0m
2021-02-09T22:26:01.698Z DEBUG modem << [00:02:07.586,456] [0m<inf> download_client: Downloaded 99000/194658 bytes (50%)[0m
2021-02-09T22:26:02.717Z DEBUG modem << [00:02:08.604,827] [0m<inf> download_client: Downloaded 100800/194658 bytes (51%)[0m
2021-02-09T22:26:03.517Z DEBUG modem << [00:02:09.404,876] [0m<inf> download_client: Downloaded 102600/194658 bytes (52%)[0m
2021-02-09T22:26:04.612Z DEBUG modem << [00:02:10.499,450] [0m<inf> download_client: Downloaded 104400/194658 bytes (53%)[0m
2021-02-09T22:26:06.055Z DEBUG modem << [00:02:11.942,382] [0m<inf> download_client: Downloaded 106200/194658 bytes (54%)[0m
2021-02-09T22:26:06.979Z DEBUG modem << [00:02:12.866,455] [0m<inf> download_client: Downloaded 108000/194658 bytes (55%)[0m
2021-02-09T22:26:07.698Z DEBUG modem << [00:02:13.586,486] [0m<inf> download_client: Downloaded 109800/194658 bytes (56%)[0m
2021-02-09T22:26:08.373Z DEBUG modem << [00:02:14.261,657] [0m<inf> download_client: Downloaded 111600/194658 bytes (57%)[0m
2021-02-09T22:26:09.047Z DEBUG modem << [00:02:14.935,668] [0m<inf> download_client: Downloaded 113400/194658 bytes (58%)[0m
2021-02-09T22:26:09.778Z DEBUG modem << [00:02:15.666,534] [0m<inf> download_client: Downloaded 115200/194658 bytes (59%)[0m
2021-02-09T22:26:10.677Z DEBUG modem << [00:02:16.564,910] [0m<inf> download_client: Downloaded 117000/194658 bytes (60%)[0m
2021-02-09T22:26:11.378Z DEBUG modem << [00:02:17.266,357] [0m<inf> download_client: Downloaded 118800/194658 bytes (61%)[0m
2021-02-09T22:26:12.201Z DEBUG modem << [00:02:18.089,538] [0m<inf> download_client: Downloaded 120600/194658 bytes (61%)[0m
2021-02-09T22:26:13.002Z DEBUG modem << [00:02:18.889,953] [0m<inf> download_client: Downloaded 122400/194658 bytes (62%)[0m
2021-02-09T22:26:13.757Z DEBUG modem << [00:02:19.645,141] [0m<inf> download_client: Downloaded 124200/194658 bytes (63%)[0m
2021-02-09T22:26:14.681Z DEBUG modem << [00:02:20.569,427] [0m<inf> download_client: Downloaded 126000/194658 bytes (64%)[0m
2021-02-09T22:26:15.551Z DEBUG modem << [00:02:21.439,025] [0m<inf> download_client: Downloaded 127800/194658 bytes (65%)[0m
2021-02-09T22:26:16.430Z DEBUG modem << [00:02:22.318,878] [0m<inf> download_client: Downloaded 129600/194658 bytes (66%)[0m
2021-02-09T22:26:17.253Z DEBUG modem << [00:02:23.141,906] [0m<inf> download_client: Downloaded 131400/194658 bytes (67%)[0m
2021-02-09T22:26:18.111Z DEBUG modem << [00:02:23.999,267] [0m<inf> download_client: Downloaded 133200/194658 bytes (68%)[0m
2021-02-09T22:26:18.898Z DEBUG modem << [00:02:24.786,804] [0m<inf> download_client: Downloaded 135000/194658 bytes (69%)[0m
2021-02-09T22:26:19.731Z DEBUG modem << [00:02:25.619,995] [0m<inf> download_client: Downloaded 136800/194658 bytes (70%)[0m
2021-02-09T22:26:20.670Z DEBUG modem << [00:02:26.559,143] [0m<inf> download_client: Downloaded 138600/194658 bytes (71%)[0m
2021-02-09T22:26:21.493Z DEBUG modem << [00:02:27.382,019] [0m<inf> download_client: Downloaded 140400/194658 bytes (72%)[0m
2021-02-09T22:26:22.454Z DEBUG modem << [00:02:28.342,041] [0m<inf> download_client: Downloaded 142200/194658 bytes (73%)[0m
2021-02-09T22:26:23.319Z DEBUG modem << [00:02:29.204,986] [0m<inf> download_client: Downloaded 144000/194658 bytes (73%)[0m
2021-02-09T22:26:24.252Z DEBUG modem << [00:02:30.136,077] [0m<inf> download_client: Downloaded 145800/194658 bytes (74%)[0m
2021-02-09T22:26:25.224Z DEBUG modem << [00:02:31.106,719] [0m<inf> download_client: Downloaded 147600/194658 bytes (75%)[0m
2021-02-09T22:26:26.186Z DEBUG modem << [00:02:32.067,108] [0m<inf> download_client: Downloaded 149400/194658 bytes (76%)[0m
2021-02-09T22:26:27.070Z DEBUG modem << [00:02:32.949,951] [0m<inf> download_client: Downloaded 151200/194658 bytes (77%)[0m
2021-02-09T22:26:27.847Z DEBUG modem << [00:02:33.726,196] [0m<inf> download_client: Downloaded 153000/194658 bytes (78%)[0m
2021-02-09T22:26:28.767Z DEBUG modem << [00:02:34.645,141] [0m<inf> download_client: Downloaded 154800/194658 bytes (79%)[0m
2021-02-09T22:26:29.529Z DEBUG modem << [00:02:35.405,517] [0m<inf> download_client: Downloaded 156600/194658 bytes (80%)[0m
2021-02-09T22:26:30.431Z DEBUG modem << [00:02:36.307,098] [0m<inf> download_client: Downloaded 158400/194658 bytes (81%)[0m
2021-02-09T22:26:31.382Z DEBUG modem << [00:02:37.256,286] [0m<inf> download_client: Downloaded 160200/194658 bytes (82%)[0m
2021-02-09T22:26:32.197Z DEBUG modem << [00:02:38.070,281] [0m<inf> download_client: Downloaded 162000/194658 bytes (83%)[0m
2021-02-09T22:26:33.090Z DEBUG modem << [00:02:38.962,951] [0m<inf> download_client: Downloaded 163800/194658 bytes (84%)[0m
2021-02-09T22:26:34.068Z DEBUG modem << [00:02:39.939,971] [0m<inf> download_client: Downloaded 165600/194658 bytes (85%)[0m
2021-02-09T22:26:35.082Z DEBUG modem << [00:02:40.952,911] [0m<inf> download_client: Downloaded 167400/194658 bytes (85%)[0m
2021-02-09T22:26:35.830Z DEBUG modem << [00:02:41.700,195] [0m<inf> download_client: Downloaded 169200/194658 bytes (86%)[0m
2021-02-09T22:26:36.843Z DEBUG modem << [00:02:42.712,402] [0m<inf> download_client: Downloaded 171000/194658 bytes (87%)[0m
2021-02-09T22:26:37.816Z DEBUG modem << [00:02:43.685,302] [0m<inf> download_client: Downloaded 172800/194658 bytes (88%)[0m
2021-02-09T22:26:38.898Z DEBUG modem << [00:02:44.766,754] [0m<inf> download_client: Downloaded 174600/194658 bytes (89%)[0m
2021-02-09T22:26:39.788Z DEBUG modem << [00:02:45.655,670] [0m<inf> download_client: Downloaded 176400/194658 bytes (90%)[0m
2021-02-09T22:26:40.499Z DEBUG modem << [00:02:46.366,546] [0m<inf> download_client: Downloaded 178200/194658 bytes (91%)[0m
2021-02-09T22:26:41.573Z DEBUG modem << [00:02:47.439,575] [0m<inf> download_client: Downloaded 180000/194658 bytes (92%)[0m
2021-02-09T22:26:42.384Z DEBUG modem << [00:02:48.250,579] [0m<inf> download_client: Downloaded 181800/194658 bytes (93%)[0m
2021-02-09T22:26:43.300Z DEBUG modem << [00:02:49.165,618] [0m<inf> download_client: Downloaded 183600/194658 bytes (94%)[0m
2021-02-09T22:26:44.031Z DEBUG modem << [00:02:49.896,636] [0m<inf> download_client: Downloaded 185400/194658 bytes (95%)[0m
2021-02-09T22:26:45.002Z DEBUG modem << [00:02:50.867,462] [0m<inf> download_client: Downloaded 187200/194658 bytes (96%)[0m
2021-02-09T22:26:45.819Z DEBUG modem << [00:02:51.683,471] [0m<inf> download_client: Downloaded 189000/194658 bytes (97%)[0m
2021-02-09T22:26:46.798Z DEBUG modem << [00:02:52.662,872] [0m<inf> download_client: Downloaded 190800/194658 bytes (98%)[0m
2021-02-09T22:26:47.587Z DEBUG modem << [00:02:53.450,775] [0m<inf> download_client: Downloaded 192600/194658 bytes (98%)[0m
2021-02-09T22:26:48.559Z DEBUG modem << [00:02:54.422,790] [0m<inf> download_client: Downloaded 194400/194658 bytes (99%)[0m
2021-02-09T22:26:49.276Z DEBUG modem << [00:02:55.139,160] [0m<inf> download_client: Downloaded 194658/194658 bytes (100%)[0m
2021-02-09T22:26:49.287Z DEBUG modem << [00:02:55.152,465] [0m<inf> download_client: Download complete[0m
2021-02-09T22:26:49.385Z DEBUG modem << [00:02:55.246,398] [0m<inf> dfu_target_mcuboot: MCUBoot image upgrade scheduled. Reset device to apply[0m
2021-02-09T22:26:49.396Z DEBUG modem << [00:02:55.257,812] [0m<dbg> azure_fota.fota_dl_handler: FOTA download completed evt received[0m
2021-02-09T22:26:49.413Z DEBUG modem << [00:02:55.266,479] [0m<dbg> azure_fota.create_report: Created FOTA report: {“firmware”:{“fwUpdateStatus”:“applying”,“currentFwVersion”:“v0.1.2”,“pendingFwVersion”:“v0.1.3”}}[0m
2021-02-09T22:26:49.418Z DEBUG modem << [00:02:55.282,104] [0m<dbg> azure_iot_hub.fota_evt_handler: AZURE_FOTA_EVT_DONE[0m
2021-02-09T22:26:49.431Z DEBUG modem << [00:02:55.289,642] [0m<dbg> azure_iot_hub.azure_iot_hub_send: Publishing to topic: $iothub/twin/PATCH/properties/reported/?$rid=175289[0m
2021-02-09T22:26:49.445Z DEBUG modem << [00:02:55.303,680] [0m<dbg> azure_iot_hub.fota_report_send: FOTA report sent[0m
2021-02-09T22:26:49.446Z DEBUG modem << AZURE_IOT_HUB_EVT_FOTA_DONE
2021-02-09T22:26:49.447Z DEBUG modem << The device will reboot in 5 seconds to apply update
2021-02-09T22:26:50.204Z DEBUG modem << [00:02:56.065,399] [0m<dbg> azure_iot_hub.on_publish: MQTT_EVT_PUBLISH: id = 6, len = 0 [0m
2021-02-09T22:26:50.215Z DEBUG modem << [00:02:56.073,638] [0m<dbg> azure_iot_hub_topic.azure_iot_hub_topic_parse: Dynamic value: 204[0m
2021-02-09T22:26:50.229Z DEBUG modem << [00:02:56.082,305] [0m<dbg> azure_iot_hub_topic.get_next_prop_bag: Key: $rid[0m
2021-02-09T22:26:50.230Z DEBUG modem << [00:02:56.089,538] [0m<dbg> azure_iot_hub_topic.get_next_prop_bag: Value: 175289[0m
2021-02-09T22:26:50.246Z DEBUG modem << [00:02:56.097,106] [0m<dbg> azure_iot_hub_topic.get_next_prop_bag: Key: $version[0m
2021-02-09T22:26:50.248Z DEBUG modem << [00:02:56.104,675] [0m<dbg> azure_iot_hub_topic.get_next_prop_bag: Value: 20[0m
2021-02-09T22:26:50.249Z DEBUG modem << [00:02:56.111,907] [0m<dbg> azure_iot_hub.on_publish: Device twin data received[0m
2021-02-09T22:26:50.253Z DEBUG modem << AZURE_IOT_HUB_EVT_TWIN_RESULT_SUCCESS, ID: 175289
2021-02-09T22:26:54.397Z DEBUG modem << RRC mode: Idle
2021-02-09T22:26:54.749Z DEBUG modem << *** Booting Zephyr OS version 2.4.99 ***
2021-02-09T22:26:54.759Z DEBUG modem << Flash regionsDomainPermissions
2021-02-09T22:26:54.760Z DEBUG modem << 00 02 0×00000 0×18000 Securerwxl
2021-02-09T22:26:54.761Z DEBUG modem << 03 31 0×18000 0×100000 Non-Securerwxl
2021-02-09T22:26:54.766Z DEBUG modem << Non-secure callable region 0 placed in flash region 2 with size 32.
2021-02-09T22:26:54.938Z DEBUG modem << SRAM regionDomainPermissions
2021-02-09T22:26:54.950Z DEBUG modem << 00 07 0×00000 0×10000 Securerwxl
2021-02-09T22:26:54.951Z DEBUG modem << 08 31 0×10000 0×40000 Non-Securerwxl
2021-02-09T22:26:54.952Z DEBUG modem << PeripheralDomainStatus
2021-02-09T22:26:54.953Z DEBUG modem << 00 NRF_P0 Non-SecureOK
2021-02-09T22:26:54.954Z DEBUG modem << 01 NRF_CLOCK Non-SecureOK
2021-02-09T22:26:54.969Z DEBUG modem << 02 NRF_RTC0 Non-SecureOK
2021-02-09T22:26:54.970Z DEBUG modem << 03 NRF_RTC1 Non-SecureOK
2021-02-09T22:26:54.971Z DEBUG modem << 04 NRF_NVMC Non-SecureOK
2021-02-09T22:26:54.972Z DEBUG modem << 05 NRF_UARTE1 Non-SecureOK
2021-02-09T22:26:54.973Z DEBUG modem << 06 NRF_UARTE2 SecureSKIP
2021-02-09T22:26:54.985Z DEBUG modem << 07 NRF_TWIM2 Non-SecureOK
2021-02-09T22:26:54.986Z DEBUG modem << 08 NRF_SPIM3 Non-SecureOK
2021-02-09T22:26:54.987Z DEBUG modem << 09 NRF_TIMER0 Non-SecureOK
2021-02-09T22:26:54.988Z DEBUG modem << 10 NRF_TIMER1 Non-SecureOK
2021-02-09T22:26:54.989Z DEBUG modem << 11 NRF_TIMER2 Non-SecureOK
2021-02-09T22:26:55.003Z DEBUG modem << 12 NRF_SAADC Non-SecureOK
2021-02-09T22:26:55.004Z DEBUG modem << 13 NRF_PWM0 Non-SecureOK
2021-02-09T22:26:55.005Z DEBUG modem << 14 NRF_PWM1 Non-SecureOK
2021-02-09T22:26:55.005Z DEBUG modem << 15 NRF_PWM2 Non-SecureOK
2021-02-09T22:26:55.006Z DEBUG modem << 16 NRF_PWM3 Non-SecureOK
2021-02-09T22:26:55.019Z DEBUG modem << 17 NRF_WDT Non-SecureOK
2021-02-09T22:26:55.020Z DEBUG modem << 18 NRF_IPC Non-SecureOK
2021-02-09T22:26:55.021Z DEBUG modem << 19 NRF_VMC Non-SecureOK
2021-02-09T22:26:55.021Z DEBUG modem << 20 NRF_FPU Non-SecureOK
2021-02-09T22:26:55.022Z DEBUG modem << 21 NRF_EGU1 Non-SecureOK
2021-02-09T22:26:55.036Z DEBUG modem << 22 NRF_EGU2 Non-SecureOK
2021-02-09T22:26:55.037Z DEBUG modem << 23 NRF_DPPIC Non-SecureOK
2021-02-09T22:26:55.038Z DEBUG modem << 24 NRF_GPIOTE1 Non-SecureOK
2021-02-09T22:26:55.039Z DEBUG modem << 25 NRF_REGULATORS Non-SecureOK
2021-02-09T22:26:55.040Z DEBUG modem << SPM: NS image at 0×1c200
2021-02-09T22:26:55.040Z DEBUG modem << SPM: NS MSP at 0×20028c30
2021-02-09T22:26:55.053Z DEBUG modem << SPM: NS reset vector at 0×22439
2021-02-09T22:26:55.054Z DEBUG modem << SPM: prepare to jump to Non-Secure image.
2021-02-09T22:26:55.254Z DEBUG modem << *** Booting Zephyr OS version 2.4.99 ***
2021-02-09T22:26:55.265Z DEBUG modem << Azure FOTA v0.1.2 started
2021-02-09T22:26:55.266Z DEBUG modem << Initializing modem library
2021-02-09T22:26:55.268Z DEBUG modem << This may take a while if a modem firmware update is pending
2021-02-09T22:26:55.269Z DEBUG modem << Modem library initialized
2021-02-09T22:26:55.286Z DEBUG modem << [00:00:00.224,090] [0m<inf> azure_iot_hub_dps: Setting DPS registration ID: mycordevice261[0m
2021-02-09T22:26:55.302Z DEBUG modem << [00:00:00.232,452] [0m<dbg> azure_iot_hub_dps.dps_reg_id_set: Saved DPS registration ID: mycordevice261[0m
2021-02-09T22:26:55.306Z DEBUG modem << [00:00:00.243,041] [0m<dbg> azure_iot_hub_dps.dps_settings_handler: Azure IoT Hub hostname found, DPS not needed: lab651hub.azure-devices.net[0m