GATT Error issue(Error 133) when DFU Firmware updating through nRFConnect application

[A sudheer Kumar]

Hi,

followed below link to do the firmware updating using DFU service with "nRF Connec"t application on Android mobile.

https://novelbits.io/nrf52-ota-dfu-ble-part-4/

While updating the Zip file I am getting Error 133 (0x85):GATT Error, please check the below log and video for more information 

nRF Connect, 2022-12-01
NordicUART (D2:EB:38:F0:47:E8)
V 12:01:54.568 Connecting to D2:EB:38:F0:47:E8...
D 12:01:54.568 gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D 12:01:54.683 [Callback] Connection state changed with status: 0 and new state: CONNECTED (2)
I 12:01:54.683 Connected to D2:EB:38:F0:47:E8
V 12:01:54.685 Discovering services...
D 12:01:54.685 gatt.discoverServices()
D 12:01:54.695 [Broadcast] Action received: android.bluetooth.device.action.ACL_CONNECTED
I 12:01:55.318 Connection parameters updated (interval: 7.5ms, latency: 0, timeout: 5000ms)
D 12:01:55.671 [Callback] Services discovered with status: 0
I 12:01:55.671 Services discovered
V 12:01:55.675 Generic Access (0x1800)
- Device Name [R W] (0x2A00)
- Appearance [R] (0x2A01)
- Peripheral Preferred Connection Parameters [R] (0x2A04)
- Central Address Resolution [R] (0x2AA6)
Generic Attribute (0x1801)
- Service Changed [I] (0x2A05)
   Client Characteristic Configuration (0x2902)
Unknown Service (6e40bbaa-b5a3-f393-e0a9-e50e24dcca9e)
- Unknown Characteristic [W WNR] (6e400004-b5a3-f393-e0a9-e50e24dcca9e)
- Unknown Characteristic [N] (6e400005-b5a3-f393-e0a9-e50e24dcca9e)
   Client Characteristic Configuration (0x2902)
Secure DFU Service (0xFE59)
- Buttonless DFU [I W] (8ec90003-f315-4f60-9fb8-838830daea50)
   Client Characteristic Configuration (0x2902)
