BLE: Re-connection after bonding not working #86613

VinFar · 2025-03-04T11:01:33Z

VinFar
Mar 4, 2025

Hi,

I have problem with re-connecting a paired BLE connection on zephyr.

I have two different HW boards which are behaiving differently with the same zephyr sample. I am using android and iOS phones with the nRF Connect mobile app.
I am building the bluetooth/peripheral sample and connecting, bonding, disconnecting and re-connection to the device using different phones (android and iOS).

When I build the sample for a ESP-WROVER board everything works as expected: When I connect to my device using iOS, I get the pairing prompt when I want to read a protected characteristic. Then I can pair with the device and read the protected characteristic. Then I can disconnect and re-connect to the device and read the protected characteristic again, without any problems. This is what I expected and want.

When I use my costum hardware (i.MX RT MCU + IW416 BT/Wifi IC) the process is as follows:

I can connect to the device, read a protected characteristic (after pairing) and disconnect. When I want to re-connect, this is not working. On iOS I do not get any error inside the nRF connect app.

This process is not the same for android: When I do the same using an android phone (Android 14 and 11 tested) everything works. I can connect, pair, read, disconnect and re-connect without any problems on both HW boards.

Things that I noticed that might be important:

For android the "sc_data" does get created and stored at the time of bonding on both HW boards. The "sc_data" for iOS does not get stored at the time of bonding. On my ESP32 the "sc_data" just gets created and stored at the time of the re-connect.
- The "sc_data" on my custom HW does not get stored at the time of the re-connect when using iOS.
When I leave the nRF connect app open, it automatically tries to re-connect to the choosen device every 30 seconds. Sometimes after a few attempts the "sc_data" gets stored and the re-connect works. But only one time. If disconnect and re-connect a second time, this is not working.
I also have tried different settings backends: FCB and settings file both are not working.

Here are logs for connecting, bondind, disconnection and re-connection on the working ESP32:

[00:00:00.179,000] <inf> esp32_bt_adapter: BT controller compile version [f7360be]
[00:00:00.399,000] <dbg> bt_smp: bt_smp_init: LE SC enabled
[00:00:00.399,000] <inf> bt_hci_core: No ID address. App must call settings_load()
Bluetooth initialized
[00:00:00.400,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/name
[00:00:00.400,000] <dbg> bt_gatt: db_hash_set: Stored Hash: 
                                  xx xx xx xx xx xx xx xx  xx xx xx xx xx xx xx xx |.K..(|.. j(..lX.k
[00:00:00.400,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/hash
[00:00:00.401,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/id
[00:00:00.401,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/irk
[00:00:00.401,000] <inf> bt_hci_core: Identity: XX:XX:XX:XX:XX:XX (public)
[00:00:00.401,000] <inf> bt_hci_core: HCI: version 4.2 (0x08) revision 0x030e, manufacturer 0x0060
[00:00:00.401,000] <inf> bt_hci_core: LMP: version 4.2 (0x08) subver 0x030e
[00:00:00.402,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:00.402,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.402,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.402,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 16
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:00.403,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:00.403,000] <dbg> bt_gatt: db_hash_gen: Hash: 
                                  xx xx xx xx xx xx xx xx  xx xx xx xx xx xx xx xx |.K..(|.. j(..lX.k
[00:00:00.403,000] <dbg> bt_gatt: do_db_hash: Database Hash matches

Advertising successfully started

Indicate VND attr 0x3f400068 (UUID 12345678-1234-5678-1234-56789abcdef1)
[00:00:00.782,000] <dbg> bt_smp: bt_smp_pkey_ready: 
[00:00:38.903,000] <dbg> bt_smp: bt_smp_accept: conn 0x3ffcdc60 handle 0
[00:00:38.903,000] <dbg> bt_smp: bt_smp_connected: chan 0x3ffcdf34 cid 0x0006
Updated MTU: TX: 23 RX: 23 bytes
[00:00:38.903,000] <dbg> bt_gatt: bt_gatt_connected: conn 0x3ffcdc60

Connected

Updated MTU: TX: 527 RX: 65 bytes
[00:00:39.131,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:39.131,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:39.131,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:39.131,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:39.131,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:39.131,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:39.131,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:39.131,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 16
[00:00:39.191,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 16
[00:00:39.491,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 34
[00:00:39.581,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:39.581,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:39.581,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:39.881,000] <dbg> bt_gatt: sc_ccc_cfg_write: value 0x0002
[00:00:39.881,000] <dbg> bt_gatt: sc_save: peer XX:XX:XX:XX:XX:XX (random) start 0x0000 end 0x0000
[00:00:39.881,000] <dbg> bt_gatt: find_sc_cfg: id: 0, addr: XX:XX:XX:XX:XX:XX (random)
[00:00:39.881,000] <dbg> bt_gatt: find_sc_cfg: id: 0, addr: 00:00:00:00:00:00 (public)
[00:00:39.881,000] <dbg> bt_gatt: bt_gatt_attr_write_ccc: handle 0x0000 value 2
[00:00:39.881,000] <dbg> bt_gatt: gatt_ccc_changed: ccc 0x3ffbe1e8 value 0x0002
[00:00:40.001,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:40.301,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:40.421,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:40.421,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:40.421,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:40.541,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:40.541,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:40.541,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:40.661,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:40.781,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:40.781,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:40.871,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:40.871,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:40.961,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19

// Bonding:

[00:00:49.291,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x01 len 6
[00:00:49.291,000] <dbg> bt_smp: smp_pairing_req: req: io_capability 0x04, oob_flag 0x00, auth_req 0x2D, max_key_size 0x10, init_key_dist 0x0B, resp_key_dist 0x0B
[00:00:49.291,000] <dbg> bt_smp: smp_init: prnd xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:49.291,000] <dbg> bt_smp: smp_pairing_req: rsp: io_capability 0x00, oob_flag 0x00, auth_req 0x0D, max_key_size 0x10, init_key_dist 0x02, resp_key_dist 0x02
[00:00:49.491,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x0c len 64
[00:00:49.491,000] <dbg> bt_smp: smp_public_key: 
Passkey for XX:XX:XX:XX:XX:XX (random): 125842
[00:00:49.680,000] <dbg> bt_smp: bt_smp_dhkey_ready: 0x3ffd0f34
[00:00:59.841,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:59.841,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:00.041,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:00.041,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:00.042,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:00.042,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:00.141,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:00.141,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:00.241,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:00.241,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:00.242,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:00.242,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:00.391,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:00.391,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:00.491,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:00.491,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:00.492,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:00.492,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:00.791,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:00.791,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:00.991,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:00.991,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:00.992,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:00.992,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:01.241,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:01.241,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:01.391,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:01.391,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:01.392,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:01.392,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:01.541,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:01.541,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:01.791,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:01.791,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:01.791,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:01.791,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:01.941,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:01.941,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:02.091,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:02.091,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:02.092,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:02.092,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:02.241,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:02.241,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:02.391,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:02.391,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:02.392,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:02.392,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:02.541,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:02.541,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:02.641,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:02.641,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:02.642,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:02.642,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:02.741,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:02.741,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:02.841,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:02.841,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:02.842,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:02.842,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:02.941,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:02.941,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:03.041,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:03.041,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:03.042,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:03.042,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:03.141,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:03.141,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:03.291,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:03.291,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:03.292,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:03.292,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:03.491,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:03.491,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:03.591,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:03.591,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:03.592,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:03.592,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:03.691,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:03.691,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:03.841,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:03.841,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:03.842,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:03.842,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:03.941,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:03.941,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:04.091,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:04.091,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:04.092,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:04.092,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:04.191,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:04.191,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:04.291,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:04.291,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:04.292,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:04.292,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:04.441,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:04.441,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:04.541,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:04.541,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:04.542,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:04.542,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:04.641,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:04.641,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:04.741,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:04.741,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:04.742,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:04.742,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:04.841,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:04.841,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:05.041,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:05.041,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:05.042,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:05.042,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:05.141,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:01:05.141,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:01:05.241,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:01:05.241,000] <dbg> bt_smp: smp_pairing_random: 
[00:01:05.242,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:05.242,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:01:05.341,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x0d len 16
[00:01:05.341,000] <dbg> bt_smp: smp_dhkey_check: 
[00:01:05.343,000] <dbg> bt_smp: sc_smp_send_dhkey_check: 
[00:01:05.842,000] <dbg> bt_smp: bt_smp_encrypt_change: chan 0x3ffcdf34 conn 0x3ffcdc60 handle 0 encrypt 0x01 hci status 0x00 
[00:01:05.842,000] <dbg> bt_gatt: bt_gatt_encrypt_change: conn 0x3ffcdc60
[00:01:05.842,000] <dbg> bt_gatt: gatt_ccc_changed: ccc 0x3ffbe1e8 value 0x0002
[00:01:05.842,000] <dbg> bt_gatt: find_sc_cfg: id: 0, addr: XX:XX:XX:XX:XX:XX (random)
[00:01:06.091,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x08 len 16
[00:01:06.091,000] <dbg> bt_smp: smp_ident_info: 
[00:01:06.091,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x09 len 7
[00:01:06.091,000] <dbg> bt_smp: smp_ident_addr_info: identity XX:XX:XX:XX:XX:XX (public)
[00:01:06.092,000] <dbg> bt_gatt: ccc_save: Storing CCCs handle 0x0004 value 0x0002
[00:01:06.093,000] <dbg> bt_gatt: bt_gatt_store_ccc: Stored CCCs for XX:XX:XX:XX:XX:XX (public)
[00:01:06.093,000] <dbg> bt_gatt: bt_gatt_store_ccc:   CCC: handle 0x0004 value 0x0002
[00:01:06.094,000] <wrn> bt_id: Set privacy mode command is not supported

// pairing complete:
[00:01:06.094,000] <dbg> bt_smp: smp_pairing_complete: got status 0x0
[00:01:06.096,000] <dbg> bt_gatt: ccc_save: Storing CCCs handle 0x0004 value 0x0002
[00:01:06.097,000] <dbg> bt_gatt: bt_gatt_store_ccc: Stored CCCs for XX:XX:XX:XX:XX:XX (public)
[00:01:06.097,000] <dbg> bt_gatt: bt_gatt_store_ccc:   CCC: handle 0x0004 value 0x0002
[00:01:06.097,000] <dbg> bt_gatt: bt_gatt_store_cf: No config for CF
[00:01:06.098,000] <dbg> bt_gatt: bt_gatt_store_cf: Stored CF for XX:XX:XX:XX:XX:XX (public)
[00:01:06.098,000] <dbg> bt_gatt: bt_gatt_store_cf: Saved data
[00:01:06.141,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 6
[00:01:07.091,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 34
[00:01:13.690,000] <dbg> bt_smp: bt_smp_disconnected: chan 0x3ffcdf34 cid 0x0006
[00:01:13.691,000] <dbg> bt_gatt: bt_gatt_disconnected: conn 0x3ffcdc60
[00:01:13.691,000] <dbg> bt_gatt: disconnected_cb: ccc 0x3ffbe1e8 reset


Disconnected, reason 0x13 

//reboot:
ets Jul 29 2019 12:21:46
rst:0x1 (POWERON_RESET),boot:0x13 (SPI_FAST_FLASH_BOOT)
configsip: 0, SPIWP:0xee
clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
mode:DIO, clock div:2
load:0x3ffbdb60,len:12428
ho 0 tail 12 room 4
load:0x40080000,len:96480
1150 mmu set 00010000, pos 00010000
entry 0x40090dfc
I (62) soc_init: ESP Simple boot
I (62) soc_init: compile time Mar  4 2025 10:52:32
W (62) soc_init: Unicore bootloader
I (62) soc_init: chip revision: v63.1
I (66) flash_init: SPI Speed      : 40MHz
I (69) flash_init: SPI Mode       : DIO
I (73) flash_init: SPI Flash Size : 4MB
I (76) boot: DRAM: lma 0x00001020 vma 0x3ffbdb60 len 0x308c   (12428)
I (83) boot: IRAM: lma 0x000040b4 vma 0x40080000 len 0x178e0  (96480)
I (89) boot: IRAM: lma 0x0001b9a8 vma 0x00000000 len 0x4650   (18000)
I (95) boot: IMAP: lma 0x00020000 vma 0x400d0000 len 0x4153c  (267580)
I (101) boot: IRAM: lma 0x00061544 vma 0x00000000 len 0xeab4   (60084)
I (107) boot: DMAP: lma 0x00070000 vma 0x3f400000 len 0x93f8   (37880)
I (114) boot: Image with 6 segments
I (117) boot: IROM segment: paddr=00020000h, vaddr=400d0000h, size=4153Ch (267580) map
I (124) boot: DROM segment: paddr=00070000h, vaddr=3f400000h, size=093F8h ( 37880) map
I (143) boot: libc heap size 45 kB.
I (144) spi_flash: detected chip: generic
I (144) spi_flash: flash io: dio
[00:00:00.178,000] <err> sdhc: disabling clk failed
[00:00:00.178,000] <err> sdhc: sdmmc_host_set_card_clk: sdmmc_host_clock_update_command returned 0x105
[00:00:00.178,000] <err> sdhc: Error configuring card clock

*** Booting Zephyr OS build v4.1.0-rc3 ***
[00:00:00.180,000] <inf> esp32_bt_adapter: BT controller compile version [f7360be]
[00:00:00.403,000] <dbg> bt_smp: bt_smp_init: LE SC enabled
[00:00:00.404,000] <inf> bt_hci_core: No ID address. App must call settings_load()
Bluetooth initialized
[00:00:00.405,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/name
[00:00:00.406,000] <dbg> bt_gatt: db_hash_set: Stored Hash: 
                                  xx xx xx xx xx xx xx xx  xx xx xx xx xx xx xx xx |.K..(|.. j(..lX.k
[00:00:00.406,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/hash
[00:00:00.407,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/id
[00:00:00.407,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/irk
[00:00:00.408,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/ccc/xxxxxxxxxxxxx
[00:00:00.408,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/keys/xxxxxxxxxxxxx
[00:00:00.409,000] <inf> bt_hci_core: Identity: XX:XX:XX:XX:XX:XX (public)
[00:00:00.409,000] <inf> bt_hci_core: HCI: version 4.2 (0x08) revision 0x030e, manufacturer 0x0060
[00:00:00.409,000] <inf> bt_hci_core: LMP: version 4.2 (0x08) subver 0x030e
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 16
[00:00:00.410,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:00.411,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:00.411,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:00.411,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:00.411,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:00.411,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:00.411,000] <dbg> bt_gatt: db_hash_gen: Hash: 
                                  xx xx xx xx xx xx xx xx  xx xx xx xx xx xx xx xx |.K..(|.. j(..lX.k
[00:00:00.411,000] <dbg> bt_gatt: do_db_hash: Database Hash matches
[00:00:00.412,000] <wrn> bt_id: Set privacy mode command is not supported

Advertising successfully started
Indicate VND attr 0x3f400068 (UUID 12345678-1234-5678-1234-56789abcdef1)

// re-connection:
[00:00:00.555,000] <dbg> bt_smp: bt_smp_accept: conn 0x3ffcdc60 handle 0
[00:00:00.555,000] <dbg> bt_smp: bt_smp_connected: chan 0x3ffcdf34 cid 0x0006
Updated MTU: TX: 23 RX: 23 bytes
[00:00:00.555,000] <dbg> bt_gatt: bt_gatt_connected: conn 0x3ffcdc60
[00:00:00.559,000] <dbg> bt_gatt: ccc_set_direct: key: bt/ccc/xxxxxxxxxxxxx
[00:00:00.559,000] <dbg> bt_gatt: ccc_set: Read CCC: handle 0x0004 value 0x0002
[00:00:00.559,000] <dbg> bt_gatt: ccc_load: Restoring CCC: handle 0x0004 value 0x0002
[00:00:00.560,000] <dbg> bt_gatt: ccc_set: Restored CCC for id:0 addr:XX:XX:XX:XX:XX:XX (public)
[00:00:00.560,000] <dbg> bt_gatt: gatt_ccc_changed: ccc 0x3ffbe1e8 value 0x0002
[00:00:00.560,000] <dbg> bt_gatt: find_sc_cfg: id: 0, addr: XX:XX:XX:XX:XX:XX (public)
[00:00:00.560,000] <dbg> bt_gatt: sc_restore: no SC data found
Connected
[00:00:00.800,000] <dbg> bt_smp: bt_smp_pkey_ready: 
[00:00:01.007,000] <dbg> bt_smp: bt_smp_encrypt_change: chan 0x3ffcdf34 conn 0x3ffcdc60 handle 0 encrypt 0x01 hci status 0x00 
[00:00:01.007,000] <dbg> bt_gatt: bt_gatt_encrypt_change: conn 0x3ffcdc60
[00:00:01.007,000] <dbg> bt_gatt: gatt_ccc_changed: ccc 0x3ffbe1e8 value 0x0002
[00:00:01.007,000] <dbg> bt_gatt: find_sc_cfg: id: 0, addr: XX:XX:XX:XX:XX:XX (public)
[00:00:01.007,000] <dbg> bt_gatt: sc_restore: no SC data found
Updated MTU: TX: 527 RX: 65 bytes
[00:00:01.427,000] <dbg> bt_gatt: sc_ccc_cfg_write: value 0x0002
[00:00:01.427,000] <dbg> bt_gatt: sc_save: peer XX:XX:XX:XX:XX:XX (public) start 0x0000 end 0x0000
[00:00:01.427,000] <dbg> bt_gatt: find_sc_cfg: id: 0, addr: XX:XX:XX:XX:XX:XX (public)
[00:00:01.428,000] <dbg> bt_gatt: find_sc_cfg: id: 0, addr: 00:00:00:00:00:00 (public)

//sc data stored:
[00:00:01.429,000] <dbg> bt_gatt: sc_store: stored SC for XX:XX:XX:XX:XX:XX (public) (0x0000-0x0000)
[00:00:01.429,000] <dbg> bt_gatt: bt_gatt_attr_write_ccc: handle 0x0000 value 2
[00:00:03.437,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 34

Logs for the same process on my custom HW board

[00:00:00.036,000] <inf> iw416: iw416: starting init
[00:00:02.095,000] <inf> sd: Card does not support CMD8, assuming legacy card
*** Booting Zephyr OS build v4.1.0-rc3-17-g9d7d0cee8988 ***
[00:00:04.450,000] <dbg> bt_smp: bt_smp_init: LE SC enabled
[00:00:04.452,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/id
[00:00:04.453,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/irk
[00:00:04.455,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/name
[00:00:04.455,000] <dbg> bt_gatt: db_hash_set: Stored Hash: 
                                  xx xx xx xx xx xx xx xx  xx xx xx xx xx xx xx xx |.K..(|.. j(..lX.k
[00:00:04.455,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/hash
[00:00:04.455,000] <inf> bt_hci_core: Identity: 88:88:88:88:88:88 (public)
[00:00:04.455,000] <inf> bt_hci_core: HCI: version 5.4 (0x0d) revision 0x8300, manufacturer 0x0048
[00:00:04.455,000] <inf> bt_hci_core: LMP: version 5.4 (0x0d) subver 0x107c
[00:00:04.456,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:04.456,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.456,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:04.457,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.458,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 16
[00:00:04.458,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:04.458,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:04.458,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:04.458,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:04.458,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:04.458,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:04.458,000] <dbg> bt_gatt: db_hash_gen: Hash: 
                                  xx xx xx xx xx xx xx xx  xx xx xx xx xx xx xx xx |.K..(|.. j(..lX.k
[00:00:04.458,000] <dbg> bt_gatt: do_db_hash: Database Hash matches
[00:00:04.458,000] <inf> bt_hci_core: Identity: 88:88:88:88:88:88 (public)
[00:00:04.458,000] <inf> bt_hci_core: HCI: version 5.4 (0x0d) revision 0x8300, manufacturer 0x0048
[00:00:04.458,000] <inf> bt_hci_core: LMP: version 5.4 (0x0d) subver 0x107c
Bluetooth initialized
[00:00:04.460,000] <wrn> bt_settings: Ignoring identities stored in flash
[00:00:04.460,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/id
[00:00:04.461,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/irk
[00:00:04.464,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/name
[00:00:04.464,000] <dbg> bt_gatt: db_hash_set: Stored Hash: 
                                  xx xx xx xx xx xx xx xx  xx xx xx xx xx xx xx xx |.K..(|.. j(..lX.k
[00:00:04.464,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/hash
Advertising successfully started
Indicate VND attr 0x600388d4 (UUID 12345678-1234-5678-1234-56789abcdef1)
[00:00:04.503,000] <dbg> bt_smp: bt_smp_pkey_ready: 
[00:00:20.164,000] <dbg> bt_smp: bt_smp_accept: conn 0x2022c990 handle 128
[00:00:20.164,000] <dbg> bt_smp: bt_smp_connected: chan 0x2022cc64 cid 0x0006
Updated MTU: TX: 23 RX: 23 bytes
[00:00:20.165,000] <dbg> bt_gatt: bt_gatt_connected: conn 0x2022c990
Connected
Updated MTU: TX: 527 RX: 65 bytes
[00:00:20.571,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:20.571,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:20.571,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:20.571,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:20.571,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:20.571,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:20.571,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:20.571,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 16
[00:00:20.623,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 16
[00:00:20.728,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 34
[00:00:20.780,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:20.780,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:20.780,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:20.884,000] <dbg> bt_gatt: sc_ccc_cfg_write: value 0x0002
[00:00:20.884,000] <dbg> bt_gatt: sc_save: peer XX:XX:XX:XX:XX:XX (random) start 0x0000 end 0x0000
[00:00:20.885,000] <dbg> bt_gatt: find_sc_cfg: id: 0, addr: XX:XX:XX:XX:XX:XX (random)
[00:00:20.885,000] <dbg> bt_gatt: find_sc_cfg: id: 0, addr: 00:00:00:00:00:00 (public)
[00:00:20.885,000] <dbg> bt_gatt: bt_gatt_attr_write_ccc: handle 0x0000 value 2
[00:00:20.885,000] <dbg> bt_gatt: gatt_ccc_changed: ccc 0x20220cf4 value 0x0002
[00:00:20.937,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:21.041,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:21.093,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:21.093,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:21.093,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:21.145,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:21.145,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:21.145,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:21.197,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:21.250,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:21.250,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:21.302,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:21.302,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:21.354,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:27.483,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x01 len 6
[00:00:27.483,000] <dbg> bt_smp: smp_pairing_req: req: io_capability 0x04, oob_flag 0x00, auth_req 0x2D, max_key_size 0x10, init_key_dist 0x0B, resp_key_dist 0x0B
[00:00:27.483,000] <dbg> bt_smp: smp_init: prnd xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:27.484,000] <dbg> bt_smp: smp_pairing_req: rsp: io_capability 0x00, oob_flag 0x00, auth_req 0x0D, max_key_size 0x10, init_key_dist 0x02, resp_key_dist 0x02
[00:00:27.566,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x0c len 64
[00:00:27.566,000] <dbg> bt_smp: smp_public_key: 
Passkey for XX:XX:XX:XX:XX:XX (random): 191452
[00:00:27.581,000] <dbg> bt_smp: bt_smp_dhkey_ready: 0x2023090c
[00:00:37.936,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:37.936,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:38.015,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:38.015,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:38.015,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:38.015,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:38.093,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:38.093,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:38.171,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:38.171,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:38.172,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:38.172,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:38.250,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:38.250,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:38.328,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:38.328,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:38.328,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:38.328,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:38.406,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:38.406,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:38.485,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:38.485,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:38.485,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:38.485,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:38.563,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:38.563,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:38.641,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:38.641,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:38.642,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:38.642,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:38.719,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:38.719,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:38.798,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:38.798,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:38.798,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:38.798,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:38.876,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:38.876,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:38.954,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:38.954,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:38.955,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:38.955,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:39.033,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:39.033,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:39.111,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:39.111,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:39.111,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:39.111,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:39.189,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:39.189,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:39.268,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:39.268,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:39.268,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:39.268,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:39.346,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:39.346,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:39.424,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:39.424,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:39.425,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:39.425,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:39.502,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:39.502,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:39.581,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:39.581,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:39.581,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:39.581,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:39.659,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:39.659,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:39.737,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:39.737,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:39.738,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:39.738,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:39.816,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:39.816,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:39.894,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:39.894,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:39.894,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:39.894,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:39.972,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:39.972,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:40.051,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:40.051,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:40.051,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:40.051,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:40.129,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:40.129,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:40.207,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:40.207,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:40.208,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:40.208,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:40.285,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:40.285,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:40.364,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:40.364,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:40.364,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:40.364,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:40.442,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:40.442,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:40.520,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:40.520,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:40.521,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:40.521,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:40.599,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:40.599,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:40.677,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:40.677,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:40.677,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:40.677,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:40.755,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:40.755,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:40.834,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:40.834,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:40.834,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:40.834,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:40.912,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x03 len 16
[00:00:40.912,000] <dbg> bt_smp: smp_pairing_confirm: 
[00:00:40.990,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x04 len 16
[00:00:40.990,000] <dbg> bt_smp: smp_pairing_random: 
[00:00:40.991,000] <dbg> bt_smp: sc_smp_check_confirm: pcnf xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:40.991,000] <dbg> bt_smp: sc_smp_check_confirm: cfm xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[00:00:41.068,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x0d len 16
[00:00:41.068,000] <dbg> bt_smp: smp_dhkey_check: 
[00:00:41.070,000] <dbg> bt_smp: sc_smp_send_dhkey_check: 
[00:00:41.304,000] <dbg> bt_smp: bt_smp_encrypt_change: chan 0x2022cc64 conn 0x2022c990 handle 128 encrypt 0x01 hci status 0x00 
[00:00:41.305,000] <dbg> bt_gatt: bt_gatt_encrypt_change: conn 0x2022c990
[00:00:41.305,000] <dbg> bt_gatt: gatt_ccc_changed: ccc 0x20220cf4 value 0x0002
[00:00:41.305,000] <dbg> bt_gatt: find_sc_cfg: id: 0, addr: XX:XX:XX:XX:XX:XX (random)
[00:00:41.421,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x08 len 16
[00:00:41.421,000] <dbg> bt_smp: smp_ident_info: 
[00:00:41.423,000] <dbg> bt_smp: bt_smp_recv: Received SMP code 0x09 len 7
[00:00:41.423,000] <dbg> bt_smp: smp_ident_addr_info: identity XX:XX:XX:XX:XX:XX (public)
[00:00:41.423,000] <dbg> bt_gatt: ccc_save: Storing CCCs handle 0x0004 value 0x0002
[00:00:41.424,000] <dbg> bt_gatt: bt_gatt_store_ccc: Stored CCCs for XX:XX:XX:XX:XX:XX (public)
[00:00:41.424,000] <dbg> bt_gatt: bt_gatt_store_ccc:   CCC: handle 0x0004 value 0x0002
[00:00:41.433,000] <dbg> bt_smp: smp_pairing_complete: got status 0x0
[00:00:41.435,000] <dbg> bt_gatt: ccc_save: Storing CCCs handle 0x0004 value 0x0002
[00:00:41.436,000] <dbg> bt_gatt: bt_gatt_store_ccc: Stored CCCs for XX:XX:XX:XX:XX:XX (public)
[00:00:41.436,000] <dbg> bt_gatt: bt_gatt_store_ccc:   CCC: handle 0x0004 value 0x0002
[00:00:41.436,000] <dbg> bt_gatt: bt_gatt_store_cf: No config for CF
[00:00:41.436,000] <dbg> bt_gatt: bt_gatt_store_cf: Stored CF for XX:XX:XX:XX:XX:XX (public)
[00:00:41.436,000] <dbg> bt_gatt: bt_gatt_store_cf: Saved data
[00:00:41.437,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 6
[00:00:42.320,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 34
[00:00:50.816,000] <dbg> bt_smp: bt_smp_disconnected: chan 0x2022cc64 cid 0x0006
[00:00:50.816,000] <dbg> bt_gatt: bt_gatt_disconnected: conn 0x2022c990
[00:00:50.816,000] <dbg> bt_gatt: disconnected_cb: ccc 0x20220cf4 reset
Disconnected, reason 0x13 

[00:00:00.036,000] <inf> iw416: iw416: starting init
[00:00:02.095,000] <inf> sd: Card does not support CMD8, assuming legacy card
*** Booting Zephyr OS build v4.1.0-rc3-17-g9d7d0cee8988 ***
[00:00:04.544,000] <dbg> bt_smp: bt_smp_init: LE SC enabled
[00:00:04.546,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/id
[00:00:04.547,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/irk
[00:00:04.549,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/name
[00:00:04.549,000] <dbg> bt_gatt: db_hash_set: Stored Hash: 
                                  xx xx xx xx xx xx xx xx  xx xx xx xx xx xx xx xx |.K..(|.. j(..lX.k
[00:00:04.549,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/hash
[00:00:04.549,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/ccc/xxxxxxxxxxxxx
[00:00:04.549,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/keys/xxxxxxxxxxxxx
[00:00:04.549,000] <inf> bt_hci_core: Identity: 88:88:88:88:88:88 (public)
[00:00:04.549,000] <inf> bt_hci_core: HCI: version 5.4 (0x0d) revision 0x8300, manufacturer 0x0048
[00:00:04.549,000] <inf> bt_hci_core: LMP: version 5.4 (0x0d) subver 0x107c
[00:00:04.550,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:04.550,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.550,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.550,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.550,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:04.550,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.550,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 16
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:04.551,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2
[00:00:04.552,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:04.552,000] <dbg> bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 19
[00:00:04.552,000] <dbg> bt_gatt: db_hash_gen: Hash: 
                                  xx xx xx xx xx xx xx xx  xx xx xx xx xx xx xx xx |.K..(|.. j(..lX.k
[00:00:04.552,000] <dbg> bt_gatt: do_db_hash: Database Hash matches
[00:00:04.561,000] <inf> bt_hci_core: Identity: 88:88:88:88:88:88 (public)
[00:00:04.561,000] <inf> bt_hci_core: HCI: version 5.4 (0x0d) revision 0x8300, manufacturer 0x0048
[00:00:04.561,000] <inf> bt_hci_core: LMP: version 5.4 (0x0d) subver 0x107c
Bluetooth initialized
[00:00:04.562,000] <wrn> bt_settings: Ignoring identities stored in flash
[00:00:04.563,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/id
[00:00:04.563,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/irk
[00:00:04.566,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/name
[00:00:04.566,000] <dbg> bt_gatt: db_hash_set: Stored Hash: 
                                  xx xx xx xx xx xx xx xx  xx xx xx xx xx xx xx xx |.K..(|.. j(..lX.k
[00:00:04.566,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/hash
[00:00:04.566,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/ccc/xxxxxxxxxxxxx
[00:00:04.566,000] <dbg> settings: settings_call_set_handler: set-value OK. key: bt/keys/xxxxxxxxxxxxx
Advertising successfully started
Indicate VND attr 0x600388d4 (UUID 12345678-1234-5678-1234-56789abcdef1)
[00:00:04.596,000] <dbg> bt_smp: bt_smp_pkey_ready: 
[00:00:10.236,000] <dbg> bt_smp: bt_smp_accept: conn 0x2022c990 handle 128
[00:00:10.236,000] <dbg> bt_smp: bt_smp_connected: chan 0x2022cc64 cid 0x0006
Updated MTU: TX: 23 RX: 23 bytes
[00:00:10.236,000] <dbg> bt_gatt: bt_gatt_connected: conn 0x2022c990
[00:00:10.242,000] <dbg> bt_gatt: ccc_set_direct: key: bt/ccc/xxxxxxxxxxxxx
[00:00:10.242,000] <dbg> bt_gatt: ccc_set: Read CCC: handle 0x0004 value 0x0002
[00:00:10.242,000] <dbg> bt_gatt: ccc_load: Restoring CCC: handle 0x0004 value 0x0002
[00:00:10.242,000] <dbg> bt_gatt: ccc_set: Restored CCC for id:0 addr:XX:XX:XX:XX:XX:XX (public)
[00:00:10.242,000] <dbg> bt_gatt: gatt_ccc_changed: ccc 0x20220cf4 value 0x0002
[00:00:10.242,000] <dbg> bt_gatt: find_sc_cfg: id: 0, addr: XX:XX:XX:XX:XX:XX (public)
[00:00:10.242,000] <dbg> bt_gatt: sc_restore: no SC data found
Connected

I am no expert, but my suggestion is that somehow the sc_data for iOS does not properly get stored on my custom HW.

Any help is appreciated!