AWS FOTA / TROUBLESHOOTING -> Failure at the exact same percent of the file

[KLarocqueEMFluids]

Hi,

So I am working with the NRF9160 dev kit. I tried using the AWS FOTA update to no avail. What's happen is that basically my job is created in IotCore, the device will start downloading the firmware update that sems not having any issues until for some reasons around 28% (always the same percentage!!) it just stop and says that the host has stopped the connections. I am not sure if its an error on amazon part or if its related to a config with the sdk, because on AWS they say that their bucket as a timeout of around 12 minutes.  I have also tried with or without http. I can send you my job document.json config.

So here are the basic information related to my update:

Here is my job document. It is 353099 bytes. 

{

    "operation": "app_fw_update",
    "fwversion": "1.2.3",
    "size": 354099,
    "location": {
        "protocol": "http:",
        "host": "bucketfortestingpurposes.s3.amazonaws.com",
        "path": "app_update.bin"
    }
}

Here is my FOTA config in the prj.conf

CONFIG_FOTA_DOWNLOAD=y

CONFIG_DFU_TARGET=y

CONFIG_DOWNLOAD_CLIENT=y

CONFIG_DOWNLOAD_CLIENT_HTTP_FRAG_SIZE_1024=y

CONFIG_DOWNLOAD_CLIENT_STACK_SIZE=4096

CONFIG_DOWNLOAD_CLIENT_BUF_SIZE=2300

CONFIG_DOWNLOAD_CLIENT_MAX_HOSTNAME_SIZE=128

Here I added a log file of my SEGGER (JLink EDU).

Fullscreen terminal_output_2023-01-30T19-58-38.061Z.txt Download

