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

[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

[Paul Clark]

Debug output from Central app with a bigger buffer...

Fullscreen 6471.debug2.txt Download

Scanning successfully started
Connected (1): 58:8E:81:BE:F6:B9 (public)
MTU (0): 23
Exchange pending...[00:01:06.889MTU exchange 0 successful (65)
,984] <dbg> bt_smp.bt_smp_accept: conn 0x20001868 handle 0
[00:01:06.890,014] <dbg> bt_smp.bt_smp_connected: chan 0x200020b8 cid 0x0006
[00:01:07.291,625] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x0b len 1
[00:01:07.291,656] <dbg> bt_smp.smp_security_request:
[00:01:07.291,656] <dbg> bt_smp.smp_send_pairing_req:
[00:01:07.292,114] <dbg> bt_smp.smp_init: prnd b123cc0d4ec226018247e942af198c79
Entering Passkey!
[00:01:08.291,595] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x02 len 6
[00:01:08.291,625] <dbg> bt_smp.smp_pairing_rsp:
[00:01:08.692,993] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x0c len 64
[00:01:08.692,993] <dbg> bt_smp.smp_public_key:
[00:01:08.694,885] <dbg> bt_smp.smp_f4: u 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:08.694,915] <dbg> bt_smp.smp_f4: v c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:08.694,946] <dbg> bt_smp.smp_f4: x b123cc0d4ec226018247e942af198c79 z 0x80
[00:01:08.696,136] <dbg> bt_smp.smp_f4: res 4275794508095bae4b3bafc66228c979
[00:01:09.091,705] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:09.091,705] <dbg> bt_smp.smp_pairing_confirm:
[00:01:09.125,793] <dbg> bt_smp.bt_smp_dhkey_ready: 0x20009979
[00:01:09.491,699] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:09.491,699] <dbg> bt_smp.smp_pairing_random:
[00:01:09.491,729] <dbg> bt_smp.smp_f4: u c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:09.491,790] <dbg> bt_smp.smp_f4: v 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:09.491,790] <dbg> bt_smp.smp_f4: x 7c846ee9aa5898014c2284488e9e8a06 z 0x80
[00:01:09.493,011] <dbg> bt_smp.smp_f4: res 95e9e5ef95e06f148d7989355ab91348
[00:01:09.493,041] <dbg> bt_smp.sc_smp_check_confirm: pcnf 95e9e5ef95e06f148d7989355ab91348
[00:01:09.493,041] <dbg> bt_smp.sc_smp_check_confirm: cfm 95e9e5ef95e06f148d7989355ab91348
[00:01:09.493,560] <dbg> bt_smp.smp_f4: u 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:09.493,591] <dbg> bt_smp.smp_f4: v c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:09.493,652] <dbg> bt_smp.smp_f4: x 763aefda24389a68875cf4ecbf0c7831 z 0x81
[00:01:09.494,842] <dbg> bt_smp.smp_f4: res 5fe09bea4d728a49729f0a0cd18b6e83
[00:01:09.891,693] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:09.891,693] <dbg> bt_smp.smp_pairing_confirm:
[00:01:10.291,687] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:10.291,687] <dbg> bt_smp.smp_pairing_random:
[00:01:10.291,748] <dbg> bt_smp.smp_f4: u c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:10.291,778] <dbg> bt_smp.smp_f4: v 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:10.291,809] <dbg> bt_smp.smp_f4: x 0a25460216b28ec84b4a6a24203f5991 z 0x81
[00:01:10.292,999] <dbg> bt_smp.smp_f4: res b2cdc4aafe20dfb837c5a75dd7a5a1ff
[00:01:10.293,029] <dbg> bt_smp.sc_smp_check_confirm: pcnf b2cdc4aafe20dfb837c5a75dd7a5a1ff
[00:01:10.293,060] <dbg> bt_smp.sc_smp_check_confirm: cfm b2cdc4aafe20dfb837c5a75dd7a5a1ff
[00:01:10.293,548] <dbg> bt_smp.smp_f4: u 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:10.293,609] <dbg> bt_smp.smp_f4: v c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:10.293,640] <dbg> bt_smp.smp_f4: x 91d8d8bfffb4de06d6293796a1a5bae8 z 0x80
[00:01:10.294,860] <dbg> bt_smp.smp_f4: res fde1e0f07b0ca64c267e13952579695c
[00:01:10.691,680] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:10.691,711] <dbg> bt_smp.smp_pairing_confirm:
[00:01:11.091,674] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:11.091,705] <dbg> bt_smp.smp_pairing_random:
[00:01:11.091,735] <dbg> bt_smp.smp_f4: u c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:11.091,766] <dbg> bt_smp.smp_f4: v 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:11.091,796] <dbg> bt_smp.smp_f4: x 00ef337ffbc6ca4f4b6abaf34beb0c2e z 0x80
[00:01:11.092,987] <dbg> bt_smp.smp_f4: res 7dc365cef565266519453da2c57e1c55
[00:01:11.093,017] <dbg> bt_smp.sc_smp_check_confirm: pcnf 7dc365cef565266519453da2c57e1c55
[00:01:11.093,048] <dbg> bt_smp.sc_smp_check_confirm: cfm 7dc365cef565266519453da2c57e1c55
[00:01:11.093,536] <dbg> bt_smp.smp_f4: u 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:11.093,597] <dbg> bt_smp.smp_f4: v c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:11.093,627] <dbg> bt_smp.smp_f4: x 3d28a6a7f2c9747197ea241ddc164ca4 z 0x80
[00:01:11.094,848] <dbg> bt_smp.smp_f4: res fe9df6a3c1a26244f2f5cc2c8097b75d
[00:01:11.491,699] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:11.491,699] <dbg> bt_smp.smp_pairing_confirm:
[00:01:11.891,693] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:11.891,693] <dbg> bt_smp.smp_pairing_random:
[00:01:11.891,723] <dbg> bt_smp.smp_f4: u c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:11.891,784] <dbg> bt_smp.smp_f4: v 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:11.891,784] <dbg> bt_smp.smp_f4: x 16177775cd986184e266c2ae1fe2e7f9 z 0x80
[00:01:11.893,005] <dbg> bt_smp.smp_f4: res 1c109d9a4d84cb4946821a96f9765fcc
[00:01:11.893,035] <dbg> bt_smp.sc_smp_check_confirm: pcnf 1c109d9a4d84cb4946821a96f9765fcc
[00:01:11.893,035] <dbg> bt_smp.sc_smp_check_confirm: cfm 1c109d9a4d84cb4946821a96f9765fcc
[00:01:11.893,524] <dbg> bt_smp.smp_f4: u 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:11.893,585] <dbg> bt_smp.smp_f4: v c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:11.893,615] <dbg> bt_smp.smp_f4: x b0e8bd96cf665673928d48bf35c135b6 z 0x81
[00:01:11.894,836] <dbg> bt_smp.smp_f4: res e8319f5d22901b44672bd4f539cd2164
[00:01:12.291,687] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:12.291,717] <dbg> bt_smp.smp_pairing_confirm:
[00:01:12.691,680] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:12.691,711] <dbg> bt_smp.smp_pairing_random:
[00:01:12.691,741] <dbg> bt_smp.smp_f4: u c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:12.691,772] <dbg> bt_smp.smp_f4: v 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:12.691,802] <dbg> bt_smp.smp_f4: x ccb708662a615bd0ad3268949f527dcd z 0x81
[00:01:12.692,993] <dbg> bt_smp.smp_f4: res 654ac953d86d89409840fb35b62a0a78
[00:01:12.693,023] <dbg> bt_smp.sc_smp_check_confirm: pcnf 654ac953d86d89409840fb35b62a0a78
[00:01:12.693,054] <dbg> bt_smp.sc_smp_check_confirm: cfm 654ac953d86d89409840fb35b62a0a78
[00:01:12.693,572] <dbg> bt_smp.smp_f4: u 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:12.693,603] <dbg> bt_smp.smp_f4: v c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:12.693,634] <dbg> bt_smp.smp_f4: x fe1a16af11ae5879b119e0047c921679 z 0x80
[00:01:12.694,854] <dbg> bt_smp.smp_f4: res 862009d9af52a98a4aa091053ff60a8c
[00:01:13.091,674] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:13.091,705] <dbg> bt_smp.smp_pairing_confirm:
[00:01:13.491,699] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:13.491,699] <dbg> bt_smp.smp_pairing_random:
[00:01:13.491,729] <dbg> bt_smp.smp_f4: u c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:13.491,760] <dbg> bt_smp.smp_f4: v 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:13.491,790] <dbg> bt_smp.smp_f4: x 004b0035866c59804516bb9a264483f2 z 0x80
[00:01:13.492,980] <dbg> bt_smp.smp_f4: res 393a701e75f13b1a18651b02ce9a9eb0
[00:01:13.493,011] <dbg> bt_smp.sc_smp_check_confirm: pcnf 393a701e75f13b1a18651b02ce9a9eb0
[00:01:13.493,041] <dbg> bt_smp.sc_smp_check_confirm: cfm 393a701e75f13b1a18651b02ce9a9eb0
[00:01:13.493,530] <dbg> bt_smp.smp_f4: u 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:13.493,591] <dbg> bt_smp.smp_f4: v c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:13.493,621] <dbg> bt_smp.smp_f4: x c2ffae6e474355f2deebe48c502116ec z 0x81
[00:01:13.494,842] <dbg> bt_smp.smp_f4: res 4d6cfa420cd1c3e4a62a7ffeeed6a188
[00:01:13.891,693] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:13.891,693] <dbg> bt_smp.smp_pairing_confirm:
[00:01:14.291,687] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:14.291,687] <dbg> bt_smp.smp_pairing_random:
[00:01:14.291,748] <dbg> bt_smp.smp_f4: u c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:14.291,778] <dbg> bt_smp.smp_f4: v 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:14.291,809] <dbg> bt_smp.smp_f4: x 204e8d55bcb4dc92aa12ca44e6ec4947 z 0x81
[00:01:14.292,999] <dbg> bt_smp.smp_f4: res 1496cfae9a3f6da97d0d07b481fc0ef7
[00:01:14.293,029] <dbg> bt_smp.sc_smp_check_confirm: pcnf 1496cfae9a3f6da97d0d07b481fc0ef7
[00:01:14.293,060] <dbg> bt_smp.sc_smp_check_confirm: cfm 1496cfae9a3f6da97d0d07b481fc0ef7
[00:01:14.293,548] <dbg> bt_smp.smp_f4: u 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:14.293,609] <dbg> bt_smp.smp_f4: v c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:14.293,640] <dbg> bt_smp.smp_f4: x 209602ad26c00707f076004943f4d584 z 0x81
[00:01:14.294,860] <dbg> bt_smp.smp_f4: res 9288cd004b2645a0c95794d32a96b7fb
[00:01:14.691,680] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:14.691,711] <dbg> bt_smp.smp_pairing_confirm:
[00:01:15.091,674] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:15.091,705] <dbg> bt_smp.smp_pairing_random:
[00:01:15.091,735] <dbg> bt_smp.smp_f4: u c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:15.091,766] <dbg> bt_smp.smp_f4: v 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:15.091,796] <dbg> bt_smp.smp_f4: x 0319e090fc6ba48ca29532b57f56979a z 0x81
[00:01:15.092,987] <dbg> bt_smp.smp_f4: res 5374de7f779a2f20321c51d3486d7d82
[00:01:15.093,017] <dbg> bt_smp.sc_smp_check_confirm: pcnf 5374de7f779a2f20321c51d3486d7d82
[00:01:15.093,048] <dbg> bt_smp.sc_smp_check_confirm: cfm 5374de7f779a2f20321c51d3486d7d82
[00:01:15.093,536] <dbg> bt_smp.smp_f4: u 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:15.093,597] <dbg> bt_smp.smp_f4: v c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:15.093,627] <dbg> bt_smp.smp_f4: x 68bf4eee43edf0ae64300dbcf07b8dd7 z 0x80
[00:01:15.094,848] <dbg> bt_smp.smp_f4: res bc81e6907f18848f4dc4f8ae3194011c
[00:01:15.491,699] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:15.491,699] <dbg> bt_smp.smp_pairing_confirm:
[00:01:15.891,693] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:15.891,693] <dbg> bt_smp.smp_pairing_random:
[00:01:15.891,723] <dbg> bt_smp.smp_f4: u c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:15.891,784] <dbg> bt_smp.smp_f4: v 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:15.891,784] <dbg> bt_smp.smp_f4: x be82ed8835a174007d22bec1587a7a8c z 0x80
[00:01:15.893,005] <dbg> bt_smp.smp_f4: res b6bf6ec81a870de4366a127ab19dd524
[00:01:15.893,035] <dbg> bt_smp.sc_smp_check_confirm: pcnf b6bf6ec81a870de4366a127ab19dd524
[00:01:15.893,035] <dbg> bt_smp.sc_smp_check_confirm: cfm b6bf6ec81a870de4366a127ab19dd524
[00:01:15.893,524] <dbg> bt_smp.smp_f4: u 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:15.893,585] <dbg> bt_smp.smp_f4: v c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:15.893,615] <dbg> bt_smp.smp_f4: x 19e509464981fd22798fbd8175f98843 z 0x80
[00:01:15.894,836] <dbg> bt_smp.smp_f4: res 0242e0a7513ec30ef39cf018f6f5577f
[00:01:16.291,687] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:16.291,717] <dbg> bt_smp.smp_pairing_confirm:
[00:01:16.691,680] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:16.691,680] <dbg> bt_smp.smp_pairing_random:
[00:01:16.691,741] <dbg> bt_smp.smp_f4: u c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:16.691,772] <dbg> bt_smp.smp_f4: v 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:16.691,802] <dbg> bt_smp.smp_f4: x edc45d1ff6220da686eaba4674ebf589 z 0x80
[00:01:16.692,993] <dbg> bt_smp.smp_f4: res d9b272d72559293f36d62333cfb2825e
[00:01:16.693,023] <dbg> bt_smp.sc_smp_check_confirm: pcnf d9b272d72559293f36d62333cfb2825e
[00:01:16.693,054] <dbg> bt_smp.sc_smp_check_confirm: cfm d9b272d72559293f36d62333cfb2825e
[00:01:16.693,542] <dbg> bt_smp.smp_f4: u 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:16.693,603] <dbg> bt_smp.smp_f4: v c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:16.693,634] <dbg> bt_smp.smp_f4: x 97f2bc770cb6f77804cd48a240a6bc9e z 0x81
[00:01:16.694,854] <dbg> bt_smp.smp_f4: res f2e6c8ae6525719e332f1df7a8b32421
[00:01:17.091,674] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:17.091,705] <dbg> bt_smp.smp_pairing_confirm:
[00:01:17.491,699] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:17.491,699] <dbg> bt_smp.smp_pairing_random:
[00:01:17.491,729] <dbg> bt_smp.smp_f4: u c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:17.491,760] <dbg> bt_smp.smp_f4: v 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:17.491,790] <dbg> bt_smp.smp_f4: x 6ac640aa8f7e0d863935f27a93a7fd0a z 0x81
[00:01:17.492,980] <dbg> bt_smp.smp_f4: res 8e452ebbf43a07622a0b2625d73d5704
[00:01:17.493,011] <dbg> bt_smp.sc_smp_check_confirm: pcnf 8e452ebbf43a07622a0b2625d73d5704
[00:01:17.493,041] <dbg> bt_smp.sc_smp_check_confirm: cfm 8e452ebbf43a07622a0b2625d73d5704
[00:01:17.493,560] <dbg> bt_smp.smp_f4: u 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:17.493,591] <dbg> bt_smp.smp_f4: v c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:17.493,621] <dbg> bt_smp.smp_f4: x e3f3513c5fb701f66b759dd1df1eb1f3 z 0x80
[00:01:17.494,842] <dbg> bt_smp.smp_f4: res 18909ff7eb76ae32e78d744944e20dec
[00:01:18.091,674] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:18.091,705] <dbg> bt_smp.smp_pairing_confirm:
[00:01:18.491,699] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:18.491,699] <dbg> bt_smp.smp_pairing_random:
[00:01:18.491,729] <dbg> bt_smp.smp_f4: u c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:18.491,760] <dbg> bt_smp.smp_f4: v 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:18.491,790] <dbg> bt_smp.smp_f4: x 0dc1051d8aa5b5c0a82320246a89d8cc z 0x80
[00:01:18.492,980] <dbg> bt_smp.smp_f4: res 8b068efc668bf38eebbe2576b3267def
[00:01:18.493,011] <dbg> bt_smp.sc_smp_check_confirm: pcnf 8b068efc668bf38eebbe2576b3267def
[00:01:18.493,041] <dbg> bt_smp.sc_smp_check_confirm: cfm 8b068efc668bf38eebbe2576b3267def
[00:01:18.493,560] <dbg> bt_smp.smp_f4: u 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:18.493,591] <dbg> bt_smp.smp_f4: v c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:18.493,621] <dbg> bt_smp.smp_f4: x 958075141f41fa9abf2e023d39011cc9 z 0x80
[00:01:18.494,842] <dbg> bt_smp.smp_f4: res dbee755ad07b0822eb0958ec99c2a1fc
[00:01:18.891,693] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:18.891,693] <dbg> bt_smp.smp_pairing_confirm:
[00:01:19.291,687] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:19.291,687] <dbg> bt_smp.smp_pairing_random:
[00:01:19.291,748] <dbg> bt_smp.smp_f4: u c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:19.291,778] <dbg> bt_smp.smp_f4: v 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:19.291,809] <dbg> bt_smp.smp_f4: x 0f81523d13977f0c1ba3fb0aaa6bb46d z 0x80
[00:01:19.292,999] <dbg> bt_smp.smp_f4: res a2eb2e524135ca9dc2055949273d4711
[00:01:19.293,029] <dbg> bt_smp.sc_smp_check_confirm: pcnf a2eb2e524135ca9dc2055949273d4711
[00:01:19.293,060] <dbg> bt_smp.sc_smp_check_confirm: cfm a2eb2e524135ca9dc2055949273d4711
[00:01:19.293,548] <dbg> bt_smp.smp_f4: u 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:19.293,609] <dbg> bt_smp.smp_f4: v c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:19.293,640] <dbg> bt_smp.smp_f4: x 60502243f20e2e90ae55d261696c314e z 0x81
[00:01:19.294,860] <dbg> bt_smp.smp_f4: res a727087fc20e16eddad150d149d5f962
[00:01:19.691,680] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:19.691,711] <dbg> bt_smp.smp_pairing_confirm:
[00:01:20.091,674] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:20.091,705] <dbg> bt_smp.smp_pairing_random:
[00:01:20.091,735] <dbg> bt_smp.smp_f4: u c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:20.091,766] <dbg> bt_smp.smp_f4: v 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:20.091,796] <dbg> bt_smp.smp_f4: x a6c701f383cbd9ae1bf3090f71eadde6 z 0x81
[00:01:20.092,987] <dbg> bt_smp.smp_f4: res fe1cb9b06b367a33659e62a4b7ea3ce4
[00:01:20.093,017] <dbg> bt_smp.sc_smp_check_confirm: pcnf fe1cb9b06b367a33659e62a4b7ea3ce4
[00:01:20.093,048] <dbg> bt_smp.sc_smp_check_confirm: cfm fe1cb9b06b367a33659e62a4b7ea3ce4
[00:01:20.093,536] <dbg> bt_smp.smp_f4: u 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:20.093,597] <dbg> bt_smp.smp_f4: v c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:20.093,627] <dbg> bt_smp.smp_f4: x 3043ac96f7bbb4abb5b4b1561e17696d z 0x80
[00:01:20.094,848] <dbg> bt_smp.smp_f4: res fb43fe196dc7a47229b35258ddd85afa
[00:01:20.491,699] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:20.491,699] <dbg> bt_smp.smp_pairing_confirm:
[00:01:20.891,693] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:20.891,693] <dbg> bt_smp.smp_pairing_random:
[00:01:20.891,723] <dbg> bt_smp.smp_f4: u c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:20.891,784] <dbg> bt_smp.smp_f4: v 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:20.891,784] <dbg> bt_smp.smp_f4: x 543d172b25340b0be4cf5ab3b9545530 z 0x80
[00:01:20.893,005] <dbg> bt_smp.smp_f4: res 3d8c802bfd4c58adda8c27ac39bd6bfe
[00:01:20.893,035] <dbg> bt_smp.sc_smp_check_confirm: pcnf 3d8c802bfd4c58adda8c27ac39bd6bfe
[00:01:20.893,035] <dbg> bt_smp.sc_smp_check_confirm: cfm 3d8c802bfd4c58adda8c27ac39bd6bfe
[00:01:20.893,554] <dbg> bt_smp.smp_f4: u 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:20.893,585] <dbg> bt_smp.smp_f4: v c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:20.893,615] <dbg> bt_smp.smp_f4: x 1ba6a2284826e88feea4e3756d9c1cc5 z 0x81
[00:01:20.894,836] <dbg> bt_smp.smp_f4: res 4b2a210978cf6bc3e17af2a65c2968b8
[00:01:21.291,687] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x03 len 16
[00:01:21.291,717] <dbg> bt_smp.smp_pairing_confirm:
[00:01:21.691,680] <dbg> bt_smp.bt_smp_recv: Received SMP code 0x04 len 16
[00:01:21.691,680] <dbg> bt_smp.smp_pairing_random:
[00:01:21.691,741] <dbg> bt_smp.smp_f4: u c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:21.691,772] <dbg> bt_smp.smp_f4: v 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:21.691,802] <dbg> bt_smp.smp_f4: x c1d6c70388ffee6344ebe239d9abeed2 z 0x81
[00:01:21.692,993] <dbg> bt_smp.smp_f4: res fd7a7fa1aacaa5dac861c7591ae027e5
[00:01:21.693,023] <dbg> bt_smp.sc_smp_check_confirm: pcnf fd7a7fa1aacaa5dac861c7591ae027e5
[00:01:21.693,054] <dbg> bt_smp.sc_smp_check_confirm: cfm fd7a7fa1aacaa5dac861c7591ae027e5
[00:01:21.693,542] <dbg> bt_smp.smp_f4: u 7dff8fdcef1aed8933cf5e11d1cc6c35c09f54588378ad2ef2466765a4d67359
[00:01:21.693,572] <dbg> bt_smp.smp_f4: v c0779b2dcf8099964dbe476e79e88e128cd60e5f07a8d58254e712e15c8e61a1
[00:01:21.693,603] <dbg> bt_smp.smp_f4: x 0bb8a0540e64311c402fb3e23c75b5f0 z 0x81
[00:01:21.694,824] <dbg> bt_smp.smp_f4: res c8f0f4d4e562f31b7983a108a3e8e9c3
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
Parsing Data: Data Type 0xff, Data Len 16, User Data '58:8E:81:BE:F6:B9 (public)'
0xbd, 0x8, 0x41, 0xcb, 0xa7, 0xf4, 0xb7, 0x5d, 0x1c, 0xe4, 0xe7, 0xd1, 0xfa, 0x5a, 0x6b, 0x1d,

