DFU over BLE using SMP failing when device is in Error state

We are using the CAF Power Manager module and CAF Simple Management Protocol module.

The DFU over SMP works like a charm using the nrf connect app when the device is in a normal state (all module report MODULE_STATE_READY). However, if a module report a MODULE_STATE_ERROR and the CAF PM module switch into the error state, the DFU fails, even if the CONFIG_CAF_POWER_MANAGER_ERROR_TIMEOUT is not yet reached. It seems that the SMP module fails to allocate the necessary buffers used for communication: 

<err> net_buf: net_buf_alloc():1259: Failed to get free buffer

<dbg> mcumgr_smp: smp_bt_chr_write: failed net_buf alloc for SMP packet

Is there a way to use DFU over SMP even from an application Error state ?

Below are the application logs and prj.conf

[00:00:00.303,833] <inf> ble_state: Bluetooth initialized
[00:00:00.304,260] <inf> app_event_manager: e:led_event led_id:1 effect:0x48cb0
[00:00:00.304,718] <inf> app_event_manager: e: power_down_event
[00:00:00.310,119] <inf> app_event_manager: e: power_down_event
[00:00:00.310,485] <inf> app_event_manager: e:module_state_event module:ble_state state:READY
[00:00:00.311,157] <inf> smp: Service initialized
[00:00:00.311,462] <inf> smp: MCUboot image version: 1.0.0+25
[00:00:00.311,859] <inf> app_event_manager: e:led_ready_event led_id:1 effect:0x48ca0
[00:00:00.312,347] <inf> battery_meas: Battery level: 93% (4109 mV)
[00:00:00.312,774] <inf> app_event_manager: e:module_state_event module:bas state:READY
[00:00:00.313,262] <inf> app_event_manager: e:module_state_event module:blx_adv state:READY
[00:00:00.313,751] <inf> app_event_manager: e:led_event led_id:0 effect:0x48c98
[00:00:00.314,208] <inf> app_event_manager: e:module_state_event module:smp state:READY
[00:00:00.314,697] <inf> app_event_manager: e:battery_level_event level=93
[00:00:00.315,155] <dbg> net_buf: net_buf_alloc_len_debug: (0x20003880) net_buf_alloc():1259: pool 0x20001814 size 70
[00:00:00.320,800] <dbg> net_buf: net_buf_alloc_len_debug: (0x20003880) allocated buf 0x2000e008
[00:00:00.321,319] <dbg> net_buf_simple: net_buf_simple_reserve: (0x20003880) buf 0x2000e014 reserve 0
[00:00:00.321,868] <dbg> net_buf_simple: net_buf_simple_add: (0x20003880) buf 0x2000e014 len 3
[00:00:00.322,357] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x20003880) buf 0x2000e014 len 15
[00:00:00.322,875] <dbg> net_buf_simple: net_buf_simple_add: (0x20003880) buf 0x2000e014 len 15
[00:00:00.323,394] <dbg> net_buf: net_buf_ref: (0x20003880) buf 0x2000e008 (old) ref 1 pool_id 5
[00:00:00.323,974] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_cmd():2671: fifo 0x20000504
[00:00:00.324,523] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_cmd():2671: buf 0x2000e008 fifo 0x20000504
[00:00:00.325,103] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000e008 (old) ref 2 pool_id 5
[00:00:00.330,749] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000e008 ref 3 pool_id 5 frags 0
[00:00:00.331,329] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000e014 reserve 0
[00:00:00.331,878] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000e014 len 6
[00:00:00.332,397] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000e014 len 6
[00:00:00.332,885] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000e014 len 2
[00:00:00.333,404] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000e014 len 3
[00:00:00.333,953] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000e008 ref 2 pool_id 5 frags 0
[00:00:00.334,625] <dbg> net_buf: net_buf_unref_debug: (0x20003880) buf 0x2000e008 ref 1 pool_id 5 frags 0
[00:00:00.335,205] <dbg> net_buf: net_buf_alloc_len_debug: (0x20003880) net_buf_alloc():1259: pool 0x20001814 size 70
[00:00:00.335,815] <dbg> net_buf: net_buf_alloc_len_debug: (0x20003880) allocated buf 0x2000e008
[00:00:00.336,303] <dbg> net_buf_simple: net_buf_simple_reserve: (0x20003880) buf 0x2000e014 reserve 0
[00:00:00.336,853] <dbg> net_buf_simple: net_buf_simple_add: (0x20003880) buf 0x2000e014 len 3
[00:00:00.347,442] <dbg> net_buf_simple: net_buf_simple_add: (0x20003880) buf 0x2000e014 len 32
[00:00:00.347,961] <dbg> net_buf: net_buf_ref: (0x20003880) buf 0x2000e008 (old) ref 1 pool_id 5
[00:00:00.348,510] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_cmd():2671: fifo 0x20000504
[00:00:00.349,060] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_cmd():2671: buf 0x2000e008 fifo 0x20000504
[00:00:00.349,670] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000e008 (old) ref 2 pool_id 5
[00:00:00.350,219] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000e008 ref 3 pool_id 5 frags 0
[00:00:00.350,830] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000e014 reserve 0
[00:00:00.351,348] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000e014 len 6
[00:00:00.351,867] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000e014 len 6
[00:00:00.352,386] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000e014 len 2
[00:00:00.352,905] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000e014 len 3
[00:00:00.353,454] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000e008 ref 2 pool_id 5 frags 0
[00:00:00.354,125] <dbg> net_buf: net_buf_unref_debug: (0x20003880) buf 0x2000e008 ref 1 pool_id 5 frags 0
[00:00:00.359,741] <dbg> net_buf: net_buf_alloc_len_debug: (0x20003880) net_buf_alloc():1259: pool 0x20001814 size 70
[00:00:00.360,351] <dbg> net_buf: net_buf_alloc_len_debug: (0x20003880) allocated buf 0x2000e008
[00:00:00.360,839] <dbg> net_buf_simple: net_buf_simple_reserve: (0x20003880) buf 0x2000e014 reserve 0
[00:00:00.361,389] <dbg> net_buf_simple: net_buf_simple_add: (0x20003880) buf 0x2000e014 len 3
[00:00:00.361,907] <dbg> net_buf_simple: net_buf_simple_add: (0x20003880) buf 0x2000e014 len 32
[00:00:00.362,426] <dbg> net_buf: net_buf_ref: (0x20003880) buf 0x2000e008 (old) ref 1 pool_id 5
[00:00:00.363,006] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_cmd():2671: fifo 0x20000504
[00:00:00.363,525] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_cmd():2671: buf 0x2000e008 fifo 0x20000504
[00:00:00.364,105] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000e008 (old) ref 2 pool_id 5
[00:00:00.364,685] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000e008 ref 3 pool_id 5 frags 0
[00:00:00.375,335] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000e014 reserve 0
[00:00:00.375,885] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000e014 len 6
[00:00:00.376,373] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000e014 len 6
[00:00:00.376,892] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000e014 len 2
[00:00:00.377,410] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000e014 len 3
[00:00:00.377,929] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000e008 ref 2 pool_id 5 frags 0
[00:00:00.378,601] <dbg> net_buf: net_buf_unref_debug: (0x20003880) buf 0x2000e008 ref 1 pool_id 5 frags 0
[00:00:00.379,211] <dbg> net_buf: net_buf_alloc_len_debug: (0x20003880) net_buf_alloc():1259: pool 0x20001814 size 70
[00:00:00.379,791] <dbg> net_buf: net_buf_alloc_len_debug: (0x20003880) allocated buf 0x2000e008
[00:00:00.380,310] <dbg> net_buf_simple: net_buf_simple_reserve: (0x20003880) buf 0x2000e014 reserve 0
[00:00:00.385,894] <dbg> net_buf_simple: net_buf_simple_add: (0x20003880) buf 0x2000e014 len 3
[00:00:00.386,383] <dbg> net_buf_simple: net_buf_simple_add_u8: (0x20003880) buf 0x2000e014 val 0x01
[00:00:00.386,932] <dbg> net_buf_simple: net_buf_simple_add: (0x20003880) buf 0x2000e014 len 1
[00:00:00.387,451] <dbg> net_buf: net_buf_ref: (0x20003880) buf 0x2000e008 (old) ref 1 pool_id 5
[00:00:00.388,000] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_cmd():2671: fifo 0x20000504
[00:00:00.388,519] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_cmd():2671: buf 0x2000e008 fifo 0x20000504
[00:00:00.389,129] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000e008 (old) ref 2 pool_id 5
[00:00:00.389,801] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000e008 ref 3 pool_id 5 frags 0
[00:00:00.390,380] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000e014 reserve 0
[00:00:00.395,965] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000e014 len 6
[00:00:00.396,484] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000e014 len 6
[00:00:00.396,972] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000e014 len 2
[00:00:00.397,521] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000e014 len 3
[00:00:00.398,040] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000e008 ref 2 pool_id 5 frags 0
[00:00:00.398,681] <dbg> net_buf: net_buf_unref_debug: (0x20003880) buf 0x2000e008 ref 1 pool_id 5 frags 0
[00:00:00.399,353] <inf> blx_adv: Bluetooth advertising started successfully
[00:00:10.312,896] <inf> battery_meas: Battery level: 93% (4110 mV)
[00:00:10.313,293] <inf> app_event_manager: e:battery_level_event level=93
[00:00:20.313,446] <inf> battery_meas: Battery level: 93% (4108 mV)
[00:00:20.313,842] <inf> app_event_manager: e:battery_level_event level=93
[00:00:30.313,995] <inf> battery_meas: Battery level: 93% (4110 mV)
[00:00:30.314,392] <inf> app_event_manager: e:battery_level_event level=93
[00:00:40.314,544] <inf> battery_meas: Battery level: 93% (4110 mV)
[00:00:40.314,941] <inf> app_event_manager: e:battery_level_event level=93
[00:00:41.048,309] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:41.048,919] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:41.049,407] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:41.049,926] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 33
[00:00:41.050,476] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 33
[00:00:41.051,086] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 2
[00:00:41.051,605] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1

