LWM2M Client fails to upgrade modem firmware

[RonanB96]

modem fw 1.3.0

ncs 1.5.0 and master

I am trying to test modem firmware upgrades using the lwm2m client sample and every time it is writing the 20% downloaded chunk, the modem throws an 

DFU_ERROR_INTERNAL_03 error. The modem firmware delta is attached

mfw_nrf9160_update_from_1.3.0_to_1.3.0-FOTA-TEST.bin

Log of downloading the firmware and error applying firmware

uart:~$ *** Booting Zephyr OS build v2.4.99-ncs1  ***
[00:00:00.207,794] <dbg> net_lwm2m_engine.lwm2m_engine_init: LWM2M engine socket receive thread started
[00:00:00.207,885] <dbg> net_lwm2m_obj_security.security_create: Create LWM2M security instance: 0
--- 20 messages dropped ---
[00:00:00.207,916] <dbg> net_lwm2m_obj_server.server_create: Create LWM2M server instance: 0
[00:00:00.208,007] <dbg> net_lwm2m_obj_device.device_create: Create LWM2M device instance: 0
[00:00:00.208,038] <dbg> net_lwm2m_obj_conn_mon.connmon_create: Create LWM2M connectivity monitoring instance: 0
[00:00:00.208,068] <dbg> net_lwm2m_obj_firmware.firmware_create: Create LWM2M firmware instance: 0
[00:00:00.208,099] <dbg> net_lwm2m_obj_location.location_create: Create Location instance: 0
[00:00:00.208,526] <inf> app_lwm2m_client: Run LWM2M client
[00:00:00.208,740] <dbg> app_lwm2m_client.main: Set DNS to custom address 8.8.8.8
[00:00:00.209,503] <inf> app_lwm2m_client: Initializing modem.
[00:00:00.233,459] <dbg> net_ipso_onoff_switch.switch_create: Create IPSO On/Off Switch instance: 1
[00:00:00.233,520] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:3342/1/5500, value:0x20025c27, len:1
[00:00:00.233,581] <dbg> net_lwm2m_engine.lwm2m_engine_create_obj_inst: path:3313/0
[00:00:00.233,642] <dbg> net_ipso_accel.accel_create: Create IPSO Accelerometer instance: 0
[00:00:00.233,734] <dbg> net_lwm2m_engine.lwm2m_engine_get: path:3/0/13, buf:0x20025c14, buflen:4
[00:00:00.233,764] <inf> app_lwm2m_accel: accelerometer normal
[00:00:00.233,795] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:3313/0/5702, value:0x20025c18, len:8
[00:00:00.233,856] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:3313/0/5703, value:0x20025c18, len:8
[00:00:00.233,917] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:3313/0/5704, value:0x20025c18, len:8
[00:00:00.233,978] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:3313/0/5518, value:0x20025c04, len:4
[00:00:00.234,039] <inf> app_lwm2m_firmware: Update Counter: current 0, update 0
[00:00:00.234,069] <inf> app_lwm2m_firmware: Image is not confirmed OK
[00:00:00.234,130] <inf> app_lwm2m_firmware: Marked image as OK
[00:00:00.234,313] <inf> app_lwm2m_firmware: Update Counter updated
[00:00:00.234,344] <inf> app_lwm2m_firmware: Firmware updated successfully
[00:00:00.234,375] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:5/0/5, value:0x20025c17, len:1
[00:00:01.896,240] <inf> app_lwm2m_client: Connecting to LTE network.
[00:00:01.896,270] <inf> app_lwm2m_client: This may take several minutes.
[00:00:01.896,331] <dbg> lte_lc.lte_lc_system_mode_set: Sending AT command to set system mode: AT%XSYSTEMMODE=0,1,1,0
[00:00:05.006,256] <dbg> lte_lc.at_handler: +CEREG notification: +CEREG: 2,"2332","02592A20",9

[00:00:05.332,702] <dbg> lte_lc.at_handler: +CSCON notification
[00:00:06.644,134] <dbg> lte_lc.at_handler: +CEREG notification: +CEREG: 5,"2332","02592A20",9,,,"11100000","00101000"

[00:00:06.644,470] <dbg> lte_lc.parse_psm_cfg: TAU: 28800 sec, active time: -1 sec