D 12:01:55.675 gatt.setCharacteristicNotification(00002a05-0000-1000-8000-00805f9b34fb, true)
D 12:01:55.678 gatt.setCharacteristicNotification(6e400005-b5a3-f393-e0a9-e50e24dcca9e, true)
I 12:01:55.759 Connection parameters updated (interval: 45.0ms, latency: 0, timeout: 5000ms)
V 12:01:58.332 Enabling indications for 8ec90003-f315-4f60-9fb8-838830daea50
D 12:01:58.332 gatt.setCharacteristicNotification(8ec90003-f315-4f60-9fb8-838830daea50, true)
D 12:01:58.334 gatt.writeDescriptor(00002902-0000-1000-8000-00805f9b34fb, value=0x0200)
I 12:01:58.414 Data written to descr. 00002902-0000-1000-8000-00805f9b34fb, value: (0x) 02-00
A 12:01:58.414 "Indications enabled" sent
V 12:01:58.416 Indications enabled for 8ec90003-f315-4f60-9fb8-838830daea50
V 12:02:04.264 [DFU] DFU service started
V 12:02:04.264 [DFU] Opening file...
I 12:02:04.292 [DFU] Firmware file opened successfully
V 12:02:04.292 [DFU] Connecting to DFU target...
D 12:02:04.292 [DFU] gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferredPhy = LE_1M | LE_2M)
I 12:02:04.301 [DFU] Connected to D2:EB:38:F0:47:E8
V 12:02:04.301 [DFU] Discovering services...
D 12:02:04.301 [DFU] gatt.discoverServices()
I 12:02:04.301 [DFU] Services discovered
D 12:02:04.314 [DFU] wait(1000)
W 12:02:05.306 [DFU] Application with buttonless update found
V 12:02:05.306 [DFU] Jumping to the DFU Bootloader...
V 12:02:05.306 [DFU] Enabling indications for 8ec90003-f315-4f60-9fb8-838830daea50
D 12:02:05.306 [DFU] gatt.setCharacteristicNotification(8ec90003-f315-4f60-9fb8-838830daea50, true)
D 12:02:05.308 [DFU] gatt.writeDescriptor(00002902-0000-1000-8000-00805f9b34fb, value=0x02-00)
I 12:02:05.479 [DFU] Data written to descr.8ec90003-f315-4f60-9fb8-838830daea50, value (0x): 02-00
V 12:02:05.479 [DFU] Notifications enabled for 8ec90003-f315-4f60-9fb8-838830daea50
A 12:02:05.479 [DFU] Indications enabled
D 12:02:05.480 [DFU] wait(1000)
V 12:02:06.495 [DFU] Writing to characteristic 8ec90003-f315-4f60-9fb8-838830daea50
D 12:02:06.496 [DFU] gatt.writeCharacteristic(8ec90003-f315-4f60-9fb8-838830daea50)
I 12:02:06.560 Indication received from 8ec90003-f315-4f60-9fb8-838830daea50, value: (0x) 20-01-01
A 12:02:06.560 "Response for: Enter bootloader
Status: Success" received
A 12:02:06.560 [DFU] Enter bootloader sent (Op Code = 1)
I 12:02:06.560 [DFU] Notification received from 8ec90003-f315-4f60-9fb8-838830daea50, value (0x): 20-01-01
A 12:02:06.560 [DFU] Response received (Op Code = 1, Status = 1)
D 12:02:06.560 [DFU] gatt.refresh() (hidden)
D 12:02:06.565 [DFU] gatt.disconnect()
D 12:02:06.565 [DFU] gatt.close()
V 12:02:06.568 [DFU] Scanning for the DFU Bootloader... (timeout 5000 ms)
I 12:02:06.635 [DFU] DFU Bootloader found with address D2:EB:38:F0:47:E8
V 12:02:06.644 [DFU] DFU service started
I 12:02:06.644 [DFU] Firmware file opened successfully
D 12:02:06.644 [DFU] wait(1000)
I 12:02:07.017 Connection parameters updated (interval: 7.5ms, latency: 0, timeout: 5000ms)
D 12:02:07.647 [DFU] wait(1000)
V 12:02:08.647 [DFU] Connecting to DFU target...
D 12:02:08.647 [DFU] gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferredPhy = LE_1M | LE_2M)
I 12:02:08.664 [DFU] Connected to D2:EB:38:F0:47:E8
V 12:02:08.664 [DFU] Discovering services...
D 12:02:08.664 [DFU] gatt.discoverServices()
D 12:02:12.030 [Callback] Connection state changed with status: 0 and new state: DISCONNECTED (0)
I 12:02:12.030 Disconnected
E 12:02:12.060 [DFU] Disconnected
D 12:02:12.060 [DFU] gatt.refresh() (hidden)
D 12:02:12.060 [DFU] gatt.disconnect()
D 12:02:12.060 [DFU] gatt.close()
D 12:02:12.060 [Broadcast] Action received: android.bluetooth.device.action.ACL_DISCONNECTED
D 12:02:12.066 [DFU] [Broadcast] Action received: android.bluetooth.device.action.ACL_DISCONNECTED
D 12:02:12.066 [DFU] wait(600)
D 12:02:12.666 gatt.close()
D 12:02:12.668 wait(200)
V 12:02:12.868 Connecting to D2:EB:38:F0:47:E8...
D 12:02:12.868 gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D 12:02:42.884 [Callback] Connection state changed with status: 133 and new state: DISCONNECTED (0)
E 12:02:42.885 Error 133 (0x85): GATT ERROR
I 12:02:42.885 Disconnected

We are using

nrf5 SDK v17.1.0  

S140 softdevice

nRF conncet on Google pixel6 phone with Android 12  

What could  be the cause for it and how to over come 

Regards,

Sudheer

[Edvin]

Hello,