BLE CONNECTED

[00:00:41.052,398] <inf> ble_state: Connected to 6B:34:21:17:B4:BE (random)
[00:00:41.052,856] <inf> ble_state: Identity 0 has 0 bonds
[00:00:41.053,253] <dbg> net_buf: net_buf_alloc_len_debug: (0x200027a8) net_buf_alloc():1259: pool 0x20001814 size 70
[00:00:41.053,833] <dbg> net_buf: net_buf_alloc_len_debug: (0x200027a8) allocated buf 0x2000e008
[00:00:41.054,351] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000e014 reserve 0
[00:00:41.054,901] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000e014 len 3
[00:00:41.060,455] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000e014 len 2
[00:00:41.060,974] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000e008 (old) ref 1 pool_id 5
[00:00:41.061,523] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_cmd():2671: fifo 0x20000504
[00:00:41.062,072] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_cmd():2671: buf 0x2000e008 fifo 0x20000504
[00:00:41.062,652] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000e008 (old) ref 2 pool_id 5
[00:00:41.063,323] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000e008 ref 3 pool_id 5 frags 0
[00:00:41.063,903] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000e014 reserve 0
[00:00:41.064,636] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000e014 len 6
[00:00:41.065,155] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000e014 len 6
[00:00:41.070,709] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000e014 len 2
[00:00:41.071,228] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000e014 len 4
[00:00:41.071,807] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000e008 ref 2 pool_id 5 frags 0
[00:00:41.072,479] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000e008 ref 1 pool_id 5 frags 0
[00:00:41.073,059] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:41.073,638] <inf> app_event_manager: e:ble_peer_event id=0x20002848 CONNECTED
[00:00:41.074,127] <inf> blx_adv: Bluetooth advertising stoped successfully
[00:00:41.074,584] <inf> app_event_manager: e:ble_peer_conn_params_event peer=0x20002848 min=18 max=18 lat=0 timeout=72 (updated)
[00:00:41.075,256] <inf> app_event_manager: e:power_manager_restrict_event module "blx_adv" restricts to ALIVE
[00:00:41.075,775] <dbg> power_manager: restrict_power_state: Power state SUSPENDED: BLOCKED
[00:00:41.081,268] <dbg> power_manager: restrict_power_state: Power state OFF: BLOCKED
[00:00:41.081,726] <dbg> power_manager: power_down_counter_abort: Power down timer aborted
[00:00:41.082,214] <inf> app_event_manager: e:led_event led_id:0 effect:0x48c98
[00:00:41.082,702] <inf> app_event_manager: e: wake_up_event
[00:00:41.083,038] <inf> power_manager: Wake up event consumed
[00:00:41.244,689] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:41.245,300] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:41.245,788] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:41.246,337] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 14
[00:00:41.246,826] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 14
[00:00:41.247,436] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 2
[00:00:41.247,955] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:41.248,504] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:41.544,067] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:41.544,860] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:41.545,349] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:41.545,867] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 8
[00:00:41.546,386] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 8
[00:00:41.546,966] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 2
[00:00:41.547,485] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:41.548,034] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:41.604,553] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:41.605,163] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:41.605,651] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:41.606,170] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 13
[00:00:41.606,689] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 13
[00:00:41.607,299] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 2
[00:00:41.607,818] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:41.608,337] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:41.664,337] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:41.665,069] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:41.665,557] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:41.666,076] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 15
[00:00:41.666,595] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 15
[00:00:41.667,205] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.667,724] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.668,243] <wrn> bt_l2cap: Ignoring data for unknown channel ID 0x003a
[00:00:41.668,670] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:41.694,305] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:41.695,037] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:41.695,556] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:41.696,075] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 11
[00:00:41.696,594] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 11
[00:00:41.697,174] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.697,692] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.698,211] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:41.698,730] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:41.699,279] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 2 pool_id 6
[00:00:41.699,798] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000dd38 reserve 8
[00:00:41.700,317] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:41.705,871] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 2
[00:00:41.706,390] <dbg> net_buf_simple: net_buf_simple_push: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.706,939] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 3 pool_id 6 frags 0
[00:00:41.707,519] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:41.708,099] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:41.708,618] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: fifo 0x20002880
[00:00:41.709,167] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: buf 0x2000dd2c fifo 0x20002880
[00:00:41.709,747] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000dd38 len 4
[00:00:41.710,296] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:41.715,911] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:41.754,333] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:00:41.755,065] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:00:41.755,554] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:00:41.756,072] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:00:41.756,591] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:00:41.757,080] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:00:41.757,659] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:00:41.758,270] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:41.758,880] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:41.759,399] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:41.764,984] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 15
[00:00:41.765,502] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 15
[00:00:41.766,113] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.766,662] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000a3fc
[00:00:41.767,242] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000edfc
[00:00:41.767,822] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.768,371] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:41.768,890] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:41.769,409] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.769,958] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 2 pool_id 6
[00:00:41.770,477] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000dd38 reserve 8
[00:00:41.771,026] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:41.776,580] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:41.777,069] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.777,587] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 2
[00:00:41.778,137] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.778,656] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 2
[00:00:41.779,174] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.779,693] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 2
[00:00:41.780,242] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.780,761] <dbg> net_buf_simple: net_buf_simple_push: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.781,311] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 3 pool_id 6 frags 0
[00:00:41.787,200] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:41.787,780] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:41.788,299] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: fifo 0x20002880
[00:00:41.788,848] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: buf 0x2000dd2c fifo 0x20002880
[00:00:41.789,459] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000dd38 len 4
[00:00:41.790,008] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:41.790,557] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:41.844,329] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:00:41.845,062] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:00:41.845,550] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:00:41.846,069] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:00:41.846,588] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:00:41.847,106] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:00:41.847,686] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:00:41.848,297] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:41.848,907] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:41.849,426] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:41.849,975] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 15
[00:00:41.850,494] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 15
[00:00:41.851,104] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.856,689] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000a3fc
[00:00:41.857,269] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000edfc
[00:00:41.857,879] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.858,398] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:41.858,947] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:41.859,466] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.860,015] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 2 pool_id 6
[00:00:41.860,534] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000dd38 reserve 8
[00:00:41.861,083] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:41.866,607] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:41.867,156] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.867,675] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 16
[00:00:41.868,194] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.868,713] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 16
[00:00:41.869,262] <dbg> net_buf_simple: net_buf_simple_push: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.869,812] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 3 pool_id 6 frags 0
[00:00:41.870,391] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:41.870,971] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:41.876,831] <dbg> net_buf: net_buf_alloc_len_debug: (0x20002728) create_frag():699: pool 0x200017d4 size 255
[00:00:41.877,441] <dbg> net_buf: net_buf_alloc_len_debug: (0x20002728) allocated buf 0x2000e248
[00:00:41.877,960] <dbg> net_buf_simple: net_buf_simple_reserve: (0x20002728) buf 0x2000e254 reserve 4
[00:00:41.878,479] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x20002728) buf 0x2000e254 len 27
[00:00:41.879,028] <dbg> net_buf_simple: net_buf_simple_add: (0x20002728) buf 0x2000e254 len 27
[00:00:41.879,547] <dbg> net_buf_simple: net_buf_simple_pull: (0x20002728) buf 0x2000dd38 len 27
[00:00:41.880,065] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000e254 len 4
[00:00:41.880,584] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000e248 ref 1 pool_id 4 frags 0
[00:00:41.881,164] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: fifo 0x20002880
[00:00:41.881,713] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: buf 0x2000dd2c fifo 0x20002880
[00:00:41.887,359] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000dd38 len 4
[00:00:41.887,908] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:41.888,488] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:41.904,754] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:00:41.905,487] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:00:41.906,005] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:00:41.906,524] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:00:41.907,043] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:00:41.907,531] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:00:41.908,081] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:00:41.934,326] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:00:41.935,058] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:00:41.935,546] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:00:41.936,096] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:00:41.936,584] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:00:41.937,103] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:00:41.937,652] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:00:41.938,262] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:41.938,873] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:41.939,392] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:41.939,910] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 15
[00:00:41.940,460] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 15
[00:00:41.941,070] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.946,624] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000a3fc
[00:00:41.947,204] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000edfc
[00:00:41.947,784] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.948,303] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:41.948,852] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:41.949,371] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.949,920] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 2 pool_id 6
[00:00:41.950,439] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000dd38 reserve 8
[00:00:41.950,958] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:41.956,512] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:41.957,031] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 3 pool_id 6 frags 0
[00:00:41.957,611] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 2 pool_id 6
[00:00:41.958,099] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000dd38 reserve 8
[00:00:41.958,648] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:41.959,136] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.959,655] <dbg> net_buf_simple: net_buf_simple_push: (0x200027a8) buf 0x2000dd38 len 4
[00:00:41.960,205] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 3 pool_id 6 frags 0
[00:00:41.960,784] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:41.966,705] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:41.967,224] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: fifo 0x20002880
[00:00:41.967,742] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: buf 0x2000dd2c fifo 0x20002880
[00:00:41.968,322] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000dd38 len 4
[00:00:41.968,872] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:41.969,421] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:42.024,322] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:00:42.025,054] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:00:42.025,543] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:00:42.026,092] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.026,580] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.027,099] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:00:42.027,648] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:00:42.028,259] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:42.028,869] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:42.029,357] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:42.029,907] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 15
[00:00:42.030,426] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 15
[00:00:42.031,036] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.036,621] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000a3fc
[00:00:42.037,200] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000edfc
[00:00:42.037,780] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.038,299] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.038,818] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.039,337] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.039,886] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 2 pool_id 6
[00:00:42.040,405] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000dd38 reserve 8
[00:00:42.040,924] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.041,442] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.046,997] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 2
[00:00:42.047,515] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 5
[00:00:42.048,004] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 2
[00:00:42.048,522] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 5
[00:00:42.049,041] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 2
[00:00:42.049,560] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 5
[00:00:42.050,079] <dbg> net_buf_simple: net_buf_simple_push: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.050,628] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 3 pool_id 6 frags 0
[00:00:42.051,177] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.051,788] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.057,617] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: fifo 0x20002880
[00:00:42.058,166] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: buf 0x2000dd2c fifo 0x20002880
[00:00:42.058,746] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000dd38 len 4
[00:00:42.059,265] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.059,844] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:42.114,318] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:00:42.115,051] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:00:42.115,539] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:00:42.116,088] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.116,577] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.117,095] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:00:42.117,645] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:00:42.118,255] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:42.118,865] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:42.119,384] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:42.124,969] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 13
[00:00:42.125,488] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 13
[00:00:42.126,098] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.126,678] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000a3fc
[00:00:42.127,258] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000edfc
[00:00:42.127,838] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.128,356] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.128,906] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.129,425] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 2 pool_id 6
[00:00:42.129,943] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000dd38 reserve 8
[00:00:42.135,528] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.136,047] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.136,566] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.137,054] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.137,573] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.138,061] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.138,580] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.139,099] <dbg> net_buf_simple: net_buf_simple_push: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.139,648] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 3 pool_id 6 frags 0
[00:00:42.140,197] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.140,808] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.141,326] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: fifo 0x20002880
[00:00:42.147,186] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: buf 0x2000dd2c fifo 0x20002880
[00:00:42.147,796] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000dd38 len 4
[00:00:42.148,315] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.148,895] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:42.204,315] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:00:42.205,047] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:00:42.205,566] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:00:42.206,085] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.206,573] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.207,092] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:00:42.207,641] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:00:42.208,251] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:42.208,862] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:42.209,381] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:42.209,899] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 13
[00:00:42.210,449] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 13
[00:00:42.216,094] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.216,613] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000a3fc
[00:00:42.217,193] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000edfc
[00:00:42.217,773] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.218,292] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.218,841] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.219,360] <dbg> net_buf_simple: net_buf_simple_pull: (0x200027a8) buf 0x2000dd38 len 2
[00:00:42.219,879] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 2 pool_id 6
[00:00:42.220,428] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000dd38 reserve 8
[00:00:42.225,982] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.226,531] <dbg> net_buf_simple: net_buf_simple_push: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.227,081] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 3 pool_id 6 frags 0
[00:00:42.227,661] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.228,240] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.228,759] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: fifo 0x20002880
[00:00:42.229,309] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: buf 0x2000dd2c fifo 0x20002880
[00:00:42.229,888] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000dd38 len 4
[00:00:42.230,438] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.231,018] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:42.264,343] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:00:42.265,075] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:00:42.265,563] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:00:42.266,082] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.266,601] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.267,120] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:00:42.267,669] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:00:42.268,310] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:42.268,920] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:42.269,439] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:42.269,958] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 15
[00:00:42.270,507] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 15
[00:00:42.271,118] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.276,672] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000a3fc
[00:00:42.277,282] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000edfc
[00:00:42.277,862] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.278,411] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.278,930] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.279,449] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.279,998] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 2 pool_id 6
[00:00:42.280,517] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000dd38 reserve 8
[00:00:42.281,066] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.286,621] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.287,139] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 2
[00:00:42.287,658] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 5
[00:00:42.288,177] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 2
[00:00:42.288,696] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 5
[00:00:42.289,215] <dbg> net_buf_simple: net_buf_simple_push: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.289,764] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 3 pool_id 6 frags 0
[00:00:42.290,344] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.290,954] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.296,783] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: fifo 0x20002880
[00:00:42.297,332] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: buf 0x2000dd2c fifo 0x20002880
[00:00:42.297,912] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000dd38 len 4
[00:00:42.298,461] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.299,041] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:42.384,338] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:00:42.385,070] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:00:42.385,559] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:00:42.386,077] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.386,596] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.387,115] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:00:42.387,664] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:00:42.388,305] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:42.388,916] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:42.389,434] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:42.394,989] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 15
[00:00:42.395,538] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 15
[00:00:42.396,118] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.396,667] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000a3fc
[00:00:42.397,247] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000edfc
[00:00:42.397,827] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.398,376] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.398,895] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.399,414] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.399,963] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 2 pool_id 6
[00:00:42.400,482] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000dd38 reserve 8
[00:00:42.401,031] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.401,550] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.407,104] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 2
[00:00:42.407,623] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 19
[00:00:42.408,142] <dbg> net_buf_simple: net_buf_simple_push: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.408,691] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 3 pool_id 6 frags 0
[00:00:42.409,271] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.409,881] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.410,400] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: fifo 0x20002880
[00:00:42.410,949] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: buf 0x2000dd2c fifo 0x20002880
[00:00:42.411,529] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000dd38 len 4
[00:00:42.417,388] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.417,968] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:42.474,334] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:00:42.475,067] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:00:42.475,555] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:00:42.476,104] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.476,593] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.477,111] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:00:42.477,661] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:00:42.478,271] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:42.478,881] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:42.479,400] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:42.479,919] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 15
[00:00:42.480,438] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 15
[00:00:42.481,048] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.486,633] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000a3fc
[00:00:42.487,213] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000edfc
[00:00:42.487,792] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.488,311] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.488,830] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.489,349] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.489,898] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 2 pool_id 6
[00:00:42.490,417] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000dd38 reserve 8
[00:00:42.490,966] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.496,520] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.497,009] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 2
[00:00:42.497,528] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 19
[00:00:42.498,046] <dbg> net_buf_simple: net_buf_simple_push: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.498,565] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 3 pool_id 6 frags 0
[00:00:42.499,145] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.499,725] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.500,274] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: fifo 0x20002880
[00:00:42.500,823] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: buf 0x2000dd2c fifo 0x20002880
[00:00:42.506,744] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000dd38 len 4
[00:00:42.507,263] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.507,812] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:42.564,331] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:00:42.565,063] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:00:42.565,551] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:00:42.566,101] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.566,619] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.567,138] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:00:42.567,718] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:00:42.568,328] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:42.568,939] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:42.569,458] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:42.569,976] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 13
[00:00:42.570,495] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 13
[00:00:42.571,105] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.576,690] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000a3fc
[00:00:42.577,301] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000edfc
[00:00:42.577,880] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.578,430] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.578,948] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.579,498] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 2 pool_id 6
[00:00:42.580,017] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000dd38 reserve 8
[00:00:42.580,535] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.581,054] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.586,608] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.587,127] <dbg> net_buf_simple: net_buf_simple_push: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.587,677] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 3 pool_id 6 frags 0
[00:00:42.588,226] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.588,836] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.589,355] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: fifo 0x20002880
[00:00:42.589,904] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: buf 0x2000dd2c fifo 0x20002880
[00:00:42.590,484] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000dd38 len 4
[00:00:42.591,003] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.596,923] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:42.624,328] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:00:42.625,061] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:00:42.625,549] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:00:42.626,098] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.626,586] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.627,105] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:00:42.627,655] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:00:42.628,265] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:42.628,875] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:42.629,394] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:42.629,943] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 13
[00:00:42.635,498] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 13
[00:00:42.636,108] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.636,627] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000a3fc
[00:00:42.637,207] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000edfc
[00:00:42.637,786] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.638,336] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.638,854] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.639,404] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 2 pool_id 6
[00:00:42.639,923] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000dd38 reserve 8
[00:00:42.640,472] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.646,026] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.646,545] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.647,064] <dbg> net_buf_simple: net_buf_simple_push: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.647,583] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 3 pool_id 6 frags 0
[00:00:42.648,162] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.648,742] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.649,291] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: fifo 0x20002880
[00:00:42.649,810] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: buf 0x2000dd2c fifo 0x20002880
[00:00:42.650,421] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000dd38 len 4
[00:00:42.656,280] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.656,860] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:42.714,324] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:00:42.715,057] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:00:42.715,576] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:00:42.716,094] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.716,613] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.717,132] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:00:42.717,712] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:00:42.718,322] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:42.718,933] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:42.719,421] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:42.719,970] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 13
[00:00:42.720,489] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 13
[00:00:42.726,135] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.726,654] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000a3fc
[00:00:42.727,264] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000edfc
[00:00:42.727,874] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.728,393] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.728,912] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.729,431] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 2 pool_id 6
[00:00:42.729,949] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000dd38 reserve 8
[00:00:42.730,468] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.736,053] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.736,541] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.737,060] <dbg> net_buf_simple: net_buf_simple_push: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.737,609] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 3 pool_id 6 frags 0
[00:00:42.738,189] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.738,769] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.739,288] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: fifo 0x20002880
[00:00:42.739,807] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: buf 0x2000dd2c fifo 0x20002880
[00:00:42.740,417] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000dd38 len 4
[00:00:42.740,936] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.746,856] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:42.774,322] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:00:42.775,054] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:00:42.775,573] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:00:42.776,092] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.776,580] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.777,099] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:00:42.777,648] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:00:42.778,259] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:42.778,869] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:42.779,388] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:42.779,937] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 13
[00:00:42.780,456] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 13
[00:00:42.786,102] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.786,651] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000a3fc
[00:00:42.787,261] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000edfc
[00:00:42.787,841] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.788,360] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.788,909] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.789,428] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 2 pool_id 6
[00:00:42.789,947] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000dd38 reserve 8
[00:00:42.790,496] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.796,051] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:42.796,569] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.797,088] <dbg> net_buf_simple: net_buf_simple_push: (0x200027a8) buf 0x2000dd38 len 4
[00:00:42.797,637] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 3 pool_id 6 frags 0
[00:00:42.798,187] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.798,797] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:42.799,316] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: fifo 0x20002880
[00:00:42.799,865] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: buf 0x2000dd2c fifo 0x20002880
[00:00:42.800,476] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000dd38 len 4
[00:00:42.806,335] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:42.806,915] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:42.864,288] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:00:42.865,020] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:00:42.865,509] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:00:42.866,058] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.866,577] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:00:42.867,095] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:00:42.867,645] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:00:42.868,316] <dbg> net_buf: net_buf_get_debug: (0x20003880) get_first_buf_matching_chan():376: fifo 0x2000a3fc
[00:00:42.868,896] <dbg> net_buf: net_buf_get_debug: (0x20003880) get_first_buf_matching_chan():376: fifo 0x2000edfc
[00:00:44.694,366] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:44.695,098] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:44.695,587] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:44.696,136] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 15
[00:00:44.696,655] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 15
[00:00:44.697,265] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:44.697,814] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:44.698,333] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:44.698,883] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:44.699,401] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:44.699,920] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 2 pool_id 6
[00:00:44.705,505] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000dd38 reserve 8
[00:00:44.706,024] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:44.706,542] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:00:44.707,061] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 2
[00:00:44.707,580] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 6
[00:00:44.708,099] <dbg> net_buf_simple: net_buf_simple_push: (0x200027a8) buf 0x2000dd38 len 4
[00:00:44.708,648] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 3 pool_id 6 frags 0
[00:00:44.709,228] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:44.709,838] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:00:44.710,357] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: fifo 0x20002880
[00:00:44.710,876] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: buf 0x2000dd2c fifo 0x20002880
[00:00:44.711,486] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000dd38 len 4
[00:00:44.717,071] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:00:44.717,620] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:44.754,302] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:00:44.755,035] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:00:44.755,554] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:00:44.756,072] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:00:44.756,591] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:00:44.757,080] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:00:44.757,659] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:00:44.758,300] <dbg> net_buf: net_buf_get_debug: (0x20003880) get_first_buf_matching_chan():376: fifo 0x2000a3fc
[00:00:44.758,880] <dbg> net_buf: net_buf_get_debug: (0x20003880) get_first_buf_matching_chan():376: fifo 0x2000edfc
[00:00:46.052,246] <dbg> net_buf: net_buf_alloc_len_debug: (0x20003880) bt_l2cap_create_pdu_timeout():645: pool 0x200016d4 size 260
[00:00:46.052,886] <dbg> net_buf: net_buf_alloc_len_debug: (0x20003880) allocated buf 0x2000ecb0
[00:00:46.053,405] <dbg> net_buf_simple: net_buf_simple_reserve: (0x20003880) buf 0x2000ecbc reserve 8
[00:00:46.053,924] <dbg> net_buf_simple: net_buf_simple_add: (0x20003880) buf 0x2000ecbc len 4
[00:00:46.054,412] <dbg> net_buf_simple: net_buf_simple_add: (0x20003880) buf 0x2000ecbc len 8
[00:00:46.054,901] <dbg> net_buf_simple: net_buf_simple_push: (0x20003880) buf 0x2000ecbc len 4
[00:00:46.055,480] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000ecb0 (old) ref 1 pool_id 0
[00:00:46.055,999] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: fifo 0x20002880
[00:00:46.056,549] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: buf 0x2000ecb0 fifo 0x20002880
[00:00:46.057,128] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000ecbc len 4
[00:00:46.062,713] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000ecb0 ref 2 pool_id 0 frags 0
[00:00:46.063,293] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000ecb0 ref 1 pool_id 0 frags 0
[00:00:46.134,368] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:00:46.135,131] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:00:46.135,620] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:00:46.136,169] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:00:46.136,688] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:00:46.137,207] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:00:46.137,756] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:00:46.164,367] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:46.165,100] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:46.165,618] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:46.166,137] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 14
[00:00:46.166,656] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 14
[00:00:46.167,266] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:46.167,785] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:46.168,334] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:00:46.168,853] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:46.344,635] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:00:46.345,214] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:00:46.345,733] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:00:46.346,252] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 12
[00:00:46.346,771] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 12
[00:00:46.347,381] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 2
[00:00:46.347,900] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:00:46.348,480] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:00:46.349,121] <inf> app_event_manager: e:ble_peer_conn_params_event peer=0x20002848 min=24 max=24 lat=0 timeout=42 (updated)