[00:00:06.644,592] <inf> app_lwm2m_client: Connected to LTE network
[00:00:06.646,118] <inf> net_lwm2m_rd_client: Start LWM2M Client: nrf-352656106650180
[00:00:06.680,450] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:4/0/0, value:0x20026d7f, len:1
[00:00:06.680,603] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:4/0/8, value:0x20026d7c, len:4
[00:00:06.680,633] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:4/0/9, value:0x20026d7e, len:2
[00:00:06.680,664] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:4/0/10, value:0x20026d7e, len:2
[00:00:06.680,786] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:4/0/2, value:0x20026d77, len:1
[00:00:07.209,075] <dbg> net_lwm2m_engine.lwm2m_engine_get: path:0/0/1, buf:0x20023767, buflen:1
[00:00:07.209,167] <dbg> net_lwm2m_engine.lwm2m_engine_get: path:0/0/10, buf:0x20023792, buflen:2
[00:00:07.209,197] <inf> net_lwm2m_rd_client: RD Client started with endpoint 'nrf-352656106650180' with client lifetime 0
[00:00:07.209,259] <dbg> net_lwm2m_engine.lwm2m_parse_peerinfo: Parse url: coaps://leshan.eclipseprojects.io
[00:00:08.624,542] <dbg> net_lwm2m_engine.lwm2m_engine_get: path:1/0/1, buf:0x20023750, buflen:4
[00:00:08.626,220] <dbg> net_lwm2m_rd_client.sm_send_registration: registration sent [23.97.187.154]
[00:00:08.804,229] <err> net_lwm2m_engine: Unknown IP address family:0
[00:00:08.804,260] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:00:08.804,260] <dbg> net_lwm2m_rd_client.do_registration_reply_cb: Registration callback (code:2.1)
[00:00:08.804,290] <dbg> app_lwm2m_client.rd_client_event: Registration complete
[00:00:08.804,321] <inf> net_lwm2m_rd_client: Registration Done (EP='ItPzcT0F3x')
[00:00:08.804,351] <dbg> net_lwm2m_engine.lwm2m_udp_receive: reply 0x2001505c handled and removed
[00:00:15.982,482] <err> net_lwm2m_engine: Unknown IP address family:0
[00:00:15.982,513] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:00:22.163,604] <err> net_lwm2m_engine: Unknown IP address family:0
[00:00:22.163,635] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:00:22.163,696] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:00:22.163,879] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:0 last:0
[00:00:22.163,909] <dbg> net_lwm2m_obj_firmware.lwm2m_firmware_set_update_state: Update state = 1
[00:00:22.164,642] <inf> dfu_target_modem_delta: Modem DFU Socket created
[00:00:22.164,947] <inf> dfu_target_modem_delta: Modem firmware version: e7f4d5d4-f607-43a6-b383-cfdb6d725c82
[00:00:22.165,710] <inf> dfu_target_modem_delta: Deleting firmware image, this can take several minutes
[00:00:32.318,206] <dbg> lte_lc.at_handler: +CSCON notification
[00:00:37.908,569] <inf> dfu_target_modem_delta: Modem FW delete complete
[00:00:37.908,599] <inf> app_lwm2m_firmware: Modem firmware download started.
[00:00:37.946,166] <inf> app_lwm2m_firmware: Downloaded 1%
[00:00:38.559,051] <dbg> lte_lc.at_handler: +CSCON notification
[00:00:38.794,372] <err> net_lwm2m_engine: Unknown IP address family:0
[00:00:38.794,372] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:00:38.794,372] <dbg> net_lwm2m_engine.lwm2m_udp_receive: No handler for response
[00:00:47.148,498] <err> net_lwm2m_engine: Unknown IP address family:0
[00:00:47.148,529] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:00:47.148,590] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:00:47.148,773] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:0 last:0
[00:00:47.148,803] <inf> app_lwm2m_firmware: Downloaded 3%
[00:00:47.149,261] <inf> app_lwm2m_firmware: Downloaded 5%
[00:00:47.449,096] <err> net_lwm2m_engine: Unknown IP address family:0
[00:00:47.449,096] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:00:47.449,188] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:00:47.449,310] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:512 last:0
[00:00:47.449,371] <inf> app_lwm2m_firmware: Downloaded 7%
[00:00:47.711,090] <err> net_lwm2m_engine: Unknown IP address family:0
[00:00:47.711,151] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:00:47.711,212] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:00:47.711,334] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:768 last:0
[00:00:47.711,395] <inf> app_lwm2m_firmware: Downloaded 9%
[00:00:48.015,106] <err> net_lwm2m_engine: Unknown IP address family:0
[00:00:48.015,136] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:00:48.015,197] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:00:48.015,350] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:1024 last:0
[00:00:48.015,380] <inf> app_lwm2m_firmware: Downloaded 11%
[00:00:48.346,099] <err> net_lwm2m_engine: Unknown IP address family:0
[00:00:48.346,130] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:00:48.346,191] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:00:48.346,343] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:1280 last:0
[00:00:48.346,374] <inf> app_lwm2m_firmware: Downloaded 12%
[00:00:48.691,131] <err> net_lwm2m_engine: Unknown IP address family:0
[00:00:48.691,162] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:00:48.691,223] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:00:48.691,375] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:1536 last:0
[00:00:48.691,406] <inf> app_lwm2m_firmware: Downloaded 14%
[00:00:49.003,143] <err> net_lwm2m_engine: Unknown IP address family:0
[00:00:49.003,173] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:00:49.003,234] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:00:49.003,387] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:1792 last:0
[00:00:49.003,448] <inf> app_lwm2m_firmware: Downloaded 16%
[00:00:49.554,168] <err> net_lwm2m_engine: Unknown IP address family:0
[00:00:49.554,199] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:00:49.554,260] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:00:49.554,412] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:2048 last:0
[00:00:49.554,443] <inf> app_lwm2m_firmware: Downloaded 18%
[00:00:49.850,189] <err> net_lwm2m_engine: Unknown IP address family:0
[00:00:49.850,219] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:00:49.850,280] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:00:49.850,433] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:2304 last:0
[00:00:49.850,463] <inf> app_lwm2m_firmware: Downloaded 20%
[00:00:49.852,050] <err> dfu_target_modem_delta: send failed, modem errno 8, dfu err -5
[00:00:49.852,050] <err> app_lwm2m_firmware: dfu_target_write error, err -14
[00:00:49.852,050] <inf> dfu_target_modem_delta: Modem upgrade aborted.
[00:00:49.852,508] <dbg> net_lwm2m_obj_firmware.lwm2m_firmware_set_update_state: Update state = 0
[00:00:49.852,539] <dbg> net_lwm2m_obj_firmware.lwm2m_firmware_set_update_result: Update result = 5
uart:~$ 