[00:00:11.071,472] <dbg> cloud_module: cloud_interface_event_handler: Message Acknowledgment not in pending QoS list, ID: 8865
[00:00:11.428,955] <inf> download_client: Connecting to bucketfortestingpurposes.s3.amazonaws.com
[00:00:11.951,965] <inf> download_client: Downloading: app_update.bin [0]
[00:00:11.951,995] <dbg> cloud_interface: aws_iot_event_handler: AWS_IOT_EVT_FOTA_START
[00:00:11.951,995] <dbg> cloud_module: cloud_interface_event_handler: CLOUD_INTERFACE_EVT_FOTA_START
[00:00:11.952,087] <inf> app_event_manager: CLOUD_EVT_FOTA_START
[00:00:11.952,514] <dbg> ui_module: state_set: State transition STATE_RUNNING --> STATE_FOTA_UPDATING
[00:00:12.750,457] <inf> download_client: Downloaded 1024/354251 bytes (0%)
[00:00:12.750,793] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x00090000
[00:00:13.411,529] <inf> download_client: Downloaded 2048/354251 bytes (0%)
[00:00:14.029,602] <inf> download_client: Downloaded 3072/354251 bytes (0%)
[00:00:14.636,535] <inf> download_client: Downloaded 4096/354251 bytes (1%)
[00:00:15.311,645] <inf> download_client: Downloaded 5120/354251 bytes (1%)
[00:00:15.311,737] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x00091000
[00:00:15.951,568] <inf> download_client: Downloaded 6144/354251 bytes (1%)
[00:00:16.529,693] <inf> download_client: Downloaded 7168/354251 bytes (2%)
[00:00:17.152,770] <inf> download_client: Downloaded 8192/354251 bytes (2%)
[00:00:17.790,710] <inf> download_client: Downloaded 9216/354251 bytes (2%)
[00:00:17.790,771] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x00092000
[00:00:18.430,725] <inf> download_client: Downloaded 10240/354251 bytes (2%)
[00:00:18.991,058] <dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -66
[00:00:19.070,739] <inf> download_client: Downloaded 11264/354251 bytes (3%)
[00:00:19.570,770] <inf> download_client: Downloaded 12288/354251 bytes (3%)
[00:00:20.050,872] <inf> download_client: Downloaded 13312/354251 bytes (3%)
[00:00:20.050,933] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x00093000
[00:00:20.796,752] <inf> download_client: Downloaded 14336/354251 bytes (4%)
[00:00:21.489,013] <inf> app_event_manager: APP_EVT_DATA_GET - Requested data types (MOD_DYN, SENS, GNSS)
[00:00:21.497,314] <inf> sensor_module: Bat mv is currently: 5207 mV
[00:00:21.497,406] <inf> app_event_manager: SENSOR_EVT_DATA_READY
[00:00:21.497,985] <dbg> codecs: cloud_codec_populate_sensor_buffer: Entry: 0 of 1 in sensor buffer filled
[00:00:21.527,954] <inf> app_event_manager: MODEM_EVT_MODEM_DYNAMIC_DATA_READY
[00:00:21.528,869] <dbg> codecs: cloud_codec_populate_modem_dynamic_buffer: Entry: 0 of 1 in dynamic modem buffer filled
[00:00:21.650,970] <inf> download_client: Downloaded 15360/354251 bytes (4%)
[00:00:22.271,850] <inf> download_client: Downloaded 16384/354251 bytes (4%)
[00:00:22.910,888] <inf> download_client: Downloaded 17408/354251 bytes (4%)
[00:00:22.910,949] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x00094000
[00:00:23.550,903] <inf> download_client: Downloaded 18432/354251 bytes (5%)
[00:00:24.190,917] <inf> download_client: Downloaded 19456/354251 bytes (5%)
[00:00:24.830,932] <inf> download_client: Downloaded 20480/354251 bytes (5%)
[00:00:25.471,984] <inf> download_client: Downloaded 21504/354251 bytes (6%)
[00:00:25.472,045] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x00095000
[00:00:26.111,999] <inf> download_client: Downloaded 22528/354251 bytes (6%)
[00:00:26.752,014] <inf> download_client: Downloaded 23552/354251 bytes (6%)
[00:00:27.391,021] <inf> download_client: Downloaded 24576/354251 bytes (6%)
[00:00:28.033,966] <inf> download_client: Downloaded 25600/354251 bytes (7%)
[00:00:28.034,027] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x00096000
[00:00:28.671,203] <inf> download_client: Downloaded 26624/354251 bytes (7%)
[00:00:29.311,096] <inf> download_client: Downloaded 27648/354251 bytes (7%)
[00:00:29.970,275] <inf> download_client: Downloaded 28672/354251 bytes (8%)
[00:00:30.485,290] <dbg> watchdog: primary_feed_worker: Feeding watchdog
[00:00:30.592,163] <inf> download_client: Downloaded 29696/354251 bytes (8%)
[00:00:30.592,224] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x00097000
[00:00:31.250,274] <inf> download_client: Downloaded 30720/354251 bytes (8%)
[00:00:31.871,215] <inf> download_client: Downloaded 31744/354251 bytes (8%)
[00:00:32.371,276] <inf> download_client: Downloaded 32768/354251 bytes (9%)
[00:00:32.851,226] <inf> download_client: Downloaded 33792/354251 bytes (9%)
[00:00:32.851,287] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x00098000
[00:00:33.471,252] <inf> download_client: Downloaded 34816/354251 bytes (9%)
[00:00:34.112,274] <inf> download_client: Downloaded 35840/354251 bytes (10%)
[00:00:34.671,600] <dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -67
[00:00:34.773,162] <inf> download_client: Downloaded 36864/354251 bytes (10%)
[00:00:35.392,333] <inf> download_client: Downloaded 37888/354251 bytes (10%)
[00:00:35.392,395] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x00099000
[00:00:36.050,445] <inf> download_client: Downloaded 38912/354251 bytes (10%)
[00:00:36.692,382] <inf> download_client: Downloaded 39936/354251 bytes (11%)
[00:00:37.311,401] <inf> download_client: Downloaded 40960/354251 bytes (11%)
[00:00:37.952,423] <inf> download_client: Downloaded 41984/354251 bytes (11%)
[00:00:37.952,484] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x0009a000
[00:00:38.591,430] <inf> download_client: Downloaded 43008/354251 bytes (12%)
[00:00:39.232,452] <inf> download_client: Downloaded 44032/354251 bytes (12%)
[00:00:39.352,691] <dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -67
[00:00:39.871,490] <inf> download_client: Downloaded 45056/354251 bytes (12%)
[00:00:40.514,495] <inf> download_client: Downloaded 46080/354251 bytes (13%)
[00:00:40.514,556] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x0009b000
[00:00:41.152,648] <inf> download_client: Downloaded 47104/354251 bytes (13%)
[00:00:41.489,013] <inf> app_event_manager: APP_EVT_DATA_GET - Requested data types (MOD_DYN, SENS, GNSS)
[00:00:41.491,363] <inf> sensor_module: Bat mv is currently: 5160 mV
[00:00:41.491,455] <inf> app_event_manager: SENSOR_EVT_DATA_READY
[00:00:41.492,065] <dbg> codecs: cloud_codec_populate_sensor_buffer: Entry: 1 of 1 in sensor buffer filled
[00:00:41.522,521] <inf> app_event_manager: MODEM_EVT_MODEM_DYNAMIC_DATA_READY
[00:00:41.523,437] <dbg> codecs: cloud_codec_populate_modem_dynamic_buffer: Entry: 1 of 1 in dynamic modem buffer filled
[00:00:41.791,564] <inf> download_client: Downloaded 48128/354251 bytes (13%)
[00:00:42.450,683] <inf> download_client: Downloaded 49152/354251 bytes (13%)
[00:00:43.391,601] <inf> download_client: Downloaded 50176/354251 bytes (14%)
[00:00:43.391,662] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x0009c000
[00:00:44.032,623] <inf> download_client: Downloaded 51200/354251 bytes (14%)
[00:00:44.672,668] <inf> download_client: Downloaded 52224/354251 bytes (14%)
[00:00:45.311,676] <inf> download_client: Downloaded 53248/354251 bytes (15%)
[00:00:45.951,721] <inf> download_client: Downloaded 54272/354251 bytes (15%)
[00:00:45.951,782] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x0009d000
[00:00:46.592,742] <inf> download_client: Downloaded 55296/354251 bytes (15%)
[00:00:47.231,750] <inf> download_client: Downloaded 56320/354251 bytes (15%)
[00:00:47.871,765] <inf> download_client: Downloaded 57344/354251 bytes (16%)
[00:00:48.512,786] <inf> download_client: Downloaded 58368/354251 bytes (16%)
[00:00:48.512,847] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x0009e000
[00:00:49.151,794] <inf> download_client: Downloaded 59392/354251 bytes (16%)
[00:00:49.791,839] <inf> download_client: Downloaded 60416/354251 bytes (17%)
[00:00:50.431,854] <inf> download_client: Downloaded 61440/354251 bytes (17%)
[00:00:51.072,784] <inf> download_client: Downloaded 62464/354251 bytes (17%)
[00:00:51.072,845] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x0009f000
[00:00:51.711,883] <inf> download_client: Downloaded 63488/354251 bytes (17%)
[00:00:52.351,928] <inf> download_client: Downloaded 64512/354251 bytes (18%)
[00:00:52.993,927] <inf> download_client: Downloaded 65536/354251 bytes (18%)
[00:00:53.631,958] <inf> download_client: Downloaded 66560/354251 bytes (18%)
[00:00:53.632,019] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x000a0000
[00:00:54.271,972] <inf> download_client: Downloaded 67584/354251 bytes (19%)
[00:00:54.912,017] <inf> download_client: Downloaded 68608/354251 bytes (19%)
[00:00:55.234,283] <dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -67
[00:00:55.552,032] <inf> download_client: Downloaded 69632/354251 bytes (19%)
[00:00:56.194,213] <inf> download_client: Downloaded 70656/354251 bytes (19%)
[00:00:56.194,274] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x000a1000
[00:00:56.313,293] <dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -66
[00:00:56.833,099] <inf> download_client: Downloaded 71680/354251 bytes (20%)
[00:00:57.472,106] <inf> download_client: Downloaded 72704/354251 bytes (20%)
[00:00:58.112,121] <inf> download_client: Downloaded 73728/354251 bytes (20%)
[00:00:58.612,152] <inf> download_client: Downloaded 74752/354251 bytes (21%)
[00:00:58.612,213] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x000a2000
[00:00:59.392,181] <inf> download_client: Downloaded 75776/354251 bytes (21%)
[00:01:00.033,203] <inf> download_client: Downloaded 76800/354251 bytes (21%)
[00:01:00.485,382] <dbg> watchdog: primary_feed_worker: Feeding watchdog
[00:01:00.674,285] <inf> download_client: Downloaded 77824/354251 bytes (21%)
[00:01:01.313,232] <inf> download_client: Downloaded 78848/354251 bytes (22%)
[00:01:01.313,323] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x000a3000
[00:01:01.489,013] <inf> app_event_manager: APP_EVT_DATA_GET - Requested data types (MOD_DYN, SENS, GNSS)
[00:01:01.531,921] <inf> sensor_module: Bat mv is currently: 5097 mV
[00:01:01.532,012] <inf> app_event_manager: SENSOR_EVT_DATA_READY
[00:01:01.532,501] <dbg> codecs: cloud_codec_populate_sensor_buffer: Entry: 0 of 1 in sensor buffer filled
[00:01:01.562,988] <inf> app_event_manager: MODEM_EVT_MODEM_DYNAMIC_DATA_READY
[00:01:01.563,903] <dbg> codecs: cloud_codec_populate_modem_dynamic_buffer: Entry: 0 of 1 in dynamic modem buffer filled
[00:01:01.971,405] <inf> download_client: Downloaded 79872/354251 bytes (22%)
[00:01:02.592,285] <inf> download_client: Downloaded 80896/354251 bytes (22%)
[00:01:03.232,299] <inf> download_client: Downloaded 81920/354251 bytes (23%)
[00:01:03.872,314] <inf> download_client: Downloaded 82944/354251 bytes (23%)
[00:01:03.872,406] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x000a4000
[00:01:04.512,359] <inf> download_client: Downloaded 83968/354251 bytes (23%)
[00:01:05.153,381] <inf> download_client: Downloaded 84992/354251 bytes (23%)
[00:01:05.792,388] <inf> download_client: Downloaded 86016/354251 bytes (24%)
[00:01:06.433,410] <inf> download_client: Downloaded 87040/354251 bytes (24%)
[00:01:06.433,502] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x000a5000
[00:01:07.072,448] <inf> download_client: Downloaded 88064/354251 bytes (24%)
[00:01:07.713,470] <inf> download_client: Downloaded 89088/354251 bytes (25%)
[00:01:08.352,478] <inf> download_client: Downloaded 90112/354251 bytes (25%)
[00:01:08.992,492] <inf> download_client: Downloaded 91136/354251 bytes (25%)
[00:01:08.992,584] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x000a6000
[00:01:09.632,537] <inf> download_client: Downloaded 92160/354251 bytes (26%)
[00:01:10.292,633] <inf> download_client: Downloaded 93184/354251 bytes (26%)
[00:01:10.912,567] <inf> download_client: Downloaded 94208/354251 bytes (26%)
[00:01:11.552,612] <inf> download_client: Downloaded 95232/354251 bytes (26%)
[00:01:11.552,673] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x000a7000
[00:01:12.194,732] <inf> download_client: Downloaded 96256/354251 bytes (27%)
[00:01:12.833,648] <inf> download_client: Downloaded 97280/354251 bytes (27%)
[00:01:13.473,663] <inf> download_client: Downloaded 98304/354251 bytes (27%)
[00:01:14.374,603] <inf> download_client: Downloaded 99328/354251 bytes (28%)
[00:01:14.374,664] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x000a8000
[00:01:15.072,723] <inf> download_client: Downloaded 100352/354251 bytes (28%)
[00:01:16.032,745] <inf> download_client: Downloaded 101376/354251 bytes (28%)
[00:01:16.475,036] <wrn> download_client: Peer closed connection, will re-connect
[00:01:16.551,879] <inf> download_client: Downloaded 102400/354251 bytes (28%)
[00:01:16.563,385] <inf> download_client: Reconnecting..
[00:01:16.817,993] <inf> download_client: Connecting to bucketfortestingpurposes.s3.amazonaws.com
[00:01:17.952,850] <inf> download_client: Downloaded 103424/354251 bytes (29%)
[00:01:17.952,911] <dbg> STREAM_FLASH: stream_flash_erase_page: Erasing page at offset 0x000a9000
[00:01:21.073,242] <dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -66
[00:01:21.489,013] <inf> app_event_manager: APP_EVT_DATA_GET - Requested data types (MOD_DYN, SENS, GNSS)
[00:01:21.503,082] <inf> sensor_module: Bat mv is currently: 5195 mV
[00:01:21.503,173] <inf> app_event_manager: SENSOR_EVT_DATA_READY
[00:01:21.503,631] <dbg> codecs: cloud_codec_populate_sensor_buffer: Entry: 1 of 1 in sensor buffer filled
[00:01:21.568,084] <dbg> modem_module: populate_event_with_dynamic_modem_data: No dynamic modem parameters have changed from the last sample request.
[00:01:21.568,145] <inf> app_event_manager: MODEM_EVT_MODEM_DYNAMIC_DATA_NOT_READY
[00:01:30.033,508] <dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -66
[00:01:30.485,473] <dbg> watchdog: primary_feed_worker: Feeding watchdog
[00:01:40.915,069] <err> download_client: Failed to send HTTP request, errno 140
[00:01:40.915,100] <wrn> fota_download: Download socket error. 2 retries left...
[00:01:40.915,100] <inf> download_client: Reconnecting..
[00:01:40.915,740] <wrn> download_client: Failed to resolve hostname bucketfortestingpurposes.s3.amazonaws.com on IPv4
[00:01:40.915,771] <err> fota_download: Download client error
[00:01:40.915,771] <inf> dfu_target_mcuboot: MCUBoot image upgrade aborted.
[00:01:40.915,802] <err> aws_fota: FOTA_DOWNLOAD_EVT_ERROR
[00:01:41.489,013] <inf> app_event_manager: APP_EVT_DATA_GET - Requested data types (MOD_DYN, SENS, GNSS)
[00:01:41.503,112] <inf> sensor_module: Bat mv is currently: 5207 mV
[00:01:41.503,204] <inf> app_event_manager: SENSOR_EVT_DATA_READY
[00:01:41.503,784] <dbg> codecs: cloud_codec_populate_sensor_buffer: Entry: 0 of 1 in sensor buffer filled
[00:01:41.658,721] <dbg> modem_module: populate_event_with_dynamic_modem_data: No dynamic modem parameters have changed from the last sample request.
[00:01:41.658,782] <inf> app_event_manager: MODEM_EVT_MODEM_DYNAMIC_DATA_NOT_READY
[00:01:46.674,072] <dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -66
[00:01:47.634,094] <dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -67
[00:02:00.485,565] <dbg> watchdog: primary_feed_worker: Feeding watchdog
[00:02:01.368,682] <dbg> modem_module: lte_evt_handler: RRC mode: Idle
[00:02:01.489,013] <inf> app_event_manager: APP_EVT_DATA_GET - Requested data types (MOD_DYN, SENS, GNSS)
[00:02:01.525,146] <inf> sensor_module: Bat mv is currently: 5179 mV
[00:02:01.525,238] <inf> app_event_manager: SENSOR_EVT_DATA_READY
[00:02:01.525,695] <dbg> codecs: cloud_codec_populate_sensor_buffer: Entry: 1 of 1 in sensor buffer filled
[00:02:01.590,270] <inf> app_event_manager: MODEM_EVT_MODEM_DYNAMIC_DATA_READY
[00:02:01.591,186] <dbg> codecs: cloud_codec_populate_modem_dynamic_buffer: Entry: 1 of 1 in dynamic modem buffer filled