What application is running on your nRF? Does the log from the nRF say anything particular at the disconnect? They are supposed to disconnect, but the nRF should reboot into DFU mode. Does it do that? If you are using an nRF52833 DK, does two of the leds turn on and remain on (indicating that it is in DFU mode)? 

Did you modify the bootloader in any way? Did you program the bootloader at all?

If you could share some more details on what's going on on the nRF side, that would be great. The phone application just says that "something is not working".

Best regards,

Edvin

[A sudheer Kumar]

Hi,

Edvin said:

What application is running on your nRF?

Customized application (modified ble_app_uart example) 

Edvin said:

If you are using an nRF52833 DK, does two of the leds turn on and remain on (indicating that it is in DFU mode)? 

No. Custom board with nRF52833, but When I tried  with nRF52833 DK the DFU process is successful.

Is any changes required for the Custom board to do DFU process?

Edvin said:

Did you modify the bootloader in any way?

modified only public_key.c 

Edvin said:

Did you program the bootloader at all?

Yes, Merged all boot loader, soft device and application hex files as single Hex and programed through J-Flash lite application

Regards,

Sudheer

[A sudheer Kumar]

Hi All,

Is there any one to help me

[Edvin]

Sorry for the late reply. I were unexpectedly out of office since Thursday. 

Ok. Thank you for the details. So running the same modified ble_app_uart example and bootloader combination on the DK works without issues?

Since you have a debugger, can you please try to replace the bootloader project (pca10100_s140_ble) with the debug bootloader (pca10100_s140_ble_debug), and monitor the RTT log from the bootloader using e.g. J-Link RTT Viewer?

After you connect to the custom board and try to perform the DFU, does the RTT log from the bootloader print anything? If so, what does it print?

Best regards,

Edvin

[A sudheer Kumar]

Hi Edvin,

thanks for reply!

Edvin said:

Since you have a debugger, can you please try to replace the bootloader project (pca10100_s140_ble) with the debug bootloader (pca10100_s140_ble_debug), and monitor the RTT log from the bootloader using e.g. J-Link RTT Viewer?

Tried with pca10100_s140_ble_debug bootloader, the following log seen on the RTT Viewer

may it will help to get some more information to check the issue, please let me know if you required more information.

Regards,

Sudheer

[A sudheer Kumar]

Hi Edvin,

My privies replay gives only partial log,

Please check the attached one for full RTT Viewer log for successful DFU process with nRF52833 DK board. 

Fullscreen dfulog.log Download