I got the same result from using NCS v1.5.0 and on master.

I added in a custom DNS server as our SIM provider doesn't serve one from the network but otherwise, the sample is unchanged

[Albrecht Markus Schellenberger]

Hello Ronan,

RonanB96 said:

I am triggering the download from the Leshan Server by uploading the fota test file to "Package" Resource in the "Firmware Upgrade" Object.

Have you adjusted the Response Timeout to >5s? This was initially the root cause for failing FOTA download during my tests. You can find the “Response Timeout” field on top of the Leshan web UI. 

RonanB96 said:

My goal is just to prove that firmware upgrades triggered by an LWM2M server are possible and the underlying SDK is mature enough for our application. The Leshan server will not be used for our application

Then I do not really understand where the custom DNS server comes in? Can you elaborate?

Regards,

Markus

[RonanB96]

Hi Markus,

I tried again with no changes and it worked this morning. So I'm very confused why it failed multiple times before at the same chunk. See the working log below. 

uart:~$ *** Booting Zephyr OS build v2.4.99-ncs1  ***
I: Starting bootloader
I: Primary image: magic=unset, swap_type=0x1, copy_done=0x3, image_ok=0x3
I: Secondary image: magic=unset, swap_type=0x1, copy_done=0x3, image_ok=0x3
I: Boot source: none
I: Swap type: none
I: Bootloader chainload address offset: 0x10000
*** Booting Zephyr OS build v2.4.99-ncs1  ***
Flash regions		Domain		Permissions
00 02 0x00000 0x18000 	Secure		rwxl
03 31 0x18000 0x100000 	Non-Secure	rwxl

Non-secure callable region 0 placed in flash region 2 with size 32.

SRAM region		Domain		Permissions
00 07 0x00000 0x10000 	Secure		rwxl
08 31 0x10000 0x40000 	Non-Secure	rwxl

Peripheral		Domain		Status
00 NRF_P0               Non-Secure	OK
01 NRF_CLOCK            Non-Secure	OK
02 NRF_RTC0             Non-Secure	OK
03 NRF_RTC1             Non-Secure	OK
04 NRF_NVMC             Non-Secure	OK
05 NRF_UARTE1           Non-Secure	OK
06 NRF_UARTE2           Secure		SKIP
07 NRF_TWIM2            Non-Secure	OK
08 NRF_SPIM3            Non-Secure	OK
09 NRF_TIMER0           Non-Secure	OK
10 NRF_TIMER1           Non-Secure	OK
11 NRF_TIMER2           Non-Secure	OK
12 NRF_SAADC            Non-Secure	OK
13 NRF_PWM0             Non-Secure	OK
14 NRF_PWM1             Non-Secure	OK
15 NRF_PWM2             Non-Secure	OK
16 NRF_PWM3             Non-Secure	OK
17 NRF_WDT              Non-Secure	OK
18 NRF_IPC              Non-Secure	OK
19 NRF_VMC              Non-Secure	OK
20 NRF_FPU              Non-Secure	OK
21 NRF_EGU1             Non-Secure	OK
22 NRF_EGU2             Non-Secure	OK
23 NRF_DPPIC            Non-Secure	OK
24 NRF_REGULATORS       Non-Secure	OK
25 NRF_GPIOTE1          Non-Secure	OK

