nrf52840-DK Central nRF Connect SDK 1.8 with BT_SECURITY_L4 connection dropped

[Paul Clark]

Hi,

I am using the nrf52840-DK with the nRF Connect SDK version 1.8.

I have modified the central_multilink sample to connect to a BLE peripheral which requires a secure connection.

I request BT_SECURITY_L4 via a call to bt_conn_set_security(conn, BT_SECURITY_L4) in the 'connected' callback handler.

The trace I have captured in Wireshark suggests that after I have established a connection using a passkey, there is some additional pairing traffic, suggesting that some negotiation (for the secure connection) or similar is occurring, but is not completing...

The initial connection looks similar to a successful attempt (e.g. from an Android phone), but shows limited 'keys', as follows:

A connection from an android phone shows additional 'keys' (and securely connects). I am not clear whether this is the problem, or how I can gain control of this via the nRF Connect SDK

[Kenneth]

It may seem from the screenshots that the difference here is the initiator of the security procedures. Typically it is the central that initiate security, and the security level aquired may depend on either the security level supported by the peer and/or the security level required by the chateristics in the peer. It's not possible to read/write to characteristics with higher security level than active at any time. As a side note: If you read the Apple Bluetooth Guidelines you can find that this procedure should be initiated by the central, and not the peripheral.

My recommendation is that you delay the bt_conn_set_security() from the peripheral, instead only use it as a fallback solution if the central for instance does not initiate it within let's say a few seconds. The central will not be able to use the characterisitcs before this occurs in any case. Let the central first spend time to execute other procedures if it needs to (e.g. feature exchange, length exchance, mtu exchange, database discovery etc, before it will initiate the security procedure).

Best regards,
Kenneth

[Paul Clark]

We modified the peripheral to prevent it requesting the security, but the end result was the same. I have attached the trace here.

 SecureConnect2.pcapng

[Kenneth]

Do you by any chance have the possibility to share a sniffer log with Android and the same BLE peripheral for comparison?

Does debugging shed any light? Set CONFIG_BT_DEBUG_LOG and CONFIG_BT_DEBUG_SMP to Y in your prj.conf.

Have you set all the callbacks? https://github.com/nrfconnect/sdk-nrf/blob/v1.8.0/samples/bluetooth/central_hids/src/main.c#L626 

Best regards,
Kenneth

[Paul Clark]

Here is a log of and android app connecting (EFR Connect)

And a log of the Android OS pairing
AndroidOSPair.pcapngAndroidEFRAppConnection.pcapng

[Paul Clark]

The callbacks I have set are as follows:

static struct bt_conn_auth_cb conn_auth_callbacks = {

.passkey_entry = auth_passkey_entry,

.passkey_confirm = auth_passkey_confirm,

.cancel = auth_cancel,

.pairing_confirm = pairing_confirm,

.pairing_complete = pairing_complete,

.pairing_failed = pairing_failed,

};

[Kenneth]

Sorry to ask but what is the difference between EFR connect and Android OS connect?

I can see the EFR connect have the same issue (also secure connection), don't you agree? Though it doesn't disconnect so it seems it eventually able to suceed.

Any debugging info available? CONFIG_BT_DEBUG_LOG and CONFIG_BT_DEBUG_SMP to Y in your prj.conf.

[Paul Clark]

Debug output from the Central app...

Fullscreen 4377.debug.txt Download