Thanks for your help. It is dearly appreciated

[KLarocqueEMFluids]

Update : 

So I used the sample for aws iot_core given by NRF and it had the same issues of being stuck and just failing and then it would say "failed to resolve hostname". It seems to still be an issue. I wonder if it's related to the LTE or something. I don't see any timeout settings in S3 buckets. I followed the exact structure mentionned under this page of the documentation

https://developer.nordicsemi.com/nRF_Connect_SDK/doc/2.0.0/nrf/libraries/networking/aws_fota.html

Also I forgot to mention that we use the NRF SDK 2.1.2

I also tried a test with the sample of just not sending any shadow update and just waiting for the FOTA download, but it also resulted in a crash even with a lower app_update.bin length of 196815 bytes I reach 72% of it. It seems to have a timeout or something I am not really sure, and it cannot relog it says that it unresolved after too.

Fullscreen terminal_output_2023-01-31T19-22-54.828Z.txt Download

Disconnected

Connected via RTT with settings mem-address:auto

SEGGER J-Link V7.84a - Real time terminal output
J-Link EDU Mini V1 compiled Dec  7 2022 12:50:10 V1.0, SN=801013833
Process: JLink.exe
*** Booting Zephyr OS build v3.1.99-ncs1-1  ***
[00:00:00.492,767] <inf> main: The AWS IoT sample started, version: v1.0.0