SMP DFU START

[00:00:46.349,121] <inf> app_event_manager: e:ble_peer_conn_params_event peer=0x20002848 min=24 max=24 lat=0 timeout=42 (updated)
[00:00:50.315,093] <inf> battery_meas: Battery level: 93% (4108 mV)
[00:00:50.315,490] <inf> app_event_manager: e:battery_level_event level=93
[00:01:00.315,643] <inf> battery_meas: Battery level: 93% (4109 mV)
[00:01:00.316,040] <inf> app_event_manager: e:battery_level_event level=93
[00:01:10.316,192] <inf> battery_meas: Battery level: 93% (4109 mV)
[00:01:10.316,589] <inf> app_event_manager: e:battery_level_event level=93
[00:01:20.316,741] <inf> battery_meas: Battery level: 93% (4112 mV)
[00:01:20.317,169] <inf> app_event_manager: e:battery_level_event level=93
[00:01:30.317,291] <inf> battery_meas: Battery level: 93% (4108 mV)
[00:01:30.317,687] <inf> app_event_manager: e:battery_level_event level=93
[00:01:35.947,845] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:01:35.948,577] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:01:35.949,096] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:01:35.949,615] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 13
[00:01:35.950,134] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 13
[00:01:35.950,714] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:01:35.951,263] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:01:35.951,782] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:01:35.952,301] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:01:35.952,819] <dbg> net_buf_simple: net_buf_simple_pull: (0x200027a8) buf 0x2000dd38 len 2
[00:01:35.953,369] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 2 pool_id 6
[00:01:35.953,887] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000dd38 reserve 8
[00:01:35.959,472] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:01:35.959,991] <dbg> net_buf_simple: net_buf_simple_push: (0x200027a8) buf 0x2000dd38 len 4
[00:01:35.960,510] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 3 pool_id 6 frags 0
[00:01:35.961,090] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:01:35.961,669] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:01:35.962,188] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: fifo 0x20002880
[00:01:35.962,738] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: buf 0x2000dd2c fifo 0x20002880
[00:01:35.963,348] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000dd38 len 4
[00:01:35.963,867] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:01:35.969,482] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:01:36.037,872] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:01:36.038,604] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:01:36.039,123] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:01:36.039,642] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:01:36.040,161] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:01:36.040,679] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:01:36.041,229] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:01:36.041,870] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:01:36.042,480] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:01:36.042,999] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:01:36.043,518] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 20
[00:01:36.049,072] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 20
[00:01:36.049,682] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:01:36.050,231] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000a3fc
[00:01:36.050,811] <dbg> net_buf: net_buf_get_debug: (0x200027a8) get_first_buf_matching_chan():376: fifo 0x2000edfc
[00:01:36.051,422] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:01:36.051,940] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:01:36.052,459] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:01:36.052,978] <dbg> net_buf_simple: net_buf_simple_pull: (0x200027a8) buf 0x2000dd38 len 2
[00:01:36.053,527] <dbg> net_buf: net_buf_alloc_len_debug: (0x200027a8) net_buf_alloc():1259: pool 0x200018d4 size 384
[00:01:36.059,173] <dbg> net_buf: net_buf_alloc_len_debug: (0x200027a8) allocated buf 0x2000d284
[00:01:36.059,661] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200027a8) buf 0x2000d290 len 9
[00:01:36.060,180] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000d290 len 9
[00:01:36.060,729] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:01:36.061,309] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:01:40.317,871] <inf> battery_meas: Battery level: 93% (4110 mV)
[00:01:40.318,267] <inf> app_event_manager: e:battery_level_event level=93
[00:01:46.028,106] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:01:46.028,839] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:01:46.029,357] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:01:46.029,876] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 20
[00:01:46.030,395] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 20
[00:01:46.031,005] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:01:46.031,524] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:01:46.032,043] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:01:46.032,592] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:01:46.033,111] <dbg> net_buf_simple: net_buf_simple_pull: (0x200027a8) buf 0x2000dd38 len 2
[00:01:46.033,660] <dbg> net_buf: net_buf_alloc_len_debug: (0x200027a8) net_buf_alloc():1259: pool 0x200018d4 size 384
[00:01:46.034,271] <dbg> net_buf: net_buf_alloc_len_debug: (0x200027a8) allocated buf 0x2000d2a4
[00:01:46.034,759] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200027a8) buf 0x2000d2b0 len 9
[00:01:46.040,344] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000d2b0 len 9
[00:01:46.040,863] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:01:46.041,442] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:01:50.318,420] <inf> battery_meas: Battery level: 93% (4107 mV)
[00:01:50.318,817] <inf> app_event_manager: e:battery_level_event level=93
[00:01:56.018,371] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:01:56.019,104] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:01:56.019,622] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:01:56.020,141] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 20
[00:01:56.020,660] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 20
[00:01:56.021,240] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:01:56.021,789] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:01:56.022,308] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:01:56.022,827] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:01:56.023,376] <dbg> net_buf_simple: net_buf_simple_pull: (0x200027a8) buf 0x2000dd38 len 2
[00:01:56.023,895] <dbg> net_buf: net_buf_alloc_len_debug: (0x200027a8) net_buf_alloc():1259: pool 0x200018d4 size 384
[00:01:56.029,541] <dbg> net_buf: net_buf_alloc_len_debug: (0x200027a8) allocated buf 0x2000d2c4
[00:01:56.030,059] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200027a8) buf 0x2000d2d0 len 9
[00:01:56.030,578] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000d2d0 len 9
[00:01:56.031,097] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:01:56.031,677] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:02:00.318,969] <inf> battery_meas: Battery level: 93% (4107 mV)
[00:02:00.319,366] <inf> app_event_manager: e:battery_level_event level=93
[00:02:06.053,680] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:02:06.054,412] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:02:06.054,901] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:02:06.055,419] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 31
[00:02:06.055,938] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 31
[00:02:06.056,518] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:02:06.057,037] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:02:06.057,586] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:02:06.058,105] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:02:06.058,624] <dbg> net_buf_simple: net_buf_simple_pull: (0x200027a8) buf 0x2000dd38 len 2
[00:02:06.059,173] <dbg> net_buf: net_buf_alloc_len_debug: (0x200027a8) net_buf_alloc():1259: pool 0x200018d4 size 384
[00:02:06.059,783] <dbg> net_buf: net_buf_alloc_len_debug: (0x200027a8) allocated buf 0x2000d2e4
[00:02:06.060,272] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200027a8) buf 0x2000d2f0 len 20
[00:02:06.065,856] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000d2f0 len 20
[00:02:06.066,375] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:02:06.066,925] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:02:10.319,519] <inf> battery_meas: Battery level: 93% (4112 mV)
[00:02:10.319,915] <inf> app_event_manager: e:battery_level_event level=93
[00:02:16.043,884] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:02:16.044,616] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:02:16.045,135] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:02:16.045,654] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 31
[00:02:16.046,173] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 31
[00:02:16.046,752] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:02:16.047,271] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:02:16.047,790] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:02:16.048,339] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:02:16.048,858] <dbg> net_buf_simple: net_buf_simple_pull: (0x200027a8) buf 0x2000dd38 len 2
[00:02:16.049,407] <dbg> net_buf: net_buf_alloc_len_debug: (0x200027a8) net_buf_alloc():1259: pool 0x200018d4 size 384
[00:02:16.050,018] <err> net_buf: net_buf_alloc():1259: Failed to get free buffer                                       <====== ERROR
[00:02:16.050,445] <dbg> mcumgr_smp: smp_bt_chr_write: failed net_buf alloc for SMP packet
[00:02:16.050,903] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:02:16.056,518] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:02:20.320,068] <inf> battery_meas: Battery level: 93% (4108 mV)
[00:02:20.320,465] <inf> app_event_manager: e:battery_level_event level=93
[00:02:26.079,132] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:02:26.079,864] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:02:26.080,383] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:02:26.080,902] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 31
[00:02:26.081,420] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 31
[00:02:26.082,000] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:02:26.082,519] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:02:26.083,038] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:02:26.083,557] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:02:26.084,075] <dbg> net_buf_simple: net_buf_simple_pull: (0x200027a8) buf 0x2000dd38 len 2
[00:02:26.084,594] <dbg> net_buf: net_buf_alloc_len_debug: (0x200027a8) net_buf_alloc():1259: pool 0x200018d4 size 384
[00:02:26.085,205] <err> net_buf: net_buf_alloc():1259: Failed to get free buffer                                       <====== ERROR
[00:02:26.085,662] <dbg> mcumgr_smp: smp_bt_chr_write: failed net_buf alloc for SMP packet
[00:02:26.086,120] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:02:26.091,735] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:02:30.320,617] <inf> battery_meas: Battery level: 93% (4109 mV)
[00:02:30.321,014] <inf> app_event_manager: e:battery_level_event level=93
[00:02:36.069,366] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:02:36.070,098] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:02:36.070,587] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:02:36.071,136] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 20
[00:02:36.071,655] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 20
[00:02:36.072,265] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:02:36.072,784] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:02:36.073,333] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:02:36.073,852] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:02:36.074,371] <dbg> net_buf_simple: net_buf_simple_pull: (0x200027a8) buf 0x2000dd38 len 2
[00:02:36.074,920] <dbg> net_buf: net_buf_alloc_len_debug: (0x200027a8) net_buf_alloc():1259: pool 0x200018d4 size 384
[00:02:36.075,500] <err> net_buf: net_buf_alloc():1259: Failed to get free buffer                                       <====== ERROR
[00:02:36.075,958] <dbg> mcumgr_smp: smp_bt_chr_write: failed net_buf alloc for SMP packet
[00:02:36.086,517] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:02:36.087,066] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:02:40.321,166] <inf> battery_meas: Battery level: 93% (4110 mV)
[00:02:40.321,563] <inf> app_event_manager: e:battery_level_event level=93
[00:02:50.321,716] <inf> battery_meas: Battery level: 93% (4112 mV)
[00:02:50.322,113] <inf> app_event_manager: e:battery_level_event level=93
[00:03:00.322,265] <inf> battery_meas: Battery level: 93% (4110 mV)
[00:03:00.322,662] <inf> app_event_manager: e:battery_level_event level=93
[00:03:10.322,814] <inf> battery_meas: Battery level: 93% (4109 mV)
[00:03:10.323,211] <inf> app_event_manager: e:battery_level_event level=93
[00:03:20.323,364] <inf> battery_meas: Battery level: 93% (4107 mV)
[00:03:20.323,760] <inf> app_event_manager: e:battery_level_event level=93
[00:03:26.065,948] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:03:26.066,680] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:03:26.067,199] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:03:26.067,718] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 20
[00:03:26.068,237] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 20
[00:03:26.068,817] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:03:26.069,335] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:03:26.069,854] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:03:26.070,373] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:03:26.070,892] <dbg> net_buf_simple: net_buf_simple_pull: (0x200027a8) buf 0x2000dd38 len 2
[00:03:26.071,441] <dbg> net_buf: net_buf_alloc_len_debug: (0x200027a8) net_buf_alloc():1259: pool 0x200018d4 size 384
[00:03:26.072,052] <err> net_buf: net_buf_alloc():1259: Failed to get free buffer                                           <====== ERROR
[00:03:26.072,479] <dbg> mcumgr_smp: smp_bt_chr_write: failed net_buf alloc for SMP packet
[00:03:26.078,002] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:03:26.078,552] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:03:30.323,913] <inf> battery_meas: Battery level: 93% (4108 mV)
[00:03:30.324,310] <inf> app_event_manager: e:battery_level_event level=93
[00:03:40.324,462] <inf> battery_meas: Battery level: 93% (4109 mV)
[00:03:40.324,859] <inf> app_event_manager: e:battery_level_event level=93
[00:03:50.325,012] <inf> battery_meas: Battery level: 93% (4112 mV)
[00:03:50.325,408] <inf> app_event_manager: e:battery_level_event level=93
[00:04:00.325,561] <inf> battery_meas: Battery level: 93% (4110 mV)
[00:04:00.325,958] <inf> app_event_manager: e:battery_level_event level=93
[00:04:10.326,110] <inf> battery_meas: Battery level: 93% (4107 mV)
[00:04:10.326,507] <inf> app_event_manager: e:battery_level_event level=93
[00:04:16.107,330] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:04:16.108,062] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:04:16.108,581] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:04:16.109,100] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 20
[00:04:16.109,619] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 20
[00:04:16.110,229] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:04:16.110,748] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:04:16.111,297] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:04:16.111,816] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:04:16.112,365] <dbg> net_buf_simple: net_buf_simple_pull: (0x200027a8) buf 0x2000dd38 len 2
[00:04:16.112,884] <dbg> net_buf: net_buf_alloc_len_debug: (0x200027a8) net_buf_alloc():1259: pool 0x200018d4 size 384
[00:04:16.113,494] <err> net_buf: net_buf_alloc():1259: Failed to get free buffer                                           <====== ERROR
[00:04:16.113,952] <dbg> mcumgr_smp: smp_bt_chr_write: failed net_buf alloc for SMP packet
[00:04:16.119,445] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:04:16.120,025] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:04:20.326,660] <inf> battery_meas: Battery level: 93% (4112 mV)
[00:04:20.327,056] <inf> app_event_manager: e:battery_level_event level=93
[00:04:30.327,209] <inf> battery_meas: Battery level: 93% (4107 mV)
[00:04:30.327,911] <inf> app_event_manager: e:battery_level_event level=93
[00:04:40.327,911] <inf> battery_meas: Battery level: 93% (4110 mV)
[00:04:40.328,308] <inf> app_event_manager: e:battery_level_event level=93
[00:04:50.328,460] <inf> battery_meas: Battery level: 93% (4108 mV)
[00:04:50.328,857] <inf> app_event_manager: e:battery_level_event level=93
[00:05:00.329,010] <inf> battery_meas: Battery level: 93% (4109 mV)
[00:05:00.329,406] <inf> app_event_manager: e:battery_level_event level=93
[00:05:06.103,820] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001854 size 260
[00:05:06.104,583] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000dd2c
[00:05:06.105,102] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000dd38 reserve 0
[00:05:06.105,621] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000dd38 len 13
[00:05:06.106,140] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000dd38 len 13
[00:05:06.106,750] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:05:06.107,299] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 4
[00:05:06.107,818] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200027a8) buf 0x2000dd38 len 1
[00:05:06.108,337] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:05:06.108,856] <dbg> net_buf_simple: net_buf_simple_pull: (0x200027a8) buf 0x2000dd38 len 2
[00:05:06.109,375] <dbg> net_buf: net_buf_ref: (0x200027a8) buf 0x2000dd2c (old) ref 2 pool_id 6
[00:05:06.109,893] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200027a8) buf 0x2000dd38 reserve 8
[00:05:06.115,478] <dbg> net_buf_simple: net_buf_simple_add: (0x200027a8) buf 0x2000dd38 len 1
[00:05:06.115,997] <dbg> net_buf_simple: net_buf_simple_push: (0x200027a8) buf 0x2000dd38 len 4
[00:05:06.116,546] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 3 pool_id 6 frags 0
[00:05:06.117,126] <dbg> net_buf: net_buf_unref_debug: (0x200027a8) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:05:06.117,706] <dbg> net_buf: net_buf_ref: (0x20002728) buf 0x2000dd2c (old) ref 1 pool_id 6
[00:05:06.118,225] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: fifo 0x20002880
[00:05:06.118,774] <dbg> net_buf: net_buf_get_debug: (0x20002728) send_frag():680: buf 0x2000dd2c fifo 0x20002880
[00:05:06.119,354] <dbg> net_buf_simple: net_buf_simple_push: (0x20002728) buf 0x2000dd38 len 4
[00:05:06.119,903] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 2 pool_id 6 frags 0
[00:05:06.125,518] <dbg> net_buf: net_buf_unref_debug: (0x20002728) buf 0x2000dd2c ref 1 pool_id 6 frags 0
[00:05:06.193,786] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) net_buf_alloc():1259: pool 0x20001894 size 7
[00:05:06.194,519] <dbg> net_buf: net_buf_alloc_len_debug: (0x200036e0) allocated buf 0x2000df5c
[00:05:06.195,007] <dbg> net_buf_simple: net_buf_simple_reserve: (0x200036e0) buf 0x2000df68 reserve 0
[00:05:06.195,526] <dbg> net_buf_simple: net_buf_simple_add_mem: (0x200036e0) buf 0x2000df68 len 7
[00:05:06.196,044] <dbg> net_buf_simple: net_buf_simple_add: (0x200036e0) buf 0x2000df68 len 7
[00:05:06.196,533] <dbg> net_buf_simple: net_buf_simple_pull_mem: (0x200036e0) buf 0x2000df68 len 2
[00:05:06.197,113] <dbg> net_buf: net_buf_unref_debug: (0x200036e0) buf 0x2000df5c ref 1 pool_id 7 frags 0
[00:05:06.197,753] <dbg> net_buf: net_buf_get_debug: (0x20003880) get_first_buf_matching_chan():376: fifo 0x2000a3fc
[00:05:06.198,333] <dbg> net_buf: net_buf_get_debug: (0x20003880) get_first_buf_matching_chan():376: fifo 0x2000edfc