Scanning successfully started
Connected (1): 58:8E:81:BE:F6:B9 (public)
MTU (0): 23
Exchange pending...[00:01:10.575MTU exchange 0 successful (65)
,561] <dbg> bt_smp.bt_smp_accept: conn 0x20001868 handle 0
[00:01:10.575,592] <dbg> bt_smp.bt_smp_connected: chan 0x200020b8 cid 0x0006
[00:01:10.977,203] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x0b len 1
[00:01:10.977,233] <dbg> bt_smp.smp_security_request:
[00:01:10.977,233] <dbg> bt_smp.smp_send_pairing_req:
[00:01:10.977,691] <dbg> bt_smp.smp_init: prnd 0ada77499e69e361c9df7dec8973e84a
Entering Passkey!
[00:01:11.977,172] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x02 len 6
[00:01:11.977,203] <dbg> bt_smp.smp_pairing_rsp:
[00:01:12.378,570] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x0c len 64
[00:01:12.378,570] <dbg> bt_smp.smp_public_key:
[00:01:12.380,462] <dbg> bt_smp.smp_f4: u c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:12.380,493] <dbg> bt_smp.smp_f4: v 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:12.380,493] <dbg> bt_smp.smp_f4: x 0ada77499e69e361c9df7dec8973e84a z 0x80
[00:01:12.381,713] <dbg> bt_smp.smp_f4: res cbd1a95d522318acd4e517ffc322e021
[00:01:12.777,252] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:12.777,282] <dbg> bt_smp.smp_pairing_confirm:
[00:01:12.810,974] <dbg> bt_smp.bt_smp_dhkey_ready: 0x200096f0
[00:01:13.177,276] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:13.177,276] <dbg> bt_smp.smp_pairing_random:
[00:01:13.177,307] <dbg> bt_smp.smp_f4: u 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:13.177,337] <dbg> bt_smp.smp_f4: v c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:13.177,368] <dbg> bt_smp.smp_f4: x f7fcaf6ff16bb49bbeb84a9175d2272f z 0x80
[00:01:13.178,588] <dbg> bt_smp.smp_f4: res e032e097829eecb5d52851f6fe8a4217
[00:01:13.178,588] <dbg> bt_smp.sc_smp_check_confirm: pcnf e032e097829eecb5d52851f6fe8a4217
[00:01:13.178,619] <dbg> bt_smp.sc_smp_check_confirm: cfm e032e097829eecb5d52851f6fe8a4217
[00:01:13.179,138] <dbg> bt_smp.smp_f4: u c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:13.179,168] <dbg> bt_smp.smp_f4: v 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:13.179,199] <dbg> bt_smp.smp_f4: x <log_strdup alloc failed> z 0x81
[00:01:13.180,419] <dbg> bt_smp.smp_f4: res <log_strdup alloc failed>
[00:01:13.577,270] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:13.577,270] <dbg> bt_smp.smp_pairing_confirm:
[00:01:13.977,264] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:13.977,264] <dbg> bt_smp.smp_pairing_random:
[00:01:13.977,294] <dbg> bt_smp.smp_f4: u 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:13.977,355] <dbg> bt_smp.smp_f4: v c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:13.977,355] <dbg> bt_smp.smp_f4: x 2bc8a0860fc747979a0d212b92522844 z 0x81
[00:01:13.978,576] <dbg> bt_smp.smp_f4: res 732f009c42d9ef0c37f2455048a3e9f8
[00:01:13.978,607] <dbg> bt_smp.sc_smp_check_confirm: pcnf 732f009c42d9ef0c37f2455048a3e9f8
[00:01:13.978,607] <dbg> bt_smp.sc_smp_check_confirm: cfm 732f009c42d9ef0c37f2455048a3e9f8
[00:01:13.979,125] <dbg> bt_smp.smp_f4: u c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:13.979,156] <dbg> bt_smp.smp_f4: v 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:13.979,187] <dbg> bt_smp.smp_f4: x <log_strdup alloc failed> z 0x80
[00:01:13.980,407] <dbg> bt_smp.smp_f4: res <log_strdup alloc failed>
[00:01:14.377,258] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:14.377,288] <dbg> bt_smp.smp_pairing_confirm:
[00:01:14.777,252] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:14.777,282] <dbg> bt_smp.smp_pairing_random:
[00:01:14.777,313] <dbg> bt_smp.smp_f4: u 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:14.777,343] <dbg> bt_smp.smp_f4: v c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:14.777,374] <dbg> bt_smp.smp_f4: x 34c0ed856ccc19a5eda9f2b59e4ec010 z 0x80
[00:01:14.778,564] <dbg> bt_smp.smp_f4: res 31ff18e3c1924c641690bdd1cdd78c12
[00:01:14.778,594] <dbg> bt_smp.sc_smp_check_confirm: pcnf 31ff18e3c1924c641690bdd1cdd78c12
[00:01:14.778,625] <dbg> bt_smp.sc_smp_check_confirm: cfm 31ff18e3c1924c641690bdd1cdd78c12
[00:01:14.779,113] <dbg> bt_smp.smp_f4: u c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:14.779,174] <dbg> bt_smp.smp_f4: v 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:14.779,205] <dbg> bt_smp.smp_f4: x <log_strdup alloc failed> z 0x80
[00:01:14.780,395] <dbg> bt_smp.smp_f4: res <log_strdup alloc failed>
[00:01:15.177,246] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:15.177,276] <dbg> bt_smp.smp_pairing_confirm:
[00:01:15.577,270] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:15.577,270] <dbg> bt_smp.smp_pairing_random:
[00:01:15.577,301] <dbg> bt_smp.smp_f4: u 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:15.577,331] <dbg> bt_smp.smp_f4: v c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:15.577,362] <dbg> bt_smp.smp_f4: x 99e73c44f9dd3eb9d57f91fef558fb14 z 0x80
[00:01:15.578,552] <dbg> bt_smp.smp_f4: res 1c11f05c7a3538b74a741bec2c0f85be
[00:01:15.578,582] <dbg> bt_smp.sc_smp_check_confirm: pcnf 1c11f05c7a3538b74a741bec2c0f85be
[00:01:15.578,613] <dbg> bt_smp.sc_smp_check_confirm: cfm 1c11f05c7a3538b74a741bec2c0f85be
[00:01:15.579,132] <dbg> bt_smp.smp_f4: u c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:15.579,162] <dbg> bt_smp.smp_f4: v 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:15.579,193] <dbg> bt_smp.smp_f4: x <log_strdup alloc failed> z 0x81
[00:01:15.580,413] <dbg> bt_smp.smp_f4: res <log_strdup alloc failed>
[00:01:15.977,264] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:15.977,264] <dbg> bt_smp.smp_pairing_confirm:
[00:01:16.377,258] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:16.377,258] <dbg> bt_smp.smp_pairing_random:
[00:01:16.377,319] <dbg> bt_smp.smp_f4: u 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:16.377,349] <dbg> bt_smp.smp_f4: v c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:16.377,380] <dbg> bt_smp.smp_f4: x 1bdcc91ed335adf9bcb5e3a6f94c8e2d z 0x81
[00:01:16.378,570] <dbg> bt_smp.smp_f4: res 2b7fac8d545377675bfdf3845a53d5f2
[00:01:16.378,601] <dbg> bt_smp.sc_smp_check_confirm: pcnf 2b7fac8d545377675bfdf3845a53d5f2
[00:01:16.378,631] <dbg> bt_smp.sc_smp_check_confirm: cfm 2b7fac8d545377675bfdf3845a53d5f2
[00:01:16.379,119] <dbg> bt_smp.smp_f4: u c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:16.379,180] <dbg> bt_smp.smp_f4: v 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:16.379,211] <dbg> bt_smp.smp_f4: x <log_strdup alloc failed> z 0x80
[00:01:16.380,401] <dbg> bt_smp.smp_f4: res <log_strdup alloc failed>
[00:01:16.777,252] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:16.777,282] <dbg> bt_smp.smp_pairing_confirm:
[00:01:17.177,276] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:17.177,276] <dbg> bt_smp.smp_pairing_random:
[00:01:17.177,307] <dbg> bt_smp.smp_f4: u 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:17.177,337] <dbg> bt_smp.smp_f4: v c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:17.177,368] <dbg> bt_smp.smp_f4: x 799c6d81a0eb67ca8c301a991001a4cc z 0x80
[00:01:17.178,558] <dbg> bt_smp.smp_f4: res 5a7d78b8df6e1a63e85408953297588c
[00:01:17.178,588] <dbg> bt_smp.sc_smp_check_confirm: pcnf 5a7d78b8df6e1a63e85408953297588c
[00:01:17.178,619] <dbg> bt_smp.sc_smp_check_confirm: cfm 5a7d78b8df6e1a63e85408953297588c
[00:01:17.179,138] <dbg> bt_smp.smp_f4: u c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:17.179,168] <dbg> bt_smp.smp_f4: v 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:17.179,199] <dbg> bt_smp.smp_f4: x <log_strdup alloc failed> z 0x81
[00:01:17.180,419] <dbg> bt_smp.smp_f4: res <log_strdup alloc failed>
[00:01:17.577,270] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:17.577,270] <dbg> bt_smp.smp_pairing_confirm:
[00:01:17.977,264] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:17.977,264] <dbg> bt_smp.smp_pairing_random:
[00:01:17.977,294] <dbg> bt_smp.smp_f4: u 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:17.977,355] <dbg> bt_smp.smp_f4: v c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:17.977,355] <dbg> bt_smp.smp_f4: x 1d42fa5b2914031bc8218b064e8f3029 z 0x81
[00:01:17.978,576] <dbg> bt_smp.smp_f4: res 80ef950749f6dc4d47b782c9de69172d
[00:01:17.978,607] <dbg> bt_smp.sc_smp_check_confirm: pcnf 80ef950749f6dc4d47b782c9de69172d
[00:01:17.978,607] <dbg> bt_smp.sc_smp_check_confirm: cfm 80ef950749f6dc4d47b782c9de69172d
[00:01:17.979,125] <dbg> bt_smp.smp_f4: u c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:17.979,156] <dbg> bt_smp.smp_f4: v 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:17.979,187] <dbg> bt_smp.smp_f4: x <log_strdup alloc failed> z 0x81
[00:01:17.980,407] <dbg> bt_smp.smp_f4: res <log_strdup alloc failed>
[00:01:18.377,258] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:18.377,288] <dbg> bt_smp.smp_pairing_confirm:
[00:01:18.777,252] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:18.777,282] <dbg> bt_smp.smp_pairing_random:
[00:01:18.777,313] <dbg> bt_smp.smp_f4: u 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:18.777,343] <dbg> bt_smp.smp_f4: v c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:18.777,374] <dbg> bt_smp.smp_f4: x 97700cb1f8717729389d59dc4bb1d2e4 z 0x81
[00:01:18.778,564] <dbg> bt_smp.smp_f4: res 663486fc7182f8106aebb6ff0c9a28b2
[00:01:18.778,594] <dbg> bt_smp.sc_smp_check_confirm: pcnf 663486fc7182f8106aebb6ff0c9a28b2
[00:01:18.778,625] <dbg> bt_smp.sc_smp_check_confirm: cfm 663486fc7182f8106aebb6ff0c9a28b2
[00:01:18.779,144] <dbg> bt_smp.smp_f4: u c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:18.779,174] <dbg> bt_smp.smp_f4: v 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:18.779,205] <dbg> bt_smp.smp_f4: x <log_strdup alloc failed> z 0x80
[00:01:18.780,426] <dbg> bt_smp.smp_f4: res <log_strdup alloc failed>
[00:01:19.177,276] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:19.177,276] <dbg> bt_smp.smp_pairing_confirm:
[00:01:19.577,270] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:19.577,270] <dbg> bt_smp.smp_pairing_random:
[00:01:19.577,301] <dbg> bt_smp.smp_f4: u 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:19.577,331] <dbg> bt_smp.smp_f4: v c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:19.577,362] <dbg> bt_smp.smp_f4: x 7507276cc26c9604891c0b6b7638b57a z 0x80
[00:01:19.578,552] <dbg> bt_smp.smp_f4: res 8443df7414543ca76a5b0869f3d6af79
[00:01:19.578,582] <dbg> bt_smp.sc_smp_check_confirm: pcnf 8443df7414543ca76a5b0869f3d6af79
[00:01:19.578,613] <dbg> bt_smp.sc_smp_check_confirm: cfm 8443df7414543ca76a5b0869f3d6af79
[00:01:19.579,132] <dbg> bt_smp.smp_f4: u c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:19.579,162] <dbg> bt_smp.smp_f4: v 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:19.579,193] <dbg> bt_smp.smp_f4: x <log_strdup alloc failed> z 0x80
[00:01:19.580,413] <dbg> bt_smp.smp_f4: res <log_strdup alloc failed>
[00:01:19.977,264] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:19.977,264] <dbg> bt_smp.smp_pairing_confirm:
[00:01:20.377,258] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:20.377,258] <dbg> bt_smp.smp_pairing_random:
[00:01:20.377,319] <dbg> bt_smp.smp_f4: u 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:20.377,349] <dbg> bt_smp.smp_f4: v c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:20.377,380] <dbg> bt_smp.smp_f4: x 77f19829cfb8b7ac92aac7aeceb6abcb z 0x80
[00:01:20.378,570] <dbg> bt_smp.smp_f4: res a1d0cad16b315822a877e12ee8b964e4
[00:01:20.378,601] <dbg> bt_smp.sc_smp_check_confirm: pcnf a1d0cad16b315822a877e12ee8b964e4
[00:01:20.378,631] <dbg> bt_smp.sc_smp_check_confirm: cfm a1d0cad16b315822a877e12ee8b964e4
[00:01:20.379,119] <dbg> bt_smp.smp_f4: u c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:20.379,150] <dbg> bt_smp.smp_f4: v 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:20.379,180] <dbg> bt_smp.smp_f4: x <log_strdup alloc failed> z 0x81
[00:01:20.380,401] <dbg> bt_smp.smp_f4: res <log_strdup alloc failed>
[00:01:20.777,252] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:20.777,282] <dbg> bt_smp.smp_pairing_confirm:
[00:01:21.177,246] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:21.177,276] <dbg> bt_smp.smp_pairing_random:
[00:01:21.177,307] <dbg> bt_smp.smp_f4: u 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:21.177,337] <dbg> bt_smp.smp_f4: v c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:21.177,368] <dbg> bt_smp.smp_f4: x f596780c2d7463b6b2d71b1a401c0529 z 0x81
[00:01:21.178,558] <dbg> bt_smp.smp_f4: res e2786e7cccf7af34531a039dd60f1da0
[00:01:21.178,588] <dbg> bt_smp.sc_smp_check_confirm: pcnf e2786e7cccf7af34531a039dd60f1da0
[00:01:21.178,619] <dbg> bt_smp.sc_smp_check_confirm: cfm e2786e7cccf7af34531a039dd60f1da0
[00:01:21.179,138] <dbg> bt_smp.smp_f4: u c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:21.179,168] <dbg> bt_smp.smp_f4: v 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:21.179,199] <dbg> bt_smp.smp_f4: x <log_strdup alloc failed> z 0x80
[00:01:21.180,419] <dbg> bt_smp.smp_f4: res <log_strdup alloc failed>
[00:01:21.577,270] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:21.577,270] <dbg> bt_smp.smp_pairing_confirm:
[00:01:22.177,276] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:22.177,276] <dbg> bt_smp.smp_pairing_random:
[00:01:22.177,307] <dbg> bt_smp.smp_f4: u 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:22.177,337] <dbg> bt_smp.smp_f4: v c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:22.177,368] <dbg> bt_smp.smp_f4: x d8c98c2b7d39e1831bae9af53b31735b z 0x80
[00:01:22.178,558] <dbg> bt_smp.smp_f4: res 580f5bf539717b70fca1f8993b7a7753
[00:01:22.178,588] <dbg> bt_smp.sc_smp_check_confirm: pcnf 580f5bf539717b70fca1f8993b7a7753
[00:01:22.178,619] <dbg> bt_smp.sc_smp_check_confirm: cfm 580f5bf539717b70fca1f8993b7a7753
[00:01:22.179,138] <dbg> bt_smp.smp_f4: u c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:22.179,168] <dbg> bt_smp.smp_f4: v 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:22.179,199] <dbg> bt_smp.smp_f4: x <log_strdup alloc failed> z 0x80
[00:01:22.180,419] <dbg> bt_smp.smp_f4: res <log_strdup alloc failed>
[00:01:22.577,270] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:22.577,270] <dbg> bt_smp.smp_pairing_confirm:
[00:01:22.977,264] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:22.977,264] <dbg> bt_smp.smp_pairing_random:
[00:01:22.977,294] <dbg> bt_smp.smp_f4: u 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:22.977,355] <dbg> bt_smp.smp_f4: v c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:22.977,355] <dbg> bt_smp.smp_f4: x 0e9ee950bed11b90d9adbcb6c3da3ff5 z 0x80
[00:01:22.978,576] <dbg> bt_smp.smp_f4: res 03d696653adf7f068bb108ec8a285739
[00:01:22.978,607] <dbg> bt_smp.sc_smp_check_confirm: pcnf 03d696653adf7f068bb108ec8a285739
[00:01:22.978,607] <dbg> bt_smp.sc_smp_check_confirm: cfm 03d696653adf7f068bb108ec8a285739
[00:01:22.979,095] <dbg> bt_smp.smp_f4: u c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:22.979,156] <dbg> bt_smp.smp_f4: v 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:22.979,187] <dbg> bt_smp.smp_f4: x <log_strdup alloc failed> z 0x81
[00:01:22.980,407] <dbg> bt_smp.smp_f4: res <log_strdup alloc failed>
[00:01:23.377,258] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:23.377,288] <dbg> bt_smp.smp_pairing_confirm:
[00:01:23.777,252] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:23.777,282] <dbg> bt_smp.smp_pairing_random:
[00:01:23.777,313] <dbg> bt_smp.smp_f4: u 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:23.777,343] <dbg> bt_smp.smp_f4: v c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:23.777,374] <dbg> bt_smp.smp_f4: x 2d50771dccccd5b50d09fcd3e8c71f3b z 0x81
[00:01:23.778,564] <dbg> bt_smp.smp_f4: res ae19be541f5385924eff4515b6588273
[00:01:23.778,594] <dbg> bt_smp.sc_smp_check_confirm: pcnf ae19be541f5385924eff4515b6588273
[00:01:23.778,625] <dbg> bt_smp.sc_smp_check_confirm: cfm ae19be541f5385924eff4515b6588273
[00:01:23.779,113] <dbg> bt_smp.smp_f4: u c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:23.779,174] <dbg> bt_smp.smp_f4: v 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:23.779,205] <dbg> bt_smp.smp_f4: x <log_strdup alloc failed> z 0x80
[00:01:23.780,426] <dbg> bt_smp.smp_f4: res <log_strdup alloc failed>
[00:01:24.177,276] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:24.177,276] <dbg> bt_smp.smp_pairing_confirm:
[00:01:24.577,270] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:24.577,270] <dbg> bt_smp.smp_pairing_random:
[00:01:24.577,301] <dbg> bt_smp.smp_f4: u 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:24.577,331] <dbg> bt_smp.smp_f4: v c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:24.577,362] <dbg> bt_smp.smp_f4: x 9af64428fc53cbdc8e4c3f0cf73bc79f z 0x80
[00:01:24.578,582] <dbg> bt_smp.smp_f4: res f72475100399a479096b3a6123f873db
[00:01:24.578,582] <dbg> bt_smp.sc_smp_check_confirm: pcnf f72475100399a479096b3a6123f873db
[00:01:24.578,613] <dbg> bt_smp.sc_smp_check_confirm: cfm f72475100399a479096b3a6123f873db
[00:01:24.579,132] <dbg> bt_smp.smp_f4: u c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:24.579,162] <dbg> bt_smp.smp_f4: v 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:24.579,193] <dbg> bt_smp.smp_f4: x <log_strdup alloc failed> z 0x81
[00:01:24.580,413] <dbg> bt_smp.smp_f4: res <log_strdup alloc failed>
[00:01:24.977,264] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:24.977,264] <dbg> bt_smp.smp_pairing_confirm:
[00:01:25.377,258] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:25.377,258] <dbg> bt_smp.smp_pairing_random:
[00:01:25.377,319] <dbg> bt_smp.smp_f4: u 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:25.377,349] <dbg> bt_smp.smp_f4: v c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:25.377,380] <dbg> bt_smp.smp_f4: x f4525eddc23582f2ebc5d96e0b1b1e11 z 0x81
[00:01:25.378,570] <dbg> bt_smp.smp_f4: res 247ef172300a9f6e249b8746224c02d4
[00:01:25.378,601] <dbg> bt_smp.sc_smp_check_confirm: pcnf 247ef172300a9f6e249b8746224c02d4
[00:01:25.378,631] <dbg> bt_smp.sc_smp_check_confirm: cfm 247ef172300a9f6e249b8746224c02d4
[00:01:25.379,119] <dbg> bt_smp.smp_f4: u c0779190a30f691e0fea7a53d73260c32885a5c03350b1533ff34560c27318e7
[00:01:25.379,180] <dbg> bt_smp.smp_f4: v 1c33c31fe08071cd5ccc0ab8bc61d66ce110476a42624d6598b7cfb6224bdaaa
[00:01:25.379,211] <dbg> bt_smp.smp_f4: x <log_strdup alloc failed> z 0x81
[00:01:25.380,401] <dbg> bt_smp.smp_f4: res <log_strdup alloc failed>
BT_SECURITY_ERR_UNSPECIFIED
Pairing failed conn: 58:8E:81:BE:F6:B9 (public), reason 9
BT_SECURITY_ERR_UNSPECIFIED
Disconnected: 58:8E:81:BE:F6:B9 (public) (reason 0x13)
REMOTE USER TERMINATED CONNECTION

EFR Connect is a SiLabs app - but running on Android.
As it requests lots of Gatt information, I thought a trace of an basic Android OS 'pair' may be useful/cleaner