[00:00:00.507,171] <wrn> main: DEVICE ID : 352656109495765
[00:00:01.303,039] <inf> main: LTE cell changed: Cell ID: 7710841, Tracking area: 29050

[00:00:01.370,971] <inf> main: RRC mode: Connected

[00:00:02.675,659] <inf> main: Network registration status: Connected - roaming

[00:00:02.684,844] <inf> main: PSM parameter update: TAU: 3240, Active time: -1

[00:00:02.696,411] <inf> main: Date time obtained

[00:00:02.702,087] <inf> main: Next connection retry in 30 seconds

[00:00:02.709,259] <inf> main: AWS_IOT_EVT_CONNECTING

[00:00:08.111,968] <inf> main: AWS_IOT_EVT_CONNECTED

[00:00:08.119,934] <inf> main: Publishing: {
        "state":        {
                "reported":     {
                        "app_version":  "v1.0.0",
                        "batv": 5191,
                        "ts":   1675192758581
                }
        }
} to AWS IoT broker

[00:00:09.092,041] <inf> main: AWS_IOT_EVT_READY

[00:00:09.729,431] <inf> main: AWS_IOT_EVT_PUBACK, message ID: 19385

[00:00:21.547,424] <inf> main: RRC mode: Idle

[00:01:08.125,946] <inf> main: Publishing: {
        "state":        {
                "reported":     {
                        "batv": 5074,
                        "ts":   1675192818582
                }
        }
} to AWS IoT broker