CONFIG_NCS_SAMPLES_DEFAULTS=y
CONFIG_UART_CONSOLE=n
CONFIG_RTT_CONSOLE=y
CONFIG_SHELL_BACKEND_RTT=y
CONFIG_USE_SEGGER_RTT=y
CONFIG_CONSOLE=y
CONFIG_SERIAL=y

CONFIG_BOOTLOADER_MCUBOOT=y

# Bluetooth
CONFIG_BT=y
CONFIG_BT_PERIPHERAL=y
CONFIG_BT_DEVICE_NAME_FORMAT="BXL-%.4s"
CONFIG_BT_DEVICE_APPEARANCE=1156
CONFIG_BT_USER_DATA_LEN_UPDATE=y
CONFIG_BT_USER_PHY_UPDATE=y
CONFIG_BT_L2CAP_TX_MTU=252
CONFIG_BT_BUF_ACL_RX_SIZE=256
CONFIG_BT_ATT_PREPARE_COUNT=2
CONFIG_BT_BUF_ACL_TX_COUNT=10
CONFIG_BT_BUF_ACL_TX_SIZE=251
CONFIG_BT_GAP_AUTO_UPDATE_CONN_PARAMS=y
# CONFIG_BT_PERIPHERAL_PREF_MIN_INT
# CONFIG_BT_PERIPHERAL_PREF_MAX_INT
# CONFIG_BT_PERIPHERAL_PREF_LATENCY
# CONFIG_BT_PERIPHERAL_PREF_TIMEOUT=100