SPM: NS image at 0x1c200
SPM: NS MSP at 0x20025c78
SPM: NS reset vector at 0x2997d
SPM: prepare to jump to Non-Secure image.


uart:~$ *** Booting Zephyr OS build v2.4.99-ncs1  ***
[00:00:00.207,794] <dbg> net_lwm2m_engine.lwm2m_engine_init: LWM2M engine socket receive thread started
[00:00:00.207,885] <dbg> net_lwm2m_obj_security.security_create: Create LWM2M security instance: 0
--- 20 messages dropped ---
[00:00:00.207,916] <dbg> net_lwm2m_obj_server.server_create: Create LWM2M server instance: 0
[00:00:00.208,007] <dbg> net_lwm2m_obj_device.device_create: Create LWM2M device instance: 0
[00:00:00.208,038] <dbg> net_lwm2m_obj_conn_mon.connmon_create: Create LWM2M connectivity monitoring instance: 0
[00:00:00.208,068] <dbg> net_lwm2m_obj_firmware.firmware_create: Create LWM2M firmware instance: 0
[00:00:00.208,099] <dbg> net_lwm2m_obj_location.location_create: Create Location instance: 0
[00:00:00.208,526] <inf> app_lwm2m_client: Run LWM2M client
[00:00:00.208,740] <dbg> app_lwm2m_client.main: Set DNS to custom address 8.8.8.8
[00:00:00.209,533] <inf> app_lwm2m_client: Initializing modem.
[00:00:00.233,489] <dbg> net_ipso_onoff_switch.switch_create: Create IPSO On/Off Switch instance: 1
[00:00:00.233,520] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:3342/1/5500, value:0x20025c27, len:1
[00:00:00.233,612] <dbg> net_lwm2m_engine.lwm2m_engine_create_obj_inst: path:3313/0
[00:00:00.233,673] <dbg> net_ipso_accel.accel_create: Create IPSO Accelerometer instance: 0
[00:00:00.233,764] <dbg> net_lwm2m_engine.lwm2m_engine_get: path:3/0/13, buf:0x20025c14, buflen:4
[00:00:00.233,795] <inf> app_lwm2m_accel: accelerometer normal
[00:00:00.233,825] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:3313/0/5702, value:0x20025c18, len:8
[00:00:00.233,886] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:3313/0/5703, value:0x20025c18, len:8
[00:00:00.233,947] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:3313/0/5704, value:0x20025c18, len:8
[00:00:00.234,008] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:3313/0/5518, value:0x20025c04, len:4
[00:00:00.234,069] <inf> app_lwm2m_firmware: Update Counter: current 0, update 0
[00:00:00.234,069] <inf> app_lwm2m_firmware: Image is not confirmed OK
[00:00:00.234,161] <inf> app_lwm2m_firmware: Marked image as OK
[00:00:00.234,344] <inf> app_lwm2m_firmware: Update Counter updated
[00:00:00.234,375] <inf> app_lwm2m_firmware: Firmware updated successfully
[00:00:00.234,405] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:5/0/5, value:0x20025c17, len:1
[00:00:00.796,508] <inf> app_lwm2m_client: Connecting to LTE network.
[00:00:00.796,539] <inf> app_lwm2m_client: This may take several minutes.
[00:00:00.796,600] <dbg> lte_lc.lte_lc_system_mode_set: Sending AT command to set system mode: AT%XSYSTEMMODE=0,1,1,0
[00:00:05.473,693] <dbg> lte_lc.at_handler: +CEREG notification: +CEREG: 2,"2332","024FDE1F",9

[00:00:07.011,352] <dbg> lte_lc.at_handler: +CSCON notification
[00:00:10.045,867] <dbg> lte_lc.at_handler: +CEREG notification: +CEREG: 5,"2332","024FDE1F",9,,,"11100000","00101000"

[00:00:10.046,173] <dbg> lte_lc.parse_psm_cfg: TAU: 28800 sec, active time: -1 sec