[00:01:08.146,728] <inf> main: Next data publication in 60 seconds

[00:01:08.253,387] <inf> main: RRC mode: Connected

[00:01:20.346,160] <inf> main: RRC mode: Idle

[00:01:28.994,110] <inf> main: RRC mode: Connected

[00:01:31.335,784] <inf> main: AWS_IOT_EVT_PUBACK, message ID: 1713

[00:01:31.754,638] <inf> download_client: Connecting to bucketfortestingpurposes.s3.amazonaws.com
[00:01:32.091,613] <inf> download_client: Downloading: app_update.bin [0]
[00:01:32.099,273] <inf> main: AWS_IOT_EVT_FOTA_START

[00:01:37.016,082] <inf> download_client: Downloaded 2048/354251 bytes (0%)
[00:01:38.033,569] <inf> download_client: Downloaded 4096/354251 bytes (1%)
[00:01:39.050,781] <inf> download_client: Downloaded 6144/354251 bytes (1%)
[00:01:40.153,656] <inf> download_client: Downloaded 8192/354251 bytes (2%)
[00:01:40.853,576] <inf> download_client: Downloaded 10240/354251 bytes (2%)
[00:01:41.812,896] <inf> download_client: Downloaded 12288/354251 bytes (3%)
[00:01:42.431,884] <inf> download_client: Downloaded 14336/354251 bytes (4%)
[00:01:43.335,937] <inf> download_client: Downloaded 16384/354251 bytes (4%)
[00:01:43.954,284] <inf> download_client: Downloaded 18432/354251 bytes (5%)
[00:01:44.593,994] <inf> download_client: Downloaded 20480/354251 bytes (5%)
[00:01:45.234,008] <inf> download_client: Downloaded 22528/354251 bytes (6%)
[00:01:45.874,359] <inf> download_client: Downloaded 24576/354251 bytes (6%)
[00:01:46.513,488] <inf> download_client: Downloaded 26624/354251 bytes (7%)
[00:01:47.154,083] <inf> download_client: Downloaded 28672/354251 bytes (8%)
[00:01:47.795,104] <inf> download_client: Downloaded 30720/354251 bytes (8%)
[00:01:48.434,143] <inf> download_client: Downloaded 32768/354251 bytes (9%)
[00:01:49.074,157] <inf> download_client: Downloaded 34816/354251 bytes (9%)
[00:01:49.716,186] <inf> download_client: Downloaded 36864/354251 bytes (10%)
[00:01:50.355,194] <inf> download_client: Downloaded 38912/354251 bytes (10%)
[00:01:50.994,079] <inf> download_client: Downloaded 40960/354251 bytes (11%)
[00:01:51.633,270] <inf> download_client: Downloaded 43008/354251 bytes (12%)
[00:01:52.514,282] <inf> download_client: Downloaded 45056/354251 bytes (12%)
[00:01:53.235,321] <inf> download_client: Downloaded 47104/354251 bytes (13%)
[00:01:53.893,280] <inf> download_client: Downloaded 49152/354251 bytes (13%)
[00:01:54.514,312] <inf> download_client: Downloaded 51200/354251 bytes (14%)
[00:01:55.315,399] <inf> download_client: Downloaded 53248/354251 bytes (15%)
[00:01:56.114,288] <inf> download_client: Downloaded 55296/354251 bytes (15%)
[00:01:56.753,265] <inf> download_client: Downloaded 57344/354251 bytes (16%)
[00:01:57.393,463] <inf> download_client: Downloaded 59392/354251 bytes (16%)
[00:01:58.034,301] <inf> download_client: Downloaded 61440/354251 bytes (17%)
[00:01:58.674,499] <inf> download_client: Downloaded 63488/354251 bytes (17%)
[00:01:59.314,422] <inf> download_client: Downloaded 65536/354251 bytes (18%)
[00:01:59.953,552] <inf> download_client: Downloaded 67584/354251 bytes (19%)
[00:02:00.596,252] <inf> download_client: Downloaded 69632/354251 bytes (19%)
[00:02:03.314,514] <inf> download_client: Downloaded 71680/354251 bytes (20%)
[00:02:04.436,553] <inf> download_client: Downloaded 73728/354251 bytes (20%)
[00:02:05.074,707] <inf> download_client: Downloaded 75776/354251 bytes (21%)
[00:02:05.714,752] <inf> download_client: Downloaded 77824/354251 bytes (21%)
[00:02:06.354,583] <inf> download_client: Downloaded 79872/354251 bytes (22%)
[00:02:06.995,605] <inf> download_client: Downloaded 81920/354251 bytes (23%)
[00:02:07.635,803] <inf> download_client: Downloaded 83968/354251 bytes (23%)
[00:02:08.155,731] <inf> main: Publishing: {
        "state":        {
                "reported":     {
                        "batv": 5222,
                        "ts":   1675192878617
                }
        }
} to AWS IoT broker