# Logging
CONFIG_LOG=y
CONFIG_LOG_MODE_IMMEDIATE=y
CONFIG_LOG_DEFAULT_LEVEL=3
CONFIG_LOG_MAX_LEVEL=4
CONFIG_CBPRINTF_FP_SUPPORT=y
CONFIG_ASSERT=y

# Hardware ID
CONFIG_HW_ID_LIBRARY=y
CONFIG_HW_ID_LIBRARY_SOURCE_DEVICE_ID=y

# Sensors
CONFIG_SPI=y
CONFIG_SENSOR=y
CONFIG_BMI160=y
CONFIG_BMI160_TRIGGER_NONE=y
CONFIG_BMI160_ACCEL_RANGE_8G=y
CONFIG_BMI160_ACCEL_ODR_200=y
CONFIG_BMI160_ACCEL_PMU_NORMAL=y

CONFIG_BLX_IMU_ARRAY_SIZE=1
CONFIG_BLX_IMU_ARRAY_HIT_HIGH_THRESHOLD=3
CONFIG_BLX_IMU_ARRAY_HIT_LOW_THRESHOLD=3
CONFIG_BLX_IMU_ARRAY_HIT_TIME_WINDOW_SIZE=5
CONFIG_BLX_IMU_ARRAY_SEGGER_RTT_ENABLE=n