[00:00:10.046,295] <inf> app_lwm2m_client: Connected to LTE network
[00:00:10.047,821] <inf> net_lwm2m_rd_client: Start LWM2M Client: nrf-352656106650180
[00:00:10.081,390] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:4/0/0, value:0x20026d7f, len:1
[00:00:10.081,542] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:4/0/8, value:0x20026d7c, len:4
[00:00:10.081,573] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:4/0/9, value:0x20026d7e, len:2
[00:00:10.081,604] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:4/0/10, value:0x20026d7e, len:2
[00:00:10.233,551] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:4/0/2, value:0x20026d77, len:1
[00:00:10.709,838] <dbg> net_lwm2m_engine.lwm2m_engine_get: path:0/0/1, buf:0x20023767, buflen:1
[00:00:10.709,930] <dbg> net_lwm2m_engine.lwm2m_engine_get: path:0/0/10, buf:0x20023792, buflen:2
[00:00:10.709,960] <inf> net_lwm2m_rd_client: RD Client started with endpoint 'nrf-352656106650180' with client lifetime 0
[00:00:10.710,021] <dbg> net_lwm2m_engine.lwm2m_parse_peerinfo: Parse url: coaps://leshan.eclipseprojects.io
[00:00:13.118,286] <dbg> net_lwm2m_engine.lwm2m_engine_get: path:1/0/1, buf:0x20023750, buflen:4
[00:00:13.119,964] <dbg> net_lwm2m_rd_client.sm_send_registration: registration sent [23.97.187.154]
[00:00:13.352,020] <err> net_lwm2m_engine: Unknown IP address family:0
[00:00:13.352,020] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:00:13.352,050] <dbg> net_lwm2m_rd_client.do_registration_reply_cb: Registration callback (code:2.1)
[00:00:13.352,081] <dbg> app_lwm2m_client.rd_client_event: Registration complete
[00:00:13.352,081] <inf> net_lwm2m_rd_client: Registration Done (EP='Ja6gYWRvy3')
[00:00:13.352,142] <dbg> net_lwm2m_engine.lwm2m_udp_receive: reply 0x2001505c handled and removed
[00:00:18.483,581] <dbg> lte_lc.at_handler: +CSCON notification
[00:00:28.772,308] <dbg> lte_lc.at_handler: +CSCON notification
[00:00:28.997,650] <err> net_lwm2m_engine: Unknown IP address family:0
[00:00:28.997,680] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:00:29.191,589] <err> net_lwm2m_engine: Unknown IP address family:0
[00:00:29.191,589] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:00:34.355,163] <dbg> lte_lc.at_handler: +CSCON notification
[00:00:59.328,399] <dbg> lte_lc.at_handler: +CSCON notification
[00:00:59.887,664] <err> net_lwm2m_engine: Unknown IP address family:0
[00:00:59.887,695] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:00:59.887,756] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:00:59.887,908] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:0 last:0
[00:00:59.887,969] <dbg> net_lwm2m_obj_firmware.lwm2m_firmware_set_update_state: Update state = 1
[00:00:59.888,702] <inf> dfu_target_modem_delta: Modem DFU Socket created
[00:00:59.889,007] <inf> dfu_target_modem_delta: Modem firmware version: e7f4d5d4-f607-43a6-b383-cfdb6d725c82
[00:00:59.889,739] <inf> dfu_target_modem_delta: Deleting firmware image, this can take several minutes
[00:01:05.040,985] <dbg> lte_lc.at_handler: +CSCON notification
[00:01:10.537,139] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:4/0/2, value:0x20026d77, len:1
[00:01:10.631,378] <inf> dfu_target_modem_delta: Modem FW delete complete
[00:01:10.631,408] <inf> app_lwm2m_firmware: Modem firmware download started.
[00:01:10.662,994] <inf> app_lwm2m_firmware: Downloaded 1%
[00:01:11.037,841] <dbg> lte_lc.at_handler: +CSCON notification
[00:01:11.594,543] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:11.594,573] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:11.594,635] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:11.594,787] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:512 last:0
[00:01:11.594,818] <inf> app_lwm2m_firmware: Downloaded 3%
[00:01:12.138,549] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:12.138,580] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:12.138,641] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:12.138,793] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:768 last:0
[00:01:12.138,824] <inf> app_lwm2m_firmware: Downloaded 5%
[00:01:12.529,541] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:12.529,571] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:12.529,632] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:12.529,785] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:1024 last:0
[00:01:12.529,846] <inf> app_lwm2m_firmware: Downloaded 7%
[00:01:12.920,593] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:12.920,593] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:12.920,654] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:12.920,806] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:1280 last:0
[00:01:12.920,867] <inf> app_lwm2m_firmware: Downloaded 9%
[00:01:13.284,606] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:13.284,637] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:13.284,698] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:13.284,851] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:1536 last:0
[00:01:13.284,881] <inf> app_lwm2m_firmware: Downloaded 11%
[00:01:13.687,622] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:13.687,622] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:13.687,683] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:13.687,835] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:1792 last:0
[00:01:13.687,896] <inf> app_lwm2m_firmware: Downloaded 12%
[00:01:14.064,636] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:14.064,666] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:14.064,727] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:14.064,880] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:2048 last:0
[00:01:14.064,910] <inf> app_lwm2m_firmware: Downloaded 14%
[00:01:14.547,637] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:14.547,668] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:14.547,729] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:14.547,882] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:2304 last:0
[00:01:14.547,912] <inf> app_lwm2m_firmware: Downloaded 16%
[00:01:14.993,713] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:14.993,743] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:14.993,804] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:14.993,957] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:2560 last:0
[00:01:14.993,988] <inf> app_lwm2m_firmware: Downloaded 18%
[00:01:15.369,659] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:15.369,659] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:15.369,720] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:15.369,873] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:2816 last:0
[00:01:15.369,934] <inf> app_lwm2m_firmware: Downloaded 20%
[00:01:17.839,813] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:17.839,843] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:17.839,904] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:17.840,057] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:3072 last:0
[00:01:17.840,087] <inf> app_lwm2m_firmware: Downloaded 22%
[00:01:18.279,815] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:18.279,846] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:18.279,907] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:18.280,059] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:3328 last:0
[00:01:18.280,090] <inf> app_lwm2m_firmware: Downloaded 24%
[00:01:18.689,819] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:18.689,849] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:18.689,910] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:18.690,063] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:3584 last:0
[00:01:18.690,093] <inf> app_lwm2m_firmware: Downloaded 26%
[00:01:19.064,849] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:19.064,849] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:19.064,941] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:19.065,124] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:3840 last:0
[00:01:19.065,155] <inf> app_lwm2m_firmware: Downloaded 27%
[00:01:19.504,852] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:19.504,882] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:19.504,974] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:19.505,096] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:4096 last:0
[00:01:19.505,157] <inf> app_lwm2m_firmware: Downloaded 29%
[00:01:19.920,867] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:19.920,898] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:19.920,959] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:19.921,112] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:4352 last:0
[00:01:19.921,142] <inf> app_lwm2m_firmware: Downloaded 31%
[00:01:20.387,908] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:20.387,939] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:20.388,000] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:20.388,153] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:4608 last:0
[00:01:20.388,183] <inf> app_lwm2m_firmware: Downloaded 33%
[00:01:20.769,897] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:20.769,897] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:20.769,958] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:20.770,111] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:4864 last:0
[00:01:20.770,141] <inf> app_lwm2m_firmware: Downloaded 35%
[00:01:21.149,902] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:21.149,932] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:21.149,993] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:21.150,146] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:5120 last:0
[00:01:21.150,177] <inf> app_lwm2m_firmware: Downloaded 37%
[00:01:21.609,924] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:21.609,924] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:21.609,985] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:21.610,137] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:5376 last:0
[00:01:21.610,198] <inf> app_lwm2m_firmware: Downloaded 39%
[00:01:22.107,971] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:22.108,001] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:22.108,062] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:22.108,215] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:5632 last:0
[00:01:22.108,245] <inf> app_lwm2m_firmware: Downloaded 40%
[00:01:22.561,065] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:22.561,065] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:22.561,126] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:22.561,279] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:5888 last:0
[00:01:22.561,340] <inf> app_lwm2m_firmware: Downloaded 42%
[00:01:23.040,985] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:23.041,015] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:23.041,076] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:23.041,229] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:6144 last:0
[00:01:23.041,290] <inf> app_lwm2m_firmware: Downloaded 44%
[00:01:23.464,996] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:23.465,026] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:23.465,087] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:23.465,240] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:6400 last:0
[00:01:23.465,270] <inf> app_lwm2m_firmware: Downloaded 46%
[00:01:26.429,199] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:26.429,229] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:26.429,290] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:26.429,443] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:6656 last:0
[00:01:26.429,504] <inf> app_lwm2m_firmware: Downloaded 48%
[00:01:26.474,151] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:26.474,151] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:26.474,212] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:26.474,273] <wrn> net_lwm2m_engine: Block already handled 26, expected 27
[00:01:26.848,114] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:26.848,175] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:26.848,236] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:26.848,388] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:6912 last:0
[00:01:26.848,419] <inf> app_lwm2m_firmware: Downloaded 50%
[00:01:27.205,139] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:27.205,169] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:27.205,230] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:27.205,383] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:7168 last:0
[00:01:27.205,444] <inf> app_lwm2m_firmware: Downloaded 52%
[00:01:27.898,162] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:27.898,193] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:27.898,254] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:27.898,406] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:7424 last:0
[00:01:27.898,437] <inf> app_lwm2m_firmware: Downloaded 53%
[00:01:28.383,178] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:28.383,209] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:28.383,300] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:28.383,422] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:7680 last:0
[00:01:28.383,483] <inf> app_lwm2m_firmware: Downloaded 55%
[00:01:28.880,187] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:28.880,218] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:28.880,279] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:28.880,432] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:7936 last:0
[00:01:28.880,462] <inf> app_lwm2m_firmware: Downloaded 57%
[00:01:29.330,200] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:29.330,230] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:29.330,291] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:29.330,444] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:8192 last:0
[00:01:29.330,474] <inf> app_lwm2m_firmware: Downloaded 59%
[00:01:29.775,238] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:29.775,238] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:29.775,329] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:29.775,451] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:8448 last:0
[00:01:29.775,512] <inf> app_lwm2m_firmware: Downloaded 61%
[00:01:30.161,254] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:30.161,285] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:30.161,346] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:30.161,499] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:8704 last:0
[00:01:30.161,529] <inf> app_lwm2m_firmware: Downloaded 63%
[00:01:30.528,259] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:30.528,289] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:30.528,350] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:30.528,503] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:8960 last:0
[00:01:30.528,533] <inf> app_lwm2m_firmware: Downloaded 65%
[00:01:30.945,312] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:30.945,343] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:30.945,404] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:30.945,556] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:9216 last:0
[00:01:30.945,617] <inf> app_lwm2m_firmware: Downloaded 66%
[00:01:31.385,284] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:31.385,314] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:31.385,375] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:31.385,528] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:9472 last:0
[00:01:31.385,559] <inf> app_lwm2m_firmware: Downloaded 68%
[00:01:32.009,307] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:32.009,338] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:32.009,399] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:32.009,552] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:9728 last:0
[00:01:32.009,613] <inf> app_lwm2m_firmware: Downloaded 70%
[00:01:32.561,340] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:32.561,370] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:32.561,431] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:32.561,553] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:9984 last:0
[00:01:32.561,614] <inf> app_lwm2m_firmware: Downloaded 72%
[00:01:33.040,344] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:33.040,374] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:33.040,435] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:33.040,588] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:10240 last:0
[00:01:33.040,649] <inf> app_lwm2m_firmware: Downloaded 74%
[00:01:33.428,375] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:33.428,375] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:33.428,436] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:33.428,588] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:10496 last:0
[00:01:33.428,649] <inf> app_lwm2m_firmware: Downloaded 76%
[00:01:33.874,389] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:33.874,420] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:33.874,481] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:33.874,633] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:10752 last:0
[00:01:33.874,664] <inf> app_lwm2m_firmware: Downloaded 78%
[00:01:34.348,388] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:34.348,419] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:34.348,480] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:34.348,632] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:11008 last:0
[00:01:34.348,663] <inf> app_lwm2m_firmware: Downloaded 79%
[00:01:37.661,560] <dbg> net_lwm2m_engine.lwm2m_engine_get: path:1/0/1, buf:0x20023750, buflen:4
[00:01:37.662,139] <dbg> net_lwm2m_rd_client.sm_send_registration: registration sent [23.97.187.154]
[00:01:37.662,292] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:37.662,322] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:37.662,384] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:37.662,445] <wrn> net_lwm2m_engine: Block already handled 43, expected 44
[00:01:38.304,565] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:38.304,595] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:38.304,656] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:38.304,809] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:11264 last:0
[00:01:38.304,870] <inf> app_lwm2m_firmware: Downloaded 81%
[00:01:38.343,109] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:38.343,139] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:38.343,170] <inf> net_lwm2m_rd_client: Update callback (code:2.4)
[00:01:38.343,170] <dbg> app_lwm2m_client.rd_client_event: Registration update complete
[00:01:38.343,200] <inf> net_lwm2m_rd_client: Update Done
[00:01:38.343,231] <dbg> net_lwm2m_engine.lwm2m_udp_receive: reply 0x2001505c handled and removed
[00:01:38.790,496] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:38.790,527] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:38.790,588] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:38.790,740] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:11520 last:0
[00:01:38.790,802] <inf> app_lwm2m_firmware: Downloaded 83%
[00:01:39.370,513] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:39.370,544] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:39.370,605] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:39.370,758] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:11776 last:0
[00:01:39.370,819] <inf> app_lwm2m_firmware: Downloaded 85%
[00:01:39.745,574] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:39.745,605] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:39.745,666] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:39.745,788] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:12032 last:0
[00:01:39.745,849] <inf> app_lwm2m_firmware: Downloaded 87%
[00:01:40.093,566] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:40.093,597] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:40.093,658] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:40.093,811] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:12288 last:0
[00:01:40.093,872] <inf> app_lwm2m_firmware: Downloaded 89%
[00:01:40.493,591] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:40.493,591] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:40.493,682] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:40.493,804] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:12544 last:0
[00:01:40.493,865] <inf> app_lwm2m_firmware: Downloaded 91%
[00:01:41.988,647] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:41.988,677] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:41.988,739] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:41.988,891] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:12800 last:0
[00:01:41.988,922] <inf> app_lwm2m_firmware: Downloaded 92%
[00:01:42.359,649] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:42.359,680] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:42.359,741] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:42.359,893] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:13056 last:0
[00:01:42.359,924] <inf> app_lwm2m_firmware: Downloaded 94%
[00:01:42.693,664] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:42.693,695] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:42.693,756] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:42.693,908] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:13312 last:0
[00:01:42.693,939] <inf> app_lwm2m_firmware: Downloaded 96%
[00:01:43.169,616] <err> net_lwm2m_engine: Unknown IP address family:0
[00:01:43.169,647] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:01:43.169,708] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:01:43.169,891] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:13568 last:1
[00:01:43.169,952] <inf> app_lwm2m_firmware: Downloaded 98%
[00:01:43.855,499] <inf> app_lwm2m_firmware: Firmware downloaded, 13768 bytes in total
[00:01:43.855,529] <dbg> net_lwm2m_obj_firmware.lwm2m_firmware_set_update_state: Update state = 2
[00:01:49.202,880] <dbg> lte_lc.at_handler: +CSCON notification
[00:02:15.862,274] <dbg> net_lwm2m_engine.lwm2m_engine_set: path:4/0/2, value:0x20026d77, len:1
[00:02:23.848,541] <dbg> lte_lc.at_handler: +CSCON notification
[00:02:24.104,827] <err> net_lwm2m_engine: Unknown IP address family:0
[00:02:24.104,827] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:02:29.515,014] <err> net_lwm2m_engine: Unknown IP address family:0
[00:02:29.515,045] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:02:29.515,075] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:02:29.515,258] <dbg> net_lwm2m_obj_firmware.lwm2m_firmware_set_update_state: Update state = 3
[00:02:29.515,258] <dbg> app_lwm2m_firmware.firmware_update_cb: Executing firmware update
[00:02:29.515,258] <inf> app_lwm2m_firmware: Update Counter: current 0, update 0
[00:02:29.515,930] <inf> dfu_target_modem_delta: Scheduling modem firmware upgrade at next boot
[00:02:30.202,362] <inf> app_lwm2m_firmware: Rebooting device
[00:02:30.203,247] <err> net_lwm2m_engine: Unknown IP address family:0
[00:02:30.203,277] <dbg> net_lwm2m_engine.lwm2m_udp_receive: checking for reply from [unk]
[00:02:30.203,338] <dbg> net_lwm2m_engine.handle_request: No accept option given. Assume OMA TLV.
[00:02:30.203,399] <err> net_lwm2m_obj_firmware: State other than downloaded: 3