# SEGGER J-Link RTT Viewer V5.12f Terminal Log File
# Compiled: 16:04:47 on May 17 2016
# Logging started @ 08 Dec 2022 14:31:15
 0> <info> app: Setting vector table to bootloader: 0x00072000
 0> <info> app: Setting vector table to main app: 0x00027000
 0> <info> app_timer: RTC: initialized.
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: Connecting to target D8C282A40A72
 0> <info> app: Connected
 0> <info> app: Connection with link 0x4 established.
 0> <info> app: Data len is set to 0xF4(244)
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: Device is preparing to enter bootloader mode
 0> 
 0> <info> app: Device will enter bootloader mode
 0> 
 0> <info> app: Power management wants to reset to DFU mode.
 0> <info> app: Power management allowed to reset to DFU mode.
 0> <info> app: Inside main
 0> <debug> app: In nrf_bootloader_init
 0> <debug> nrf_dfu_settings: Calling nrf_dfu_settings_init()...
 0> <debug> nrf_dfu_flash: Initializing nrf_fstorage_nvmc backend.
 0> <debug> nrf_dfu_settings: Using settings page.
 0> <debug> nrf_dfu_settings: Copying forbidden parts from backup page.
 0> <debug> nrf_dfu_settings: Destination settings are identical to source, write not needed. Skipping.
 0> <info> nrf_dfu_settings: Backing up settings page to address 0x7E000.
 0> <debug> nrf_dfu_settings: Destination settings are identical to source, write not needed. Skipping.
 0> <debug> app: Enter nrf_bootloader_fw_activate
 0> <info> app: No firmware to activate.
 0> <debug> app: App is valid
 0> <debug> app: DFU mode requested via GPREGRET.
 0> <info> nrf_bootloader_wdt: WDT is not enabled
 0> <debug> app: in weak nrf_dfu_init_user
 0> <debug> app: timer_stop (0x20002B04)
 0> <debug> app: timer_activate (0x20002B04)
 0> <info> app: Entering DFU mode.
 0> <debug> app: Initializing transports (found: 1)
 0> <debug> nrf_dfu_ble: Initializing BLE DFU transport
 0> <debug> nrf_dfu_ble: Setting up vector table: 0x00072000
 0> <debug> nrf_dfu_ble: Enabling SoftDevice.
 0> <debug> nrf_dfu_ble: Configuring BLE stack.
 0> <debug> nrf_dfu_ble: Enabling the BLE stack.
 0> <debug> nrf_dfu_ble: No advertising name found
 0> <debug> nrf_dfu_ble: Using default advertising name
 0> <debug> nrf_dfu_ble: Advertising...
 0> <debug> nrf_dfu_ble: BLE DFU transport initialized.
 0> <debug> nrf_dfu_flash: Initializing nrf_fstorage_sd backend.
 0> <debug> app: Enter main loop
 0> <debug> nrf_dfu_ble: Connected
 0> <debug> nrf_dfu_ble: Received BLE_GAP_EVT_CONN_PARAM_UPDATE
 0> <debug> nrf_dfu_ble: max_conn_interval: 12
 0> <debug> nrf_dfu_ble: min_conn_interval: 12
 0> <debug> nrf_dfu_ble: slave_latency: 0
 0> <debug> nrf_dfu_ble: conn_sup_timeout: 600
 0> <debug> nrf_dfu_ble: Received BLE_GAP_EVT_CONN_PARAM_UPDATE
 0> <debug> nrf_dfu_ble: max_conn_interval: 6
 0> <debug> nrf_dfu_ble: min_conn_interval: 6
 0> <debug> nrf_dfu_ble: slave_latency: 0
 0> <debug> nrf_dfu_ble: conn_sup_timeout: 500
 0> <debug> nrf_dfu_ble: Received BLE_GAP_EVT_CONN_PARAM_UPDATE
 0> <debug> nrf_dfu_ble: max_conn_interval: 12
 0> <debug> nrf_dfu_ble: min_conn_interval: 12
 0> <debug> nrf_dfu_ble: slave_latency: 0
 0> <debug> nrf_dfu_ble: conn_sup_timeout: 600
 0> <debug> nrf_dfu_ble: Received BLE_GATTS_EVT_EXCHANGE_MTU_REQUEST (request: 517, reply: 247).
 0> <debug> nrf_dfu_ble: Received BLE_GAP_EVT_DATA_LENGTH_UPDATE_REQUEST.
 0> <debug> nrf_dfu_ble: Received BLE_GAP_EVT_DATA_LENGTH_UPDATE (251, max_rx_time 2120).
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_SELECT (command)
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_ble: Set receipt notif
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_RECEIPT_NOTIF_SET
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> app: Shutting down transports (found: 1)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_CREATE (command)
 0> <debug> app: timer_stop (0x20002B04)
 0> <debug> app: timer_activate (0x20002B04)
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 141 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (command)
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_CRC_GET (command)
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_EXECUTE (command)
 0> <debug> nrf_dfu_validation: PB: Init packet data len: 64
 0> <info> nrf_dfu_validation: Signature required. Checking signature.
 0> <info> nrf_dfu_validation: Calculating hash (len: 64)
 0> <info> nrf_dfu_validation: Verify signature
 0> <info> nrf_dfu_validation: Image verified
 0> <debug> app: Enter nrf_dfu_cache_prepare()
 0> <debug> app: required_size: 0x14658.
 0> <debug> app: single_bank: false.
 0> <debug> app: keep_app: false.
 0> <debug> app: keep_softdevice: true.
 0> <debug> app: pass: 0.
 0> .
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00046000, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00046000, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000460F4, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000460F4, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000461E8, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000461E8, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000462DC, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000462DC, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000463D0, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000463D0, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000464C4, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000464C4, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000465B8, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000465B8, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000466AC, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000466AC, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000467A0, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000467A0, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00046894, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00046894, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00046988, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00046988, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00046A7C, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00046A7C, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00046B70, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00046B70, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00046C64, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00046C64, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00046D58, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00046D58, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00046E4C, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00046E4C, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 192 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00046F40, src=0x20005430, len=192 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_CRC_GET (data)
 0> <debug> nrf_dfu_req_handler: Offset:65536, CRC:0x5972154D
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00046F40, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_EXECUTE (data)
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> app: timer_stop (0x20002B04)
 0> <debug> app: timer_activate (0x20002B04)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_CREATE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_erase(addr=0x0x00047000, len=1 pages), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Creating object with size: 4096. Offset: 0x00010000, CRC: 0x5972154D
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash erase success: addr=0x00047000, pending 0
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00047000, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00047000, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000470F4, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000470F4, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000471E8, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000471E8, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000472DC, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000472DC, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000473D0, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000473D0, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000474C4, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000474C4, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000475B8, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000475B8, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000476AC, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000476AC, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000477A0, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000477A0, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00047894, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00047894, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00047988, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00047988, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00047A7C, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00047A7C, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00047B70, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00047B70, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00047C64, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00047C64, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00047D58, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00047D58, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00047E4C, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00047E4C, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 192 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00047F40, src=0x20005430, len=192 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_CRC_GET (data)
 0> <debug> nrf_dfu_req_handler: Offset:69632, CRC:0xC64C2E66
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00047F40, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_EXECUTE (data)
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> app: timer_stop (0x20002B04)
 0> <debug> app: timer_activate (0x20002B04)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_CREATE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_erase(addr=0x0x00048000, len=1 pages), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Creating object with size: 4096. Offset: 0x00011000, CRC: 0xC64C2E66
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash erase success: addr=0x00048000, pending 0
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00048000, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00048000, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000480F4, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000480F4, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000481E8, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000481E8, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000482DC, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000482DC, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000483D0, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000483D0, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000484C4, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000484C4, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000485B8, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000485B8, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000486AC, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000486AC, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000487A0, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000487A0, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00048894, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00048894, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00048988, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00048988, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00048A7C, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00048A7C, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00048B70, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00048B70, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00048C64, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00048C64, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00048D58, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00048D58, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00048E4C, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00048E4C, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 192 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00048F40, src=0x20005430, len=192 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_CRC_GET (data)
 0> <debug> nrf_dfu_req_handler: Offset:73728, CRC:0xD9B632FF
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00048F40, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_EXECUTE (data)
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> app: timer_stop (0x20002B04)
 0> <debug> app: timer_activate (0x20002B04)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_CREATE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_erase(addr=0x0x00049000, len=1 pages), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Creating object with size: 4096. Offset: 0x00012000, CRC: 0xD9B632FF
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash erase success: addr=0x00049000, pending 0
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00049000, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00049000, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000490F4, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000490F4, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000491E8, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000491E8, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000492DC, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000492DC, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000493D0, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000493D0, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000494C4, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000494C4, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000495B8, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000495B8, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000496AC, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000496AC, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x000497A0, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x000497A0, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00049894, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00049894, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00049988, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00049988, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00049A7C, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00049A7C, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00049B70, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00049B70, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00049C64, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00049C64, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00049D58, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00049D58, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00049E4C, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00049E4C, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 192 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00049F40, src=0x20005430, len=192 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_CRC_GET (data)
 0> <debug> nrf_dfu_req_handler: Offset:77824, CRC:0x5E79F19B
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00049F40, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_EXECUTE (data)
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> app: timer_stop (0x20002B04)
 0> <debug> app: timer_activate (0x20002B04)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_CREATE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_erase(addr=0x0x0004A000, len=1 pages), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Creating object with size: 4096. Offset: 0x00013000, CRC: 0x5E79F19B
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash erase success: addr=0x0004A000, pending 0
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004A000, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004A000, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004A0F4, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004A0F4, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004A1E8, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004A1E8, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004A2DC, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004A2DC, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004A3D0, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004A3D0, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004A4C4, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004A4C4, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004A5B8, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004A5B8, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004A6AC, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004A6AC, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004A7A0, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004A7A0, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004A894, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004A894, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004A988, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004A988, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004AA7C, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004AA7C, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004AB70, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004AB70, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004AC64, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004AC64, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004AD58, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004AD58, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004AE4C, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004AE4C, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 192 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004AF40, src=0x20005430, len=192 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_CRC_GET (data)
 0> <debug> nrf_dfu_req_handler: Offset:81920, CRC:0x1B8B225B
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004AF40, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_EXECUTE (data)
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> app: timer_stop (0x20002B04)
 0> <debug> app: timer_activate (0x20002B04)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_CREATE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_erase(addr=0x0x0004B000, len=1 pages), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Creating object with size: 1624. Offset: 0x00014000, CRC: 0x1B8B225B
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash erase success: addr=0x0004B000, pending 0
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004B000, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004B000, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004B0F4, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004B0F4, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004B1E8, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004B1E8, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004B2DC, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004B2DC, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004B3D0, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004B3D0, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 244 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004B4C4, src=0x20005430, len=244 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004B4C4, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_ble: Buffer 0x20005430 acquired, len 160 (244)
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_WRITE (data)
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0004B5B8, src=0x20005430, len=160 bytes), queue usage: 0
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_CRC_GET (data)
 0> <debug> nrf_dfu_req_handler: Offset:83544, CRC:0x0E644B8A
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0004B5B8, pending 0
 0> <debug> nrf_dfu_ble: Freeing buffer 0x20005430
 0> <debug> nrf_dfu_req_handler: Handle NRF_DFU_OP_OBJECT_EXECUTE (data)
 0> <debug> nrf_dfu_req_handler: Whole firmware image received. Postvalidating.
 0> <debug> nrf_dfu_validation: Hash verification. start address: 0x37000, size: 0x14658
 0> <debug> nrf_dfu_validation: Invalidating old application in bank 0.
 0> <debug> nrf_dfu_settings: Writing settings...
 0> <debug> nrf_dfu_settings: Erasing old settings at: 0x0007F000
 0> <debug> nrf_dfu_flash: nrf_fstorage_erase(addr=0x0x0007F000, len=1 pages), queue usage: 0
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0007F000, src=0x2000684C, len=896 bytes), queue usage: 1
 0> <info> nrf_dfu_settings: Backing up settings page to address 0x7E000.
 0> <debug> nrf_dfu_settings: Writing settings...
 0> <debug> nrf_dfu_settings: Erasing old settings at: 0x0007E000
 0> <debug> nrf_dfu_flash: nrf_fstorage_erase(addr=0x0x0007E000, len=1 pages), queue usage: 2
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0007E000, src=0x200064CC, len=896 bytes), queue usage: 3
 0> <debug> nrf_dfu_req_handler: Request handling complete. Result: 0x1
 0> <debug> app: timer_stop (0x20002B04)
 0> <debug> app: timer_activate (0x20002B04)
 0> <debug> nrf_dfu_flash: Flash erase success: addr=0x0007F000, pending 3
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x0007F000, pending 2
 0> <debug> nrf_dfu_flash: Flash erase success: addr=0x0007E000, pending 1
 0> <debug> nrf_dfu_flas<info> app: Inside main
 0> <debug> app: In nrf_bootloader_init
 0> <debug> nrf_dfu_settings: Calling nrf_dfu_settings_init()...
 0> <debug> nrf_dfu_flash: Initializing nrf_fstorage_nvmc backend.
 0> <debug> nrf_dfu_settings: Using settings page.
 0> <debug> nrf_dfu_settings: Copying forbidden parts from backup page.
 0> <debug> nrf_dfu_settings: Destination settings are identical to source, write not needed. Skipping.
 0> <info> nrf_dfu_settings: Backing up settings page to address 0x7E000.
 0> <debug> nrf_dfu_settings: Destination settings are identical to source, write not needed. Skipping.
 0> <debug> app: Enter nrf_bootloader_fw_activate
 0> <debug> app: Valid App
 0> <debug> app: Enter nrf_dfu_app_continue
 0> <info> nrf_bootloader_wdt: WDT is not enabled
 0> <debug> nrf_dfu_flash: nrf_fstorage_erase(addr=0x0x00027000, len=8 pages), queue usage: 0
 0> <debug> nrf_dfu_flash: Flash erase success: addr=0x00027000, pending 0
 0> <debug> app: Copying 0x37000 to 0x27000, size: 0x8000
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x00027000, src=0x00037000, len=32768 bytes), queue usage: 1
 0> <debug> nrf_dfu_flash: Flash write success: addr=0x00027000, pending 0
 0> <debug> nrf_dfu_settings: Writing settings...
 0> <debug> nrf_dfu_settings: Erasing old settings at: 0x0007F000
 0> <debug> nrf_dfu_flash: nrf_fstorage_erase(addr=0x0x0007F000, len=1 pages), queue usage: 1
 0> <debug> nrf_dfu_flash: Flash erase success: addr=0x0007F000, pending 0
 0> <debug> nrf_dfu_flash: nrf_fstorage_write(addr=0x0007F000, src=0x2000684C, len=896 bytes), queue usage: 1
 0> <debug> nrf_d<info> app: Inside main
 0> <debug> app: In nrf_bootloader_init
 0> <debug> nrf_dfu_settings: Calling nrf_dfu_settings_init()...
 0> <debug> nrf_dfu_flash: Initializing nrf_fstorage_nvmc backend.
 0> <debug> nrf_dfu_settings: Using settings page.
 0> <debug> nrf_dfu_settings: Copying forbidden parts from backup page.
 0> <debug> nrf_dfu_settings: Destination settings are identical to source, write not needed. Skipping.
 0> <info> nrf_dfu_settings: Backing up settings page to address 0x7E000.
 0> <debug> nrf_dfu_settings: Destination settings are identical to source, write not needed. Skipping.
 0> <debug> app: Enter nrf_bootloader_fw_activate
 0> <info> app: No firmware to activate.
 0> <debug> app: App is valid
 0> <info> nrf_dfu_settings: Backing up settings page to address 0x7E000.
 0> <debug> nrf_dfu_settings: Destination settings are identical to source, write not needed. Skipping.
 0> <debug> app: Running nrf_bootloader_app_start with address: 0x00001000
 0> <debug> app: Disabling interrupts. NVIC->ICER[0]: 0x0

 