# BT DFU
CONFIG_MCUMGR=y
CONFIG_MCUMGR_TRANSPORT_BT=y
CONFIG_MCUMGR_GRP_IMG=y
CONFIG_MCUMGR_GRP_OS=y
CONFIG_MCUMGR_TRANSPORT_BT_AUTHEN=n
CONFIG_IMG_ERASE_PROGRESSIVELY=y

CONFIG_MCUMGR_MGMT_NOTIFICATION_HOOKS=y
CONFIG_MCUMGR_GRP_IMG_UPLOAD_CHECK_HOOK=y
CONFIG_MCUMGR_LOG_LEVEL_DBG=y
CONFIG_MCUMGR_TRANSPORT_LOG_LEVEL_DBG=y
CONFIG_CAF_BLE_SMP=y
CONFIG_CAF_BLE_SMP_LOG_LEVEL_DBG=y
CONFIG_CAF_BLE_SMP_TRANSFER_EVENTS=y

CONFIG_NET_BUF_LOG=y
CONFIG_NET_BUF_LOG_LEVEL_DBG=y
CONFIG_NET_BUF_SIMPLE_LOG=y
CONFIG_NET_BUF_POOL_USAGE=y
CONFIG_NET_BUF_VARIABLE_DATA_SIZE=y
CONFIG_NETWORKING=y
CONFIG_NET_BUF_DATA_POOL_SIZE=5000
CONFIG_PM_DEVICE=y