[Kenneth]

When establish connection from the nRF52840, can you lower the connection interval? I can see that nRF52840 establish connection with 200ms interval, while Android establish connection with 30ms. Reason for asking:

Android does 82 of these packets in 7.2seconds->succeed.
nRF52840 does 66 packets in 14.2seconds->Silabs terminate.

Edit:

Our softdevice controller team have tried to replicate the issue, but they are not able to based on the provided info. Are you able to provide some more details on how to reproduce it? Their initial thought is that there is some problem when calling bt_conn_set_security(conn, BT_SECURITY_L4) function from the connected callback, maybe try to look into this.

Edit2:

Since you are doing LESC with passkey (BLUETOOTH CORE SPECIFICATION Version 5.3 | Vol 3, Part H page 1582). It supposed to be 20*4 packets, 4 packets per passkey bit. Protocol should abort if confirm value for subsequent bit does not match. Maybe the passkey is wrong somehow? 
https://developer.nordicsemi.com/nRF_Connect_SDK/doc/latest/zephyr/reference/bluetooth/connection_mgmt.html?highlight=bt_conn_auth_cb#c.bt_conn_auth_cb 

For instance is the passkey zero-padded?
https://developer.nordicsemi.com/nRF_Connect_SDK/doc/latest/zephyr/reference/bluetooth/connection_mgmt.html?highlight=bt_conn_auth_cb#c.bt_conn_auth_cb.passkey_entry 

[Paul Clark]

The passkey is correct - it is a 6 digit code with no zeros anyway.
If I deliberately set a wrong passkey, this is detected/handled.

The connection interval appears to be the issue! I lowered the interval from 

0x00A0 to 0xx0050 and the connection succeeded.

Many thanks - and well diagnosed!