"Pairing not supported" when reconnecting to multiple devices using Android BLE Library

[Poorp]

Hi

We are seeing a strange issue when trying to use the Android BLE Library to Android BLE Library to reconnect to two devices. The phone succeeds in connecting to one device but not the other, and because of this failure it deletes the bonding information. Meaning it can not connect again until the user goes through the whole bonding procedure.

The same devices work fine when using ios, we tested the reconnecting on ios 100 times and never saw the issue.
The issue does not occur every time, but seems to only be occurring when both devices are found (and being connected to) simultaneously
The issue can be triggered by either walking in and out of range, or by turning off and on bluetooth

We would be very grateful for any help in figuring this out.
Please let us know if you require any further information

the device starting with e3 is the embedded device, running zephyr with nrf connect sdk v1.5.1, the other device is the phone


hci log

Fullscreen failedreconnection.log Download

btsnoop
�
�Dd*dw 
�Dd*i�Dd+
�	
�D��V 
(*�D�…

�D�2�
6
�D�4 	

6�D�4�
-
�D�6* 

JJ�D�6P~EA��R���7�����X UE�v~��h��|���P�_�$��zj��8�]�`�R��v�x��v��  
�D�8:Q AFs��g���{6�� D�n+�  
�D�8=<�2̬�O��v�;��Ȃe����
�D�8>�໡hF?UC�՝Dkr�
�D�8A ?�{T�@~�]�GPq	�D�8mW1�����q
6҅
�D�9�8 ݅-#���r|,�����D�]�D
�}�s�i�W��N	
�D֦�r 
˃e��Ԑ�w��H��D֧�c@�o(}��-�b�r��D֧��	�DnFP��D֯@�
6
�D֯u� �D֯w&
��*
�D֯�� 
		eSeat�D֯�N
��(
�D֯�
 

	�D֯�W��(
�Dְ" ?f8�����Iꐀ��Dְ&��(
�Dְ\�  ';�Dְ_[(��(
�Dְ�
 (0
1346�Dְ�7��(
�Dְ�� 7=צ�N�ơLW��c��Dְ�>��(
�Dֱk 	
>
�Dֱ!
(
�DֱE� 	


�DֱGw
(
�Dֱ�� 	 *
)+�Dֱ�G(
�Dֱ�� 
		*+�Dֱ��(
�Dֱ�* 	

�Dֱ�6	
�Dֲ0� 

)�Dֲ3�	(
�Dֲk� 	
	
�Dֲm�	(
�Dֲ�� 	
*

*�Dֲ��
(
�Dֲ�� 
		*�Dֲ�(
�Dֳ8� 	

�Dֳ=�(
�Dֳs] 	

�Dֳv�(  
�Dֳ�� 	%q�˿J��K&_���Dֳ��(  
�Dִ� 	v�� �r��O��j�I �Dִ$a(
�Dִz� 	

�Dִ}j	
�Dִ�m 


)�Dִ��	
�Dֵ� 


)�DֵU(
�DֵGA 	

�DֵH�(
�Dֵ�� 
		*�Dֵ��(
�Dֵ�: 	

�Dֵ�)	
�Dֵ�� 

)�Dֵ�(
�Dֶ3Y 	

�Dֶ5
(
�DֶnE 	�*�*�DֶpB(
�Dֶ�� 	

�Dֶ�� '(
�Dֶ�� 	
 
�Dֶ�� '(
�Dַ� 	!"++$"%,+�Dַ�%'(
�Dַ�: 	
%
�Dַ�s	##
�Dַ�l 

#)�Dַ�o	&'
�Dָ 

&)'
)�Dָ	y(0(
�Dָ_� 	
(
�Dָbr(0(
�Dָ�K 	)*$*+,)*�Dָ��,0(
�Dָվ 	-.&*/0'*�Dָ�P00(
�Dֹ� 	
0
�Dֹ�13(
�DֹK 	
1
�DֹM�13(
�Dֹ�� 
		2
3*�Dֹ�|33(
�Dֹ� 	
3
�Dֹ�46(
�Dֺ. 	
4
�DֺB46(
�DֺQ� 
		56*�DֺT,66(
�Dֺ�� 	
6
�Dֺ�m7=(
�Dֺǧ 	
7
�Dֺ��7=(  
�Dֻ� 	89�ڦ�N�ơLW��c��Dֻr9=(  
�Dֻ< 	;<&�mgi��H�#��
�Dֻ>�<=(
�Dֻv� 	
<
�Dֻyf	::
�Dֻ�� 

:
)�Dֻ�<	==
�Dֻ�� 

=
)�D؈��	


�D؉h� 
�D؉�B

�D؊� 
eSeat�D؊؊	



�D،(� 
�D،?|

�D؍�� V�D؍�y	&


�D؎�� 
�D؏*R"



�D؏
%
�DؐG_ �

�Dؐa2
R"


�Dؐo

%
�Dؑ�! �



�D���� 
�D����D�ҋv	

typical reconnection failure log from nrf connect

Fullscreen dkdidntwork.txt Download

nRF Connect, 2021-04-29
aname (E3:09:89:D2:8A:8C)
V	16:37:23.444	Connecting to E3:09:89:D2:8A:8C...
D	16:37:23.444	gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D	16:37:23.457	[Callback] Connection state changed with status: 0 and new state: CONNECTED (2)
I	16:37:23.457	Connected to E3:09:89:D2:8A:8C
D	16:37:23.458	wait(1600ms)
V	16:37:25.059	Discovering services...
D	16:37:25.059	gatt.discoverServices()
D	16:37:25.070	[Callback] Services discovered with status: 0
I	16:37:25.070	Services discovered
V	16:37:25.100	Generic Attribute (0x1801)
- Service Changed [I] (0x2A05)
   Client Characteristic Configuration (0x2902)
- Client Supported Features [R W] (0x2B29)
- Database Hash [R] (0x2B2A)
Generic Access (0x1800)
- Device Name [R] (0x2A00)
- Appearance [R] (0x2A01)
- Peripheral Preferred Connection Parameters [R] (0x2A04)
Unknown Service (ef048090-ea1a-49c2-8105-d9d9ab38663f)
- Unknown Characteristic [W] (defd035f-0226-4beb-874a-bfcbb8e67125)
   Characteristic User Description (0x2901)
- Unknown Characteristic [W] (2049b16a-9387-4f9f-9372-ab207fb78776)
   Characteristic User Description (0x2901)
Battery Service (0x180F)
- Battery Level [N R] (0x2A19)
   Client Characteristic Configuration (0x2902)
Bond Management (0x181E)
- Bond Management Control Point [W] (0x2AA4)
- Bond Management Feature [R] (0x2AA5)
Binary Sensor (0x183B)
- BSS Control Point [W WNR] (0x2B2B)
   Client Characteristic Configuration (0x2902)
- BSS Response [I R] (0x2B2C)
   Client Characteristic Configuration (0x2902)
   Characteristic User Description (0x2901)
Device Information (0x180A)
- Model Number String [R] (0x2A24)
- Manufacturer Name String [R] (0x2A29)
- Firmware Revision String [R] (0x2A26)
- Hardware Revision String [R] (0x2A27)
Link Loss (0x1803)
- Alert Level [R W] (0x2A06)
Tx Power (0x1804)
- Tx Power Level [R] (0x2A07)
Unknown Service (bd63dd8b-5708-4c1f-a1c6-f24e92a6d715)
- Unknown Characteristic [W] (bd63dd8b-5708-4c1f-a1c6-f24e92a6daaa)
   Characteristic User Description (0x2901)
- Unknown Characteristic [W] (0db81abf-23fd-48a2-b611-69676dc0261a)
   Characteristic User Description (0x2901)
D	16:37:25.100	gatt.setCharacteristicNotification(00002a05-0000-1000-8000-00805f9b34fb, true)
D	16:37:25.103	gatt.setCharacteristicNotification(00002a19-0000-1000-8000-00805f9b34fb, true)
D	16:37:25.104	gatt.setCharacteristicNotification(00002b2c-0000-1000-8000-00805f9b34fb, true)
D	16:37:41.361	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to TURNING OFF
I	16:37:41.374	Disconnected
D	16:37:41.375	gatt.close()
D	16:37:41.378	wait(200)
D	16:37:41.793	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to OFF
D	16:37:49.427	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to TURNING ON
D	16:37:49.556	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to ON
D	16:37:51.027	[Broadcast] Action received: android.bluetooth.device.action.ACL_CONNECTED
V	16:37:51.051	Connecting to E3:09:89:D2:8A:8C...
D	16:37:51.051	gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D	16:37:51.065	[Callback] Connection state changed with status: 0 and new state: CONNECTED (2)
I	16:37:51.065	Connected to E3:09:89:D2:8A:8C
D	16:37:51.067	wait(1600ms)
D	16:37:51.211	[Callback] Connection state changed with status: 19 and new state: DISCONNECTED (0)
W	16:37:51.211	Connection terminated by peer (status 19)
I	16:37:51.211	Disconnected
D	16:37:51.217	[Broadcast] Action received: android.bluetooth.device.action.ACL_DISCONNECTED
V	16:39:40.586	Removing bond information...
D	16:39:40.586	device.removeBond() (hidden)
D	16:39:40.650	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_NONE (10)
I	16:39:40.650	Bond information deleted
D	16:42:01.712	[Broadcast] Action received: android.bluetooth.device.action.ACL_CONNECTED
D	16:42:01.712	gatt.close()
D	16:42:01.734	wait(200)
V	16:42:01.938	Connecting to E3:09:89:D2:8A:8C...
D	16:42:01.938	gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D	16:42:01.969	[Callback] Connection state changed with status: 0 and new state: CONNECTED (2)
I	16:42:01.969	Connected to E3:09:89:D2:8A:8C
V	16:42:01.986	Discovering services...
D	16:42:01.986	gatt.discoverServices()
I	16:42:02.156	Connection parameters updated (interval: 7.5ms, latency: 0, timeout: 5000ms)
D	16:42:03.266	[Callback] Services discovered with status: 0
I	16:42:03.266	Services discovered
V	16:42:03.285	Generic Attribute (0x1801)
- Service Changed [I] (0x2A05)
   Client Characteristic Configuration (0x2902)
- Client Supported Features [R W] (0x2B29)
- Database Hash [R] (0x2B2A)
Generic Access (0x1800)
- Device Name [R] (0x2A00)
- Appearance [R] (0x2A01)
- Peripheral Preferred Connection Parameters [R] (0x2A04)
Unknown Service (ef048090-ea1a-49c2-8105-d9d9ab38663f)
- Unknown Characteristic [W] (defd035f-0226-4beb-874a-bfcbb8e67125)
   Characteristic User Description (0x2901)
- Unknown Characteristic [W] (2049b16a-9387-4f9f-9372-ab207fb78776)
   Characteristic User Description (0x2901)
Battery Service (0x180F)
- Battery Level [N R] (0x2A19)
   Client Characteristic Configuration (0x2902)
Bond Management (0x181E)
- Bond Management Control Point [W] (0x2AA4)
- Bond Management Feature [R] (0x2AA5)
Binary Sensor (0x183B)
- BSS Control Point [W WNR] (0x2B2B)
   Client Characteristic Configuration (0x2902)
- BSS Response [I R] (0x2B2C)
   Client Characteristic Configuration (0x2902)
   Characteristic User Description (0x2901)
Device Information (0x180A)
- Model Number String [R] (0x2A24)
- Manufacturer Name String [R] (0x2A29)
- Firmware Revision String [R] (0x2A26)
- Hardware Revision String [R] (0x2A27)
Link Loss (0x1803)
- Alert Level [R W] (0x2A06)
Tx Power (0x1804)
- Tx Power Level [R] (0x2A07)
Unknown Service (bd63dd8b-5708-4c1f-a1c6-f24e92a6d715)
- Unknown Characteristic [W] (bd63dd8b-5708-4c1f-a1c6-f24e92a6daaa)
   Characteristic User Description (0x2901)
- Unknown Characteristic [W] (0db81abf-23fd-48a2-b611-69676dc0261a)
   Characteristic User Description (0x2901)
D	16:42:03.285	gatt.setCharacteristicNotification(00002a05-0000-1000-8000-00805f9b34fb, true)
D	16:42:03.287	gatt.setCharacteristicNotification(00002a19-0000-1000-8000-00805f9b34fb, true)
D	16:42:03.287	gatt.setCharacteristicNotification(00002b2c-0000-1000-8000-00805f9b34fb, true)
I	16:42:03.353	Connection parameters updated (interval: 45.0ms, latency: 0, timeout: 5000ms)
D	16:42:03.875	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDING (11)
D	16:42:04.311	[Broadcast] Action received: android.bluetooth.device.action.PAIRING_REQUEST, pairing variant: PAIRING_VARIANT_PASSKEY_CONFIRMATION (2)
I	16:42:04.388	Connection parameters updated (interval: 7.5ms, latency: 0, timeout: 5000ms)
D	16:42:08.906	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDED (12)
I	16:42:08.906	Device bonded
I	16:42:09.760	Connection parameters updated (interval: 45.0ms, latency: 0, timeout: 420ms)
I	16:42:11.550	Indication received from 00002b2c-0000-1000-8000-00805f9b34fb, value: (0x) 81-00-04-00-02-02-01-00-00-00-0A-02-00-00-00-08
A	16:42:11.550	"(0x) 81-00-04-00-02-02-01-00-00-00-0A-02-00-00-00-08" received
I	16:42:12.202	Indication received from 00002b2c-0000-1000-8000-00805f9b34fb, value: (0x) 81-00-04-00-02-02-01-00-00-00-0A-02-00-00-00-08
A	16:42:12.202	"(0x) 81-00-04-00-02-02-01-00-00-00-0A-02-00-00-00-08" received
I	16:42:12.559	Indication received from 00002b2c-0000-1000-8000-00805f9b34fb, value: (0x) 81-00-04-00-02-02-01-00-00-00-0A-02-00-00-01-00
A	16:42:12.559	"(0x) 81-00-04-00-02-02-01-00-00-00-0A-02-00-00-01-00" received
I	16:42:12.737	Indication received from 00002b2c-0000-1000-8000-00805f9b34fb, value: (0x) 81-00-04-00-02-02-01-00-00-00-0A-02-00-00-01-08
A	16:42:12.737	"(0x) 81-00-04-00-02-02-01-00-00-00-0A-02-00-00-01-08" received
D	16:42:52.715	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to TURNING OFF
I	16:42:52.723	Disconnected
D	16:42:52.723	gatt.close()
D	16:42:52.729	wait(200)
D	16:42:53.148	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to OFF
D	16:43:18.465	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to TURNING ON
D	16:43:18.630	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to ON
D	16:43:19.911	[Broadcast] Action received: android.bluetooth.device.action.ACL_CONNECTED
V	16:43:19.927	Connecting to E3:09:89:D2:8A:8C...
D	16:43:19.928	gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D	16:43:19.938	[Callback] Connection state changed with status: 0 and new state: CONNECTED (2)
I	16:43:19.938	Connected to E3:09:89:D2:8A:8C
D	16:43:19.940	wait(1600ms)
I	16:43:20.334	PHY updated (TX: LE 2M, RX: LE 2M)
D	16:43:20.710	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDING (11)
D	16:43:20.710	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDED (12)
I	16:43:20.710	Device bonded
I	16:43:21.139	Connection parameters updated (interval: 7.5ms, latency: 0, timeout: 5000ms)
V	16:43:21.541	Discovering services...
D	16:43:21.541	gatt.discoverServices()
D	16:43:21.875	[Callback] Services discovered with status: 0
I	16:43:21.875	Services discovered
V	16:43:21.895	Generic Attribute (0x1801)
- Service Changed [I] (0x2A05)
   Client Characteristic Configuration (0x2902)
- Client Supported Features [R W] (0x2B29)
- Database Hash [R] (0x2B2A)
Generic Access (0x1800)
- Device Name [R] (0x2A00)
- Appearance [R] (0x2A01)
- Peripheral Preferred Connection Parameters [R] (0x2A04)
Unknown Service (ef048090-ea1a-49c2-8105-d9d9ab38663f)
- Unknown Characteristic [W] (defd035f-0226-4beb-874a-bfcbb8e67125)
   Characteristic User Description (0x2901)
- Unknown Characteristic [W] (2049b16a-9387-4f9f-9372-ab207fb78776)
   Characteristic User Description (0x2901)
Battery Service (0x180F)
- Battery Level [N R] (0x2A19)
   Client Characteristic Configuration (0x2902)
Bond Management (0x181E)
- Bond Management Control Point [W] (0x2AA4)
- Bond Management Feature [R] (0x2AA5)
Binary Sensor (0x183B)
- BSS Control Point [W WNR] (0x2B2B)
   Client Characteristic Configuration (0x2902)
- BSS Response [I R] (0x2B2C)
   Client Characteristic Configuration (0x2902)
   Characteristic User Description (0x2901)
Device Information (0x180A)
- Model Number String [R] (0x2A24)
- Manufacturer Name String [R] (0x2A29)
- Firmware Revision String [R] (0x2A26)
- Hardware Revision String [R] (0x2A27)
Link Loss (0x1803)
- Alert Level [R W] (0x2A06)
Tx Power (0x1804)
- Tx Power Level [R] (0x2A07)
Unknown Service (bd63dd8b-5708-4c1f-a1c6-f24e92a6d715)
- Unknown Characteristic [W] (bd63dd8b-5708-4c1f-a1c6-f24e92a6daaa)
   Characteristic User Description (0x2901)
- Unknown Characteristic [W] (0db81abf-23fd-48a2-b611-69676dc0261a)
   Characteristic User Description (0x2901)
D	16:43:21.895	gatt.setCharacteristicNotification(00002a05-0000-1000-8000-00805f9b34fb, true)
D	16:43:21.897	gatt.setCharacteristicNotification(00002a19-0000-1000-8000-00805f9b34fb, true)
D	16:43:21.898	gatt.setCharacteristicNotification(00002b2c-0000-1000-8000-00805f9b34fb, true)
I	16:43:21.953	Connection parameters updated (interval: 45.0ms, latency: 0, timeout: 5000ms)
I	16:43:25.309	Connection parameters updated (interval: 45.0ms, latency: 0, timeout: 420ms)
D	16:43:28.771	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to TURNING OFF
I	16:43:28.771	Disconnected
D	16:43:28.782	gatt.close()
D	16:43:28.798	wait(200)
D	16:43:29.211	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to OFF
D	16:43:37.175	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to TURNING ON
D	16:43:37.293	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to ON
D	16:43:38.916	[Broadcast] Action received: android.bluetooth.device.action.ACL_CONNECTED
V	16:43:38.926	Connecting to E3:09:89:D2:8A:8C...
D	16:43:38.926	gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D	16:43:38.934	[Callback] Connection state changed with status: 0 and new state: CONNECTED (2)
I	16:43:38.934	Connected to E3:09:89:D2:8A:8C
D	16:43:38.935	wait(1600ms)
D	16:43:39.101	[Callback] Connection state changed with status: 19 and new state: DISCONNECTED (0)
W	16:43:39.101	Connection terminated by peer (status 19)
I	16:43:39.101	Disconnected
D	16:43:39.112	[Broadcast] Action received: android.bluetooth.device.action.ACL_DISCONNECTED

Atypical reconnection failure, logs from nrf connect

Fullscreen deleted bond Log 2021-05-04 14_29_02.txt Download

nRF Connect, 2021-05-04
aname (E3:09:89:D2:8A:8C)
V	14:08:02.706	Connecting to E3:09:89:D2:8A:8C...
D	14:08:02.706	gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D	14:08:02.716	[Callback] Connection state changed with status: 0 and new state: CONNECTED (2)
I	14:08:02.716	Connected to E3:09:89:D2:8A:8C
D	14:08:02.716	wait(1600ms)
V	14:08:04.318	Discovering services...
D	14:08:04.318	gatt.discoverServices()
D	14:08:04.329	[Callback] Services discovered with status: 0
I	14:08:04.329	Services discovered
V	14:08:04.345	Generic Attribute (0x1801)
- Service Changed [I] (0x2A05)
   Client Characteristic Configuration (0x2902)
- Client Supported Features [R W] (0x2B29)
- Database Hash [R] (0x2B2A)
Generic Access (0x1800)
- Device Name [R] (0x2A00)
- Appearance [R] (0x2A01)
- Peripheral Preferred Connection Parameters [R] (0x2A04)
Battery Service (0x180F)
- Battery Level [N R] (0x2A19)
   Client Characteristic Configuration (0x2902)
Bond Management (0x181E)
- Bond Management Control Point [W] (0x2AA4)
- Bond Management Feature [R] (0x2AA5)
Binary Sensor (0x183B)
- BSS Control Point [W WNR] (0x2B2B)
   Client Characteristic Configuration (0x2902)
- BSS Response [I R] (0x2B2C)
   Client Characteristic Configuration (0x2902)
   Characteristic User Description (0x2901)
Device Information (0x180A)
- Model Number String [R] (0x2A24)
- Manufacturer Name String [R] (0x2A29)
- Firmware Revision String [R] (0x2A26)
- Hardware Revision String [R] (0x2A27)
Nordic LED Button Service (00001523-1212-efde-1523-785feabcd123)
- Button [N R] (00001524-1212-efde-1523-785feabcd123)
   Client Characteristic Configuration (0x2902)
- LED [W] (00001525-1212-efde-1523-785feabcd123)
Link Loss (0x1803)
- Alert Level [R W] (0x2A06)
Tx Power (0x1804)
- Tx Power Level [R] (0x2A07)
D	14:08:04.345	gatt.setCharacteristicNotification(00002a05-0000-1000-8000-00805f9b34fb, true)
D	14:08:04.347	gatt.setCharacteristicNotification(00002a19-0000-1000-8000-00805f9b34fb, true)
D	14:08:04.349	gatt.setCharacteristicNotification(00002b2c-0000-1000-8000-00805f9b34fb, true)
D	14:08:04.351	gatt.setCharacteristicNotification(00001524-1212-efde-1523-785feabcd123, true)
I	14:08:08.968	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 28, "("
A	14:08:08.968	"40%" received
I	14:08:19.001	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 1E
A	14:08:19.001	"30%" received
D	14:08:24.421	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to TURNING OFF
I	14:08:24.435	Disconnected
D	14:08:24.436	gatt.close()
D	14:08:24.444	wait(200)
D	14:08:24.941	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to OFF
D	14:08:31.464	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to TURNING ON
D	14:08:31.597	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to ON
D	14:08:32.896	[Broadcast] Action received: android.bluetooth.device.action.ACL_CONNECTED
V	14:08:32.899	Connecting to E3:09:89:D2:8A:8C...
D	14:08:32.899	gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D	14:08:32.903	[Callback] Connection state changed with status: 0 and new state: CONNECTED (2)
I	14:08:32.903	Connected to E3:09:89:D2:8A:8C
D	14:08:32.904	wait(1600ms)
I	14:08:33.416	PHY updated (TX: LE 2M, RX: LE 2M)
D	14:08:33.696	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDING (11)
D	14:08:33.726	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDED (12)
I	14:08:33.726	Device bonded
I	14:08:34.133	Connection parameters updated (interval: 7.5ms, latency: 0, timeout: 5000ms)
V	14:08:34.504	Discovering services...
D	14:08:34.504	gatt.discoverServices()
D	14:08:34.809	[Callback] Services discovered with status: 0
I	14:08:34.810	Services discovered
V	14:08:34.840	Generic Attribute (0x1801)
- Service Changed [I] (0x2A05)
   Client Characteristic Configuration (0x2902)
- Client Supported Features [R W] (0x2B29)
- Database Hash [R] (0x2B2A)
Generic Access (0x1800)
- Device Name [R] (0x2A00)
- Appearance [R] (0x2A01)
- Peripheral Preferred Connection Parameters [R] (0x2A04)
Battery Service (0x180F)
- Battery Level [N R] (0x2A19)
   Client Characteristic Configuration (0x2902)
Bond Management (0x181E)
- Bond Management Control Point [W] (0x2AA4)
- Bond Management Feature [R] (0x2AA5)
Binary Sensor (0x183B)
- BSS Control Point [W WNR] (0x2B2B)
   Client Characteristic Configuration (0x2902)
- BSS Response [I R] (0x2B2C)
   Client Characteristic Configuration (0x2902)
   Characteristic User Description (0x2901)
Device Information (0x180A)
- Model Number String [R] (0x2A24)
- Manufacturer Name String [R] (0x2A29)
- Firmware Revision String [R] (0x2A26)
- Hardware Revision String [R] (0x2A27)
Nordic LED Button Service (00001523-1212-efde-1523-785feabcd123)
- Button [N R] (00001524-1212-efde-1523-785feabcd123)
   Client Characteristic Configuration (0x2902)
- LED [W] (00001525-1212-efde-1523-785feabcd123)
Link Loss (0x1803)
- Alert Level [R W] (0x2A06)
Tx Power (0x1804)
- Tx Power Level [R] (0x2A07)
D	14:08:34.840	gatt.setCharacteristicNotification(00002a05-0000-1000-8000-00805f9b34fb, true)
D	14:08:34.844	gatt.setCharacteristicNotification(00002a19-0000-1000-8000-00805f9b34fb, true)
D	14:08:34.849	gatt.setCharacteristicNotification(00002b2c-0000-1000-8000-00805f9b34fb, true)
D	14:08:34.852	gatt.setCharacteristicNotification(00001524-1212-efde-1523-785feabcd123, true)
I	14:08:34.868	Connection parameters updated (interval: 45.0ms, latency: 0, timeout: 5000ms)
I	14:08:38.439	Connection parameters updated (interval: 45.0ms, latency: 0, timeout: 420ms)
I	14:08:38.977	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 0A
A	14:08:38.977	"10%" received
I	14:08:48.988	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 00
A	14:08:48.988	"0%" received
I	14:08:48.989	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 64, "d"
A	14:08:48.989	"100%" received
I	14:08:58.989	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 5A, "Z"
A	14:08:58.989	"90%" received
I	14:09:08.979	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 50, "P"
A	14:09:08.979	"80%" received
I	14:09:19.014	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 46, "F"
A	14:09:19.014	"70%" received
D	14:09:22.434	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to TURNING OFF
I	14:09:22.435	Disconnected
D	14:09:22.444	gatt.close()
D	14:09:22.457	wait(200)
D	14:09:22.890	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to OFF
D	14:09:30.305	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to TURNING ON
D	14:09:30.422	[Broadcast] Action received: android.bluetooth.adapter.action.STATE_CHANGED, state changed to ON
D	14:09:31.224	[Broadcast] Action received: android.bluetooth.device.action.ACL_CONNECTED
V	14:09:31.241	Connecting to E3:09:89:D2:8A:8C...
D	14:09:31.241	gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D	14:09:31.261	[Callback] Connection state changed with status: 0 and new state: CONNECTED (2)
I	14:09:31.261	Connected to E3:09:89:D2:8A:8C
D	14:09:31.262	wait(1600ms)
I	14:09:31.666	PHY updated (TX: LE 2M, RX: LE 2M)
D	14:09:32.030	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDING (11)
D	14:09:32.046	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDED (12)
I	14:09:32.046	Device bonded
I	14:09:32.478	Connection parameters updated (interval: 7.5ms, latency: 0, timeout: 5000ms)
V	14:09:32.863	Discovering services...
D	14:09:32.863	gatt.discoverServices()
D	14:09:33.100	[Callback] Services discovered with status: 0
I	14:09:33.100	Services discovered
V	14:09:33.129	Generic Attribute (0x1801)
- Service Changed [I] (0x2A05)
   Client Characteristic Configuration (0x2902)
- Client Supported Features [R W] (0x2B29)
- Database Hash [R] (0x2B2A)
Generic Access (0x1800)
- Device Name [R] (0x2A00)
- Appearance [R] (0x2A01)
- Peripheral Preferred Connection Parameters [R] (0x2A04)
Battery Service (0x180F)
- Battery Level [N R] (0x2A19)
   Client Characteristic Configuration (0x2902)
Bond Management (0x181E)
- Bond Management Control Point [W] (0x2AA4)
- Bond Management Feature [R] (0x2AA5)
Binary Sensor (0x183B)
- BSS Control Point [W WNR] (0x2B2B)
   Client Characteristic Configuration (0x2902)
- BSS Response [I R] (0x2B2C)
   Client Characteristic Configuration (0x2902)
   Characteristic User Description (0x2901)
Device Information (0x180A)
- Model Number String [R] (0x2A24)
- Manufacturer Name String [R] (0x2A29)
- Firmware Revision String [R] (0x2A26)
- Hardware Revision String [R] (0x2A27)
Nordic LED Button Service (00001523-1212-efde-1523-785feabcd123)
- Button [N R] (00001524-1212-efde-1523-785feabcd123)
   Client Characteristic Configuration (0x2902)
- LED [W] (00001525-1212-efde-1523-785feabcd123)
Link Loss (0x1803)
- Alert Level [R W] (0x2A06)
Tx Power (0x1804)
- Tx Power Level [R] (0x2A07)
D	14:09:33.129	gatt.setCharacteristicNotification(00002a05-0000-1000-8000-00805f9b34fb, true)
D	14:09:33.130	gatt.setCharacteristicNotification(00002a19-0000-1000-8000-00805f9b34fb, true)
D	14:09:33.131	gatt.setCharacteristicNotification(00002b2c-0000-1000-8000-00805f9b34fb, true)
D	14:09:33.132	gatt.setCharacteristicNotification(00001524-1212-efde-1523-785feabcd123, true)
I	14:09:33.184	Connection parameters updated (interval: 45.0ms, latency: 0, timeout: 5000ms)
I	14:09:36.807	Connection parameters updated (interval: 45.0ms, latency: 0, timeout: 420ms)
I	14:09:38.969	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 32, "2"
A	14:09:38.969	"50%" received
I	14:09:49.028	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 28, "("
A	14:09:49.028	"40%" received
I	14:09:59.099	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 1E
A	14:09:59.099	"30%" received
I	14:10:09.054	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 14
A	14:10:09.054	"20%" received
D	14:10:11.098	[Callback] Connection state changed with status: 8 and new state: DISCONNECTED (0)
E	14:10:11.098	Error 8 (0x8): GATT CONN TIMEOUT
I	14:10:11.098	Disconnected
D	14:10:11.120	[Broadcast] Action received: android.bluetooth.device.action.ACL_DISCONNECTED
D	14:10:11.206	[Broadcast] Action received: android.bluetooth.device.action.ACL_CONNECTED
D	14:10:11.206	gatt.close()
D	14:10:11.223	wait(200)
V	14:10:11.426	Connecting to E3:09:89:D2:8A:8C...
D	14:10:11.426	gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D	14:10:11.440	[Callback] Connection state changed with status: 0 and new state: CONNECTED (2)
I	14:10:11.440	Connected to E3:09:89:D2:8A:8C
D	14:10:11.442	wait(1600ms)
I	14:10:11.736	PHY updated (TX: LE 2M, RX: LE 2M)
V	14:10:13.044	Discovering services...
D	14:10:13.044	gatt.discoverServices()
D	14:10:13.069	[Callback] Services discovered with status: 0
I	14:10:13.069	Services discovered
V	14:10:13.104	Generic Attribute (0x1801)
- Service Changed [I] (0x2A05)
   Client Characteristic Configuration (0x2902)
- Client Supported Features [R W] (0x2B29)
- Database Hash [R] (0x2B2A)
Generic Access (0x1800)
- Device Name [R] (0x2A00)
- Appearance [R] (0x2A01)
- Peripheral Preferred Connection Parameters [R] (0x2A04)
Battery Service (0x180F)
- Battery Level [N R] (0x2A19)
   Client Characteristic Configuration (0x2902)
Bond Management (0x181E)
- Bond Management Control Point [W] (0x2AA4)
- Bond Management Feature [R] (0x2AA5)
Binary Sensor (0x183B)
- BSS Control Point [W WNR] (0x2B2B)
   Client Characteristic Configuration (0x2902)
- BSS Response [I R] (0x2B2C)
   Client Characteristic Configuration (0x2902)
   Characteristic User Description (0x2901)
Device Information (0x180A)
- Model Number String [R] (0x2A24)
- Manufacturer Name String [R] (0x2A29)
- Firmware Revision String [R] (0x2A26)
- Hardware Revision String [R] (0x2A27)
Nordic LED Button Service (00001523-1212-efde-1523-785feabcd123)
- Button [N R] (00001524-1212-efde-1523-785feabcd123)
   Client Characteristic Configuration (0x2902)
- LED [W] (00001525-1212-efde-1523-785feabcd123)
Link Loss (0x1803)
- Alert Level [R W] (0x2A06)
Tx Power (0x1804)
- Tx Power Level [R] (0x2A07)
D	14:10:13.104	gatt.setCharacteristicNotification(00002a05-0000-1000-8000-00805f9b34fb, true)
D	14:10:13.107	gatt.setCharacteristicNotification(00002a19-0000-1000-8000-00805f9b34fb, true)
D	14:10:13.109	gatt.setCharacteristicNotification(00002b2c-0000-1000-8000-00805f9b34fb, true)
D	14:10:13.110	gatt.setCharacteristicNotification(00001524-1212-efde-1523-785feabcd123, true)
D	14:10:14.288	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDING (11)
D	14:10:14.296	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDED (12)
I	14:10:14.297	Device bonded
I	14:10:14.708	Connection parameters updated (interval: 7.5ms, latency: 0, timeout: 5000ms)
I	14:10:18.971	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 0A
A	14:10:18.971	"10%" received
I	14:10:19.023	Connection parameters updated (interval: 45.0ms, latency: 0, timeout: 420ms)
D	14:10:20.199	[Callback] Connection state changed with status: 8 and new state: DISCONNECTED (0)
E	14:10:20.199	Error 8 (0x8): GATT CONN TIMEOUT
I	14:10:20.199	Disconnected
D	14:10:20.228	[Broadcast] Action received: android.bluetooth.device.action.ACL_DISCONNECTED
D	14:10:20.338	[Broadcast] Action received: android.bluetooth.device.action.ACL_CONNECTED
D	14:10:20.338	gatt.close()
D	14:10:20.349	wait(200)
V	14:10:20.551	Connecting to E3:09:89:D2:8A:8C...
D	14:10:20.552	gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D	14:10:20.566	[Callback] Connection state changed with status: 0 and new state: CONNECTED (2)
I	14:10:20.566	Connected to E3:09:89:D2:8A:8C
D	14:10:20.567	wait(1600ms)
I	14:10:22.133	PHY updated (TX: LE 2M, RX: LE 2M)
V	14:10:22.167	Discovering services...
D	14:10:22.168	gatt.discoverServices()
D	14:10:22.178	[Callback] Services discovered with status: 0
I	14:10:22.178	Services discovered
V	14:10:22.207	Generic Attribute (0x1801)
- Service Changed [I] (0x2A05)
   Client Characteristic Configuration (0x2902)
- Client Supported Features [R W] (0x2B29)
- Database Hash [R] (0x2B2A)
Generic Access (0x1800)
- Device Name [R] (0x2A00)
- Appearance [R] (0x2A01)
- Peripheral Preferred Connection Parameters [R] (0x2A04)
Battery Service (0x180F)
- Battery Level [N R] (0x2A19)
   Client Characteristic Configuration (0x2902)
Bond Management (0x181E)
- Bond Management Control Point [W] (0x2AA4)
- Bond Management Feature [R] (0x2AA5)
Binary Sensor (0x183B)
- BSS Control Point [W WNR] (0x2B2B)
   Client Characteristic Configuration (0x2902)
- BSS Response [I R] (0x2B2C)
   Client Characteristic Configuration (0x2902)
   Characteristic User Description (0x2901)
Device Information (0x180A)
- Model Number String [R] (0x2A24)
- Manufacturer Name String [R] (0x2A29)
- Firmware Revision String [R] (0x2A26)
- Hardware Revision String [R] (0x2A27)
Nordic LED Button Service (00001523-1212-efde-1523-785feabcd123)
- Button [N R] (00001524-1212-efde-1523-785feabcd123)
   Client Characteristic Configuration (0x2902)
- LED [W] (00001525-1212-efde-1523-785feabcd123)
Link Loss (0x1803)
- Alert Level [R W] (0x2A06)
Tx Power (0x1804)
- Tx Power Level [R] (0x2A07)
D	14:10:22.207	gatt.setCharacteristicNotification(00002a05-0000-1000-8000-00805f9b34fb, true)
D	14:10:22.209	gatt.setCharacteristicNotification(00002a19-0000-1000-8000-00805f9b34fb, true)
D	14:10:22.211	gatt.setCharacteristicNotification(00002b2c-0000-1000-8000-00805f9b34fb, true)
D	14:10:22.212	gatt.setCharacteristicNotification(00001524-1212-efde-1523-785feabcd123, true)
D	14:10:23.284	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDING (11)
D	14:10:23.295	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDED (12)
I	14:10:23.296	Device bonded
I	14:10:23.711	Connection parameters updated (interval: 7.5ms, latency: 0, timeout: 5000ms)
I	14:10:26.368	Connection parameters updated (interval: 45.0ms, latency: 0, timeout: 420ms)
I	14:10:29.043	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 00
A	14:10:29.043	"0%" received
I	14:10:29.044	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 64, "d"
A	14:10:29.044	"100%" received
I	14:10:39.030	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 5A, "Z"
A	14:10:39.030	"90%" received
I	14:10:49.031	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 50, "P"
A	14:10:49.032	"80%" received
I	14:10:59.008	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 46, "F"
A	14:10:59.008	"70%" received
I	14:11:08.999	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 3C, "<"
A	14:11:08.999	"60%" received
I	14:11:18.988	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 32, "2"
A	14:11:18.988	"50%" received
I	14:11:28.979	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 28, "("
A	14:11:28.979	"40%" received
I	14:11:38.968	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 1E
A	14:11:38.968	"30%" received
I	14:11:49.004	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 14
A	14:11:49.004	"20%" received
I	14:11:59.067	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 0A
A	14:11:59.067	"10%" received
I	14:12:09.033	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 00
A	14:12:09.033	"0%" received
I	14:12:09.033	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 64, "d"
A	14:12:09.033	"100%" received
I	14:12:19.034	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 5A, "Z"
A	14:12:19.034	"90%" received
I	14:12:29.067	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 50, "P"
A	14:12:29.067	"80%" received
I	14:12:39.052	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 46, "F"
A	14:12:39.052	"70%" received
I	14:12:49.047	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 3C, "<"
A	14:12:49.047	"60%" received
I	14:12:58.980	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 32, "2"
A	14:12:58.980	"50%" received
I	14:13:09.028	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 28, "("
A	14:13:09.028	"40%" received
I	14:13:19.063	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 1E
A	14:13:19.063	"30%" received
I	14:13:29.052	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 14
A	14:13:29.052	"20%" received
I	14:13:39.044	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 0A
A	14:13:39.044	"10%" received
I	14:13:49.030	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 00
A	14:13:49.030	"0%" received
I	14:13:49.035	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 64, "d"
A	14:13:49.035	"100%" received
I	14:13:59.197	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 5A, "Z"
A	14:13:59.197	"90%" received
D	14:14:07.863	[Callback] Connection state changed with status: 8 and new state: DISCONNECTED (0)
E	14:14:07.863	Error 8 (0x8): GATT CONN TIMEOUT
I	14:14:07.863	Disconnected
D	14:14:07.885	[Broadcast] Action received: android.bluetooth.device.action.ACL_DISCONNECTED
D	14:23:36.599	[Broadcast] Action received: android.bluetooth.device.action.ACL_CONNECTED
D	14:23:36.600	gatt.close()
D	14:23:36.644	wait(200)
V	14:23:36.848	Connecting to E3:09:89:D2:8A:8C...
D	14:23:36.848	gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D	14:23:36.860	[Callback] Connection state changed with status: 0 and new state: CONNECTED (2)
I	14:23:36.860	Connected to E3:09:89:D2:8A:8C
D	14:23:36.868	wait(1600ms)
I	14:23:37.033	PHY updated (TX: LE 2M, RX: LE 2M)
D	14:23:37.717	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDING (11)
D	14:23:37.728	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDED (12)
I	14:23:37.728	Device bonded
I	14:23:38.156	Connection parameters updated (interval: 7.5ms, latency: 0, timeout: 5000ms)
V	14:23:38.469	Discovering services...
D	14:23:38.469	gatt.discoverServices()
D	14:23:39.333	[Callback] Services discovered with status: 0
I	14:23:39.333	Services discovered
V	14:23:39.366	Generic Attribute (0x1801)
- Service Changed [I] (0x2A05)
   Client Characteristic Configuration (0x2902)
- Client Supported Features [R W] (0x2B29)
- Database Hash [R] (0x2B2A)
Generic Access (0x1800)
- Device Name [R] (0x2A00)
- Appearance [R] (0x2A01)
- Peripheral Preferred Connection Parameters [R] (0x2A04)
Battery Service (0x180F)
- Battery Level [N R] (0x2A19)
   Client Characteristic Configuration (0x2902)
Bond Management (0x181E)
- Bond Management Control Point [W] (0x2AA4)
- Bond Management Feature [R] (0x2AA5)
Binary Sensor (0x183B)
- BSS Control Point [W WNR] (0x2B2B)
   Client Characteristic Configuration (0x2902)
- BSS Response [I R] (0x2B2C)
   Client Characteristic Configuration (0x2902)
   Characteristic User Description (0x2901)
Device Information (0x180A)
- Model Number String [R] (0x2A24)
- Manufacturer Name String [R] (0x2A29)
- Firmware Revision String [R] (0x2A26)
- Hardware Revision String [R] (0x2A27)
Nordic LED Button Service (00001523-1212-efde-1523-785feabcd123)
- Button [N R] (00001524-1212-efde-1523-785feabcd123)
   Client Characteristic Configuration (0x2902)
- LED [W] (00001525-1212-efde-1523-785feabcd123)
Link Loss (0x1803)
- Alert Level [R W] (0x2A06)
Tx Power (0x1804)
- Tx Power Level [R] (0x2A07)
D	14:23:39.367	gatt.setCharacteristicNotification(00002a05-0000-1000-8000-00805f9b34fb, true)
D	14:23:39.369	gatt.setCharacteristicNotification(00002a19-0000-1000-8000-00805f9b34fb, true)
D	14:23:39.370	gatt.setCharacteristicNotification(00002b2c-0000-1000-8000-00805f9b34fb, true)
D	14:23:39.372	gatt.setCharacteristicNotification(00001524-1212-efde-1523-785feabcd123, true)
I	14:23:39.422	Connection parameters updated (interval: 45.0ms, latency: 0, timeout: 5000ms)
I	14:23:41.885	Connection parameters updated (interval: 45.0ms, latency: 0, timeout: 420ms)
D	14:23:48.863	[Callback] Connection state changed with status: 8 and new state: DISCONNECTED (0)
E	14:23:48.864	Error 8 (0x8): GATT CONN TIMEOUT
I	14:23:48.864	Disconnected
D	14:23:48.918	[Broadcast] Action received: android.bluetooth.device.action.ACL_DISCONNECTED
D	14:23:56.458	[Broadcast] Action received: android.bluetooth.device.action.ACL_CONNECTED
D	14:23:56.458	gatt.close()
D	14:23:56.483	wait(200)
V	14:23:56.686	Connecting to E3:09:89:D2:8A:8C...
D	14:23:56.687	gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D	14:23:56.696	[Callback] Connection state changed with status: 0 and new state: CONNECTED (2)
I	14:23:56.696	Connected to E3:09:89:D2:8A:8C
D	14:23:56.697	wait(1600ms)
I	14:23:56.892	PHY updated (TX: LE 2M, RX: LE 2M)
D	14:23:57.990	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDING (11)
D	14:23:57.993	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDED (12)
I	14:23:57.993	Device bonded
V	14:23:58.298	Discovering services...
D	14:23:58.298	gatt.discoverServices()
D	14:23:58.309	[Callback] Services discovered with status: 0
I	14:23:58.309	Services discovered
V	14:23:58.334	Generic Attribute (0x1801)
- Service Changed [I] (0x2A05)
   Client Characteristic Configuration (0x2902)
- Client Supported Features [R W] (0x2B29)
- Database Hash [R] (0x2B2A)
Generic Access (0x1800)
- Device Name [R] (0x2A00)
- Appearance [R] (0x2A01)
- Peripheral Preferred Connection Parameters [R] (0x2A04)
Battery Service (0x180F)
- Battery Level [N R] (0x2A19)
   Client Characteristic Configuration (0x2902)
Bond Management (0x181E)
- Bond Management Control Point [W] (0x2AA4)
- Bond Management Feature [R] (0x2AA5)
Binary Sensor (0x183B)
- BSS Control Point [W WNR] (0x2B2B)
   Client Characteristic Configuration (0x2902)
- BSS Response [I R] (0x2B2C)
   Client Characteristic Configuration (0x2902)
   Characteristic User Description (0x2901)
Device Information (0x180A)
- Model Number String [R] (0x2A24)
- Manufacturer Name String [R] (0x2A29)
- Firmware Revision String [R] (0x2A26)
- Hardware Revision String [R] (0x2A27)
Nordic LED Button Service (00001523-1212-efde-1523-785feabcd123)
- Button [N R] (00001524-1212-efde-1523-785feabcd123)
   Client Characteristic Configuration (0x2902)
- LED [W] (00001525-1212-efde-1523-785feabcd123)
Link Loss (0x1803)
- Alert Level [R W] (0x2A06)
Tx Power (0x1804)
- Tx Power Level [R] (0x2A07)
D	14:23:58.334	gatt.setCharacteristicNotification(00002a05-0000-1000-8000-00805f9b34fb, true)
D	14:23:58.336	gatt.setCharacteristicNotification(00002a19-0000-1000-8000-00805f9b34fb, true)
D	14:23:58.337	gatt.setCharacteristicNotification(00002b2c-0000-1000-8000-00805f9b34fb, true)
D	14:23:58.339	gatt.setCharacteristicNotification(00001524-1212-efde-1523-785feabcd123, true)
I	14:23:58.430	Connection parameters updated (interval: 7.5ms, latency: 0, timeout: 5000ms)
I	14:23:58.963	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 5A, "Z"
A	14:23:58.965	"90%" received
D	14:24:08.920	[Callback] Connection state changed with status: 8 and new state: DISCONNECTED (0)
E	14:24:08.921	Error 8 (0x8): GATT CONN TIMEOUT
I	14:24:08.921	Disconnected
D	14:24:08.944	[Broadcast] Action received: android.bluetooth.device.action.ACL_DISCONNECTED
D	14:24:18.989	[Broadcast] Action received: android.bluetooth.device.action.ACL_CONNECTED
D	14:24:18.989	gatt.close()
D	14:24:18.998	wait(200)
V	14:24:19.202	Connecting to E3:09:89:D2:8A:8C...
D	14:24:19.202	gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D	14:24:19.216	[Callback] Connection state changed with status: 0 and new state: CONNECTED (2)
I	14:24:19.216	Connected to E3:09:89:D2:8A:8C
D	14:24:19.218	wait(1600ms)
I	14:24:19.468	PHY updated (TX: LE 2M, RX: LE 2M)
D	14:24:20.693	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDING (11)
D	14:24:20.698	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDED (12)
I	14:24:20.699	Device bonded
V	14:24:20.819	Discovering services...
D	14:24:20.819	gatt.discoverServices()
D	14:24:20.830	[Callback] Services discovered with status: 0
I	14:24:20.830	Services discovered
V	14:24:20.844	Generic Attribute (0x1801)
- Service Changed [I] (0x2A05)
   Client Characteristic Configuration (0x2902)
- Client Supported Features [R W] (0x2B29)
- Database Hash [R] (0x2B2A)
Generic Access (0x1800)
- Device Name [R] (0x2A00)
- Appearance [R] (0x2A01)
- Peripheral Preferred Connection Parameters [R] (0x2A04)
Battery Service (0x180F)
- Battery Level [N R] (0x2A19)
   Client Characteristic Configuration (0x2902)
Bond Management (0x181E)
- Bond Management Control Point [W] (0x2AA4)
- Bond Management Feature [R] (0x2AA5)
Binary Sensor (0x183B)
- BSS Control Point [W WNR] (0x2B2B)
   Client Characteristic Configuration (0x2902)
- BSS Response [I R] (0x2B2C)
   Client Characteristic Configuration (0x2902)
   Characteristic User Description (0x2901)
Device Information (0x180A)
- Model Number String [R] (0x2A24)
- Manufacturer Name String [R] (0x2A29)
- Firmware Revision String [R] (0x2A26)
- Hardware Revision String [R] (0x2A27)
Nordic LED Button Service (00001523-1212-efde-1523-785feabcd123)
- Button [N R] (00001524-1212-efde-1523-785feabcd123)
   Client Characteristic Configuration (0x2902)
- LED [W] (00001525-1212-efde-1523-785feabcd123)
Link Loss (0x1803)
- Alert Level [R W] (0x2A06)
Tx Power (0x1804)
- Tx Power Level [R] (0x2A07)
D	14:24:20.844	gatt.setCharacteristicNotification(00002a05-0000-1000-8000-00805f9b34fb, true)
D	14:24:20.845	gatt.setCharacteristicNotification(00002a19-0000-1000-8000-00805f9b34fb, true)
D	14:24:20.846	gatt.setCharacteristicNotification(00002b2c-0000-1000-8000-00805f9b34fb, true)
D	14:24:20.847	gatt.setCharacteristicNotification(00001524-1212-efde-1523-785feabcd123, true)
I	14:24:21.134	Connection parameters updated (interval: 7.5ms, latency: 0, timeout: 5000ms)
I	14:24:23.833	Connection parameters updated (interval: 45.0ms, latency: 0, timeout: 5000ms)
I	14:24:24.335	Connection parameters updated (interval: 45.0ms, latency: 0, timeout: 420ms)
D	14:24:25.532	[Callback] Connection state changed with status: 8 and new state: DISCONNECTED (0)
E	14:24:25.532	Error 8 (0x8): GATT CONN TIMEOUT
I	14:24:25.532	Disconnected
D	14:24:25.545	[Broadcast] Action received: android.bluetooth.device.action.ACL_DISCONNECTED
D	14:24:27.844	[Broadcast] Action received: android.bluetooth.device.action.ACL_CONNECTED
D	14:24:27.844	gatt.close()
D	14:24:27.853	wait(200)
V	14:24:28.061	Connecting to E3:09:89:D2:8A:8C...
D	14:24:28.062	gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D	14:24:28.071	[Callback] Connection state changed with status: 0 and new state: CONNECTED (2)
I	14:24:28.071	Connected to E3:09:89:D2:8A:8C
D	14:24:28.072	wait(1600ms)
I	14:24:28.423	PHY updated (TX: LE 2M, RX: LE 2M)
D	14:24:29.247	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDING (11)
D	14:24:29.257	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_BONDED (12)
I	14:24:29.257	Device bonded
V	14:24:29.672	Discovering services...
D	14:24:29.672	gatt.discoverServices()
I	14:24:29.683	Connection parameters updated (interval: 7.5ms, latency: 0, timeout: 5000ms)
D	14:24:31.426	[Callback] Services discovered with status: 0
I	14:24:31.426	Services discovered
V	14:24:31.447	Generic Attribute (0x1801)
- Service Changed [I] (0x2A05)
   Client Characteristic Configuration (0x2902)
- Client Supported Features [R W] (0x2B29)
- Database Hash [R] (0x2B2A)
Generic Access (0x1800)
- Device Name [R] (0x2A00)
- Appearance [R] (0x2A01)
- Peripheral Preferred Connection Parameters [R] (0x2A04)
Battery Service (0x180F)
- Battery Level [N R] (0x2A19)
   Client Characteristic Configuration (0x2902)
Bond Management (0x181E)
- Bond Management Control Point [W] (0x2AA4)
- Bond Management Feature [R] (0x2AA5)
Binary Sensor (0x183B)
- BSS Control Point [W WNR] (0x2B2B)
   Client Characteristic Configuration (0x2902)
- BSS Response [I R] (0x2B2C)
   Client Characteristic Configuration (0x2902)
   Characteristic User Description (0x2901)
Device Information (0x180A)
- Model Number String [R] (0x2A24)
- Manufacturer Name String [R] (0x2A29)
- Firmware Revision String [R] (0x2A26)
- Hardware Revision String [R] (0x2A27)
Nordic LED Button Service (00001523-1212-efde-1523-785feabcd123)
- Button [N R] (00001524-1212-efde-1523-785feabcd123)
   Client Characteristic Configuration (0x2902)
- LED [W] (00001525-1212-efde-1523-785feabcd123)
Link Loss (0x1803)
- Alert Level [R W] (0x2A06)
Tx Power (0x1804)
- Tx Power Level [R] (0x2A07)
D	14:24:31.447	gatt.setCharacteristicNotification(00002a05-0000-1000-8000-00805f9b34fb, true)
D	14:24:31.449	gatt.setCharacteristicNotification(00002a19-0000-1000-8000-00805f9b34fb, true)
D	14:24:31.450	gatt.setCharacteristicNotification(00002b2c-0000-1000-8000-00805f9b34fb, true)
D	14:24:31.451	gatt.setCharacteristicNotification(00001524-1212-efde-1523-785feabcd123, true)
I	14:24:31.534	Connection parameters updated (interval: 45.0ms, latency: 0, timeout: 5000ms)
I	14:24:33.239	Connection parameters updated (interval: 45.0ms, latency: 0, timeout: 420ms)
I	14:24:39.022	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 32, "2"
A	14:24:39.022	"50%" received
I	14:24:49.010	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 28, "("
A	14:24:49.010	"40%" received
I	14:24:58.999	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 1E
A	14:24:58.999	"30%" received
I	14:25:08.999	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 14
A	14:25:08.999	"20%" received
I	14:25:18.982	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 0A
A	14:25:18.982	"10%" received
I	14:25:29.019	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 00
A	14:25:29.019	"0%" received
I	14:25:29.026	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 64, "d"
A	14:25:29.026	"100%" received
I	14:25:39.012	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 5A, "Z"
A	14:25:39.012	"90%" received
I	14:25:48.996	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 50, "P"
A	14:25:48.996	"80%" received
I	14:25:58.994	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 46, "F"
A	14:25:58.994	"70%" received
I	14:26:09.000	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 3C, "<"
A	14:26:09.000	"60%" received
I	14:26:19.006	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 32, "2"
A	14:26:19.006	"50%" received
I	14:26:28.978	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 28, "("
A	14:26:28.978	"40%" received
I	14:26:38.967	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 1E
A	14:26:38.967	"30%" received
I	14:26:49.122	Notification received from 00002a19-0000-1000-8000-00805f9b34fb, value: (0x) 14
A	14:26:49.123	"20%" received
D	14:26:58.389	[Callback] Connection state changed with status: 8 and new state: DISCONNECTED (0)
E	14:26:58.389	Error 8 (0x8): GATT CONN TIMEOUT
I	14:26:58.389	Disconnected
D	14:26:58.429	[Broadcast] Action received: android.bluetooth.device.action.ACL_DISCONNECTED
D	14:26:59.547	[Broadcast] Action received: android.bluetooth.device.action.ACL_CONNECTED
D	14:26:59.547	gatt.close()
D	14:26:59.567	wait(200)
V	14:26:59.769	Connecting to E3:09:89:D2:8A:8C...
D	14:26:59.769	gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D	14:26:59.788	[Callback] Connection state changed with status: 0 and new state: CONNECTED (2)
I	14:26:59.789	Connected to E3:09:89:D2:8A:8C
D	14:26:59.790	wait(1600ms)
I	14:27:00.072	PHY updated (TX: LE 2M, RX: LE 2M)
V	14:27:01.391	Discovering services...
D	14:27:01.391	gatt.discoverServices()
D	14:27:01.399	[Callback] Services discovered with status: 0
I	14:27:01.399	Services discovered
V	14:27:01.413	Generic Attribute (0x1801)
- Service Changed [I] (0x2A05)
   Client Characteristic Configuration (0x2902)
- Client Supported Features [R W] (0x2B29)
- Database Hash [R] (0x2B2A)
Generic Access (0x1800)
- Device Name [R] (0x2A00)
- Appearance [R] (0x2A01)
- Peripheral Preferred Connection Parameters [R] (0x2A04)
Battery Service (0x180F)
- Battery Level [N R] (0x2A19)
   Client Characteristic Configuration (0x2902)
Bond Management (0x181E)
- Bond Management Control Point [W] (0x2AA4)
- Bond Management Feature [R] (0x2AA5)
Binary Sensor (0x183B)
- BSS Control Point [W WNR] (0x2B2B)
   Client Characteristic Configuration (0x2902)
- BSS Response [I R] (0x2B2C)
   Client Characteristic Configuration (0x2902)
   Characteristic User Description (0x2901)
Device Information (0x180A)
- Model Number String [R] (0x2A24)
- Manufacturer Name String [R] (0x2A29)
- Firmware Revision String [R] (0x2A26)
- Hardware Revision String [R] (0x2A27)
Nordic LED Button Service (00001523-1212-efde-1523-785feabcd123)
- Button [N R] (00001524-1212-efde-1523-785feabcd123)
   Client Characteristic Configuration (0x2902)
- LED [W] (00001525-1212-efde-1523-785feabcd123)
Link Loss (0x1803)
- Alert Level [R W] (0x2A06)
Tx Power (0x1804)
- Tx Power Level [R] (0x2A07)
D	14:27:01.413	gatt.setCharacteristicNotification(00002a05-0000-1000-8000-00805f9b34fb, true)
D	14:27:01.414	gatt.setCharacteristicNotification(00002a19-0000-1000-8000-00805f9b34fb, true)
D	14:27:01.415	gatt.setCharacteristicNotification(00002b2c-0000-1000-8000-00805f9b34fb, true)
D	14:27:01.416	gatt.setCharacteristicNotification(00001524-1212-efde-1523-785feabcd123, true)
W	14:27:04.973	Connection parameters update failed with status 8 (interval: 45.0ms, latency: 0, timeout: 5000ms)
E	14:27:04.973	Error 8 (0x8): GATT INSUF AUTHORIZATION
D	14:27:04.987	[Callback] Connection state changed with status: 8 and new state: DISCONNECTED (0)
E	14:27:04.987	Error 8 (0x8): GATT CONN TIMEOUT
I	14:27:04.987	Disconnected
D	14:27:04.988	[Broadcast] Action received: android.bluetooth.device.action.BOND_STATE_CHANGED, bond state changed to: BOND_NONE (10)
I	14:27:04.988	Bond information deleted
D	14:27:05.017	[Broadcast] Action received: android.bluetooth.device.action.ACL_DISCONNECTED

[Kenneth]

I was thinking of the bt_conn_set_security() in bt_gatt_connected(), so if you could comment out that line in gatt.c that would be helpful.

Could you also share what phones you are testing with here?

Thanks,
Kenneth

[Poorp]

We have tested with the following phones: Pixel, Nexus 6P, and Pixel 4a

Our testing seems to indicate that commenting out that line solved the problem. Thanks a lot! I'm really happy to have this issue 'solved'!

So I have the following questions, ordered in order of priority if you don't have time to answer them all

1. Is it safe to leave this line commented out in a shipping product?

2. Will you release a patch for this with the next nrf connect sdk and if so do you have an estimate for when that will be released?

3. Could you explain why this solved our issue? I could find nothing in the bluetooth specification indicating that one has to wait for a specific time interval before sending the pairing request, rather the opposite.
The only part I could find that I think maybe could be the cause of this issue is

2.4.6 Slave Security Request
[...]
If pairing or encryption mode is not supported or cannot be initiated at the time
when the slave’s Security Request command is received, then the master shall
respond with a Pairing Failed command with the reason set to “Pairing Not
Supported.”

4. Do you know of any good resources where one can read up on these types of peculiarities? I found the following document from apple, are there other good documents out there?
https://developer.apple.com/accessories/Accessory-Design-Guidelines.pdf

[Kenneth]

Poorp said:

1. Is it safe to leave this line commented out in a shipping product?

Many choose to remove the security request on the nRF5 SDK, so I don't see any problem doing the same for ncs. You may consider adding a timer, such that if the link is not encrypted within let's say a few seconds you can call the bt_conn_set_security() from the application. However this should already be handled by the peer (central device in this case).

Poorp said:

2. Will you release a patch for this with the next nrf connect sdk and if so do you have an estimate for when that will be released?

 They are still discussing if this will make it into next release or not, I don't have any date yet. 

Poorp said:

3. Could you explain why this solved our issue?

It must be race condition in the peer (e.g. some events are passed in an order the host does not handle well), there is nothing in the spec that should prevent this to work. 

Poorp said:

4. Do you know of any good resources where one can read up on these types of peculiarities? I found the following document from apple, are there other good documents out there?

The apple guidelines should be good, since that seem to work and tested by everyone. Though I am not aware of any similar guidelines for other vendors or manufacturers.

Kenneth

[Poorp]

We have now done extensive testing, and this definitely solved the problem.

I just wanted to thank you Kenneth for your help, we are very grateful.

The fact that nordic is always very helpful in my experience, combined with nordics support for OSS projects such as zephyr means that I will always steer projects to use nordic chips as opposed to a competing chipset whenever possible.

Thanks again!