CONFIG_NET_BUF=y
CONFIG_ZCBOR=y
CONFIG_CRC=y
CONFIG_IMG_MANAGER=y
CONFIG_STREAM_FLASH=y
CONFIG_FLASH_MAP=y
CONFIG_FLASH=y

CONFIG_APP_EVENT_MANAGER=y
CONFIG_CAF=y
CONFIG_HEAP_MEM_POOL_SIZE=256

CONFIG_CAF_POWER_MANAGER=y
CONFIG_CAF_POWER_MANAGER_EVENTS=y
CONFIG_CAF_POWER_MANAGER_STAY_ON=y
CONFIG_CAF_POWER_MANAGER_TIMEOUT=20
CONFIG_CAF_POWER_MANAGER_ERROR_TIMEOUT=60
CONFIG_CAF_POWER_MANAGER_LOG_LEVEL_DBG=y

CONFIG_ADC=y
CONFIG_ADC_ASYNC=y
CONFIG_ADC_NRFX_SAADC=y
CONFIG_DESKTOP_BATTERY_MEAS=y
CONFIG_DESKTOP_BATTERY_MEAS_HAS_ENABLE_PIN=n
CONFIG_DESKTOP_BATTERY_MEAS_POLL_INTERVAL_MS=10000
CONFIG_DESKTOP_BATTERY_MEAS_HAS_VOLTAGE_DIVIDER=y
CONFIG_DESKTOP_BATTERY_MEAS_VOLTAGE_DIVIDER_LOWER=100
CONFIG_DESKTOP_BATTERY_MEAS_VOLTAGE_DIVIDER_UPPER=100
CONFIG_DESKTOP_BATTERY_MEAS_LOG_LEVEL_DBG=y

