Hi All,
I am working on FOTA feature for Thingy91. My country is Vietnam and I only can use NB IoT to connect to cloud service.
I tested project FOTA example. My device downloaded the firmware but it never finished as well. It randomly dropped connection meanwhile it was downloading new firmware.
The attached file is log I collected in serial.
Did you guys passed FOTA example with NB IoT conection? Please help me if you have any advise
2019-11-11T01:46:36.145Z DEBUG modem << ***** Booting Zephyr OS build v2.0.99-ncs1-rc1-3-gfc95403819ca *****\x0D\x0A
2019-11-11T01:46:36.153Z DEBUG modem << Flash region\x09\x09Domain\x09\x09Permissions\x0D\x0A
2019-11-11T01:46:36.165Z DEBUG modem << 00 0x00000 0x08000 \x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:46:36.172Z DEBUG modem << 01 0x08000 0x10000 \x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:46:36.460Z DEBUG modem << 02 0x10000 0x18000 \x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:46:36.468Z DEBUG modem << 03 0x18000 0x20000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.473Z DEBUG modem << 04 0x20000 0x28000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.479Z DEBUG modem << 05 0x28000 0x30000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.487Z DEBUG modem << 06 0x30000 0x38000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.494Z DEBUG modem << 07 0x38000 0x40000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.501Z DEBUG modem << 08 0x40000 0x48000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.507Z DEBUG modem << 09 0x48000 0x50000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.512Z DEBUG modem << 10 0x50000 0x58000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.518Z DEBUG modem << 11 0x58000 0x60000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.523Z DEBUG modem << 12 0x60000 0x68000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.529Z DEBUG modem << 13 0x68000 0x70000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.534Z DEBUG modem << 14 0x70000 0x78000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.539Z DEBUG modem << 15 0x78000 0x80000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.544Z DEBUG modem << 16 0x80000 0x88000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.552Z DEBUG modem << 17 0x88000 0x90000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.557Z DEBUG modem << 18 0x90000 0x98000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.851Z DEBUG modem << 19 0x98000 0xa0000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.858Z DEBUG modem << 20 0xa0000 0xa8000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.869Z DEBUG modem << 21 0xa8000 0xb0000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.878Z DEBUG modem << 22 0xb0000 0xb8000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.884Z DEBUG modem << 23 0xb8000 0xc0000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.888Z DEBUG modem << 24 0xc0000 0xc8000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.894Z DEBUG modem << 25 0xc8000 0xd0000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.899Z DEBUG modem << 26 0xd0000 0xd8000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.904Z DEBUG modem << 27 0xd8000 0xe0000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.909Z DEBUG modem << 28 0xe0000 0xe8000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.914Z DEBUG modem << 29 0xe8000 0xf0000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.919Z DEBUG modem << 30 0xf0000 0xf8000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.924Z DEBUG modem << 31 0xf8000 0x100000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.929Z DEBUG modem << Non-secure callable region 0 placed in flash region 2 with size 32.\x0D\x0A\x0D\x0A\x0D\x0A
2019-11-11T01:46:36.934Z DEBUG modem << SRAM region\x09\x09Domain\x09\x09Permissions\x0D\x0A
2019-11-11T01:46:36.938Z DEBUG modem << 00 0x00000 0x02000\x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:46:36.944Z DEBUG modem << 01 0x02000 0x04000\x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:46:36.948Z DEBUG modem << 02 0x04000 0x06000\x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:46:36.953Z DEBUG modem << 03 0x06000 0x08000\x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:46:36.957Z DEBUG modem << 04 0x08000 0x0a000\x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:46:36.962Z DEBUG modem << 05 0x0a000 0x0c000\x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:46:36.967Z DEBUG modem << 06 0x0c000 0x0e000\x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:46:36.971Z DEBUG modem << 07 0x0e000 0x10000\x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:46:36.976Z DEBUG modem << 08 0x10000 0x12000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.981Z DEBUG modem << 09 0x12000 0x14000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.985Z DEBUG modem << 10 0x14000 0x16000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.990Z DEBUG modem << 11 0x16000 0x18000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.994Z DEBUG modem << 12 0x18000 0x1a000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:36.999Z DEBUG modem << 13 0x1a000 0x1c000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:37.003Z DEBUG modem << 14 0x1c000 0x1e000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:37.008Z DEBUG modem << 15 0x1e000 0x20000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:37.012Z DEBUG modem << 16 0x20000 0x22000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:37.018Z DEBUG modem << 17 0x22000 0x24000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:37.023Z DEBUG modem << 18 0x24000 0x26000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:37.027Z DEBUG modem << 19 0x26000 0x28000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:37.032Z DEBUG modem << 20 0x28000 0x2a000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:37.037Z DEBUG modem << 21 0x2a000 0x2c000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:37.041Z DEBUG modem << 22 0x2c000 0x2e000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:37.046Z DEBUG modem << 23 0x2e000 0x30000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:37.050Z DEBUG modem << 24 0x30000 0x32000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:37.055Z DEBUG modem << 25 0x32000 0x34000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:37.060Z DEBUG modem << 26 0x34000 0x36000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:37.065Z DEBUG modem << 27 0x36000 0x38000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:37.069Z DEBUG modem << 28 0x38000 0x3a000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:37.074Z DEBUG modem << 29 0x3a000 0x3c000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:37.079Z DEBUG modem << 30 0x3c000 0x3e000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:46:37.084Z DEBUG modem << 31 0x3e000 0x40000\x09Non-Secure\x09rwxl\x0D\x0A\x0D\x0A
2019-11-11T01:46:37.089Z DEBUG modem << Peripheral\x09\x09Domain\x09\x09Status\x0D\x0A
2019-11-11T01:46:37.094Z DEBUG modem << 00 NRF_P0 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.099Z DEBUG modem << 01 NRF_CLOCK Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.103Z DEBUG modem << 02 NRF_RTC1 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.108Z DEBUG modem << 03 NRF_NVMC Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.113Z DEBUG modem << 04 NRF_UARTE1 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.117Z DEBUG modem << 05 NRF_UARTE2 Secure\x09\x09SKIP\x0D\x0A
2019-11-11T01:46:37.122Z DEBUG modem << 06 NRF_TWIM2 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.127Z DEBUG modem << 07 NRF_SPIM3 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.133Z DEBUG modem << 08 NRF_TIMER0 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.137Z DEBUG modem << 09 NRF_TIMER1 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.141Z DEBUG modem << 10 NRF_TIMER2 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.146Z DEBUG modem << 11 NRF_SAADC Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.150Z DEBUG modem << 12 NRF_PWM0 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.155Z DEBUG modem << 13 NRF_PWM1 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.159Z DEBUG modem << 14 NRF_PWM2 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.164Z DEBUG modem << 15 NRF_PWM3 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.169Z DEBUG modem << 16 NRF_WDT Secure\x09\x09SKIP\x0D\x0A
2019-11-11T01:46:37.173Z DEBUG modem << 17 NRF_IPC Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.483Z DEBUG modem << 18 NRF_VMC Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.491Z DEBUG modem << 19 NRF_FPU Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.498Z DEBUG modem << 20 NRF_EGU1 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.506Z DEBUG modem << 21 NRF_EGU2 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.514Z DEBUG modem << 22 NRF_DPPIC Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.520Z DEBUG modem << 23 NRF_GPIOTE1 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:46:37.525Z DEBUG modem << 24 NRF_REGULATORS Secure\x09\x09SKIP\x0D\x0A\x0D\x0A
2019-11-11T01:46:37.530Z DEBUG modem << SPM: NS image at 0x18200\x0D\x0A
2019-11-11T01:46:37.535Z DEBUG modem << SPM: NS MSP at 0x20028630\x0D\x0A
2019-11-11T01:46:37.540Z DEBUG modem << SPM: NS reset vector at 0x1c175\x0D\x0A
2019-11-11T01:46:37.545Z DEBUG modem << SPM: prepare to jump to Non-Secure image.\x0D\x0A
2019-11-11T01:46:37.550Z DEBUG modem << ***** Booting Zephyr OS build v2.0.99-ncs1-rc1-3-gfc95403819ca *****\x0D\x0A
2019-11-11T01:46:37.554Z DEBUG modem << MQTT AWS Jobs FOTA Sample, version: v1.0.0\x0D\x0A
2019-11-11T01:46:37.559Z DEBUG modem << Initializing bsdlib\x0D\x0A
2019-11-11T01:46:37.565Z DEBUG modem << [00:00:00.006,347] \x1B[1;31m<err> board_nonsecure: AT socket could not be opened\x1B[0m\x0D\x0A
2019-11-11T01:46:37.569Z DEBUG modem << [00:00:00.006,347] \x1B[1;31m<err> board_nonsecure: pca20035_magpio_configure failed with error: -14\x1B[0m\x0D\x0A
2019-11-11T01:46:37.574Z DEBUG modem << Initialized bsdlib\x0D\x0A
2019-11-11T01:46:37.579Z DEBUG modem << ************************* WARNING *************************\x0D\x0A
2019-11-11T01:46:37.583Z DEBUG modem << provision_certificates called do not use this in production!\x0D\x0A
2019-11-11T01:46:37.588Z DEBUG modem << This will store the certificates in readable flash and leave\x0D\x0A
2019-11-11T01:46:37.592Z DEBUG modem << them exposed on modem_traces. Only use this once for\x0D\x0A
2019-11-11T01:46:37.597Z DEBUG modem << provisioning certificates for development to reduce flash tear.\x0D\x0A
2019-11-11T01:46:37.602Z DEBUG modem << ************************* WARNING *************************\x0D\x0A
2019-11-11T01:46:38.971Z DEBUG modem << nrf_inbuilt_key_delete(12345678, 0) => result=0\x0D
2019-11-11T01:46:39.269Z DEBUG modem << \x0A
2019-11-11T01:46:39.576Z DEBUG modem << nrf_inbuilt_key_delete(12345678, 1) => result=0\x0D\x0A
2019-11-11T01:46:39.926Z DEBUG modem << nrf_inbuilt_key_delete(12345678, 2) => result=0\x0D\x0A
2019-11-11T01:46:40.441Z DEBUG modem << nrf_inbuilt_key_write => result=0\x0D\x0A
2019-11-11T01:46:40.972Z DEBUG modem << nrf_inbuilt_key_write => result=0\x0D\x0A
2019-11-11T01:46:41.436Z DEBUG modem << nrf_inbuilt_key_write => result=0\x0D\x0A
2019-11-11T01:46:41.442Z DEBUG modem << LTE Link Connecting ...\x0D\x0A
2019-11-11T01:46:42.480Z DEBUG modem << [00:00:04.844,390] \x1B[0m<dbg> lte_lc.w_lte_lc_connect: Network mode: AT%XSYSTEMMODE=0,1,0,0\x1B[0m\x0D\x0A
2019-11-11T01:46:45.631Z DEBUG modem << [00:00:08.020,355] \x1B[0m<dbg> lte_lc.at_handler: recv: +CEREG: 2,"61AB","090B1217",9,0,0,"11100000","11100000"\x0D\x0A
2019-11-11T01:46:45.644Z DEBUG modem << \x1B[0m\x0D\x0A
2019-11-11T01:46:45.656Z DEBUG modem << [00:00:08.020,507] \x1B[0m<dbg> lte_lc.parse_nw_reg_status: Network registration status: 2\x1B[0m\x0D\x0A
2019-11-11T01:46:46.419Z DEBUG modem << LTE Link Connected!\x0D\x0A
2019-11-11T01:46:46.880Z DEBUG modem << IPv4 Address 52.69.102.85\x0D\x0A
2019-11-11T01:46:46.892Z DEBUG modem << client_id: nrf-352656100451635\x0D\x0A
2019-11-11T01:46:47.408Z DEBUG modem << [00:00:09.798,828] \x1B[0m<dbg> lte_lc.at_handler: recv: +CEREG: 1,"61AB","090B1217",9,,,"11100000","11100000"\x0D
2019-11-11T01:46:47.698Z DEBUG modem << \x0A
2019-11-11T01:46:47.706Z DEBUG modem << \x1B[0m\x0D\x0A
2019-11-11T01:46:48.001Z DEBUG modem << [00:00:09.798,980] \x1B[0m<dbg> lte_lc.parse_nw_reg_status: Network registration status: 1\x1B[0m\x0D\x0A
2019-11-11T01:46:55.759Z DEBUG modem << [mqtt_evt_handler:170] MQTT client connected!\x0D\x0A
2019-11-11T01:46:56.484Z DEBUG modem << [mqtt_evt_handler:223] PUBACK packet id: 37632\x0D\x0A
2019-11-11T01:46:56.795Z DEBUG modem << [mqtt_evt_handler:233] SUBACK packet id: 2114\x0D\x0A
2019-11-11T01:46:56.806Z DEBUG modem << [mqtt_evt_handler:223] PUBACK packet id: 49968\x0D\x0A
2019-11-11T01:46:56.818Z DEBUG modem << [00:00:19.141,876] \x1B[0m<inf> aws_jobs: Subscribe: $aws/things/nrf-352656100451635/jobs/notify-next\x1B[0m\x0D\x0A
2019-11-11T01:46:56.824Z DEBUG modem << [00:00:19.143,371] \x1B[0m<inf> aws_jobs: Subscribe: $aws/things/nrf-352656100451635/jobs/$next/get/#\x1B[0m\x0D\x0A
2019-11-11T01:46:56.832Z DEBUG modem << [00:00:19.524,780] \x1B[0m<inf> aws_fota: subscribed to notify-next topic\x1B[0m\x0D\x0A
2019-11-11T01:46:56.838Z DEBUG modem << [00:00:19.524,871] \x1B[0m<inf> aws_jobs: Publish topic: $aws/things/nrf-352656100451635/jobs/$next/get\x1B[0m\x0D\x0A
2019-11-11T01:46:57.140Z DEBUG modem << [00:00:19.524,902] \x1B[0m<inf> aws_jobs: Publish payload {"clientToken": ""}\x1B[0m\x0D\x0A
2019-11-11T01:46:57.455Z DEBUG modem << [mqtt_evt_handler:238] default: 8\x0D\x0A
2019-11-11T01:46:58.040Z DEBUG modem << [00:00:20.423,248] \x1B[0m<inf> aws_fota: Received topic: $aws/things/nrf-352656100451635/jobs/$next/get/accepted\x1B[0m\x0D\x0A
2019-11-11T01:46:58.344Z DEBUG modem << [00:00:20.423,828] \x1B[0m<inf> aws_jobs: Unsubscribe: $aws/things/nrf-352656100451635/jobs/notify-next\x1B[0m\x0D\x0A
2019-11-11T01:46:58.647Z DEBUG modem << [00:00:20.424,804] \x1B[0m<inf> aws_jobs: Subscribe: $aws/things/nrf-352656100451635/jobs/asdfad/update/#\x1B[0m\x0D\x0A
2019-11-11T01:46:58.655Z DEBUG modem << [00:00:21.072,052] \x1B[0m<inf> aws_jobs: Publish topic: $aws/things/nrf-352656100451635/jobs/asdfad/update\x1B[0m\x0D\x0A
2019-11-11T01:46:58.664Z DEBUG modem << [00:00:21.072,082] \x1B[0m<inf> aws_jobs: Publish payload {"status":"IN_PROGRESS","statusDetails": {"nextState":"download_firmware"},"expectedVersion": "1","clientToken": ""}\x1B[0m\x0D\x0A
2019-11-11T01:46:59.018Z DEBUG modem << [mqtt_evt_handler:223] PUBACK packet id: 35130\x0D\x0A
2019-11-11T01:46:59.414Z DEBUG modem << [00:00:21.803,344] \x1B[0m<inf> aws_fota: Received topic: $aws/things/nrf-352656100451635/jobs/asdfad/update/accepted\x1B[0m\x0D\x0A
2019-11-11T01:46:59.704Z DEBUG modem << [00:00:21.803,527] \x1B[0m<inf> aws_fota: Start downloading firmware from thingy91-firmware.s3-ap-northeast-1.amazonaws.comthingy91-fw_v1.0.1.bin\x1B[0m\x0D\x0A
2019-11-11T01:46:59.712Z DEBUG modem << [00:00:22.181,610] \x1B[0m<inf> download_client: Attempting to connect over IPv4\x1B[0m\x0D\x0A
2019-11-11T01:46:59.716Z DEBUG modem << [00:00:22.373,413] \x1B[0m<inf> download_client: Connected to thingy91-firmware.s3-ap-northeast-1.amazonaws.com\x1B[0m\x0D\x0A
2019-11-11T01:46:59.721Z DEBUG modem << [00:00:22.373,443] \x1B[0m<inf> download_client: Downloading: thingy91-fw_v1.0.1.bin [0]\x1B[0m\x0D\x0A
2019-11-11T01:47:06.734Z DEBUG modem << [00:00:29.124,969] \x1B[0m<inf> download_client: Downloaded 4096/194848 bytes (2%)\x1B[0m\x0D\x0A
2019-11-11T01:47:06.740Z DEBUG modem << [00:00:29.125,000] \x1B[0m<inf> fota_download: Offset: 0x0\x1B[0m\x0D\x0A
2019-11-11T01:47:10.222Z DEBUG modem << [00:00:32.615,051] \x1B[0m<inf> download_client: Downloaded 8192/194848 bytes (4%)\x1B[0m\x0D
2019-11-11T01:47:10.534Z DEBUG modem << \x0A
2019-11-11T01:47:13.357Z DEBUG modem << [00:00:35.750,152] \x1B[0m<inf> download_client: Downloaded 12288/194848 bytes (6%)\x1B[0m\x0D
2019-11-11T01:47:13.362Z DEBUG modem << \x0A
2019-11-11T01:47:16.218Z DEBUG modem << [00:00:38.599,273] \x1B[0m<inf> download_client: Downloaded 16384/194848 bytes (8%)\x1B[0m\x0D\x0A
2019-11-11T01:47:19.469Z DEBUG modem << [00:00:41.843,261] \x1B[0m<inf> download_client: Downloaded 20480/194848 bytes (10%)\x1B[0m\x0D\x0A
2019-11-11T01:47:47.620Z DEBUG modem << [00:01:10.001,831] \x1B[0m<inf> download_client: Downloaded 24576/194848 bytes (12%)\x1B[0m\x0D\x0A
2019-11-11T01:47:47.966Z DEBUG modem << AWS_FOTA_EVT_ERROR\x0D\x0A
2019-11-11T01:47:48.744Z DEBUG modem << [00:01:11.110,961] \x1B[1;33m<wrn> download_client: Peer closed connection!\x1B[0m\x0D\x0A
2019-11-11T01:47:48.755Z DEBUG modem << [00:01:11.111,541] \x1B[1;31m<err> fota_download: Download client error\x1B[0m\x0D\x0A
2019-11-11T01:47:49.065Z DEBUG modem << [00:01:11.111,572] \x1B[0m<inf> dfu_target_mcuboot: MCUBoot image upgrade aborted.\x1B[0m\x0D\x0A
2019-11-11T01:47:49.075Z DEBUG modem << [00:01:11.111,572] \x1B[1;31m<err> aws_fota: FOTA download failed, report back\x1B[0m\x0D\x0A
2019-11-11T01:47:49.083Z DEBUG modem << [00:01:11.111,785] \x1B[0m<inf> aws_jobs: Publish topic: $aws/things/nrf-352656100451635/jobs/asdfad/update\x1B[0m\x0D\x0A
2019-11-11T01:47:49.090Z DEBUG modem << [00:01:11.111,816] \x1B[0m<inf> aws_jobs: Publish payload {"status":"FAILED","statusDetails": {"nextState":"download_firmware"},"expectedVersion": "2","clientToken": ""}\x1B[0m\x0D\x0A
2019-11-11T01:47:50.268Z DEBUG modem << [mqtt_evt_handler:223] PUBACK packet id: 36433\x0D\x0A
2019-11-11T01:47:51.451Z DEBUG modem << [00:01:13.830,200] \x1B[0m<inf> aws_fota: Received topic: $aws/things/nrf-352656100451635/jobs/asdfad/update/accepted\x1B[0m\x0D\x0A
2019-11-11T01:47:51.773Z DEBUG modem << [00:01:13.830,352] \x1B[0m<inf> aws_fota: Start downloading firmware from thingy91-firmware.s3-ap-northeast-1.amazonaws.comthingy91-fw_v1.0.1.bin\x1B[0m\x0D\x0A
2019-11-11T01:47:51.781Z DEBUG modem << [00:01:14.214,752] \x1B[0m<inf> download_client: Attempting to connect over IPv4\x1B[0m\x0D\x0A
2019-11-11T01:47:52.110Z DEBUG modem << [00:01:14.439,575] \x1B[0m<inf> download_client: Connected to thingy91-firmware.s3-ap-northeast-1.amazonaws.com\x1B[0m\x0D\x0A
2019-11-11T01:47:52.119Z DEBUG modem << [00:01:14.439,605] \x1B[0m<inf> download_client: Downloading: thingy91-fw_v1.0.1.bin [0]\x1B[0m\x0D\x0A
2019-11-11T01:47:58.467Z DEBUG modem << [00:01:20.841,125] \x1B[0m<inf> download_client: Downloaded 4096/194848 bytes (2%)\x1B[0m\x0D\x0A
2019-11-11T01:47:58.798Z DEBUG modem << [00:01:20.841,156] \x1B[0m<inf> fota_download: Offset: 0x6000\x1B[0m\x0D\x0A
2019-11-11T01:47:58.805Z DEBUG modem << [00:01:20.841,186] \x1B[0m<inf> fota_download: Refuse fragment, restart with offset\x1B[0m\x0D\x0A
2019-11-11T01:47:58.812Z DEBUG modem << [00:01:20.841,186] \x1B[0m<inf> download_client: Fragment refused, download stopped.\x1B[0m\x0D\x0A
2019-11-11T01:47:58.817Z DEBUG modem << [00:01:21.841,339] \x1B[0m<inf> download_client: Downloading: thingy91-fw_v1.0.1.bin [24576]\x1B[0m\x0D\x0A
2019-11-11T01:47:59.154Z DEBUG modem << [00:01:21.841,979] \x1B[0m<inf> fota_download: Downloading from offset: 0x6000\x1B[0m\x0D\x0A
2019-11-11T01:48:29.175Z DEBUG modem << AWS_FOTA_EVT_ERROR\x0D\x0A
2019-11-11T01:48:30.146Z DEBUG modem << [00:01:52.518,737] \x1B[0m<inf> download_client: Downloaded 28672/194848 bytes (14%)\x1B[0m\x0D\x0A
2019-11-11T01:48:30.467Z DEBUG modem << [00:01:52.571,624] \x1B[1;31m<err> download_client: Failed to send HTTP request, errno 57\x1B[0m\x0D\x0A
2019-11-11T01:48:30.475Z DEBUG modem << [00:01:52.572,143] \x1B[1;31m<err> fota_download: Download client error\x1B[0m\x0D\x0A
2019-11-11T01:48:30.480Z DEBUG modem << [00:01:52.572,143] \x1B[0m<inf> dfu_target_mcuboot: MCUBoot image upgrade aborted.\x1B[0m\x0D\x0A
2019-11-11T01:48:30.489Z DEBUG modem << [00:01:52.572,143] \x1B[1;31m<err> aws_fota: FOTA download failed, report back\x1B[0m\x0D\x0A
2019-11-11T01:48:30.808Z DEBUG modem << [00:01:52.572,387] \x1B[0m<inf> aws_jobs: Publish topic: $aws/things/nrf-352656100451635/jobs/asdfad/update\x1B[0m\x0D\x0A
2019-11-11T01:48:30.816Z DEBUG modem << [00:01:52.572,418] \x1B[0m<inf> aws_jobs: Publish payload {"status":"FAILED","statusDetails": {"nextState":"download_firmware"},"expectedVersion": "3","clientToken": ""}\x1B[0m\x0D\x0A
2019-11-11T01:48:31.142Z DEBUG modem << [mqtt_evt_handler:223] PUBACK packet id: 18757\x0D\x0A
2019-11-11T01:48:31.473Z DEBUG modem << AWS_FOTA_EVT_ERROR\x0D\x0A
2019-11-11T01:48:31.479Z DEBUG modem << aws_fota_mqtt_evt_handler: Failed! -14\x0D\x0A
2019-11-11T01:48:31.486Z DEBUG modem << [mqtt_evt_handler:188] MQTT PUBLISH result=0 len=265\x0D\x0A
2019-11-11T01:48:31.494Z DEBUG modem << mqtt_read_publish_payload: Failed! -36\x0D\x0A
2019-11-11T01:48:31.501Z DEBUG modem << Disconnecting MQTT client...\x0D\x0A
2019-11-11T01:48:31.507Z DEBUG modem << Received: \x0D\x0A
2019-11-11T01:48:31.833Z DEBUG modem << [mqtt_evt_handler:181] MQTT client disconnected 0\x0D\x0A
2019-11-11T01:48:31.843Z DEBUG modem << POLLNVAL\x0D\x0A
2019-11-11T01:48:31.853Z DEBUG modem << Disconnecting MQTT client...\x0D\x0A
2019-11-11T01:48:31.860Z DEBUG modem << Could not disconnect MQTT client. Error: -57\x0D\x0A
2019-11-11T01:48:32.364Z DEBUG modem << [00:01:54.726,165] \x1B[0m<inf> aws_fota: Received topic: $aws/things/nrf-352656100451635/jobs/asdfad/update/rejected\x1B[0m\x0D\x0A
2019-11-11T01:48:32.378Z DEBUG modem << [00:01:54.726,257] \x1B[1;31m<err> aws_fota: Job document update was rejected\x1B[0m\x0D\x0A
2019-11-11T01:49:20.184Z DEBUG modem << ***** Booting Zephyr OS build v2.0.99-ncs1-rc1-3-gfc95403819ca *****\x0D\x0A
2019-11-11T01:49:20.201Z DEBUG modem << Flash region\x09\x09Domain\x09\x09Permissions\x0D\x0A
2019-11-11T01:49:20.212Z DEBUG modem << 00 0x00000 0x08000 \x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:49:20.611Z DEBUG modem << 01 0x08000 0x10000 \x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:49:20.618Z DEBUG modem << 02 0x10000 0x18000 \x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:49:20.624Z DEBUG modem << 03 0x18000 0x20000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:20.630Z DEBUG modem << 04 0x20000 0x28000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:20.638Z DEBUG modem << 05 0x28000 0x30000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:20.643Z DEBUG modem << 06 0x30000 0x38000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:20.652Z DEBUG modem << 07 0x38000 0x40000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:20.657Z DEBUG modem << 08 0x40000 0x48000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:20.663Z DEBUG modem << 09 0x48000 0x50000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:20.668Z DEBUG modem << 10 0x50000 0x58000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:20.674Z DEBUG modem << 11 0x58000 0x60000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:20.679Z DEBUG modem << 12 0x60000 0x68000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:20.685Z DEBUG modem << 13 0x68000 0x70000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:20.690Z DEBUG modem << 14 0x70000 0x78000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:20.698Z DEBUG modem << 15 0x78000 0x80000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:20.703Z DEBUG modem << 16 0x80000 0x88000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:20.711Z DEBUG modem << 17 0x88000 0x90000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:20.717Z DEBUG modem << 18 0x90000 0x98000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:20.727Z DEBUG modem << 19 0x98000 0xa0000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.192Z DEBUG modem << 20 0xa0000 0xa8000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.200Z DEBUG modem << 21 0xa8000 0xb0000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.206Z DEBUG modem << 22 0xb0000 0xb8000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.217Z DEBUG modem << 23 0xb8000 0xc0000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.227Z DEBUG modem << 24 0xc0000 0xc8000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.233Z DEBUG modem << 25 0xc8000 0xd0000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.238Z DEBUG modem << 26 0xd0000 0xd8000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.243Z DEBUG modem << 27 0xd8000 0xe0000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.248Z DEBUG modem << 28 0xe0000 0xe8000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.253Z DEBUG modem << 29 0xe8000 0xf0000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.257Z DEBUG modem << 30 0xf0000 0xf8000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.263Z DEBUG modem << 31 0xf8000 0x100000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.267Z DEBUG modem << Non-secure callable region 0 placed in flash region 2 with size 32.\x0D\x0A\x0D\x0A\x0D\x0A
2019-11-11T01:49:21.272Z DEBUG modem << SRAM region\x09\x09Domain\x09\x09Permissions\x0D\x0A
2019-11-11T01:49:21.277Z DEBUG modem << 00 0x00000 0x02000\x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:49:21.282Z DEBUG modem << 01 0x02000 0x04000\x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:49:21.286Z DEBUG modem << 02 0x04000 0x06000\x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:49:21.291Z DEBUG modem << 03 0x06000 0x08000\x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:49:21.295Z DEBUG modem << 04 0x08000 0x0a000\x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:49:21.300Z DEBUG modem << 05 0x0a000 0x0c000\x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:49:21.305Z DEBUG modem << 06 0x0c000 0x0e000\x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:49:21.309Z DEBUG modem << 07 0x0e000 0x10000\x09Secure\x09\x09rwxl\x0D\x0A
2019-11-11T01:49:21.314Z DEBUG modem << 08 0x10000 0x12000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.319Z DEBUG modem << 09 0x12000 0x14000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.323Z DEBUG modem << 10 0x14000 0x16000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.327Z DEBUG modem << 11 0x16000 0x18000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.332Z DEBUG modem << 12 0x18000 0x1a000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.337Z DEBUG modem << 13 0x1a000 0x1c000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.341Z DEBUG modem << 14 0x1c000 0x1e000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.345Z DEBUG modem << 15 0x1e000 0x20000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.350Z DEBUG modem << 16 0x20000 0x22000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.356Z DEBUG modem << 17 0x22000 0x24000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.361Z DEBUG modem << 18 0x24000 0x26000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.366Z DEBUG modem << 19 0x26000 0x28000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.371Z DEBUG modem << 20 0x28000 0x2a000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.381Z DEBUG modem << 21 0x2a000 0x2c000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.390Z DEBUG modem << 22 0x2c000 0x2e000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.398Z DEBUG modem << 23 0x2e000 0x30000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.405Z DEBUG modem << 24 0x30000 0x32000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.411Z DEBUG modem << 25 0x32000 0x34000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.416Z DEBUG modem << 26 0x34000 0x36000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.429Z DEBUG modem << 27 0x36000 0x38000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.436Z DEBUG modem << 28 0x38000 0x3a000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.444Z DEBUG modem << 29 0x3a000 0x3c000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.452Z DEBUG modem << 30 0x3c000 0x3e000\x09Non-Secure\x09rwxl\x0D\x0A
2019-11-11T01:49:21.458Z DEBUG modem << 31 0x3e000 0x40000\x09Non-Secure\x09rwxl\x0D\x0A\x0D\x0A
2019-11-11T01:49:21.465Z DEBUG modem << Peripheral\x09\x09Domain\x09\x09Status\x0D\x0A
2019-11-11T01:49:21.472Z DEBUG modem << 00 NRF_P0 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.478Z DEBUG modem << 01 NRF_CLOCK Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.488Z DEBUG modem << 02 NRF_RTC1 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.496Z DEBUG modem << 03 NRF_NVMC Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.503Z DEBUG modem << 04 NRF_UARTE1 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.508Z DEBUG modem << 05 NRF_UARTE2 Secure\x09\x09SKIP\x0D\x0A
2019-11-11T01:49:21.513Z DEBUG modem << 06 NRF_TWIM2 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.519Z DEBUG modem << 07 NRF_SPIM3 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.525Z DEBUG modem << 08 NRF_TIMER0 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.529Z DEBUG modem << 09 NRF_TIMER1 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.534Z DEBUG modem << 10 NRF_TIMER2 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.538Z DEBUG modem << 11 NRF_SAADC Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.543Z DEBUG modem << 12 NRF_PWM0 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.548Z DEBUG modem << 13 NRF_PWM1 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.552Z DEBUG modem << 14 NRF_PWM2 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.557Z DEBUG modem << 15 NRF_PWM3 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.562Z DEBUG modem << 16 NRF_WDT Secure\x09\x09SKIP\x0D\x0A
2019-11-11T01:49:21.567Z DEBUG modem << 17 NRF_IPC Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.571Z DEBUG modem << 18 NRF_VMC Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.575Z DEBUG modem << 19 NRF_FPU Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.580Z DEBUG modem << 20 NRF_EGU1 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.584Z DEBUG modem << 21 NRF_EGU2 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.589Z DEBUG modem << 22 NRF_DPPIC Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.593Z DEBUG modem << 23 NRF_GPIOTE1 Non-Secure\x09OK\x0D\x0A
2019-11-11T01:49:21.600Z DEBUG modem << 24 NRF_REGULATORS Secure\x09\x09SKIP\x0D\x0A\x0D\x0A
2019-11-11T01:49:21.607Z DEBUG modem << SPM: NS image at 0x18200\x0D\x0A
2019-11-11T01:49:21.615Z DEBUG modem << SPM: NS MSP at 0x20028630\x0D\x0A
2019-11-11T01:49:21.621Z DEBUG modem << SPM: NS reset vector at 0x1c175\x0D\x0A
2019-11-11T01:49:21.626Z DEBUG modem << SPM: prepare to jump to Non-Secure image.\x0D\x0A
2019-11-11T01:49:21.631Z DEBUG modem << ***** Booting Zephyr OS build v2.0.99-ncs1-rc1-3-gfc95403819ca *****\x0D\x0A
2019-11-11T01:49:21.636Z DEBUG modem << MQTT AWS Jobs FOTA Sample, version: v1.0.0\x0D\x0A
2019-11-11T01:49:21.641Z DEBUG modem << Initializing bsdlib\x0D\x0A
2019-11-11T01:49:22.014Z DEBUG modem << [00:00:00.006,347] \x1B[1;31m<err> board_nonsecure: AT socket could not be opened\x1B[0m\x0D\x0A
2019-11-11T01:49:22.025Z DEBUG modem << [00:00:00.006,347] \x1B[1;31m<err> board_nonsecure: pca20035_magpio_configure failed with error: -14\x1B[0m\x0D\x0A
2019-11-11T01:49:22.034Z DEBUG modem << Initialized bsdlib\x0D\x0A
2019-11-11T01:49:22.043Z DEBUG modem << ************************* WARNING *************************\x0D\x0A
2019-11-11T01:49:22.050Z DEBUG modem << provision_certificates called do not use this in production!\x0D\x0A
2019-11-11T01:49:22.056Z DEBUG modem << This will store the certificates in readable flash and leave\x0D\x0A
2019-11-11T01:49:22.061Z DEBUG modem << them exposed on modem_traces. Only use this once for\x0D\x0A
2019-11-11T01:49:22.065Z DEBUG modem << provisioning certificates for development to reduce flash tear.\x0D\x0A
2019-11-11T01:49:22.071Z DEBUG modem << ************************* WARNING *************************\x0D\x0A
2019-11-11T01:49:23.017Z DEBUG modem << nrf_inbuilt_key_delete(12345678, 0) => result=0\x0D\x0A
2019-11-11T01:49:23.478Z DEBUG modem << nrf_inbuilt_key_delete(12345678, 1) => result=0\x0D\x0A
2019-11-11T01:49:23.988Z DEBUG modem << nrf_inbuilt_key_delete(12345678, 2) => result=0\x0D\x0A
2019-11-11T01:49:24.506Z DEBUG modem << nrf_inbuilt_key_write => result=0\x0D\x0A
2019-11-11T01:49:25.052Z DEBUG modem << nrf_inbuilt_key_write => result=0\x0D\x0A
2019-11-11T01:49:25.492Z DEBUG modem << nrf_inbuilt_key_write => result=0\x0D\x0A
2019-11-11T01:49:25.501Z DEBUG modem << LTE Link Connecting ...\x0D\x0A
2019-11-11T01:49:26.510Z DEBUG modem << [00:00:04.844,573] \x1B[0m<dbg> lte_lc.w_lte_lc_connect: Network mode: AT%XSYSTEMMODE=0,1,0,0\x1B[0m\x0D
2019-11-11T01:49:26.841Z DEBUG modem << \x0A
2019-11-11T01:49:29.505Z DEBUG modem << [00:00:07.821,746] \x1B[0m<dbg> lte_lc.at_handler: recv: +CEREG: 2,"61AB","090B1217",9,0,0,"11100000","11100000"\x0D\x0A
2019-11-11T01:49:29.519Z DEBUG modem << \x1B[0m\x0D\x0A
2019-11-11T01:49:29.528Z DEBUG modem << [00:00:07.821,929] \x1B[0m<dbg> lte_lc.parse_nw_reg_status: Network registration status: 2\x1B[0m\x0D\x0A
2019-11-11T01:49:30.299Z DEBUG modem << LTE Link Connected!\x0D\x0A
2019-11-11T01:49:30.999Z DEBUG modem << IPv4 Address 18.179.234.98\x0D\x0A
2019-11-11T01:49:31.007Z DEBUG modem << client_id: nrf-352656100451635\x0D\x0A
2019-11-11T01:49:31.360Z DEBUG modem << [00:00:09.627,929] \x1B[0m<dbg> lte_lc.at_handler: recv: +CEREG: 1,"61AB","090B1217",9,,,"11100000","11100000"\x0D\x0A
2019-11-11T01:49:31.368Z DEBUG modem << \x1B[0m\x0D\x0A
2019-11-11T01:49:31.376Z DEBUG modem << [00:00:09.628,112] \x1B[0m<dbg> lte_lc.parse_nw_reg_status: Network registration status: 1\x1B[0m\x0D\x0A
2019-11-11T01:49:39.811Z DEBUG modem << [mqtt_evt_handler:170] MQTT client connected!\x0D\x0A
2019-11-11T01:49:40.403Z DEBUG modem << [mqtt_evt_handler:223] PUBACK packet id: 37383\x0D\x0A
2019-11-11T01:49:40.775Z DEBUG modem << [mqtt_evt_handler:233] SUBACK packet id: 2114\x0D\x0A
2019-11-11T01:49:41.109Z DEBUG modem << [00:00:19.134,277] \x1B[0m<inf> aws_jobs: Subscribe: $aws/things/nrf-352656100451635/jobs/notify-next\x1B[0m\x0D\x0A
2019-11-11T01:49:41.450Z DEBUG modem << [00:00:19.135,772] \x1B[0m<inf> aws_jobs: Subscribe: $aws/things/nrf-352656100451635/jobs/$next/get/#\x1B[0m\x0D\x0A
2019-11-11T01:49:41.457Z DEBUG modem << [00:00:19.519,195] \x1B[0m<inf> aws_fota: subscribed to notify-next topic\x1B[0m\x0D\x0A
2019-11-11T01:49:41.467Z DEBUG modem << [00:00:19.519,287] \x1B[0m<inf> aws_jobs: Publish topic: $aws/things/nrf-352656100451635/jobs/$next/get\x1B[0m\x0D\x0A
2019-11-11T01:49:41.472Z DEBUG modem << [00:00:19.519,287] \x1B[0m<inf> aws_jobs: Publish payload {"clientToken": ""}\x1B[0m\x0D\x0A
2019-11-11T01:49:41.480Z DEBUG modem << [mqtt_evt_handler:223] PUBACK packet id: 49784\x0D\x0A
2019-11-11T01:49:41.808Z DEBUG modem << [mqtt_evt_handler:238] default: 8\x0D\x0A
2019-11-11T01:49:42.158Z DEBUG modem << [00:00:20.324,920] \x1B[0m<inf> aws_fota: Received topic: $aws/things/nrf-352656100451635/jobs/$next/get/accepted\x1B[0m\x0D\x0A
2019-11-11T01:49:42.169Z DEBUG modem << [00:00:20.325,531] \x1B[0m<inf> aws_jobs: Unsubscribe: $aws/things/nrf-352656100451635/jobs/notify-next\x1B[0m\x0D\x0A
2019-11-11T01:49:42.176Z DEBUG modem << [00:00:20.326,507] \x1B[0m<inf> aws_jobs: Subscribe: $aws/things/nrf-352656100451635/jobs/NewTask/update/#\x1B[0m\x0D\x0A
2019-11-11T01:49:42.191Z DEBUG modem << [00:00:20.894,470] \x1B[0m<inf> aws_jobs: Publish topic: $aws/things/nrf-352656100451635/jobs/NewTask/update\x1B[0m\x0D\x0A
2019-11-11T01:49:42.197Z DEBUG modem << [00:00:20.894,500] \x1B[0m<inf> aws_jobs: Publish payload {"status":"IN_PROGRESS","statusDetails": {"nextState":"download_firmware"},"expectedVersion": "1","clientToken": ""}\x1B[0m\x0D\x0A
2019-11-11T01:49:42.554Z DEBUG modem << [mqtt_evt_handler:223] PUBACK packet id: 29311\x0D\x0A
2019-11-11T01:49:43.283Z DEBUG modem << [00:00:21.633,666] \x1B[0m<inf> aws_fota: Received topic: $aws/things/nrf-352656100451635/jobs/NewTask/update/accepted\x1B[0m\x0D
2019-11-11T01:49:43.612Z DEBUG modem << \x0A
2019-11-11T01:49:43.924Z DEBUG modem << [00:00:21.633,850] \x1B[0m<inf> aws_fota: Start downloading firmware from thingy91-firmware.s3-ap-northeast-1.amazonaws.comthingy91-fw_v1.0.1.bin\x1B[0m\x0D\x0A
2019-11-11T01:49:43.932Z DEBUG modem << [00:00:21.982,971] \x1B[0m<inf> download_client: Attempting to connect over IPv4\x1B[0m\x0D\x0A
2019-11-11T01:49:43.937Z DEBUG modem << [00:00:22.178,833] \x1B[0m<inf> download_client: Connected to thingy91-firmware.s3-ap-northeast-1.amazonaws.com\x1B[0m\x0D\x0A
2019-11-11T01:49:43.943Z DEBUG modem << [00:00:22.178,863] \x1B[0m<inf> download_client: Downloading: thingy91-fw_v1.0.1.bin [0]\x1B[0m\x0D\x0A
2019-11-11T01:49:50.073Z DEBUG modem << [00:00:28.420,379] \x1B[0m<inf> download_client: Downloaded 4096/194848 bytes (2%)\x1B[0m\x0D\x0A
2019-11-11T01:49:50.084Z DEBUG modem << [00:00:28.420,410] \x1B[0m<inf> fota_download: Offset: 0x0\x1B[0m\x0D\x0A
2019-11-11T01:49:54.054Z DEBUG modem << [00:00:32.384,490] \x1B[0m<inf> download_client: Downloaded 8192/194848 bytes (4%)\x1B[0m\x0D\x0A
2019-11-11T01:49:57.231Z DEBUG modem << [00:00:35.584,625] \x1B[0m<inf> download_client: Downloaded 12288/194848 bytes (6%)\x1B[0m\x0D\x0A
2019-11-11T01:50:00.139Z DEBUG modem << [00:00:38.462,585] \x1B[0m<inf> download_client: Downloaded 16384/194848 bytes (8%)\x1B[0m\x0D\x0A
2019-11-11T01:50:03.187Z DEBUG modem << [00:00:41.536,621] \x1B[0m<inf> download_client: Downloaded 20480/194848 bytes (10%)\x1B[0m\x0D\x0A
2019-11-11T01:50:31.454Z DEBUG modem << [00:01:09.802,246] \x1B[0m<inf> download_client: Downloaded 24576/194848 bytes (12%)\x1B[0m\x0D\x0A
2019-11-11T01:50:31.782Z DEBUG modem << AWS_FOTA_EVT_ERROR\x0D\x0A
2019-11-11T01:50:32.518Z DEBUG modem << [00:01:10.850,372] \x1B[1;33m<wrn> download_client: Peer closed connection!\x1B[0m\x0D\x0A
2019-11-11T01:50:32.531Z DEBUG modem << [00:01:10.850,952] \x1B[1;31m<err> fota_download: Download client error\x1B[0m\x0D\x0A
2019-11-11T01:50:32.874Z DEBUG modem << [00:01:10.850,982] \x1B[0m<inf> dfu_target_mcuboot: MCUBoot image upgrade aborted.\x1B[0m\x0D\x0A
2019-11-11T01:50:32.882Z DEBUG modem << [00:01:10.850,982] \x1B[1;31m<err> aws_fota: FOTA download failed, report back\x1B[0m\x0D\x0A
2019-11-11T01:50:32.888Z DEBUG modem << [00:01:10.851,196] \x1B[0m<inf> aws_jobs: Publish topic: $aws/things/nrf-352656100451635/jobs/NewTask/update\x1B[0m\x0D\x0A
2019-11-11T01:50:32.895Z DEBUG modem << [00:01:10.851,226] \x1B[0m<inf> aws_jobs: Publish payload {"status":"FAILED","statusDetails": {"nextState":"download_firmware"},"expectedVersion": "2","clientToken": ""}\x1B[0m\x0D\x0A
2019-11-11T01:50:33.737Z DEBUG modem << [mqtt_evt_handler:223] PUBACK packet id: 27894\x0D\x0A
2019-11-11T01:50:34.939Z DEBUG modem << [00:01:13.279,479] \x1B[0m<inf> aws_fota: Received topic: $aws/things/nrf-352656100451635/jobs/NewTask/update/accepted\x1B[0m\x0D\x0A
2019-11-11T01:50:35.349Z DEBUG modem << [00:01:13.279,632] \x1B[0m<inf> aws_fota: Start downloading firmware from thingy91-firmware.s3-ap-northeast-1.amazonaws.comthingy91-fw_v1.0.1.bin\x1B[0m\x0D\x0A
2019-11-11T01:50:35.358Z DEBUG modem << [00:01:13.665,161] \x1B[0m<inf> download_client: Attempting to connect over IPv4\x1B[0m\x0D\x0A
2019-11-11T01:50:35.732Z DEBUG modem << [00:01:13.858,978] \x1B[0m<inf> download_client: Connected to thingy91-firmware.s3-ap-northeast-1.amazonaws.com\x1B[0m\x0D\x0A
2019-11-11T01:50:35.747Z DEBUG modem << [00:01:13.859,008] \x1B[0m<inf> download_client: Downloading: thingy91-fw_v1.0.1.bin [0]\x1B[0m\x0D\x0A
2019-11-11T01:50:41.968Z DEBUG modem << [00:01:20.322,509] \x1B[0m<inf> download_client: Downloaded 4096/194848 bytes (2%)\x1B[0m\x0D
2019-11-11T01:50:42.421Z DEBUG modem << \x0A
2019-11-11T01:50:42.435Z DEBUG modem << [00:01:20.322,540] \x1B[0m<inf> fota_download: Offset: 0x6000\x1B[0m\x0D\x0A
2019-11-11T01:50:42.441Z DEBUG modem << [00:01:20.322,570] \x1B[0m<inf> fota_download: Refuse fragment, restart with offset\x1B[0m\x0D\x0A
2019-11-11T01:50:42.763Z DEBUG modem << [00:01:20.322,570] \x1B[0m<inf> download_client: Fragment refused, download stopped.\x1B[0m\x0D\x0A
2019-11-11T01:50:42.779Z DEBUG modem << [00:01:21.322,723] \x1B[0m<inf> download_client: Downloading: thingy91-fw_v1.0.1.bin [24576]\x1B[0m\x0D\x0A
2019-11-11T01:50:42.790Z DEBUG modem << [00:01:21.323,364] \x1B[0m<inf> fota_download: Downloading from offset: 0x6000\x1B[0m\x0D\x0A
2019-11-11T01:50:46.568Z DEBUG modem << [00:01:24.900,604] \x1B[0m<inf> download_client: Downloaded 28672/194848 bytes (14%)\x1B[0m\x0D\x0A
2019-11-11T01:50:49.687Z DEBUG modem << [00:01:28.031,738] \x1B[0m<inf> download_client: Downloaded 32768/194848 bytes (16%)\x1B[0m\x0D\x0A
2019-11-11T01:51:17.846Z DEBUG modem << AWS_FOTA_EVT_ERROR\x0D\x0A
2019-11-11T01:51:18.164Z DEBUG modem << [00:01:56.289,306] \x1B[0m<inf> download_client: Downloaded 36864/194848 bytes (18%)\x1B[0m\x0D\x0A
2019-11-11T01:51:18.172Z DEBUG modem << [00:01:57.186,370] \x1B[1;33m<wrn> download_client: Peer closed connection!\x1B[0m\x0D\x0A
2019-11-11T01:51:18.182Z DEBUG modem << [00:01:57.186,950] \x1B[1;31m<err> fota_download: Download client error\x1B[0m\x0D\x0A
2019-11-11T01:51:18.191Z DEBUG modem << [00:01:57.186,950] \x1B[0m<inf> dfu_target_mcuboot: MCUBoot image upgrade aborted.\x1B[0m\x0D\x0A
2019-11-11T01:51:18.519Z DEBUG modem << [00:01:57.186,981] \x1B[1;31m<err> aws_fota: FOTA download failed, report back\x1B[0m\x0D\x0A
2019-11-11T01:51:18.529Z DEBUG modem << [00:01:57.187,194] \x1B[0m<inf> aws_jobs: Publish topic: $aws/things/nrf-352656100451635/jobs/NewTask/update\x1B[0m\x0D\x0A
2019-11-11T01:51:18.534Z DEBUG modem << [00:01:57.187,225] \x1B[0m<inf> aws_jobs: Publish payload {"status":"FAILED","statusDetails": {"nextState":"download_firmware"},"expectedVersion": "3","clientToken": ""}\x1B[0m\x0D\x0A
2019-11-11T01:51:19.464Z DEBUG modem << [mqtt_evt_handler:223] PUBACK packet id: 38903\x0D\x0A
2019-11-11T01:51:44.843Z DEBUG modem << AWS_FOTA_EVT_ERROR\x0D\x0A
2019-11-11T01:51:45.226Z DEBUG modem << aws_fota_mqtt_evt_handler: Failed! -14\x0D\x0A
2019-11-11T01:51:45.234Z DEBUG modem << [mqtt_evt_handler:188] MQTT PUBLISH result=0 len=266\x0D\x0A
2019-11-11T01:51:45.242Z DEBUG modem << mqtt_read_publish_payload: Failed! -36\x0D\x0A
2019-11-11T01:51:45.247Z DEBUG modem << Disconnecting MQTT client...\x0D\x0A
2019-11-11T01:51:45.255Z DEBUG modem << Received: \x0D\x0A
2019-11-11T01:51:45.756Z DEBUG modem << [mqtt_evt_handler:181] MQTT client disconnected 0\x0D\x0A
2019-11-11T01:51:45.767Z DEBUG modem << POLLNVAL\x0D\x0A
2019-11-11T01:51:45.777Z DEBUG modem << Disconnecting MQTT client...\x0D\x0A
2019-11-11T01:51:45.785Z DEBUG modem << Could not disconnect MQTT client. Error: -57\x0D\x0A
2019-11-11T01:51:46.131Z DEBUG modem << [00:02:24.192,291] \x1B[0m<inf> aws_fota: Received topic: $aws/things/nrf-352656100451635/jobs/NewTask/update/rejected\x1B[0m\x0D\x0A
2019-11-11T01:51:46.142Z DEBUG modem << [00:02:24.192,382] \x1B[1;31m<err> aws_fota: Job document update was rejected\x1B[0m\x0D\x0A
Thanks and Best Regards
Kien