and here is the custom board log (failure DFU )

Fullscreen log_custom.log Download

# SEGGER J-Link RTT Viewer V5.12f Terminal Log File
# Compiled: 16:04:47 on May 17 2016
# Logging started @ 08 Dec 2022 15:12:28
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: 1Sec Over!
 0> <info> app: Device is preparing to enter bootloader mode
 0> 
 0> <info> app: Device will enter bootloader mode
 0> 
 0> <info> app: Power management wants to reset to DFU mode.
 0> <info> app: Power management allowed to reset to DFU mode.

# Logging stopped @ 08 Dec 2022 15:16:57

Regards,

Sudheer

[Edvin]

Hello Sudheer,

Thank you for the detailed logs. If the last log also contain the debug bootloader, apparently it doesn't start the bootloader. But then again, the bootloader didn't log anything before starting the app the first time either, which is kind of strange. 

Did you build the bootloader in an IDE? Can you try to set a breakpoint inside the bootloader and see if it hits (both before the app starts and after you try to reset into DFU mode?

And finally, can you please read out the flash of both the DK and the custom board? For this you can use the command:

nrfjprog --readcode my_hex_dump.hex

And upload these files here. I can't say what's actually going on there, just by looking at the .hex files, but I can compare the two to see whether all the components are present.

Best regards,

Edvin