CONFIG_BT=y
CONFIG_BT_SMP=y
CONFIG_CAF_BLE_STATE=y

CONFIG_CAF_BLE_STATE_PM=n


CONFIG_DESKTOP_BAS_ENABLE=y


CONFIG_BLE_BXL_ADV_ENABLE=y
CONFIG_LOG_HIT_EVENT=y

CONFIG_CAF_LEDS=y
CONFIG_CAF_BLE_COMMON_EVENTS=y
CONFIG_LED=y
CONFIG_LED_PWM=y
CONFIG_CAF_LEDS_PWM=y
CONFIG_PWM=y

# Prevent BLE connection drop
CONFIG_CLOCK_CONTROL_NRF_K32SRC_500PPM=y
CONFIG_CLOCK_CONTROL_NRF=y

Parents
  • Hi,

    I am not immediately able to see from the log where the buffer is attemted to be allocated, and also not why (seemingly) buffers are not freed, so that you run out of buffers. Can you elaboreate how this happens and perhaps how to reproduce?

  • Hi Einar,

    I tried to create a minimal reproducible exemple, but I couldn't get the DFU over SMP to work. I kept getting the same kind of error as in my app when in an error state. This would then indicate that something is wrong in my configuration ? Here is the MRE:

    prj.conf

     

    CONFIG_UART_CONSOLE=n
    CONFIG_RTT_CONSOLE=y
    CONFIG_USE_SEGGER_RTT=y
    
    CONFIG_NCS_SAMPLES_DEFAULTS=y
    CONFIG_NCS_SAMPLE_MCUMGR_BT_OTA_DFU=y
    CONFIG_BOOTLOADER_MCUBOOT=y
    
    CONFIG_BT=y
    CONFIG_BT_PERIPHERAL=y
    CONFIG_BT_DEVICE_NAME="HelloWorld"
    
    CONFIG_LOG=y
    CONFIG_LOG_MODE_IMMEDIATE=y
    CONFIG_CBPRINTF_FP_SUPPORT=y
    CONFIG_ASSERT=y
    
    CONFIG_APP_EVENT_MANAGER=y
    CONFIG_CAF=y
    CONFIG_HEAP_MEM_POOL_SIZE=4096
    
    CONFIG_BT_SMP=y
    CONFIG_CAF_BLE_STATE=y
    CONFIG_CAF_BLE_ADV=y
    
    CONFIG_PM_POLICY_CUSTOM=y
    CONFIG_CAF_POWER_MANAGER=y
    CONFIG_CAF_POWER_MANAGER_EVENTS=y
    CONFIG_CAF_POWER_MANAGER_STAY_ON=y
    CONFIG_CAF_POWER_MANAGER_TIMEOUT=120
    
    CONFIG_NET_BUF_LOG=y
    

    main.c

    #include <zephyr/logging/log.h>
    LOG_MODULE_REGISTER(main);
    
    #include <app_event_manager.h>
    #define MODULE main
    #include <caf/events/module_state_event.h>
    
    int main(void)
    {
            if (app_event_manager_init())
            {
                    LOG_ERR("Application Event Manager not initialized");
            }
            else
            {
                    module_set_state(MODULE_STATE_READY);
            }
    
            for (;;)
            {
            }
    }

    I get the same behaviour than previously stated; when connected with the nrf Connect App, I can start a dfu update but it get stuck for a while at "Requesting McuMgr parameters..." then at "Requesting bootloader info..." and finally at "Validating", all while the progress stays at 0.00%. In the RTT logs, I keep seeing multiple net_buf_alloc():1258: Failed to get free buffer.

  • Hi,

    The CAF modules are not much used outside of nRF Dekstop so I must admitt I don't ahve much experience with it, but I am having some problems understanding the big picture. This only happens when the state is MODULE_STATE_ERROR right? And this indicate a critical (non-recoverable) error, which also puts the power maner in POWER_STATE_ERROR. Is it expected and desierable to do DFU in that case and not just reset?

    Also, out of curiosity, may I ask why you are using CAF?

  • Hi,

    After conducting further testing, I discovered that removing the for (;;) {} loop in the main function resolves the issue. This loop was responsible for sampling multiple sensors. When encountering a MODULE_STATE_ERROR, the sampling was bypassed, resulting in what seemed like an empty while (true) loop. My understanding is that this perpetually empty loop failed to yield back the system thread, potentially leading to allocation issues, although I'm not entirely certain about the underlying behavior.

    Regarding the specific use case, I aim for testers to update the firmware through our mobile application even after a critical error, particularly if the error stemmed from a misconfiguration (e.g., a sensor receiving the wrong address). Since the SWD port is not easily accessible within the testing product, having to dismantle everything to reach the SWD port due to a typo in firmware flashed during a previous DFU would be cumbersome.

    I'm somewhat surprised to learn that CAF is not commonly used in consumer applications. To avoid reinventing the wheel, we are leveraging the CAF BLE state module, CAF LED module, and CAF power management module, along with a slightly modified version of the Desktop Battery measurement. I'm curious whether the common approach is to rewrite these functionalities from scratch or if consumer applications typically do not utilize the Application Event Manager framework. I'm relatively new to the nRF Connect ecosystem; hence, my main references were the nRF Desktop sample application, although it may not necessarily exemplify the preferred approach.

  • Hi,

    That makes sense. If you have an empty while loop without any k_sleep() or similar it will consume all available CPU resources, blocking any lower priority threads.

    CAF is used by several of our reference applications, and can definetly be usefull. But compared to with some other SDKs (includign our older nRF5 SDK), you generally do not need that much boiler plate code with Zephyr, which is perhaps why such libraries are not that much used. But to be clear, there is nothing wrong with using CAF modules if those solves an issue for you and it  handles of tasks you would otherwise have to implement handling for yourself.

Reply
  • Hi,

    That makes sense. If you have an empty while loop without any k_sleep() or similar it will consume all available CPU resources, blocking any lower priority threads.

    CAF is used by several of our reference applications, and can definetly be usefull. But compared to with some other SDKs (includign our older nRF5 SDK), you generally do not need that much boiler plate code with Zephyr, which is perhaps why such libraries are not that much used. But to be clear, there is nothing wrong with using CAF modules if those solves an issue for you and it  handles of tasks you would otherwise have to implement handling for yourself.

Children
No Data
Related