[00:02:08.170,501] <inf> main: Next data publication in 60 seconds

[00:02:08.291,748] <inf> download_client: Downloaded 86016/354251 bytes (24%)
[00:02:08.834,655] <inf> download_client: Downloaded 88064/354251 bytes (24%)
[00:02:09.554,687] <inf> download_client: Downloaded 90112/354251 bytes (25%)
[00:02:10.194,915] <inf> download_client: Downloaded 92160/354251 bytes (26%)
[00:02:10.834,747] <inf> download_client: Downloaded 94208/354251 bytes (26%)
[00:02:11.474,761] <inf> download_client: Downloaded 96256/354251 bytes (27%)
[00:02:12.115,814] <inf> download_client: Downloaded 98304/354251 bytes (27%)
[00:02:12.755,828] <inf> download_client: Downloaded 100352/354251 bytes (28%)
[00:02:13.396,026] <inf> download_client: Downloaded 102400/354251 bytes (28%)
[00:02:14.035,034] <inf> download_client: Downloaded 104448/354251 bytes (29%)
[00:02:14.674,957] <inf> download_client: Downloaded 106496/354251 bytes (30%)
[00:02:15.315,093] <inf> download_client: Downloaded 108544/354251 bytes (30%)
[00:02:15.955,108] <inf> download_client: Downloaded 110592/354251 bytes (31%)
[00:02:16.595,092] <inf> download_client: Downloaded 112640/354251 bytes (31%)
[00:02:17.235,992] <inf> download_client: Downloaded 114688/354251 bytes (32%)
[00:02:17.877,136] <inf> download_client: Downloaded 116736/354251 bytes (32%)
[00:02:18.777,099] <inf> download_client: Downloaded 118784/354251 bytes (33%)
[00:02:19.476,226] <inf> download_client: Downloaded 120832/354251 bytes (34%)
[00:02:20.116,943] <inf> download_client: Downloaded 122880/354251 bytes (34%)
[00:02:21.075,103] <inf> download_client: Downloaded 124928/354251 bytes (35%)
[00:02:21.734,283] <inf> download_client: Downloaded 126976/354251 bytes (35%)
[00:02:22.355,346] <inf> download_client: Downloaded 129024/354251 bytes (36%)
[00:02:22.977,355] <inf> download_client: Downloaded 131072/354251 bytes (36%)
[00:02:23.635,223] <inf> download_client: Downloaded 133120/354251 bytes (37%)
[00:02:24.275,726] <inf> download_client: Downloaded 135168/354251 bytes (38%)
[00:02:24.915,405] <inf> download_client: Downloaded 137216/354251 bytes (38%)
[00:02:25.876,312] <inf> download_client: Downloaded 139264/354251 bytes (39%)
[00:02:26.516,479] <inf> download_client: Downloaded 141312/354251 bytes (39%)
[00:02:27.155,456] <inf> download_client: Downloaded 143360/354251 bytes (40%)
[00:02:50.356,140] <err> download_client: Failed to send HTTP request, errno 140
[00:02:50.364,685] <wrn> fota_download: Download socket error. 2 retries left...
[00:02:50.373,229] <inf> download_client: Reconnecting..
[00:02:50.379,882] <wrn> download_client: Failed to resolve hostname bucketfortestingpurposes.s3.amazonaws.com on IPv4
[00:02:50.391,876] <err> fota_download: Download client error
[00:02:50.398,681] <inf> dfu_target_mcuboot: MCUBoot image upgrade aborted.
[00:02:50.406,463] <err> aws_fota: FOTA_DOWNLOAD_EVT_ERROR
[00:03:01.848,785] <inf> main: RRC mode: Idle

[00:03:03.717,468] <inf> main: RRC mode: Connected

[00:03:08.185,607] <inf> main: Publishing: {
        "state":        {
                "reported":     {
                        "batv": 5289,
                        "ts":   1675192938641
                }
        }
} to AWS IoT broker

[Michal]

Hello,

Thanks for the update, I will look into it.

Best regards,

Michal

[KLarocqueEMFluids]

Any updates ? It's been 2 weeks since I wrote this post. Have you had time to look a bit into it? Thanks for your help btw.

[Michal]

Sorry, I had a bit of backlog and I was on a sick leave the last few days.

I have asked my colleagues for their insight with this issue and will get back to you.

Best regards,

Michal

[Michal]

Hello,

Could you provide us with a modem trace?

Best regards,

Michal