I have been working on another LWM2M ticket and was told to try to do a full erase before testing, maybe this somehow fixed it?

But anyway, would you be able to tell me what the DFU_ERROR_INTERNAL_03 error I was receiving before means? Is it a corrupted chunk error?

Regarding the DNS, iBasis does not have coverage in Ireland so I must use another SIM provider. The SIM provider I'm using does not serve a DNS server on network registration so I must define one myself. 

[Albrecht Markus Schellenberger]

Thanks for the feedback, Ronan! DFU_ERROR_INTERNAL_03, is this something you see in the modem trace?

Regards,

Markus

[RonanB96]

Hi Markus,

Sorry looks like I had a mix up, see below the error from my original log

[00:00:49.850,433] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:2304 last:0
[00:00:49.850,463] <inf> app_lwm2m_firmware: Downloaded 20%
[00:00:49.852,050] <err> dfu_target_modem_delta: send failed, modem errno 8, dfu err -5
[00:00:49.852,050] <err> app_lwm2m_firmware: dfu_target_write error, err -14

I mixed up "errno 8" and "dfu err -5", "dfu err -5" maps to DFU_INVALID_DATA and not DFU_ERROR_INTERNAL_03.

So I'm assuming DFU_INVALID_DATA is from a corrupt chunk?

[Albrecht Markus Schellenberger]

Hello Ronan,

yes, modem error 8 and dfu err -5 occur due to the fact that first block of the FOTA image is send twice:

[00:00:22.163,879] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:0 last:0
[00:00:47.148,773] <dbg> net_lwm2m_engine.lwm2m_write_handler: BLOCK1: total:13772 current:0 last:0

Regards,

Markus