SEGGER J-Link V6.94a - Real time terminal output
J-Link OB-SAM3U128-V2-NordicSemi compiled Mar 17 2020 14:43:00 V1.0, SN=682591817
Process: JLinkExe
*** Booting Zephyr OS build v2.4.99-ncs1  **
[00:00:00.005,187] <dbg> powermgr_device.powermgr_init_device: battery voltage: 2908mV
[00:00:00.005,584] <inf> bms_device: BMS service start successfull

[00:00:00.014,343] <inf> fs_nvs: 6 Sectors of 4096 bytes
[00:00:00.014,343] <inf> fs_nvs: alloc wra: 0, ff0
[00:00:00.014,343] <inf> fs_nvs: data wra: 0, 0
[00:00:00.014,587] <inf> sdc_hci_driver: SoftDevice Controller build revision:
                                         e5 c7 9c d9 91 00 1d 66  ea fb 6e 7b 98 2f 42 0d |.......f ..n{./B.
                                         f1 60 93 c8                                      |.`..
[00:00:00.019,226] <inf> bt_hci_core: HW Platform: Nordic Semiconductor (0x0002)
[00:00:00.019,256] <inf> bt_hci_core: HW Variant: nRF52x (0x0002)
[00:00:00.019,256] <inf> bt_hci_core: Firmware: Standard Bluetooth controller (0x00) Version 229.40135 Build 486576601
[00:00:00.019,653] <dbg> bt_smp.bt_smp_init: LE SC enabled
[00:00:00.019,836] <inf> bt_hci_core: No ID address. App must call settings_load()
[00:00:00.019,866] <dbg> ble_device.ble_init_pre_settings_device: BLE initalized
[00:00:00.020,690] <inf> bt_hci_core: Identity: E3:09:89:D2:8A:8C (random)
[00:00:00.020,721] <inf> bt_hci_core: HCI: version 5.2 (0x0b) revision 0x1190, manufacturer 0x0059
[00:00:00.020,721] <inf> bt_hci_core: LMP: version 5.2 (0x0b) subver 0x1190
[00:00:00.026,428] <dbg> bt_smp.bt_smp_pkey_ready:
[00:00:00.031,433] <dbg> bt_keys.keys_commit:
[00:00:00.031,555] <dbg> ble_device.ble_init_post_settings_device: for each bond whitelisting is initialized
[00:00:00.031,585] <dbg> ble_device.bonded_count: the bond counter found 0 bonds

[00:00:00.031,585] <inf> ble_device: 0 semaphores are taken at startup

[00:00:00.033,386] <inf> ble_device: Fast advertising started

messages dropped:   1

[00:02:29.350,341] <inf> devicestate_device: released UUUU
[00:02:29.350,402] <dbg> ble_device.confirm_bonding_sequence: confirming all pending bonds
[00:02:29.350,402] <err> bt_smp: Unable to find SMP channel
[00:02:29.350,433] <inf> ble_device: Available number of semaphores are 8

[00:02:30.730,712] <inf> devicestate_device: released UUUU
[00:02:30.730,773] <dbg> ble_device.confirm_bonding_sequence: confirming all pending bonds
[00:02:30.730,773] <err> bt_smp: Unable to find SMP channel
[00:02:30.730,804] <inf> ble_device: Available number of semaphores are 8

[00:02:40.386,566] <inf> devicestate_device: pressed BBBB
[00:02:40.388,580] <inf> ble_device: Fast advertising started

[00:02:41.574,951] <inf> devicestate_device: released UUUU
[00:02:41.575,042] <dbg> ble_device.confirm_bonding_sequence: confirming all pending bonds
[00:02:41.575,073] <err> bt_smp: Unable to find SMP channel
[00:02:41.575,073] <inf> ble_device: Available number of semaphores are 8

[00:02:42.495,452] <dbg> bt_keys.bt_keys_find_irk: 76:56:A0:39:CE:4C (random)
[00:02:42.495,605] <dbg> bt_keys.bt_keys_find_irk: No IRK for 76:56:A0:39:CE:4C (random)
[00:02:42.496,215] <dbg> bt_smp.bt_smp_accept: conn 0x20001e00 handle 0
[00:02:42.496,215] <dbg> bt_smp.bt_smp_connected: chan 0x20002a88 cid 0x0006
[00:02:42.496,246] <inf> ble_device: Connected

[00:02:42.496,398] <dbg> bt_keys.bt_keys_find_addr: 76:56:A0:39:CE:4C (random)
[00:02:47.822,174] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x01 len 6
[00:02:47.822,174] <dbg> bt_smp.smp_pairing_req:
[00:02:47.822,326] <dbg> bt_keys.bt_keys_get_addr: 76:56:A0:39:CE:4C (random)
[00:02:47.822,479] <dbg> bt_keys.bt_keys_get_addr: created 0x20006fdc for 76:56:A0:39:CE:4C (random)
[00:02:47.822,540] <dbg> bt_smp.smp_init: prnd 94e943f6b97ce8cb5b8747b5b95a6c0~
[00:02:47.957,702] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x0c len 64
[00:02:47.957,702] <dbg> bt_smp.smp_public_key:
[00:02:47.957,824] <dbg> bt_smp.smp_f4: u e69d350e480103ccdbfdf4ac1191f4e~
[00:02:47.957,855] <dbg> bt_smp.smp_f4: v <log_strdup alloc failed>
[00:02:47.957,885] <dbg> bt_smp.smp_f4: x <log_strdup alloc failed> z 0x0
[00:02:47.959,106] <dbg> bt_smp.smp_f4: res <log_strdup alloc failed>
[00:02:48.182,128] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:02:48.182,128] <dbg> bt_smp.smp_pairing_random:
[00:02:48.182,159] <dbg> bt_smp.smp_g2: u <log_strdup alloc failed>
[00:02:48.182,189] <dbg> bt_smp.smp_g2: v <log_strdup alloc failed>
[00:02:48.182,220] <dbg> bt_smp.smp_g2: x <log_strdup alloc failed>
[00:02:48.182,250] <dbg> bt_smp.smp_g2: y <log_strdup alloc failed>
[00:02:48.183,441] <dbg> bt_smp.smp_g2: res <log_strdup alloc failed>
[00:02:48.183,441] <dbg> bt_smp.smp_g2: passkey 157118
[00:02:48.183,441] <inf> ble_device: Press pair on your phone then push Button1 on dev board

[00:02:48.183,471] <inf> buzzerdevice_device: current alert 4
[00:02:48.183,502] <dbg> ble_device.passkey_confirm: incoming conn is not in bonded list, Semaphore is taken

[00:02:48.395,019] <dbg> bt_smp.bt_smp_dhkey_ready: 0x2000d734
[00:02:50.391,143] <inf> ble_device: Slow advertising started

[00:02:50.480,834] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x0d len 16
[00:02:50.480,865] <dbg> bt_smp.smp_dhkey_check:
[00:02:53.137,878] <inf> devicestate_device: pressed BBBB
[00:02:53.140,136] <inf> ble_device: Fast advertising started

[00:02:54.281,311] <inf> devicestate_device: released UUUU
[00:02:54.281,372] <dbg> ble_device.confirm_bonding_sequence: confirming all pending bonds
[00:02:54.281,433] <dbg> bt_smp.smp_f5: w f42195ddd530a30545c097bcb1d2cc0~
[00:02:54.281,433] <dbg> bt_smp.smp_f5: n1 f8bd4e7d866d23aa8dafdddb74a2072~
[00:02:54.281,463] <dbg> bt_smp.smp_f5: n2 94e943f6b97ce8cb5b8747b5b95a6c0~
[00:02:54.282,104] <dbg> bt_smp.smp_f5: t 729e917b804df633eb65844c7cff8e5~
[00:02:54.283,081] <dbg> bt_smp.smp_f5: mackey <log_strdup alloc failed>
[00:02:54.284,088] <dbg> bt_smp.smp_f5: ltk <log_strdup alloc failed>
[00:02:54.284,118] <dbg> bt_smp.smp_f6: w <log_strdup alloc failed>
[00:02:54.284,118] <dbg> bt_smp.smp_f6: n1 <log_strdup alloc failed>
[00:02:54.284,179] <dbg> bt_smp.smp_f6: n2 <log_strdup alloc failed>
[00:02:54.284,179] <dbg> bt_smp.smp_f6: r <log_strdup alloc failed>
[00:02:54.284,210] <dbg> bt_smp.smp_f6: io_cap <log_strdup alloc failed>
[00:02:54.284,210] <dbg> bt_smp.smp_f6: a1 <log_strdup alloc failed>
[00:02:54.284,240] <dbg> bt_smp.smp_f6: a2 <log_strdup alloc failed>
[00:02:54.285,430] <dbg> bt_smp.smp_f6: res <log_strdup alloc failed>
[00:02:54.285,461] <dbg> bt_smp.smp_f6: w <log_strdup alloc failed>
[00:02:54.285,461] <dbg> bt_smp.smp_f6: n1 <log_strdup alloc failed>
[00:02:54.285,491] <dbg> bt_smp.smp_f6: n2 <log_strdup alloc failed>
[00:02:54.285,491] <dbg> bt_smp.smp_f6: r <log_strdup alloc failed>
[00:02:54.285,522] <dbg> bt_smp.smp_f6: io_cap <log_strdup alloc failed>
[00:02:54.285,522] <dbg> bt_smp.smp_f6: a1 <log_strdup alloc failed>
[00:02:54.285,552] <dbg> bt_smp.smp_f6: a2 <log_strdup alloc failed>
[00:02:54.286,743] <dbg> bt_smp.smp_f6: res <log_strdup alloc failed>
[00:02:54.286,743] <dbg> bt_smp.sc_smp_send_dhkey_check:
[00:02:54.286,834] <inf> ble_device: Available number of semaphores are 7

[00:02:54.286,834] <err> bt_smp: Unable to find SMP channel
messages dropped:   1

[00:02:54.396,026] <dbg> bt_keys.bt_keys_clear: 76:56:A0:39:CE:4C (random) (keys 0x0000)
[00:02:54.396,179] <dbg> bt_keys.bt_keys_clear: Deleting key bt/keys/7656a039ce4c1
[00:02:54.396,453] <dbg> bt_keys.bt_keys_get_addr: 76:56:A0:39:CE:4C (random)
[00:02:54.396,606] <dbg> bt_keys.bt_keys_get_addr: created 0x20006fdc for 76:56:A0:39:CE:4C (random)
[00:02:54.396,759] <dbg> bt_smp.bt_smp_encrypt_change: chan 0x20002a88 conn 0x20001e00 handle 0 encrypt 0x01 hci status 0x00
[00:02:54.396,911] <dbg> ble_device.security_changed: Security changed: <log_strdup alloc failed> level 4

[00:02:54.403,350] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x08 len 16
[00:02:54.403,350] <dbg> bt_smp.smp_ident_info:
[00:02:54.403,503] <dbg> bt_keys.bt_keys_get_type: type 2 <log_strdup alloc failed>
[00:02:54.403,625] <dbg> bt_keys.bt_keys_find: type 2 <log_strdup alloc failed>
[00:02:54.403,808] <dbg> bt_keys.bt_keys_get_addr: <log_strdup alloc failed>
[00:02:54.410,888] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x09 len 7
[00:02:54.411,071] <dbg> bt_smp.smp_ident_addr_info: identity A4:50:46:6E:44:D6 (public)
[00:02:54.411,224] <dbg> bt_keys.bt_keys_get_type: type 2 76:56:A0:39:CE:4C (random)
[00:02:54.411,346] <dbg> bt_keys.bt_keys_find: type 2 76:56:A0:39:CE:4C (random)
[00:02:54.413,513] <dbg> bt_smp.smp_pairing_complete: status 0x0
[00:02:54.490,966] <dbg> bt_keys.bt_keys_store: Stored keys for A4:50:46:6E:44:D6 (public) (<log_strdup alloc failed>)
[00:02:54.491,119] <inf> ble_device: Pairing completed: <log_strdup alloc failed>, bonded: 1

[00:02:54.491,943] <dbg> ble_device.add_phone_to_whitelist:  Phone is added to whitelist

[00:02:54.494,018] <inf> ble_device: Fast advertising started

[00:02:54.494,110] <inf> buzzerdevice_device: current alert 6
[00:02:54.496,490] <inf> ble_device: Slow advertising started

[00:03:03.706,726] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:03:03.886,627] <inf> bas: BAS Notifications enabled
[00:03:04.156,982] <inf> bss_parser: Received unknown packet type 2
[00:03:04.157,135] <inf> bbs_device: Received read request
[00:03:04.247,039] <inf> bbs_device: Received read request
[00:03:04.496,246] <inf> ble_device: Slow advertising started

[00:04:11.205,413] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:04:11.205,657] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:04:11.205,657] <inf> bas: BAS Notifications disabled
[00:04:11.205,841] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:04:11.206,054] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:04:11.221,496] <dbg> bt_keys.bt_keys_find_addr: <log_strdup alloc failed>
[00:04:11.221,679] <dbg> bt_smp.bt_smp_disconnected: chan 0x20002a88 cid 0x0006
[00:04:11.221,710] <inf> ble_device: Disconnected (reason 8)

[00:04:11.223,663] <inf> ble_device: Fast advertising started

[00:04:41.225,646] <inf> ble_device: Slow advertising started

[00:05:48.560,729] <dbg> bt_smp.bt_smp_accept: conn 0x20001e00 handle 0
[00:05:48.560,760] <dbg> bt_smp.bt_smp_connected: chan 0x20002a88 cid 0x0006
[00:05:48.560,760] <inf> ble_device: Connected

[00:05:48.560,913] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:05:48.563,171] <inf> bas: BAS Notifications enabled
[00:05:48.563,262] <dbg> bt_smp.smp_send_security_req:
[00:05:48.563,415] <dbg> bt_keys.bt_keys_get_addr: A4:50:46:6E:44:D6 (public)
[00:05:48.563,476] <dbg> bt_smp.smp_init: prnd 1798e22fff92690ecece1355d01579e~
[00:05:48.712,432] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:05:49.387,329] <dbg> bt_smp.bt_smp_encrypt_change: chan 0x20002a88 conn 0x20001e00 handle 0 encrypt 0x01 hci status 0x00
[00:05:49.387,512] <dbg> ble_device.security_changed: Security changed: <log_strdup alloc failed> level 4

[00:05:50.868,408] <inf> bss_parser: Received unknown packet type 2
[00:05:50.875,854] <inf> bbs_device: Received read request
[00:05:50.898,376] <inf> bbs_device: Received read request
[00:06:02.932,250] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:06:02.932,464] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:06:02.932,464] <inf> bas: BAS Notifications disabled
[00:06:02.932,647] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:06:02.932,861] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:06:02.934,539] <dbg> bt_keys.bt_keys_find_addr: <log_strdup alloc failed>
[00:06:02.934,722] <dbg> bt_smp.bt_smp_disconnected: chan 0x20002a88 cid 0x0006
[00:06:02.934,753] <inf> ble_device: Disconnected (reason 8)

[00:06:02.936,706] <inf> ble_device: Fast advertising started

[00:06:32.552,337] <dbg> bt_smp.bt_smp_accept: conn 0x20001ec0 handle 0
[00:06:32.552,368] <dbg> bt_smp.bt_smp_connected: chan 0x20002a88 cid 0x0006
[00:06:32.552,368] <inf> ble_device: Connected

[00:06:32.552,551] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:06:32.554,809] <inf> bas: BAS Notifications enabled
[00:06:32.554,870] <dbg> bt_smp.smp_send_security_req:
[00:06:32.555,023] <dbg> bt_keys.bt_keys_get_addr: A4:50:46:6E:44:D6 (public)
[00:06:32.555,084] <dbg> bt_smp.smp_init: prnd 6576c11851c8f8eb36e230cf11fdbba~
[00:06:32.730,590] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:06:32.938,873] <inf> ble_device: Slow advertising started

[00:06:33.540,588] <dbg> bt_smp.bt_smp_encrypt_change: chan 0x20002a88 conn 0x20001ec0 handle 0 encrypt 0x01 hci status 0x00
[00:06:33.540,740] <dbg> ble_device.security_changed: Security changed: A4:50:46:6E:44:D6 (public) level 4

[00:06:35.096,740] <inf> bss_parser: Received unknown packet type 2
[00:06:35.111,694] <inf> bbs_device: Received read request
[00:06:35.206,054] <inf> bbs_device: Received read request
[00:06:38.671,478] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:06:38.671,691] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:06:38.671,722] <inf> bas: BAS Notifications disabled
[00:06:38.671,875] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:06:38.672,088] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:06:38.673,767] <dbg> bt_keys.bt_keys_find_addr: <log_strdup alloc failed>
[00:06:38.673,950] <dbg> bt_smp.bt_smp_disconnected: chan 0x20002a88 cid 0x0006
[00:06:38.673,980] <inf> ble_device: Disconnected (reason 8)

[00:06:38.675,933] <inf> ble_device: Fast advertising started

[00:06:56.442,596] <dbg> bt_smp.bt_smp_accept: conn 0x20001e00 handle 0
[00:06:56.442,626] <dbg> bt_smp.bt_smp_connected: chan 0x20002a88 cid 0x0006
[00:06:56.442,626] <inf> ble_device: Connected

[00:06:56.442,779] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:06:56.445,037] <inf> bas: BAS Notifications enabled
[00:06:56.445,129] <dbg> bt_smp.smp_send_security_req:
[00:06:56.445,281] <dbg> bt_keys.bt_keys_get_addr: A4:50:46:6E:44:D6 (public)
[00:06:56.445,343] <dbg> bt_smp.smp_init: prnd 83cd1bf3ef6bc792c0c306f413e203c~
[00:06:56.560,363] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:06:58.585,052] <dbg> bt_smp.bt_smp_encrypt_change: chan 0x20002a88 conn 0x20001e00 handle 0 encrypt 0x01 hci status 0x00
[00:06:58.585,205] <dbg> ble_device.security_changed: Security changed: A4:50:46:6E:44:D6 (public) level 4

[00:07:03.635,070] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:07:03.635,284] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:07:03.635,314] <inf> bas: BAS Notifications disabled
[00:07:03.635,467] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:07:03.635,681] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:07:03.637,359] <dbg> bt_keys.bt_keys_find_addr: <log_strdup alloc failed>
[00:07:03.637,542] <dbg> bt_smp.bt_smp_disconnected: chan 0x20002a88 cid 0x0006
[00:07:03.637,573] <inf> ble_device: Disconnected (reason 8)

[00:07:03.639,526] <inf> ble_device: Fast advertising started

[00:07:13.993,530] <dbg> bt_smp.bt_smp_accept: conn 0x20001ec0 handle 0
[00:07:13.993,560] <dbg> bt_smp.bt_smp_connected: chan 0x20002a88 cid 0x0006
[00:07:13.993,560] <inf> ble_device: Connected

[00:07:13.993,713] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:07:13.995,971] <inf> bas: BAS Notifications enabled
[00:07:13.996,063] <dbg> bt_smp.smp_send_security_req:
[00:07:13.996,215] <dbg> bt_keys.bt_keys_get_addr: A4:50:46:6E:44:D6 (public)
[00:07:13.996,276] <dbg> bt_smp.smp_init: prnd 982eaf8ea3d075d76882c7d6cac5d3a~
[00:07:17.587,768] <dbg> bt_smp.bt_smp_encrypt_change: chan 0x20002a88 conn 0x20001ec0 handle 0 encrypt 0x01 hci status 0x00
[00:07:17.587,921] <dbg> ble_device.security_changed: Security changed: A4:50:46:6E:44:D6 (public) level 4

[00:07:20.628,540] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:07:21.187,164] <inf> bss_parser: Received unknown packet type 2
[00:07:21.322,113] <inf> bbs_device: Received read request
[00:07:21.412,750] <inf> bbs_device: Received read request
[00:07:27.802,886] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:07:27.803,100] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:07:27.803,100] <inf> bas: BAS Notifications disabled
[00:07:27.803,283] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:07:27.803,497] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:07:27.805,175] <dbg> bt_keys.bt_keys_find_addr: <log_strdup alloc failed>
[00:07:27.805,358] <dbg> bt_smp.bt_smp_disconnected: chan 0x20002a88 cid 0x0006
[00:07:27.805,358] <inf> ble_device: Disconnected (reason 8)

[00:07:27.807,342] <inf> ble_device: Fast advertising started

[00:07:53.135,955] <dbg> bt_smp.bt_smp_accept: conn 0x20001e00 handle 0
[00:07:53.135,986] <dbg> bt_smp.bt_smp_connected: chan 0x20002a88 cid 0x0006
[00:07:53.135,986] <inf> ble_device: Connected

[00:07:53.136,138] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:07:53.138,397] <inf> bas: BAS Notifications enabled
[00:07:53.138,488] <dbg> bt_smp.smp_send_security_req:
[00:07:53.138,641] <dbg> bt_keys.bt_keys_get_addr: A4:50:46:6E:44:D6 (public)
[00:07:53.138,702] <dbg> bt_smp.smp_init: prnd f0fc17941d8db108819720019a364a5~
[00:07:53.329,620] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:07:54.094,512] <dbg> bt_smp.bt_smp_encrypt_change: chan 0x20002a88 conn 0x20001e00 handle 0 encrypt 0x01 hci status 0x00
[00:07:54.094,665] <dbg> ble_device.security_changed: Security changed: <log_strdup alloc failed> level 4

[00:07:56.302,917] <inf> bss_parser: Received unknown packet type 2
[00:07:56.310,546] <inf> bbs_device: Received read request
[00:07:56.389,099] <inf> bbs_device: Received read request
[00:07:57.809,539] <inf> ble_device: Slow advertising started

[00:08:24.828,826] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:08:24.829,040] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:08:24.829,040] <inf> bas: BAS Notifications disabled
[00:08:24.829,223] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:08:24.829,437] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:08:24.831,115] <dbg> bt_keys.bt_keys_find_addr: <log_strdup alloc failed>
[00:08:24.831,298] <dbg> bt_smp.bt_smp_disconnected: chan 0x20002a88 cid 0x0006
[00:08:24.831,298] <inf> ble_device: Disconnected (reason 8)

[00:08:24.833,282] <inf> ble_device: Fast advertising started

[00:08:35.762,878] <dbg> bt_smp.bt_smp_accept: conn 0x20001ec0 handle 0
[00:08:35.762,908] <dbg> bt_smp.bt_smp_connected: chan 0x20002a88 cid 0x0006
[00:08:35.762,939] <inf> ble_device: Connected

[00:08:35.763,092] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:08:35.765,350] <inf> bas: BAS Notifications enabled
[00:08:35.765,411] <dbg> bt_smp.smp_send_security_req:
[00:08:35.765,563] <dbg> bt_keys.bt_keys_get_addr: A4:50:46:6E:44:D6 (public)
[00:08:35.765,625] <dbg> bt_smp.smp_init: prnd be5c4392d9c1f28fd810a8801632e12~
[00:08:35.912,902] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:08:36.677,795] <dbg> bt_smp.bt_smp_encrypt_change: chan 0x20002a88 conn 0x20001ec0 handle 0 encrypt 0x01 hci status 0x00
[00:08:36.677,947] <dbg> ble_device.security_changed: Security changed: <log_strdup alloc failed> level 4

[00:08:39.970,031] <inf> bss_parser: Received unknown packet type 2
[00:08:39.977,600] <inf> bbs_device: Received read request
[00:08:40.289,276] <inf> bbs_device: Received read request
[00:08:42.089,660] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:08:42.089,904] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:08:42.089,904] <inf> bas: BAS Notifications disabled
[00:08:42.090,087] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:08:42.090,301] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:08:42.091,979] <dbg> bt_keys.bt_keys_find_addr: <log_strdup alloc failed>
[00:08:42.092,132] <dbg> bt_smp.bt_smp_disconnected: chan 0x20002a88 cid 0x0006
[00:08:42.092,163] <inf> ble_device: Disconnected (reason 8)

[00:08:42.094,116] <inf> ble_device: Fast advertising started

[00:08:50.327,850] <dbg> bt_smp.bt_smp_accept: conn 0x20001e00 handle 0
[00:08:50.327,880] <dbg> bt_smp.bt_smp_connected: chan 0x20002a88 cid 0x0006
[00:08:50.327,880] <inf> ble_device: Connected

[00:08:50.328,033] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:08:50.330,291] <inf> bas: BAS Notifications enabled
[00:08:50.330,383] <dbg> bt_smp.smp_send_security_req:
[00:08:50.330,535] <dbg> bt_keys.bt_keys_get_addr: A4:50:46:6E:44:D6 (public)
[00:08:50.330,596] <dbg> bt_smp.smp_init: prnd f7f391416aff21123724eeea9f4aea9~
[00:08:50.402,130] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:08:52.157,135] <dbg> bt_smp.bt_smp_encrypt_change: chan 0x20002a88 conn 0x20001e00 handle 0 encrypt 0x01 hci status 0x00
[00:08:52.157,318] <dbg> ble_device.security_changed: Security changed: A4:50:46:6E:44:D6 (public) level 4

[00:08:55.813,812] <inf> bss_parser: Received unknown packet type 2
[00:08:55.813,964] <inf> bbs_device: Received read request
[00:08:56.061,492] <inf> bbs_device: Received read request
[00:08:58.285,644] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:08:58.285,858] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:08:58.285,858] <inf> bas: BAS Notifications disabled
[00:08:58.286,041] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:08:58.286,254] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:08:58.287,933] <dbg> bt_keys.bt_keys_find_addr: <log_strdup alloc failed>
[00:08:58.288,116] <dbg> bt_smp.bt_smp_disconnected: chan 0x20002a88 cid 0x0006
[00:08:58.288,146] <inf> ble_device: Disconnected (reason 8)

[00:08:58.290,100] <inf> ble_device: Fast advertising started

[00:09:00.554,870] <dbg> bt_smp.bt_smp_accept: conn 0x20001ec0 handle 0
[00:09:00.554,901] <dbg> bt_smp.bt_smp_connected: chan 0x20002a88 cid 0x0006
[00:09:00.554,931] <inf> ble_device: Connected

[00:09:00.555,084] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:09:00.557,342] <inf> bas: BAS Notifications enabled
[00:09:00.557,403] <dbg> bt_smp.smp_send_security_req:
[00:09:00.557,556] <dbg> bt_keys.bt_keys_get_addr: A4:50:46:6E:44:D6 (public)
[00:09:00.557,617] <dbg> bt_smp.smp_init: prnd 4e31f640cf91dc64827075b2210c4a7~
[00:09:00.977,264] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:09:03.362,091] <dbg> bt_smp.bt_smp_encrypt_change: chan 0x20002a88 conn 0x20001ec0 handle 0 encrypt 0x01 hci status 0x00
[00:09:03.362,243] <dbg> ble_device.security_changed: Security changed: A4:50:46:6E:44:D6 (public) level 4

[00:09:10.847,076] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:09:10.847,290] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:09:10.847,290] <inf> bas: BAS Notifications disabled
[00:09:10.847,473] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:09:10.847,686] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:09:10.849,365] <dbg> bt_keys.bt_keys_find_addr: <log_strdup alloc failed>
[00:09:10.849,548] <dbg> bt_smp.bt_smp_disconnected: chan 0x20002a88 cid 0x0006
[00:09:10.849,578] <inf> ble_device: Disconnected (reason 8)

[00:09:10.851,531] <inf> ble_device: Fast advertising started

[00:09:18.026,855] <dbg> bt_smp.bt_smp_accept: conn 0x20001e00 handle 0
[00:09:18.026,885] <dbg> bt_smp.bt_smp_connected: chan 0x20002a88 cid 0x0006
[00:09:18.026,916] <inf> ble_device: Connected

[00:09:18.027,069] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:09:18.029,388] <inf> bas: BAS Notifications enabled
[00:09:18.029,510] <dbg> bt_smp.smp_send_security_req:
[00:09:18.029,663] <dbg> bt_keys.bt_keys_get_addr: A4:50:46:6E:44:D6 (public)
[00:09:18.029,724] <dbg> bt_smp.smp_init: prnd 37bed0f69a32e4cd5af4c3fbaccf8a8~
[00:09:18.481,475] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x05 len 1
[00:09:18.481,475] <err> bt_smp: reason 0x5
[00:09:18.481,506] <dbg> bt_smp.smp_pairing_complete: status 0x5
[00:09:18.481,536] <dbg> bt_smp.bt_smp_encrypt_change: chan 0x20002a88 conn 0x20001e00 handle 0 encrypt 0x00 hci status 0x05
[00:09:18.481,658] <err> ble_device: Security failed: A4:50:46:6E:44:D6 (public) level 1 err 5

[00:09:18.481,842] <inf> ble_device: Pairing failed conn: <log_strdup alloc failed>, reason 5

[00:09:18.481,842] <inf> buzzerdevice_device: current alert 5
[00:09:18.571,685] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:09:22.306,579] <dbg> bt_smp.bt_smp_encrypt_change: chan 0x20002a88 conn 0x20001e00 handle 0 encrypt 0x01 hci status 0x00
[00:09:22.306,732] <dbg> ble_device.security_changed: Security changed: A4:50:46:6E:44:D6 (public) level 4

[00:09:22.756,561] <inf> bss_parser: Received unknown packet type 2
[00:09:23.161,285] <inf> bbs_device: Received read request
[00:09:24.781,768] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:09:24.781,982] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:09:24.781,982] <inf> bas: BAS Notifications disabled
[00:09:24.782,165] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:09:24.782,379] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:09:24.784,057] <dbg> bt_keys.bt_keys_find_addr: <log_strdup alloc failed>
[00:09:24.784,240] <dbg> bt_smp.bt_smp_disconnected: chan 0x20002a88 cid 0x0006
[00:09:24.784,240] <inf> ble_device: Disconnected (reason 8)

[00:09:24.786,224] <inf> ble_device: Fast advertising started

[00:09:45.721,557] <dbg> bt_smp.bt_smp_accept: conn 0x20001ec0 handle 0
[00:09:45.721,588] <dbg> bt_smp.bt_smp_connected: chan 0x20002a88 cid 0x0006
[00:09:45.721,588] <inf> ble_device: Connected

[00:09:45.721,740] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:09:45.723,999] <inf> bas: BAS Notifications enabled
[00:09:45.724,090] <dbg> bt_smp.smp_send_security_req:
[00:09:45.724,334] <dbg> bt_keys.bt_keys_get_addr: A4:50:46:6E:44:D6 (public)
[00:09:45.724,395] <dbg> bt_smp.smp_init: prnd f722c66fc70fef46debdb694089a5d4~
[00:09:46.056,518] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:09:50.511,474] <dbg> bt_smp.bt_smp_encrypt_change: chan 0x20002a88 conn 0x20001ec0 handle 0 encrypt 0x01 hci status 0x00
[00:09:50.511,627] <dbg> ble_device.security_changed: Security changed: A4:50:46:6E:44:D6 (public) level 4

[00:09:54.788,879] <inf> ble_device: Slow advertising started

[00:10:07.979,644] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:10:07.979,858] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:10:07.979,888] <inf> bas: BAS Notifications disabled
[00:10:07.980,072] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:10:07.980,255] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:10:07.981,933] <dbg> bt_keys.bt_keys_find_addr: <log_strdup alloc failed>
[00:10:07.982,116] <dbg> bt_smp.bt_smp_disconnected: chan 0x20002a88 cid 0x0006
[00:10:07.982,147] <inf> ble_device: Disconnected (reason 8)

[00:10:07.984,100] <inf> ble_device: Fast advertising started

[00:10:11.557,861] <dbg> bt_smp.bt_smp_accept: conn 0x20001e00 handle 0
[00:10:11.557,891] <dbg> bt_smp.bt_smp_connected: chan 0x20002a88 cid 0x0006
[00:10:11.557,922] <inf> ble_device: Connected

[00:10:11.558,074] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:10:11.560,333] <inf> bas: BAS Notifications enabled
[00:10:11.560,394] <dbg> bt_smp.smp_send_security_req:
[00:10:11.560,546] <dbg> bt_keys.bt_keys_get_addr: A4:50:46:6E:44:D6 (public)
[00:10:11.560,607] <dbg> bt_smp.smp_init: prnd 2b607499b1fe4461f0b98e8b4a1e5c9~
[00:10:11.631,286] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:10:13.611,053] <dbg> bt_smp.bt_smp_encrypt_change: chan 0x20002a88 conn 0x20001e00 handle 0 encrypt 0x01 hci status 0x00
[00:10:13.611,206] <dbg> ble_device.security_changed: Security changed: A4:50:46:6E:44:D6 (public) level 4

[00:10:17.840,820] <inf> bss_parser: Received unknown packet type 2
[00:10:17.885,772] <inf> bbs_device: Received read request
[00:10:17.976,440] <inf> bbs_device: Received read request
[00:10:24.546,508] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:10:24.546,722] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:10:24.546,722] <inf> bas: BAS Notifications disabled
[00:10:24.546,905] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:10:24.547,119] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:10:24.548,797] <dbg> bt_keys.bt_keys_find_addr: <log_strdup alloc failed>
[00:10:24.548,980] <dbg> bt_smp.bt_smp_disconnected: chan 0x20002a88 cid 0x0006
[00:10:24.549,011] <inf> ble_device: Disconnected (reason 8)

[00:10:24.550,964] <inf> ble_device: Fast advertising started

[00:10:26.528,594] <dbg> bt_smp.bt_smp_accept: conn 0x20001ec0 handle 0
[00:10:26.528,625] <dbg> bt_smp.bt_smp_connected: chan 0x20002a88 cid 0x0006
[00:10:26.528,625] <inf> ble_device: Connected

[00:10:26.528,778] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:10:26.531,036] <inf> bas: BAS Notifications enabled
[00:10:26.531,127] <dbg> bt_smp.smp_send_security_req:
[00:10:26.531,280] <dbg> bt_keys.bt_keys_get_addr: A4:50:46:6E:44:D6 (public)
[00:10:26.531,341] <dbg> bt_smp.smp_init: prnd c864780a142f8d6d61fec5b979e7ef6~
[00:10:27.102,783] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:10:33.450,622] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:10:33.450,836] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:10:33.450,836] <inf> bas: BAS Notifications disabled
[00:10:33.451,080] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:10:33.452,758] <dbg> bt_keys.bt_keys_find_addr: A4:50:46:6E:44:D6 (public)
[00:10:33.452,941] <dbg> bt_smp.bt_smp_disconnected: chan 0x20002a88 cid 0x0006
[00:10:33.452,972] <inf> ble_device: Disconnected (reason 8)

[00:10:33.454,925] <inf> ble_device: Fast advertising started

[00:11:03.456,909] <inf> ble_device: Slow advertising started

