* Issue: Bluez: Bluetooth Mouse Disconnects Soon After Pairing
@ 2016-09-11 3:06 Naveen Kaje
2016-09-11 16:59 ` Luiz Augusto von Dentz
0 siblings, 1 reply; 7+ messages in thread
From: Naveen Kaje @ 2016-09-11 3:06 UTC (permalink / raw)
To: me; +Cc: linux-bluetooth
Hello,
I am seeing a disconnection behavior with Bluetooth mouse (Microsoft
Desginer Mouse). Software Baseline 3.18 based kernel and Bluez 5.41.
The device is discovered with bluetoothctl. The mouse pairs briefly (for
about 30 seconds) and then the host disconnects.
Bluetoothctl error reads Failed to pair:
org.bluez.Error.AuthenticationCanceled
Observations:
1) The time it takes for the mouse to stop working is about 30 seconds
since the pair command. This time is consistent.
2) The btmon report says Reason: Remote User Terminated Connection (0x13),
which I found to be not entirely true.
I hacked the transfport layer (UART) in Kernel to skip sending
disconnection message which resulted in the Mouse device
continuing to send reports even after this point (Observed on btmon).
Bluetoothctl and btmon logs are shown below. What could I be missing?
Thanks in advance for the help.
******************** Bluetoothctl log begin ****************************
[bluetooth]# show
Controller 44:1C:A8:6C:8F:5E
Name: BTHost
Alias: BTHost
Class: 0x000000
Powered: yes
Discoverable: no
Pairable: yes
UUID: Generic Attribute Profile
(00001801-0000-1000-8000-00805f9b34fb)
UUID: A/V Remote Control
(0000110e-0000-1000-8000-00805f9b34fb)
UUID: PnP Information
(00001200-0000-1000-8000-00805f9b34fb)
UUID: Generic Access Profile
(00001800-0000-1000-8000-00805f9b34fb)
UUID: A/V Remote Control Target
(0000110c-0000-1000-8000-00805f9b34fb)
Modalias: usb:v1D6Bp0246d0529
[bluetooth]# discoverable on
Changing discoverable on succeeded
[bluetooth]# pairable on
Changing pairable on succeeded
[bluetooth]# scan on
Discovery started
[CHG] Controller 44:1C:A8:6C:8F:5E Discovering: yes
[NEW] Device F5:95:EC:5D:0F:D3 Designer Mouse
[bluetooth]# pair F5:95:EC:5D:0F:D3
Attempting to pair with F5:95:EC:5D:0F:D3
[CHG] Device F5:95:EC:5D:0F:D3 Connected: yes
[CHG] Device F5:95:EC:5D:0F:D3 UUIDs: 00001800-0000-1000-8000-00805f9b34fb
[CHG] Device F5:95:EC:5D:0F:D3 UUIDs: 00001801-0000-1000-8000-00805f9b34fb
[CHG] Device F5:95:EC:5D:0F:D3 UUIDs: 0000180a-0000-1000-8000-00805f9b34fb
[CHG] Device F5:95:EC:5D:0F:D3 UUIDs: 0000180f-0000-1000-8000-00805f9b34fb
[CHG] Device F5:95:EC:5D:0F:D3 UUIDs: 00001812-0000-1000-8000-00805f9b34fb
[CHG] Device F5:95:EC:5D:0F:D3 Modalias: usb:v045Ep0805d0110
[Designer Mouse]# info
Device F5:95:EC:5D:0F:D3
Name: Designer Mouse
Alias: Designer Mouse
Appearance: 0x03c2
Icon: input-mouse
Paired: no
Trusted: no
Blocked: no
Connected: yes
LegacyPairing: no
UUID: Generic Access Profile
(00001800-0000-1000-8000-00805f9b34fb)
UUID: Generic Attribute Profile
(00001801-0000-1000-8000-00805f9b34fb)
UUID: Device Information
(0000180a-0000-1000-8000-00805f9b34fb)
UUID: Battery Service
(0000180f-0000-1000-8000-00805f9b34fb)
UUID: Human Interface Device
(00001812-0000-1000-8000-00805f9b34fb)
Modalias: usb:v045Ep0805d0110
RSSI: -91
Failed to pair: org.bluez.Error.AuthenticationCanceled
[CHG] Controller 44:1C:A8:6C:8F:5E Discoverable: no
********************* Bluetoothctl log end *****************************
********************* btmon log begin **********************************
... snip ...
< HCI Command: LE Set Advertise Enable (0x08|0x000a) plen 1 [hci0]
59.630036
Advertising: Disabled (0x00)
> HCI Event: Command Complete (0x0e) plen 4 [hci0]
59.636835
LE Set Advertise Enable (0x08|0x000a) ncmd 1
Status: Success (0x00)
< HCI Command: LE Set Scan Parameters (0x08|0x000b) plen 7 [hci0]
59.636902
Type: Active (0x01)
Interval: 11.250 msec (0x0012)
Window: 11.250 msec (0x0012)
Own address type: Random (0x01)
Filter policy: Accept all advertisement (0x00)
> HCI Event: Command Complete (0x0e) plen 4 [hci0]
59.641740
LE Set Scan Parameters (0x08|0x000b) ncmd 1
Status: Success (0x00)
< HCI Command: LE Set Scan Enable (0x08|0x000c) plen 2 [hci0]
59.641816
Scanning: Enabled (0x01)
Filter duplicates: Enabled (0x01)
> HCI Event: Command Complete (0x0e) plen 4 [hci0]
59.646217
LE Set Scan Enable (0x08|0x000c) ncmd 1
Status: Success (0x00)
@ Discovering: 0x01 (7)
> HCI Event: LE Meta Event (0x3e) plen 39 [hci0]
59.725430
LE Advertising Report (0x02)
Num reports: 1
Event type: Connectable undirected - ADV_IND (0x00)
Address type: Random (0x01)
Address: F5:95:EC:5D:0F:D3 (Static)
Data length: 27
Name (complete): Designer Mouse
Appearance: Mouse (0x03c2)
Flags: 0x05
LE Limited Discoverable Mode
BR/EDR Not Supported
16-bit Service UUIDs (complete): 1 entry
Human Interface Device (0x1812)
RSSI: -91 dBm (0xa5)
> HCI Event: LE Meta Event (0x3e) plen 12 [hci0]
59.725446
LE Advertising Report (0x02)
Num reports: 1
Event type: Scan response - SCAN_RSP (0x04)
Address type: Random (0x01)
Address: F5:95:EC:5D:0F:D3 (Static)
Data length: 0
RSSI: -91 dBm (0xa5)
@ Device Found: F5:95:EC:5D:0F:D3 (2) rssi -91 flags 0x0000
0f 09 44 65 73 69 67 6e 65 72 20 4d 6f 75 73 65 ..Designer Mouse
03 19 c2 03 02 01 05 03 03 12 18 ...........
< HCI Command: LE Set Scan Enable (0x08|0x000c) plen 2 [hci0]
64.768705
Scanning: Disabled (0x00)
Filter duplicates: Disabled (0x00)
> HCI Event: Command Complete (0x0e) plen 4 [hci0]
64.775294
LE Set Scan Enable (0x08|0x000c) ncmd 1
Status: Success (0x00)
< HCI Command: Inquiry (0x01|0x0001) plen 5 [hci0]
64.775373
Access code: 0x9e8b33 (General Inquiry)
Length: 5.12s (0x04)
Num responses: 0
> HCI Event: Command Status (0x0f) plen 4 [hci0]
64.780135
Inquiry (0x01|0x0001) ncmd 1
Status: Success (0x00)
< HCI Command: Inquiry Cancel (0x01|0x0002) plen 0 [hci0]
68.459476
> HCI Event: Command Complete (0x0e) plen 4 [hci0]
68.466205
Inquiry Cancel (0x01|0x0002) ncmd 1
Status: Success (0x00)
@ Discovering: 0x00 (7)
< HCI Command: LE Create Connection (0x08|0x000d) plen 25 [hci0]
68.466479
Scan interval: 60.000 msec (0x0060)
Scan window: 30.000 msec (0x0030)
Filter policy: White list is not used (0x00)
Peer address type: Random (0x01)
Peer address: F5:95:EC:5D:0F:D3 (Static)
Own address type: Public (0x00)
Min connection interval: 50.00 msec (0x0028)
Max connection interval: 70.00 msec (0x0038)
Connection latency: 0x0000
Supervision timeout: 420 msec (0x002a)
Min connection length: 0.000 msec (0x0000)
Max connection length: 0.000 msec (0x0000)
> HCI Event: Command Status (0x0f) plen 4 [hci0]
68.473054
LE Create Connection (0x08|0x000d) ncmd 1
Status: Success (0x00)
> HCI Event: LE Meta Event (0x3e) plen 19 [hci0]
68.488954
LE Connection Complete (0x01)
Status: Success (0x00)
Handle: 1
Role: Master (0x00)
Peer address type: Random (0x01)
Peer address: F5:95:EC:5D:0F:D3 (Static)
Connection interval: 67.50 msec (0x0036)
Connection latency: 0.00 msec (0x0000)
Supervision timeout: 420 msec (0x002a)
Master clock accuracy: 0x00
@ Device Connected: F5:95:EC:5D:0F:D3 (2) flags 0x0000
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
68.489272
SMP: Pairing Request (0x01) len 6
IO capability: NoInputNoOutput (0x03)
OOB data: Authentication data not present (0x00)
Authentication requirement: Bonding, No MITM, Legacy, No Keypresses
(0x01)
Max encryption key size: 16
Initiator key distribution: EncKey Sign (0x05)
Responder key distribution: EncKey IdKey Sign (0x07)
< ACL Data TX: Handle 1 flags 0x00 dlen 7 [hci0]
68.493087
ATT: Exchange MTU Request (0x02) len 2
Client RX MTU: 517
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
68.527412
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 11 [hci0]
68.595889
SMP: Pairing Response (0x02) len 6
IO capability: NoInputNoOutput (0x03)
OOB data: Authentication data not present (0x00)
Authentication requirement: Bonding, No MITM, Legacy, No Keypresses
(0x01)
Max encryption key size: 16
Initiator key distribution: Sign (0x04)
Responder key distribution: EncKey IdKey (0x03)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
68.595909
Num handles: 1
Handle: 1
Count: 1
< ACL Data TX: Handle 1 flags 0x00 dlen 21 [hci0]
68.596024
SMP: Pairing Confirm (0x03) len 16
Confim value: 707320123462a854e5cd118b1a00f1c1
> ACL Data RX: Handle 1 flags 0x02 dlen 7 [hci0]
68.663066
ATT: Exchange MTU Response (0x03) len 2
Server RX MTU: 23
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
68.663082
Num handles: 1
Handle: 1
Count: 1
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
68.663439
ATT: Read By Group Type Request (0x10) len 6
Handle range: 0x0001-0xffff
Attribute group type: Primary Service (0x2800)
> ACL Data RX: Handle 1 flags 0x02 dlen 21 [hci0]
68.731850
SMP: Pairing Confirm (0x03) len 16
Confim value: e6f1f687ecc8c45a249ecbc6f0785a40
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
68.731868
Num handles: 1
Handle: 1
Count: 1
< ACL Data TX: Handle 1 flags 0x00 dlen 21 [hci0]
68.732015
SMP: Pairing Random (0x04) len 16
Random value: 5467954ff3f0295156090e71e844e49f
> ACL Data RX: Handle 1 flags 0x02 dlen 24 [hci0]
68.799826
ATT: Read By Group Type Response (0x11) len 19
Attribute data length: 6
Attribute group list: 3 entries
Handle range: 0x0001-0x0007
UUID: Generic Access Profile (0x1800)
Handle range: 0x0008-0x0008
UUID: Generic Attribute Profile (0x1801)
Handle range: 0x0009-0x000d
UUID: Device Information (0x180a)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
68.799845
Num handles: 1
Handle: 1
Count: 1
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
68.800186
ATT: Read By Group Type Request (0x10) len 6
Handle range: 0x000e-0xffff
Attribute group type: Primary Service (0x2800)
> ACL Data RX: Handle 1 flags 0x02 dlen 21 [hci0]
68.866793
SMP: Pairing Random (0x04) len 16
Random value: 337c2f5aef77c869bfdb794a7bb7822d
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
68.866814
Num handles: 1
Handle: 1
Count: 1
< HCI Command: LE Start Encryption (0x08|0x0019) plen 28 [hci0]
68.866977
Handle: 1
Random number: 0x0000000000000000
Encrypted diversifier: 0x0000
Long term key: a1230fff3c4a7f9cfb937bcc4105bf36
> HCI Event: Command Status (0x0f) plen 4 [hci0]
68.873480
LE Start Encryption (0x08|0x0019) ncmd 1
Status: Success (0x00)
> ACL Data RX: Handle 1 flags 0x02 dlen 18 [hci0]
68.933300
ATT: Read By Group Type Response (0x11) len 13
Attribute data length: 6
Attribute group list: 2 entries
Handle range: 0x000e-0x0011
UUID: Battery Service (0x180f)
Handle range: 0x0012-0xffff
UUID: Human Interface Device (0x1812)
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
68.934710
ATT: Read By Group Type Request (0x10) len 6
Handle range: 0x0001-0xffff
Attribute group type: Secondary Service (0x2801)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
68.999515
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 9 [hci0]
69.067411
ATT: Error Response (0x01) len 4
Read By Group Type Request (0x10)
Handle: 0x0001
Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
69.067732
ATT: Read By Type Request (0x08) len 6
Handle range: 0x0001-0x0007
Attribute type: Include (0x2802)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
69.134511
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 9 [hci0]
69.202622
ATT: Error Response (0x01) len 4
Read By Type Request (0x08)
Handle: 0x0001
Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
69.202972
ATT: Read By Type Request (0x08) len 6
Handle range: 0x0009-0x000d
Attribute type: Include (0x2802)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
69.269695
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 9 [hci0]
69.337568
ATT: Error Response (0x01) len 4
Read By Type Request (0x08)
Handle: 0x0009
Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
69.337909
ATT: Read By Type Request (0x08) len 6
Handle range: 0x000e-0x0011
Attribute type: Include (0x2802)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
69.404491
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 9 [hci0]
69.472447
ATT: Error Response (0x01) len 4
Read By Type Request (0x08)
Handle: 0x000e
Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
69.472738
ATT: Read By Type Request (0x08) len 6
Handle range: 0x0012-0xffff
Attribute type: Include (0x2802)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
69.539509
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 9 [hci0]
69.607424
ATT: Error Response (0x01) len 4
Read By Type Request (0x08)
Handle: 0x0012
Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
69.607734
ATT: Read By Type Request (0x08) len 6
Handle range: 0x0001-0x0007
Attribute type: Characteristic (0x2803)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
69.674436
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 27 [hci0]
69.744145
ATT: Read By Type Response (0x09) len 22
Attribute data length: 7
Attribute data list: 3 entries
Handle: 0x0002
Value: 0a0300002a
Handle: 0x0004
Value: 020500012a
Handle: 0x0006
Value: 020700042a
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
69.744447
ATT: Read By Type Request (0x08) len 6
Handle range: 0x0007-0x0007
Attribute type: Characteristic (0x2803)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
69.809365
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 9 [hci0]
69.877367
ATT: Error Response (0x01) len 4
Read By Type Request (0x08)
Handle: 0x0007
Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
69.877711
ATT: Read By Type Request (0x08) len 6
Handle range: 0x0009-0x000d
Attribute type: Characteristic (0x2803)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
69.944414
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 20 [hci0]
70.013493
ATT: Read By Type Response (0x09) len 15
Attribute data length: 7
Attribute data list: 2 entries
Handle: 0x000a
Value: 020b00292a
Handle: 0x000c
Value: 020d00502a
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
70.013807
ATT: Read By Type Request (0x08) len 6
Handle range: 0x000d-0x000d
Attribute type: Characteristic (0x2803)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
70.079454
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 9 [hci0]
70.147597
ATT: Error Response (0x01) len 4
Read By Type Request (0x08)
Handle: 0x000d
Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
70.147935
ATT: Read By Type Request (0x08) len 6
Handle range: 0x000e-0x0011
Attribute type: Characteristic (0x2803)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
70.214503
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 13 [hci0]
70.282728
ATT: Read By Type Response (0x09) len 8
Attribute data length: 7
Attribute data list: 1 entry
Handle: 0x000f
Value: 121000192a
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
70.283063
ATT: Read By Type Request (0x08) len 6
Handle range: 0x0010-0x0011
Attribute type: Characteristic (0x2803)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
70.349702
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 9 [hci0]
70.417446
ATT: Error Response (0x01) len 4
Read By Type Request (0x08)
Handle: 0x0010
Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
70.417802
ATT: Find Information Request (0x04) len 4
Handle range: 0x0011-0x0011
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
70.484463
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 10 [hci0]
70.552541
ATT: Find Information Response (0x05) len 5
Format: UUID-16 (0x01)
Handle: 0x0011
UUID: Client Characteristic Configuration (0x2902)
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
70.552907
ATT: Read By Type Request (0x08) len 6
Handle range: 0x0012-0xffff
Attribute type: Characteristic (0x2803)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
70.619445
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 27 [hci0]
70.689192
ATT: Read By Type Response (0x09) len 22
Attribute data length: 7
Attribute data list: 3 entries
Handle: 0x0013
Value: 0614004e2a
Handle: 0x0015
Value: 1a16004d2a
Handle: 0x0019
Value: 1a1a004d2a
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
70.689497
ATT: Read By Type Request (0x08) len 6
Handle range: 0x001a-0xffff
Attribute type: Characteristic (0x2803)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
70.754566
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 27 [hci0]
70.824156
ATT: Read By Type Response (0x09) len 22
Attribute data length: 7
Attribute data list: 3 entries
Handle: 0x001d
Value: 0a1e004d2a
Handle: 0x0020
Value: 0a21004d2a
Handle: 0x0023
Value: 0224004b2a
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
70.824470
ATT: Read By Type Request (0x08) len 6
Handle range: 0x0024-0xffff
Attribute type: Characteristic (0x2803)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
70.889443
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 27 [hci0]
70.959020
ATT: Read By Type Response (0x09) len 22
Attribute data length: 7
Attribute data list: 3 entries
Handle: 0x0025
Value: 1a2600332a
Handle: 0x0028
Value: 0229004a2a
Handle: 0x002a
Value: 042b004c2a
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
70.959445
ATT: Read By Type Request (0x08) len 6
Handle range: 0x002b-0xffff
Attribute type: Characteristic (0x2803)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
71.092121
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 9 [hci0]
71.159885
ATT: Error Response (0x01) len 4
Read By Type Request (0x08)
Handle: 0x002b
Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
71.160381
ATT: Find Information Request (0x04) len 4
Handle range: 0x0017-0x0018
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
71.227106
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 14 [hci0]
71.295425
ATT: Find Information Response (0x05) len 9
Format: UUID-16 (0x01)
Handle: 0x0017
UUID: Client Characteristic Configuration (0x2902)
Handle: 0x0018
UUID: Report Reference (0x2908)
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
71.295840
ATT: Find Information Request (0x04) len 4
Handle range: 0x001b-0x001c
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
71.362129
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 14 [hci0]
71.430411
ATT: Find Information Response (0x05) len 9
Format: UUID-16 (0x01)
Handle: 0x001b
UUID: Client Characteristic Configuration (0x2902)
Handle: 0x001c
UUID: Report Reference (0x2908)
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
71.430819
ATT: Find Information Request (0x04) len 4
Handle range: 0x001f-0x001f
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
71.496863
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 10 [hci0]
71.565376
ATT: Find Information Response (0x05) len 5
Format: UUID-16 (0x01)
Handle: 0x001f
UUID: Report Reference (0x2908)
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
71.565662
ATT: Find Information Request (0x04) len 4
Handle range: 0x0022-0x0022
> ACL Data RX: Handle 1 flags 0x02 dlen 21 [hci0]
71.971916
SMP: Encryption Information (0x06) len 16
Long term key: b3e6dfedf5ac5f3f4da735acb21005ab
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
71.971932
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 10 [hci0]
72.037591
ATT: Find Information Response (0x05) len 5
Format: UUID-16 (0x01)
Handle: 0x0022
UUID: Report Reference (0x2908)
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
72.037895
ATT: Find Information Request (0x04) len 4
Handle range: 0x0027-0x0027
> ACL Data RX: Handle 1 flags 0x02 dlen 15 [hci0]
72.106366
SMP: Master Identification (0x07) len 10
EDIV: 0xd974
Rand: 0xd06f95a7d5f1a6aa
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
72.106381
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 10 [hci0]
72.172715
ATT: Find Information Response (0x05) len 5
Format: UUID-16 (0x01)
Handle: 0x0027
UUID: Client Characteristic Configuration (0x2902)
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
72.173101
ATT: Find Information Request (0x04) len 4
Handle range: 0x002c-0xffff
> ACL Data RX: Handle 1 flags 0x02 dlen 21 [hci0]
72.241881
SMP: Identity Information (0x08) len 16
Identity resolving key: ea7102bac3d692626259c72b1613a1ca
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
72.241896
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 9 [hci0]
72.307467
ATT: Error Response (0x01) len 4
Find Information Request (0x04)
Handle: 0x002c
Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 1 flags 0x00 dlen 7 [hci0]
72.323193
ATT: Read Request (0x0a) len 2
Handle: 0x0003
> ACL Data RX: Handle 1 flags 0x02 dlen 12 [hci0]
72.375939
SMP: Identity Address Information (0x09) len 7
Address type: Random (0x01)
Address: F5:95:EC:5D:0F:D3 (Static)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
72.375954
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 19 [hci0]
72.443348
ATT: Read Response (0x0b) len 14
Value: 44657369676e6572204d6f757365
< ACL Data TX: Handle 1 flags 0x00 dlen 7 [hci0]
72.443709
ATT: Read Request (0x0a) len 2
Handle: 0x0005
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
72.509421
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 7 [hci0]
72.577327
ATT: Read Response (0x0b) len 2
Value: c203
< ACL Data TX: Handle 1 flags 0x00 dlen 7 [hci0]
72.577664
ATT: Read Request (0x0a) len 2
Handle: 0x000d
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
72.644640
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 12 [hci0]
72.712766
ATT: Read Response (0x0b) len 7
Value: 025e0405081001
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
72.713149
ATT: Read By Type Request (0x08) len 6
Handle range: 0x0012-0xffff
Attribute type: Characteristic (0x2803)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
72.779410
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 27 [hci0]
72.849315
ATT: Read By Type Response (0x09) len 22
Attribute data length: 7
Attribute data list: 3 entries
Handle: 0x0013
Value: 0614004e2a
Handle: 0x0015
Value: 1a16004d2a
Handle: 0x0019
Value: 1a1a004d2a
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
72.849718
ATT: Read By Type Request (0x08) len 6
Handle range: 0x001a-0xffff
Attribute type: Characteristic (0x2803)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
72.914521
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 27 [hci0]
72.984203
ATT: Read By Type Response (0x09) len 22
Attribute data length: 7
Attribute data list: 3 entries
Handle: 0x001d
Value: 0a1e004d2a
Handle: 0x0020
Value: 0a21004d2a
Handle: 0x0023
Value: 0224004b2a
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
72.984591
ATT: Read By Type Request (0x08) len 6
Handle range: 0x0024-0xffff
Attribute type: Characteristic (0x2803)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
73.049409
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 27 [hci0]
73.119009
ATT: Read By Type Response (0x09) len 22
Attribute data length: 7
Attribute data list: 3 entries
Handle: 0x0025
Value: 1a2600332a
Handle: 0x0028
Value: 0229004a2a
Handle: 0x002a
Value: 042b004c2a
< ACL Data TX: Handle 1 flags 0x00 dlen 11 [hci0]
73.119396
ATT: Read By Type Request (0x08) len 6
Handle range: 0x002b-0xffff
Attribute type: Characteristic (0x2803)
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
73.184528
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 9 [hci0]
73.252330
ATT: Error Response (0x01) len 4
Read By Type Request (0x08)
Handle: 0x002b
Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 1 flags 0x00 dlen 7 [hci0]
73.252799
ATT: Read Request (0x0a) len 2
Handle: 0x0016
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
73.319491
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 5 [hci0]
73.387062
ATT: Read Response (0x0b) len 0
Value:
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
73.387421
ATT: Find Information Request (0x04) len 4
Handle range: 0x0017-0x0018
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
73.454437
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 14 [hci0]
73.522949
ATT: Find Information Response (0x05) len 9
Format: UUID-16 (0x01)
Handle: 0x0017
UUID: Client Characteristic Configuration (0x2902)
Handle: 0x0018
UUID: Report Reference (0x2908)
< ACL Data TX: Handle 1 flags 0x00 dlen 7 [hci0]
73.523309
ATT: Read Request (0x0a) len 2
Handle: 0x001a
> ACL Data RX: Handle 1 flags 0x02 dlen 16 [hci0]
73.591406
LE L2CAP: Connection Parameter Update Request (0x12) ident 2 len 8
Min interval: 6
Max interval: 6
Slave latency: 60
Timeout multiplier: 300
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
73.591424
Num handles: 1
Handle: 1
Count: 1
< ACL Data TX: Handle 1 flags 0x00 dlen 10 [hci0]
73.591512
LE L2CAP: Connection Parameter Update Response (0x13) ident 2 len 2
Result: Connection Parameters accepted (0x0000)
< HCI Command: LE Connection Update (0x08|0x0013) plen 14 [hci0]
73.591525
Handle: 1
Min connection interval: 7.50 msec (0x0006)
Max connection interval: 7.50 msec (0x0006)
Connection latency: 0x003c
Supervision timeout: 3000 msec (0x012c)
Min connection length: 0.000 msec (0x0000)
Max connection length: 0.000 msec (0x0000)
@ New Conn Param: F5:95:EC:5D:0F:D3 (2) hint 1 min 0x0006 max 0x0006
latency 0x003c timeout 0x012c
> HCI Event: Command Status (0x0f) plen 4 [hci0]
73.599539
LE Connection Update (0x08|0x0013) ncmd 1
Status: Success (0x00)
> ACL Data RX: Handle 1 flags 0x02 dlen 5 [hci0]
73.724738
ATT: Read Response (0x0b) len 0
Value:
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
73.725072
ATT: Find Information Request (0x04) len 4
Handle range: 0x001b-0x001c
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
73.792598
Num handles: 1
Handle: 1
Count: 1
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
73.859373
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 14 [hci0]
73.927886
ATT: Find Information Response (0x05) len 9
Format: UUID-16 (0x01)
Handle: 0x001b
UUID: Client Characteristic Configuration (0x2902)
Handle: 0x001c
UUID: Report Reference (0x2908)
< ACL Data TX: Handle 1 flags 0x00 dlen 7 [hci0]
73.928248
ATT: Read Request (0x0a) len 2
Handle: 0x001e
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
73.995803
Num handles: 1
Handle: 1
Count: 1
> HCI Event: LE Meta Event (0x3e) plen 10 [hci0]
73.995817
LE Connection Update Complete (0x03)
Status: Success (0x00)
Handle: 1
Connection interval: 7.50 msec (0x0006)
Connection latency: 75.00 msec (0x003c)
Supervision timeout: 3000 msec (0x012c)
> ACL Data RX: Handle 1 flags 0x02 dlen 5 [hci0]
74.069660
ATT: Read Response (0x0b) len 0
Value:
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
74.070064
ATT: Find Information Request (0x04) len 4
Handle range: 0x001f-0x001f
> ACL Data RX: Handle 1 flags 0x02 dlen 10 [hci0]
74.542546
ATT: Find Information Response (0x05) len 5
Format: UUID-16 (0x01)
Handle: 0x001f
UUID: Report Reference (0x2908)
< ACL Data TX: Handle 1 flags 0x00 dlen 7 [hci0]
74.542973
ATT: Read Request (0x0a) len 2
Handle: 0x0021
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
74.549640
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 5 [hci0]
74.557107
ATT: Read Response (0x0b) len 0
Value:
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
74.557465
ATT: Find Information Request (0x04) len 4
Handle range: 0x0022-0x0022
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
74.564356
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 10 [hci0]
74.572608
ATT: Find Information Response (0x05) len 5
Format: UUID-16 (0x01)
Handle: 0x0022
UUID: Report Reference (0x2908)
< ACL Data TX: Handle 1 flags 0x00 dlen 7 [hci0]
74.572968
ATT: Read Request (0x0a) len 2
Handle: 0x0024
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
74.579593
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 27 [hci0]
74.589327
ATT: Read Response (0x0b) len 22
Value: 05010902a10105010902a102851a0901a10005091901
< ACL Data TX: Handle 1 flags 0x00 dlen 7 [hci0]
74.589688
ATT: Read Request (0x0a) len 2
Handle: 0x0014
> ACL Data RX: Handle 1 flags 0x02 dlen 6 [hci0]
75.059972
ATT: Read Response (0x0b) len 1
Value: 01
< ACL Data TX: Handle 1 flags 0x00 dlen 7 [hci0]
75.060353
ATT: Read Request (0x0a) len 2
Handle: 0x0029
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
75.066971
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 9 [hci0]
75.074901
ATT: Read Response (0x0b) len 4
Value: 01010003
< ACL Data TX: Handle 1 flags 0x00 dlen 7 [hci0]
75.075257
ATT: Read Request (0x0a) len 2
Handle: 0x0018
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
75.082131
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 7 [hci0]
75.090148
ATT: Read Response (0x0b) len 2
Value: 1a01
< ACL Data TX: Handle 1 flags 0x00 dlen 7 [hci0]
75.090513
ATT: Read Request (0x0a) len 2
Handle: 0x001c
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
75.096994
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 7 [hci0]
75.104749
ATT: Read Response (0x0b) len 2
Value: 2701
< ACL Data TX: Handle 1 flags 0x00 dlen 7 [hci0]
75.105121
ATT: Read Request (0x0a) len 2
Handle: 0x001f
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
75.111899
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 7 [hci0]
75.119756
ATT: Read Response (0x0b) len 2
Value: 2203
< ACL Data TX: Handle 1 flags 0x00 dlen 7 [hci0]
75.120156
ATT: Read Request (0x0a) len 2
Handle: 0x0022
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
75.127135
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 7 [hci0]
75.134857
ATT: Read Response (0x0b) len 2
Value: 2403
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
75.135229
ATT: Read Blob Request (0x0c) len 4
Handle: 0x0024
Offset: 0x0016
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
75.149659
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 27 [hci0]
75.159073
ATT: Read Blob Response (0x0d) len 22
29 05 95 05 75 01 15 00 25 01 81 02 75 03 95 01 )...u...%...u...
81 01 05 01 09 30 .....0
< ACL Data TX: Handle 1 flags 0x00 dlen 7 [hci0]
75.159485
ATT: Read Request (0x0a) len 2
Handle: 0x0017
> ACL Data RX: Handle 1 flags 0x02 dlen 7 [hci0]
75.629710
ATT: Read Response (0x0b) len 2
Value: 0000
< ACL Data TX: Handle 1 flags 0x00 dlen 7 [hci0]
75.630157
ATT: Read Request (0x0a) len 2
Handle: 0x001b
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
75.636845
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 7 [hci0]
75.644908
ATT: Read Response (0x0b) len 2
Value: 0000
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
75.645224
ATT: Read Blob Request (0x0c) len 4
Handle: 0x0024
Offset: 0x002c
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
75.651910
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 27 [hci0]
75.661587
ATT: Read Blob Response (0x0d) len 22
09 31 95 02 75 10 16 01 80 26 ff 7f 81 06 a1 02 .1..u....&......
85 1a 09 38 35 00 ...85.
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
75.661866
ATT: Write Request (0x12) len 4
Handle: 0x0017
Data: 0100
> ACL Data RX: Handle 1 flags 0x02 dlen 5 [hci0]
76.132205
ATT: Write Response (0x13) len 0
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
76.132488
ATT: Write Request (0x12) len 4
Handle: 0x001b
Data: 0100
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
76.139397
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 5 [hci0]
76.147080
ATT: Write Response (0x13) len 0
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
76.147363
ATT: Read Blob Request (0x0c) len 4
Handle: 0x0024
Offset: 0x0042
> HCI Event: Number of Completed Packets (0x13) plen 5 [hci0]
76.154433
Num handles: 1
Handle: 1
Count: 1
> ACL Data RX: Handle 1 flags 0x02 dlen 27 [hci0]
76.164326
ATT: Read Blob Response (0x0d) len 22
45 00 95 01 75 10 16 01 80 26 ff 7f 81 06 c0 a1 E...u....&......
02 85 1a 05 0c 95 ......
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
76.164618
ATT: Read Blob Request (0x0c) len 4
Handle: 0x0024
Offset: 0x0058
> ACL Data RX: Handle 1 flags 0x02 dlen 27 [hci0]
76.636564
ATT: Read Blob Response (0x0d) len 22
01 75 10 16 01 80 26 ff 7f 0a 38 02 81 06 c0 c0 .u....&...8.....
c0 c0 06 bc ff 09 ......
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
76.636864
ATT: Read Blob Request (0x0c) len 4
Handle: 0x0024
Offset: 0x006e
> ACL Data RX: Handle 1 flags 0x02 dlen 27 [hci0]
77.109219
ATT: Read Blob Response (0x0d) len 22
88 a1 01 85 22 06 00 ff 15 00 26 ff 00 75 08 95 ....".....&..u..
13 0a 0a fa b1 02 ......
< ACL Data TX: Handle 1 flags 0x00 dlen 9 [hci0]
77.109532
ATT: Read Blob Request (0x0c) len 4
Handle: 0x0024
Offset: 0x0084
> ACL Data RX: Handle 1 flags 0x02 dlen 26 [hci0]
77.581736
ATT: Read Blob Response (0x0d) len 21
85 24 06 00 ff 0a 0a fa b1 02 85 27 06 00 ff 0a .$.........'....
0a fa 81 02 c0 .....
> ACL Data RX: Handle 1 flags 0x02 dlen 16 [hci0]
91.897992
ATT: Handle Value Notification (0x1b) len 11
Handle: 0x0016
Data: 000100000000000000
... ( snip Mouse movement reports continue)...
> ACL Data RX: Handle 1 flags 0x02 dlen 16 [hci0]
93.113028
ATT: Handle Value Notification (0x1b) len 11
Handle: 0x0016
Data: 000500030000000000
> ACL Data RX: Handle 1 flags 0x02 dlen 16 [hci0]
93.120639
ATT: Handle Value Notification (0x1b) len 11
Handle: 0x0016
Data: 000200010000000000
> ACL Data RX: Handle 1 flags 0x02 dlen 16 [hci0]
93.127978
ATT: Handle Value Notification (0x1b) len 11
Handle: 0x0016
Data: 000100010000000000
< HCI Command: Disconnect (0x01|0x0006) plen 3 [hci0]
98.788720
Handle: 1
Reason: Remote User Terminated Connection (0x13)
> HCI Event: Command Status (0x0f) plen 4 [hci0]
98.795175
Disconnect (0x01|0x0006) ncmd 1
Status: Success (0x00)
< HCI Command: LE Add Device To White.. (0x08|0x0011) plen 7 [hci0]
188.669226
Address type: Random (0x01)
Address: F5:95:EC:5D:0F:D3 (Static)
@ Device Added: F5:95:EC:5D:0F:D3 (2) 2
> HCI Event: Command Complete (0x0e) plen 4 [hci0]
188.676819
LE Add Device To White List (0x08|0x0011) ncmd 1
Status: Success (0x00)
< HCI Command: LE Set Scan Parameters (0x08|0x000b) plen 7 [hci0]
188.676888
Type: Passive (0x00)
Interval: 60.000 msec (0x0060)
Window: 30.000 msec (0x0030)
Own address type: Public (0x00)
Filter policy: Ignore not in white list (0x01)
> HCI Event: Command Complete (0x0e) plen 4 [hci0]
188.681718
LE Set Scan Parameters (0x08|0x000b) ncmd 1
Status: Success (0x00)
< HCI Command: LE Set Scan Enable (0x08|0x000c) plen 2 [hci0]
188.681782
Scanning: Enabled (0x01)
Filter duplicates: Enabled (0x01)
> HCI Event: Command Complete (0x0e) plen 4 [hci0]
188.686100
LE Set Scan Enable (0x08|0x000c) ncmd 1
Status: Success (0x00)
< HCI Command: LE Set Scan Enable (0x08|0x000c) plen 2 [hci0]
193.634834
************************** btmon logs end
**************************************
Thanks,
Naveen
^ permalink raw reply [flat|nested] 7+ messages in thread* Re: Issue: Bluez: Bluetooth Mouse Disconnects Soon After Pairing 2016-09-11 3:06 Issue: Bluez: Bluetooth Mouse Disconnects Soon After Pairing Naveen Kaje @ 2016-09-11 16:59 ` Luiz Augusto von Dentz 2016-09-12 16:15 ` Naveen Kaje 0 siblings, 1 reply; 7+ messages in thread From: Luiz Augusto von Dentz @ 2016-09-11 16:59 UTC (permalink / raw) To: Naveen Kaje; +Cc: linux-bluetooth Hi Naveen, On Sun, Sep 11, 2016 at 6:06 AM, Naveen Kaje <nckaje@gmail.com> wrote: > Hello, > I am seeing a disconnection behavior with Bluetooth mouse (Microsoft > Desginer Mouse). Software Baseline 3.18 based kernel and Bluez 5.41. > > The device is discovered with bluetoothctl. The mouse pairs briefly (for > about 30 seconds) and then the host disconnects. > Bluetoothctl error reads Failed to pair: > org.bluez.Error.AuthenticationCanceled > > Observations: > 1) The time it takes for the mouse to stop working is about 30 seconds > since the pair command. This time is consistent. > > 2) The btmon report says Reason: Remote User Terminated Connection (0x13), > which I found to be not entirely true. > I hacked the transfport layer (UART) in Kernel to skip sending > disconnection message which resulted in the Mouse device > continuing to send reports even after this point (Observed on btmon). > > Bluetoothctl and btmon logs are shown below. What could I be missing? > > Thanks in advance for the help. > > ******************** Bluetoothctl log begin **************************** > [bluetooth]# show > Controller 44:1C:A8:6C:8F:5E > Name: BTHost > Alias: BTHost > Class: 0x000000 > Powered: yes > Discoverable: no > Pairable: yes > UUID: Generic Attribute Profile > (00001801-0000-1000-8000-00805f9b34fb) > UUID: A/V Remote Control > (0000110e-0000-1000-8000-00805f9b34fb) > UUID: PnP Information > (00001200-0000-1000-8000-00805f9b34fb) > UUID: Generic Access Profile > (00001800-0000-1000-8000-00805f9b34fb) > UUID: A/V Remote Control Target > (0000110c-0000-1000-8000-00805f9b34fb) > Modalias: usb:v1D6Bp0246d0529 > [bluetooth]# discoverable on > Changing discoverable on succeeded > [bluetooth]# pairable on > Changing pairable on succeeded > [bluetooth]# scan on > Discovery started > [CHG] Controller 44:1C:A8:6C:8F:5E Discovering: yes > [NEW] Device F5:95:EC:5D:0F:D3 Designer Mouse > [bluetooth]# pair F5:95:EC:5D:0F:D3 > Attempting to pair with F5:95:EC:5D:0F:D3 > [CHG] Device F5:95:EC:5D:0F:D3 Connected: yes > [CHG] Device F5:95:EC:5D:0F:D3 UUIDs: 00001800-0000-1000-8000-00805f9b34fb > [CHG] Device F5:95:EC:5D:0F:D3 UUIDs: 00001801-0000-1000-8000-00805f9b34fb > [CHG] Device F5:95:EC:5D:0F:D3 UUIDs: 0000180a-0000-1000-8000-00805f9b34fb > [CHG] Device F5:95:EC:5D:0F:D3 UUIDs: 0000180f-0000-1000-8000-00805f9b34fb > [CHG] Device F5:95:EC:5D:0F:D3 UUIDs: 00001812-0000-1000-8000-00805f9b34fb > [CHG] Device F5:95:EC:5D:0F:D3 Modalias: usb:v045Ep0805d0110 > [Designer Mouse]# info > Device F5:95:EC:5D:0F:D3 > Name: Designer Mouse > Alias: Designer Mouse > Appearance: 0x03c2 > Icon: input-mouse > Paired: no > Trusted: no > Blocked: no > Connected: yes > LegacyPairing: no > UUID: Generic Access Profile > (00001800-0000-1000-8000-00805f9b34fb) > UUID: Generic Attribute Profile > (00001801-0000-1000-8000-00805f9b34fb) > UUID: Device Information > (0000180a-0000-1000-8000-00805f9b34fb) > UUID: Battery Service > (0000180f-0000-1000-8000-00805f9b34fb) > UUID: Human Interface Device > (00001812-0000-1000-8000-00805f9b34fb) > Modalias: usb:v045Ep0805d0110 > RSSI: -91 > Failed to pair: org.bluez.Error.AuthenticationCancele-- It doesn't look like you have an agent registered which is probably why the authentication fails, try with: > agent on > default-agent iirc the same mouse used to just work with more recent kernel and latest userspace. Luiz Augusto von Dentz ^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: Issue: Bluez: Bluetooth Mouse Disconnects Soon After Pairing 2016-09-11 16:59 ` Luiz Augusto von Dentz @ 2016-09-12 16:15 ` Naveen Kaje [not found] ` <CAHFy41-2omfTux6-3VPcv0nHBZEdDu0r0ap2UTrc2z=3c_dtCg@mail.gmail.com> 0 siblings, 1 reply; 7+ messages in thread From: Naveen Kaje @ 2016-09-12 16:15 UTC (permalink / raw) To: Luiz Augusto von Dentz; +Cc: linux-bluetooth, me Hi Luiz, Thanks for the input. I tried turning on agent (and various combinations, such as NoInputNoOutput, default-agent and the exact steps suggested by you.) I still see similar behavior. The behavior is similar with Microsoft Disigner Keyboard (for keyboard, I tried with agent KeyboardOnly and KeyboardDisplay). Bluetoothctl snippet below ************* begin ************* [bluetooth]# power on Changing power on succeeded [bluetooth]# agent on Agent registered [bluetooth]# default-agent Default agent request successful [bluetooth]# discoverable on Changing discoverable on succeeded [CHG] Controller 44:1C:A8:6C:8F:5E Discoverable: yes [bluetooth]# pairable on Changing pairable on succeeded [bluetooth]# scan on Discovery started [CHG] Controller 44:1C:A8:6C:8F:5E Discovering: yes [NEW] Device F5:95:EC:5E:0F:D3 Designer Mouse [NEW] Device 74:9A:AA:38:11:48 74-9A-AA-38-11-48 [NEW] Device 70:97:AD:98:C7:30 70-97-AD-98-C7-30 [NEW] Device 49:5E:30:B2:A7:59 49-5E-30-B2-A7-59 [NEW] Device 6F:9A:D1:81:6C:A2 6F-9A-D1-81-6C-A2 [NEW] Device F3:26:FB:A7:7B:00 F3-26-FB-A7-7B-00 [bluetooth]# pair F5:95:EC:5E:0F:D3 Attempting to pair with F5:95:EC:5E:0F:D3 [CHG] Device F5:95:EC:5E:0F:D3 Connected: yes [CHG] Device F5:95:EC:5E:0F:D3 UUIDs: 00001800-0000-1000-8000-00805f9b34fb [CHG] Device F5:95:EC:5E:0F:D3 UUIDs: 00001801-0000-1000-8000-00805f9b34fb [CHG] Device F5:95:EC:5E:0F:D3 UUIDs: 0000180a-0000-1000-8000-00805f9b34fb [CHG] Device F5:95:EC:5E:0F:D3 UUIDs: 0000180f-0000-1000-8000-00805f9b34fb [CHG] Device F5:95:EC:5E:0F:D3 UUIDs: 00001812-0000-1000-8000-00805f9b34fb <--- At this point, Bluetooth Mouse Reports seen here and mouse can be used [CHG] Device F5:95:EC:5E:0F:D3 Connected: no <--- At this point, Mouse stops working, btmon shows disconnection Failed to pair: org.bluez.Error.AuthenticationCanceled ************ end ********************* It is encouraging that you were able to use the same device. Could you please share which Kernel version and Bluez Version you used? I am on Kernel 3.18 and Bluez 5.41. Thanks in advance. Naveen On Sun, Sep 11, 2016 at 10:59 AM, Luiz Augusto von Dentz <luiz.dentz@gmail.com> wrote: > Hi Naveen, > > On Sun, Sep 11, 2016 at 6:06 AM, Naveen Kaje <nckaje@gmail.com> wrote: >> Hello, >> I am seeing a disconnection behavior with Bluetooth mouse (Microsoft >> Desginer Mouse). Software Baseline 3.18 based kernel and Bluez 5.41. >> >> The device is discovered with bluetoothctl. The mouse pairs briefly (for >> about 30 seconds) and then the host disconnects. >> Bluetoothctl error reads Failed to pair: >> org.bluez.Error.AuthenticationCanceled >> >> Observations: >> 1) The time it takes for the mouse to stop working is about 30 seconds >> since the pair command. This time is consistent. >> >> 2) The btmon report says Reason: Remote User Terminated Connection (0x13), >> which I found to be not entirely true. >> I hacked the transfport layer (UART) in Kernel to skip sending >> disconnection message which resulted in the Mouse device >> continuing to send reports even after this point (Observed on btmon). >> >> Bluetoothctl and btmon logs are shown below. What could I be missing? >> >> Thanks in advance for the help. >> >> ******************** Bluetoothctl log begin **************************** >> [bluetooth]# show >> Controller 44:1C:A8:6C:8F:5E >> Name: BTHost >> Alias: BTHost >> Class: 0x000000 >> Powered: yes >> Discoverable: no >> Pairable: yes >> UUID: Generic Attribute Profile >> (00001801-0000-1000-8000-00805f9b34fb) >> UUID: A/V Remote Control >> (0000110e-0000-1000-8000-00805f9b34fb) >> UUID: PnP Information >> (00001200-0000-1000-8000-00805f9b34fb) >> UUID: Generic Access Profile >> (00001800-0000-1000-8000-00805f9b34fb) >> UUID: A/V Remote Control Target >> (0000110c-0000-1000-8000-00805f9b34fb) >> Modalias: usb:v1D6Bp0246d0529 >> [bluetooth]# discoverable on >> Changing discoverable on succeeded >> [bluetooth]# pairable on >> Changing pairable on succeeded >> [bluetooth]# scan on >> Discovery started >> [CHG] Controller 44:1C:A8:6C:8F:5E Discovering: yes >> [NEW] Device F5:95:EC:5D:0F:D3 Designer Mouse >> [bluetooth]# pair F5:95:EC:5D:0F:D3 >> Attempting to pair with F5:95:EC:5D:0F:D3 >> [CHG] Device F5:95:EC:5D:0F:D3 Connected: yes >> [CHG] Device F5:95:EC:5D:0F:D3 UUIDs: 00001800-0000-1000-8000-00805f9b34fb >> [CHG] Device F5:95:EC:5D:0F:D3 UUIDs: 00001801-0000-1000-8000-00805f9b34fb >> [CHG] Device F5:95:EC:5D:0F:D3 UUIDs: 0000180a-0000-1000-8000-00805f9b34fb >> [CHG] Device F5:95:EC:5D:0F:D3 UUIDs: 0000180f-0000-1000-8000-00805f9b34fb >> [CHG] Device F5:95:EC:5D:0F:D3 UUIDs: 00001812-0000-1000-8000-00805f9b34fb >> [CHG] Device F5:95:EC:5D:0F:D3 Modalias: usb:v045Ep0805d0110 >> [Designer Mouse]# info >> Device F5:95:EC:5D:0F:D3 >> Name: Designer Mouse >> Alias: Designer Mouse >> Appearance: 0x03c2 >> Icon: input-mouse >> Paired: no >> Trusted: no >> Blocked: no >> Connected: yes >> LegacyPairing: no >> UUID: Generic Access Profile >> (00001800-0000-1000-8000-00805f9b34fb) >> UUID: Generic Attribute Profile >> (00001801-0000-1000-8000-00805f9b34fb) >> UUID: Device Information >> (0000180a-0000-1000-8000-00805f9b34fb) >> UUID: Battery Service >> (0000180f-0000-1000-8000-00805f9b34fb) >> UUID: Human Interface Device >> (00001812-0000-1000-8000-00805f9b34fb) >> Modalias: usb:v045Ep0805d0110 >> RSSI: -91 >> Failed to pair: org.bluez.Error.AuthenticationCancele-- > > It doesn't look like you have an agent registered which is probably > why the authentication fails, try with: >> agent on >> default-agent > > iirc the same mouse used to just work with more recent kernel and > latest userspace. > > Luiz Augusto von Dentz ^ permalink raw reply [flat|nested] 7+ messages in thread
[parent not found: <CAHFy41-2omfTux6-3VPcv0nHBZEdDu0r0ap2UTrc2z=3c_dtCg@mail.gmail.com>]
* Re: Issue: Bluez: Bluetooth Mouse Disconnects Soon After Pairing [not found] ` <CAHFy41-2omfTux6-3VPcv0nHBZEdDu0r0ap2UTrc2z=3c_dtCg@mail.gmail.com> @ 2016-09-13 9:58 ` Luiz Augusto von Dentz 2016-09-15 18:58 ` Naveen Kaje 0 siblings, 1 reply; 7+ messages in thread From: Luiz Augusto von Dentz @ 2016-09-13 9:58 UTC (permalink / raw) To: Joseph Hwang; +Cc: Naveen Kaje, linux-bluetooth Hi, On Tue, Sep 13, 2016 at 4:36 AM, Joseph Hwang <josephsih@google.com> wrote: > This looks a bit weird to me. The disconnection reason in btmon log is 0x13: > > < HCI Command: Disconnect (0x01|0x0006) plen 3 [hci0] > 98.788720 > Handle: 1 > Reason: Remote User Terminated Connection (0x13) > > > Looking at src/adapter.c, the 0x13 indicates HCI_OE_USER_ENDED_CONNECTION > which is sent in reply_pending_requests() which in turn is called by > adapter_stop(). Why adapter_stop() is called? > > Joseph > > > On Tue, Sep 13, 2016 at 12:15 AM, Naveen Kaje <nckaje@gmail.com> wrote: >> >> Hi Luiz, >> Thanks for the input. I tried turning on agent (and various >> combinations, such as NoInputNoOutput, default-agent and the exact >> steps suggested by you.) I still see similar behavior. The behavior is >> similar with Microsoft Disigner Keyboard (for keyboard, I tried with >> agent KeyboardOnly and KeyboardDisplay). >> >> Bluetoothctl snippet below >> >> ************* begin ************* >> [bluetooth]# power on >> Changing power on succeeded >> [bluetooth]# agent on >> Agent registered >> [bluetooth]# default-agent >> Default agent request successful >> [bluetooth]# discoverable on >> Changing discoverable on succeeded >> [CHG] Controller 44:1C:A8:6C:8F:5E Discoverable: yes >> [bluetooth]# pairable on >> Changing pairable on succeeded >> [bluetooth]# scan on >> Discovery started >> [CHG] Controller 44:1C:A8:6C:8F:5E Discovering: yes >> [NEW] Device F5:95:EC:5E:0F:D3 Designer Mouse >> [NEW] Device 74:9A:AA:38:11:48 74-9A-AA-38-11-48 >> [NEW] Device 70:97:AD:98:C7:30 70-97-AD-98-C7-30 >> [NEW] Device 49:5E:30:B2:A7:59 49-5E-30-B2-A7-59 >> [NEW] Device 6F:9A:D1:81:6C:A2 6F-9A-D1-81-6C-A2 >> [NEW] Device F3:26:FB:A7:7B:00 F3-26-FB-A7-7B-00 >> [bluetooth]# pair F5:95:EC:5E:0F:D3 >> Attempting to pair with F5:95:EC:5E:0F:D3 >> [CHG] Device F5:95:EC:5E:0F:D3 Connected: yes >> [CHG] Device F5:95:EC:5E:0F:D3 UUIDs: 00001800-0000-1000-8000-00805f9b34fb >> [CHG] Device F5:95:EC:5E:0F:D3 UUIDs: 00001801-0000-1000-8000-00805f9b34fb >> [CHG] Device F5:95:EC:5E:0F:D3 UUIDs: 0000180a-0000-1000-8000-00805f9b34fb >> [CHG] Device F5:95:EC:5E:0F:D3 UUIDs: 0000180f-0000-1000-8000-00805f9b34fb >> [CHG] Device F5:95:EC:5E:0F:D3 UUIDs: >> 00001812-0000-1000-8000-00805f9b34fb <--- At this point, Bluetooth >> Mouse Reports seen here and mouse can be used >> [CHG] Device F5:95:EC:5E:0F:D3 Connected: no <--- At this point, Mouse >> stops working, btmon shows disconnection >> Failed to pair: org.bluez.Error.AuthenticationCanceled With upstream the mouse is still working fine: [bluetooth]# scan on Discovery started [CHG] Controller 7C:7A:91:18:82:46 Discovering: yes [NEW] Device F3:F8:16:59:F6:74 Designer Mouse [NEW] Device CB:43:A1:BF:48:16 Surge [NEW] Device 77:39:0B:40:81:C4 77-39-0B-40-81-C4 [bluetooth]# scan off [CHG] Device 77:39:0B:40:81:C4 RSSI is nil [CHG] Device CB:43:A1:BF:48:16 RSSI is nil [CHG] Device F3:F8:16:59:F6:74 RSSI is nil Discovery stopped [CHG] Controller 7C:7A:91:18:82:46 Discovering: no [bluetooth]# pair F3:F8:16:59:F6:74 Attempting to pair with F3:F8:16:59:F6:74 [CHG] Device F3:F8:16:59:F6:74 Connected: yes [CHG] Device F3:F8:16:59:F6:74 UUIDs: 00001800-0000-1000-8000-00805f9b34fb [CHG] Device F3:F8:16:59:F6:74 UUIDs: 00001801-0000-1000-8000-00805f9b34fb [CHG] Device F3:F8:16:59:F6:74 UUIDs: 0000180a-0000-1000-8000-00805f9b34fb [CHG] Device F3:F8:16:59:F6:74 UUIDs: 0000180f-0000-1000-8000-00805f9b34fb [CHG] Device F3:F8:16:59:F6:74 UUIDs: 00001812-0000-1000-8000-00805f9b34fb [CHG] Device F3:F8:16:59:F6:74 ServicesResolved: yes [CHG] Device F3:F8:16:59:F6:74 Paired: yes [NEW] Primary Service /org/bluez/hci0/dev_F3_F8_16_59_F6_74/service0008 Generic Attribute Profile [NEW] Primary Service /org/bluez/hci0/dev_F3_F8_16_59_F6_74/service0009 Device Information [NEW] Characteristic /org/bluez/hci0/dev_F3_F8_16_59_F6_74/service0009/char000a Manufacturer Name String [NEW] Characteristic /org/bluez/hci0/dev_F3_F8_16_59_F6_74/service0009/char000c PnP ID [NEW] Primary Service /org/bluez/hci0/dev_F3_F8_16_59_F6_74/service000e Battery Service [NEW] Characteristic /org/bluez/hci0/dev_F3_F8_16_59_F6_74/service000e/char000f Battery Level [NEW] Descriptor /org/bluez/hci0/dev_F3_F8_16_59_F6_74/service000e/char000f/desc0011 Client Characteristic Configuration Pairing successful [CHG] Device F3:F8:16:59:F6:74 Modalias: usb:v045Ep0805d0100 Btw, by upstream I mean a very recent kernel such as 4.6.5-300.fc24.x86_64 and latest BlueZ userspace. -- Luiz Augusto von Dentz ^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: Issue: Bluez: Bluetooth Mouse Disconnects Soon After Pairing 2016-09-13 9:58 ` Luiz Augusto von Dentz @ 2016-09-15 18:58 ` Naveen Kaje 2016-09-16 10:02 ` Szymon Janc 0 siblings, 1 reply; 7+ messages in thread From: Naveen Kaje @ 2016-09-15 18:58 UTC (permalink / raw) To: me; +Cc: linux-bluetooth Hi, Thanks for all the input so far. I found out that the disconnection is coming from Bluetooth SMP in Kernel. After host transmits SMP_CMD_PAIRING_RANDOM, the HID device sends: SMP_CMD_ENCRYPT_INFO SMP_CMD_MASTER_IDENT SMP_CMD_IDENT_ADDR_INFO SMP_CMD_IDENT_INFO messages which get filtered out in smp_sig_channel as they don't seem to be allowed. This resulted in smp->security_timer firing and disconnection. I made the following change to smp to allow these commands in SMP. I am not sure if this the best way to fix it. Looking for input. With this change, the mouse device pairs successfully (see bluetoothctl logs below) However, I see the following "Unable to register GATT service with handle 0x0008 for device F5:95:EC:6F:0F:D3" in /var/log/syslog from bluetoothd. I am not sure if this is concerning. I understand I am trying to mix and match older Kernel stack with newer Bluez. But the tree for my ARM64 board has divereged from mainline. Hence upgrading the kernel is not easy. (3.18.20 based Kernel and 5.41 Bluez). Thanks in advance. Logs below. ****************** Begin Kernel Change ***************************************** @@ -1515,6 +1515,10 @@ static int smp_sig_channel(struct l2cap_chan *chan, struct sk_buff *skb) case SMP_CMD_PAIRING_RANDOM: reason = smp_cmd_pairing_random(conn, skb); + SMP_ALLOW_CMD(smp, SMP_CMD_ENCRYPT_INFO); + SMP_ALLOW_CMD(smp, SMP_CMD_MASTER_IDENT); + SMP_ALLOW_CMD(smp, SMP_CMD_IDENT_ADDR_INFO); + SMP_ALLOW_CMD(smp, SMP_CMD_IDENT_INFO); break; ****************** End Kernel Change ******************************************* ******************************* /var/log/syslog begin ************************** Jan 6 18:26:58 Deb-Jessie kernel: [ 118.599398] Bluetooth: Frame Reassembly Failed Jan 6 18:26:58 Deb-Jessie bluetoothd[629]: Unable to register GATT service with handle 0x0008 for device F5:95:EC:6F:0F:D3 Jan 6 18:27:00 Deb-Jessie kernel: [ 121.224593] Bluetooth: Frame Reassembly Failed Jan 6 18:27:01 Deb-Jessie kernel: [ 121.692871] Bluetooth: Frame Reassembly Failed Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) Jan 6 18:27:01 Deb-Jessie kernel: [ 122.146168] input: Designer Mouse as /devices/virtual/misc/uhid/0005:0000:0000.0003/input/input8 Jan 6 18:27:01 Deb-Jessie kernel: [ 122.146868] hid-generic 0005:0000:0000.0003: input,hidraw2: BLUETOOTH HID v0.00 Mouse [Designer Mouse] on 44:1C:A8:6C:8F:5E ******************************* /var/log/syslog end **************************** ******************************* bluetoothctl logs **************************** root@Deb-Jessie:~# bluetoothctl [NEW] Controller 44:1C:A8:6C:8F:5E Deb-Jessie [default] [bluetooth]# select 44:1C:A8:6C:8F:5E [bluetooth]# power on Changing power on succeeded [bluetooth]# discoverable on Changing discoverable on succeeded [bluetooth]# pairable on Changing pairable on succeeded [bluetooth]# agent on Agent registered [bluetooth]# default-agent Default agent request successful [bluetooth]# scan on Discovery started [CHG] Controller 44:1C:A8:6C:8F:5E Discovering: yes [NEW] Device F5:95:EC:6F:0F:D3 Designer Mouse [bluetooth]# pair F5:95:EC:6F:0F:D3 Attempting to pair with F5:95:EC:6F:0F:D3 [CHG] Device F5:95:EC:6F:0F:D3 Connected: yes [CHG] Device F5:95:EC:6F:0F:D3 UUIDs: 00001800-0000-1000-8000-00805f9b34fb [CHG] Device F5:95:EC:6F:0F:D3 UUIDs: 00001801-0000-1000-8000-00805f9b34fb [CHG] Device F5:95:EC:6F:0F:D3 UUIDs: 0000180a-0000-1000-8000-00805f9b34fb [CHG] Device F5:95:EC:6F:0F:D3 UUIDs: 0000180f-0000-1000-8000-00805f9b34fb [CHG] Device F5:95:EC:6F:0F:D3 UUIDs: 00001812-0000-1000-8000-00805f9b34fb [CHG] Device F5:95:EC:6F:0F:D3 ServicesResolved: yes [CHG] Device F5:95:EC:6F:0F:D3 Paired: yes [NEW] Primary Service /org/bluez/hci0/dev_F5_95_EC_6F_0F_D3/service0008 Generic Attribute Profile [NEW] Primary Service /org/bluez/hci0/dev_F5_95_EC_6F_0F_D3/service0009 Device Information [NEW] Characteristic /org/bluez/hci0/dev_F5_95_EC_6F_0F_D3/service0009/char000a Manufacturer Name String [NEW] Characteristic /org/bluez/hci0/dev_F5_95_EC_6F_0F_D3/service0009/char000c PnP ID [NEW] Primary Service /org/bluez/hci0/dev_F5_95_EC_6F_0F_D3/service000e Battery Service [NEW] Characteristic /org/bluez/hci0/dev_F5_95_EC_6F_0F_D3/service000e/char000f Battery Level [NEW] Descriptor /org/bluez/hci0/dev_F5_95_EC_6F_0F_D3/service000e/char000f/desc0011 Client Characteristic Configuration Pairing successful [CHG] Device F5:95:EC:6F:0F:D3 Modalias: usb:v045Ep0805d0110 [Designer Mouse]# info F5:95:EC:6F:0F:D3 Device F5:95:EC:6F:0F:D3 Name: Designer Mouse Alias: Designer Mouse Appearance: 0x03c2 Icon: input-mouse Paired: yes Trusted: no Blocked: no Connected: yes LegacyPairing: no UUID: Generic Access Profile (00001800-0000-1000-8000-00805f9b34fb) UUID: Generic Attribute Profile (00001801-0000-1000-8000-00805f9b34fb) UUID: Device Information (0000180a-0000-1000-8000-00805f9b34fb) UUID: Battery Service (0000180f-0000-1000-8000-00805f9b34fb) UUID: Human Interface Device (00001812-0000-1000-8000-00805f9b34fb) Modalias: usb:v045Ep0805d0110 RSSI: -46 **************************** bluetoothctl end ********************************** Thanks, Naveen ^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: Issue: Bluez: Bluetooth Mouse Disconnects Soon After Pairing 2016-09-15 18:58 ` Naveen Kaje @ 2016-09-16 10:02 ` Szymon Janc 2016-09-16 17:53 ` Naveen Kaje 0 siblings, 1 reply; 7+ messages in thread From: Szymon Janc @ 2016-09-16 10:02 UTC (permalink / raw) To: Naveen Kaje; +Cc: linux-bluetooth Hi Naveen, On 15 September 2016 at 20:58, Naveen Kaje <nckaje@gmail.com> wrote: > Hi, > Thanks for all the input so far. I found out that the disconnection is > coming from Bluetooth SMP in Kernel. After host transmits > SMP_CMD_PAIRING_RANDOM, > the HID device sends: > > SMP_CMD_ENCRYPT_INFO > SMP_CMD_MASTER_IDENT > SMP_CMD_IDENT_ADDR_INFO > SMP_CMD_IDENT_INFO Could you get btmon traces from pairing so that we don't need to speculate? In general slave should distribute keys only after master enabled encryption. There is also a small chance of race between encryption event and ACL data on USB transport... I've seen this few times on UPFs but have no idea on how this could be solved without enhancing HCI specification > > messages which get filtered out in smp_sig_channel as they don't seem > to be allowed. This resulted in smp->security_timer firing and > disconnection. > > I made the following change to smp to allow these commands in SMP. I > am not sure if this the best way to fix it. Looking for input. > > With this change, the mouse device pairs successfully (see > bluetoothctl logs below) > However, I see the following > > "Unable to register GATT service with handle 0x0008 for device > F5:95:EC:6F:0F:D3" > > in /var/log/syslog from bluetoothd. I am not sure if this is concerning. > > I understand I am trying to mix and match older Kernel stack with > newer Bluez. But the tree for my ARM64 board has divereged from > mainline. > Hence upgrading the kernel is not easy. (3.18.20 based Kernel and 5.41 Bluez). > > Thanks in advance. Logs below. > > ****************** Begin Kernel Change ***************************************** > @@ -1515,6 +1515,10 @@ static int smp_sig_channel(struct l2cap_chan > *chan, struct sk_buff *skb) > > case SMP_CMD_PAIRING_RANDOM: > reason = smp_cmd_pairing_random(conn, skb); > + SMP_ALLOW_CMD(smp, SMP_CMD_ENCRYPT_INFO); > + SMP_ALLOW_CMD(smp, SMP_CMD_MASTER_IDENT); > + SMP_ALLOW_CMD(smp, SMP_CMD_IDENT_ADDR_INFO); > + SMP_ALLOW_CMD(smp, SMP_CMD_IDENT_INFO); > break; > > ****************** End Kernel Change ******************************************* > > ******************************* /var/log/syslog begin ************************** > > Jan 6 18:26:58 Deb-Jessie kernel: [ 118.599398] Bluetooth: Frame > Reassembly Failed > Jan 6 18:26:58 Deb-Jessie bluetoothd[629]: Unable to register GATT > service with handle 0x0008 for device F5:95:EC:6F:0F:D3 > Jan 6 18:27:00 Deb-Jessie kernel: [ 121.224593] Bluetooth: Frame > Reassembly Failed > Jan 6 18:27:01 Deb-Jessie kernel: [ 121.692871] Bluetooth: Frame > Reassembly Failed > Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) > Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) > Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) > Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) > Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) > Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) > Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) > Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) > Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) > Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) > Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) > Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) > Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) > Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) > Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) > Jan 6 18:27:01 Deb-Jessie bluetoothd[629]: bt_uhid_send: Invalid argument (22) > Jan 6 18:27:01 Deb-Jessie kernel: [ 122.146168] input: Designer > Mouse as /devices/virtual/misc/uhid/0005:0000:0000.0003/input/input8 > Jan 6 18:27:01 Deb-Jessie kernel: [ 122.146868] hid-generic > 0005:0000:0000.0003: input,hidraw2: BLUETOOTH HID v0.00 Mouse > [Designer Mouse] on 44:1C:A8:6C:8F:5E > > ******************************* /var/log/syslog end **************************** > > ******************************* bluetoothctl logs **************************** > root@Deb-Jessie:~# bluetoothctl > [NEW] Controller 44:1C:A8:6C:8F:5E Deb-Jessie [default] > [bluetooth]# select 44:1C:A8:6C:8F:5E > [bluetooth]# power on > Changing power on succeeded > [bluetooth]# discoverable on > Changing discoverable on succeeded > [bluetooth]# pairable on > Changing pairable on succeeded > [bluetooth]# agent on > Agent registered > [bluetooth]# default-agent > Default agent request successful > [bluetooth]# scan on > Discovery started > [CHG] Controller 44:1C:A8:6C:8F:5E Discovering: yes > [NEW] Device F5:95:EC:6F:0F:D3 Designer Mouse > [bluetooth]# pair F5:95:EC:6F:0F:D3 > Attempting to pair with F5:95:EC:6F:0F:D3 > [CHG] Device F5:95:EC:6F:0F:D3 Connected: yes > [CHG] Device F5:95:EC:6F:0F:D3 UUIDs: 00001800-0000-1000-8000-00805f9b34fb > [CHG] Device F5:95:EC:6F:0F:D3 UUIDs: 00001801-0000-1000-8000-00805f9b34fb > [CHG] Device F5:95:EC:6F:0F:D3 UUIDs: 0000180a-0000-1000-8000-00805f9b34fb > [CHG] Device F5:95:EC:6F:0F:D3 UUIDs: 0000180f-0000-1000-8000-00805f9b34fb > [CHG] Device F5:95:EC:6F:0F:D3 UUIDs: 00001812-0000-1000-8000-00805f9b34fb > [CHG] Device F5:95:EC:6F:0F:D3 ServicesResolved: yes > [CHG] Device F5:95:EC:6F:0F:D3 Paired: yes > [NEW] Primary Service > /org/bluez/hci0/dev_F5_95_EC_6F_0F_D3/service0008 > Generic Attribute Profile > [NEW] Primary Service > /org/bluez/hci0/dev_F5_95_EC_6F_0F_D3/service0009 > Device Information > [NEW] Characteristic > /org/bluez/hci0/dev_F5_95_EC_6F_0F_D3/service0009/char000a > Manufacturer Name String > [NEW] Characteristic > /org/bluez/hci0/dev_F5_95_EC_6F_0F_D3/service0009/char000c > PnP ID > [NEW] Primary Service > /org/bluez/hci0/dev_F5_95_EC_6F_0F_D3/service000e > Battery Service > [NEW] Characteristic > /org/bluez/hci0/dev_F5_95_EC_6F_0F_D3/service000e/char000f > Battery Level > [NEW] Descriptor > /org/bluez/hci0/dev_F5_95_EC_6F_0F_D3/service000e/char000f/desc0011 > Client Characteristic Configuration > Pairing successful > [CHG] Device F5:95:EC:6F:0F:D3 Modalias: usb:v045Ep0805d0110 > [Designer Mouse]# info F5:95:EC:6F:0F:D3 > Device F5:95:EC:6F:0F:D3 > Name: Designer Mouse > Alias: Designer Mouse > Appearance: 0x03c2 > Icon: input-mouse > Paired: yes > Trusted: no > Blocked: no > Connected: yes > LegacyPairing: no > UUID: Generic Access Profile (00001800-0000-1000-8000-00805f9b34fb) > UUID: Generic Attribute Profile (00001801-0000-1000-8000-00805f9b34fb) > UUID: Device Information (0000180a-0000-1000-8000-00805f9b34fb) > UUID: Battery Service (0000180f-0000-1000-8000-00805f9b34fb) > UUID: Human Interface Device (00001812-0000-1000-8000-00805f9b34fb) > Modalias: usb:v045Ep0805d0110 > RSSI: -46 > > **************************** bluetoothctl end ********************************** > Thanks, > Naveen > -- > To unsubscribe from this list: send the line "unsubscribe linux-bluetooth" in > the body of a message to majordomo@vger.kernel.org > More majordomo info at http://vger.kernel.org/majordomo-info.html -- pozdrawiam Szymon K. Janc ^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: Issue: Bluez: Bluetooth Mouse Disconnects Soon After Pairing 2016-09-16 10:02 ` Szymon Janc @ 2016-09-16 17:53 ` Naveen Kaje 0 siblings, 0 replies; 7+ messages in thread From: Naveen Kaje @ 2016-09-16 17:53 UTC (permalink / raw) To: Szymon Janc; +Cc: linux-bluetooth Hi Szymon, Thanks for the feedback. Here are the btmon logs when the device pairs successfully with the SMP Kernel change. The behaviour is similar with Bluez 5.23, that comes default with Debian Jessie. Around 52 seconds mark in the logs, soon after SMP: Pairing Random (0x04) len 16, LE Start Encryption (0x08|0x0019) plen 28 is seen. and then around 55 seconds, SMP: Encryption Information SMP: Master Identification SMP: Identity Address Information SMP: Signing Information messages are seen. Logs below: Software Baseline: Kernel 5.41, Bluez 5.42 FYI: BTMon Logs with the issue is included in my original message http://marc.info/?l=linux-bluetooth&m=147356320523622&w=2 **************** Begin BTMon Logs **************************** Bluez Version 5.41 root@Deb-Jessie:~# btmon Bluetooth monitor ver 5.41 = New Index: 44:1C:A8:6C:8F:5E (Primary,UART,hci0) [hci0] 0.513794 < HCI Command: LE Set Random Address (0x08|0x0005) plen 6 [hci0] 46.048934 Address: 27:5A:29:B2:21:56 (Non-Resolvable) > HCI Event: Command Complete (0x0e) plen 4 [hci0] 46.056068 LE Set Random Address (0x08|0x0005) ncmd 1 Status: Success (0x00) > HCI Event: Command Complete (0x0e) plen 4 [hci0] 46.056082 LE Set Random Address (0x08|0x0005) ncmd 1 Status: Success (0x00) < HCI Command: LE Set Scan Parameters (0x08|0x000b) plen 7 [hci0] 46.056152 Type: Active (0x01) Interval: 11.250 msec (0x0012) Window: 11.250 msec (0x0012) Own address type: Random (0x01) Filter policy: Accept all advertisement (0x00) > HCI Event: Command Complete (0x0e) plen 4 [hci0] 46.060881 LE Set Scan Parameters (0x08|0x000b) ncmd 1 Status: Success (0x00) < HCI Command: LE Set Scan Enable (0x08|0x000c) plen 2 [hci0] 46.060960 Scanning: Enabled (0x01) Filter duplicates: Enabled (0x01) > HCI Event: Command Complete (0x0e) plen 4 [hci0] 46.065508 LE Set Scan Enable (0x08|0x000c) ncmd 1 Status: Success (0x00) @ Discovering: 0x01 (7) > HCI Event: LE Meta Event (0x3e) plen 35 [hci0] 46.133086 LE Advertising Report (0x02) Num reports: 1 Event type: Connectable undirected - ADV_IND (0x00) Address type: Random (0x01) Address: 74:9A:AA:38:11:48 (Resolvable) Data length: 23 Flags: 0x06 LE General Discoverable Mode BR/EDR Not Supported Company: Apple, Inc. (76) Type: Unknown (12) Data: 00f9b4952a77038efd49b600f8a8 RSSI: -86 dBm (0xaa) > HCI Event: LE Meta Event (0x3e) plen 12 [hci0] 46.133100 LE Advertising Report (0x02) Num reports: 1 Event type: Scan response - SCAN_RSP (0x04) Address type: Random (0x01) Address: 74:9A:AA:38:11:48 (Resolvable) Data length: 0 RSSI: -83 dBm (0xad) @ Device Found: 74:9A:AA:38:11:48 (2) rssi -83 flags 0x0000 02 01 06 13 ff 4c 00 0c 0e 00 f9 b4 95 2a 77 03 .....L.......*w. 8e fd 49 b6 00 f8 a8 ..I.... > HCI Event: LE Meta Event (0x3e) plen 39 [hci0] 46.144510 LE Advertising Report (0x02) Num reports: 1 Event type: Connectable undirected - ADV_IND (0x00) Address type: Random (0x01) Address: F5:95:EC:75:0F:D3 (Static) Data length: 27 Name (complete): Designer Mouse Appearance: Mouse (0x03c2) Flags: 0x05 LE Limited Discoverable Mode BR/EDR Not Supported 16-bit Service UUIDs (complete): 1 entry Human Interface Device (0x1812) RSSI: -45 dBm (0xd3) > HCI Event: LE Meta Event (0x3e) plen 12 [hci0] 46.144526 LE Advertising Report (0x02) Num reports: 1 Event type: Scan response - SCAN_RSP (0x04) Address type: Random (0x01) Address: F5:95:EC:75:0F:D3 (Static) Data length: 0 RSSI: -45 dBm (0xd3) @ Device Found: F5:95:EC:75:0F:D3 (2) rssi -45 flags 0x0000 0f 09 44 65 73 69 67 6e 65 72 20 4d 6f 75 73 65 ..Designer Mouse 03 19 c2 03 02 01 05 03 03 12 18 ........... > HCI Event: LE Meta Event (0x3e) plen 23 [hci0] 46.532010 LE Advertising Report (0x02) Num reports: 1 Event type: Connectable undirected - ADV_IND (0x00) Address type: Random (0x01) Address: 5E:57:57:96:E8:82 (Resolvable) Data length: 11 Flags: 0x1a LE General Discoverable Mode Simultaneous LE and BR/EDR (Controller) Simultaneous LE and BR/EDR (Host) Company: Apple, Inc. (76) Type: Unknown (16) Data: 0b00 RSSI: -68 dBm (0xbc) > HCI Event: LE Meta Event (0x3e) plen 12 [hci0] 46.532025 LE Advertising Report (0x02) Num reports: 1 Event type: Scan response - SCAN_RSP (0x04) Address type: Random (0x01) Address: 5E:57:57:96:E8:82 (Resolvable) Data length: 0 RSSI: -68 dBm (0xbc) @ Device Found: 5E:57:57:96:E8:82 (2) rssi -68 flags 0x0000 02 01 1a 07 ff 4c 00 10 02 0b 00 .....L..... > HCI Event: LE Meta Event (0x3e) plen 27 [hci0] 48.054711 LE Advertising Report (0x02) Num reports: 1 Event type: Connectable undirected - ADV_IND (0x00) Address type: Public (0x00) Address: 6C:94:F8:DA:38:93 (OUI 6C-94-F8) Data length: 15 Flags: 0x1a LE General Discoverable Mode Simultaneous LE and BR/EDR (Controller) Simultaneous LE and BR/EDR (Host) Company: Apple, Inc. (76) Type: Apple TV (9) Data: 0312c0a80137 RSSI: -82 dBm (0xae) > HCI Event: LE Meta Event (0x3e) plen 19 [hci0] 48.102354 LE Advertising Report (0x02) Num reports: 1 Event type: Connectable undirected - ADV_IND (0x00) Address type: Random (0x01) Address: FE:7D:EE:A0:1B:05 (Static) Data length: 7 Flags: 0x06 LE General Discoverable Mode BR/EDR Not Supported 16-bit Service UUIDs (complete): 1 entry Tile, Inc. (0xfeed) RSSI: -78 dBm (0xb2) @ Device Found: 6C:94:F8:DA:38:93 (1) rssi -82 flags 0x0000 02 01 1a 0b ff 4c 00 09 06 03 12 c0 a8 01 37 .....L........7 < HCI Command: LE Set Scan Enable (0x08|0x000c) plen 2 [hci0] 51.188339 Scanning: Disabled (0x00) Filter duplicates: Disabled (0x00) > HCI Event: Command Complete (0x0e) plen 4 [hci0] 51.194672 LE Set Scan Enable (0x08|0x000c) ncmd 1 Status: Success (0x00) < HCI Command: Inquiry (0x01|0x0001) plen 5 [hci0] 51.194766 Access code: 0x9e8b33 (General Inquiry) Length: 5.12s (0x04) Num responses: 0 @ Device Found: FE:7D:EE:A0:1B:05 (2) rssi -78 flags 0x0000 02 01 06 03 03 ed fe ....... > HCI Event: Command Status (0x0f) plen 4 [hci0] 51.199560 Inquiry (0x01|0x0001) ncmd 1 Status: Success (0x00) < HCI Command: Inquiry Cancel (0x01|0x0002) plen 0 [hci0] 51.499269 > HCI Event: Command Complete (0x0e) plen 4 [hci0] 51.506928 Inquiry Cancel (0x01|0x0002) ncmd 1 Status: Success (0x00) @ Discovering: 0x00 (7) < HCI Command: LE Create Connection (0x08|0x000d) plen 25 [hci0] 51.520384 Scan interval: 60.000 msec (0x0060) Scan window: 30.000 msec (0x0030) Filter policy: White list is not used (0x00) Peer address type: Random (0x01) Peer address: F5:95:EC:75:0F:D3 (Static) Own address type: Public (0x00) Min connection interval: 50.00 msec (0x0028) Max connection interval: 70.00 msec (0x0038) Connection latency: 0x0000 Supervision timeout: 420 msec (0x002a) Min connection length: 0.000 msec (0x0000) Max connection length: 0.000 msec (0x0000) > HCI Event: Command Status (0x0f) plen 4 [hci0] 51.527099 LE Create Connection (0x08|0x000d) ncmd 1 Status: Success (0x00) > HCI Event: LE Meta Event (0x3e) plen 19 [hci0] 51.539824 LE Connection Complete (0x01) Status: Success (0x00) Handle: 2 Role: Master (0x00) Peer address type: Random (0x01) Peer address: F5:95:EC:75:0F:D3 (Static) Connection interval: 67.50 msec (0x0036) Connection latency: 0.00 msec (0x0000) Supervision timeout: 420 msec (0x002a) Master clock accuracy: 0x00 < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 51.540129 SMP: Pairing Request (0x01) len 6 IO capability: KeyboardDisplay (0x04) OOB data: Authentication data not present (0x00) Authentication requirement: Bonding, MITM, Legacy, No Keypresses (0x05) Max encryption key size: 16 Initiator key distribution: EncKey Sign (0x05) Responder key distribution: EncKey IdKey Sign (0x07) @ Device Connected: F5:95:EC:75:0F:D3 (2) flags 0x0000 < ACL Data TX: Handle 2 flags 0x00 dlen 7 [hci0] 51.544335 ATT: Exchange MTU Request (0x02) len 2 Client RX MTU: 517 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 51.584603 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 11 [hci0] 51.652973 SMP: Pairing Response (0x02) len 6 IO capability: NoInputNoOutput (0x03) OOB data: Authentication data not present (0x00) Authentication requirement: Bonding, No MITM, Legacy, No Keypresses (0x01) Max encryption key size: 16 Initiator key distribution: Sign (0x04) Responder key distribution: EncKey IdKey (0x03) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 51.652988 Num handles: 1 Handle: 2 Count: 1 < ACL Data TX: Handle 2 flags 0x00 dlen 21 [hci0] 51.653107 SMP: Pairing Confirm (0x03) len 16 Confim value: e9c9ebe86a8fe83383a9a94af6db8f92 > ACL Data RX: Handle 2 flags 0x02 dlen 7 [hci0] 51.720076 ATT: Exchange MTU Response (0x03) len 2 Server RX MTU: 23 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 51.720091 Num handles: 1 Handle: 2 Count: 1 < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 51.720552 ATT: Read By Group Type Request (0x10) len 6 Handle range: 0x0001-0xffff Attribute group type: Primary Service (0x2800) > ACL Data RX: Handle 2 flags 0x02 dlen 21 [hci0] 51.788846 SMP: Pairing Confirm (0x03) len 16 Confim value: 073fac8c702d72ea5815e0bd08866a3c > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 51.788863 Num handles: 1 Handle: 2 Count: 1 < ACL Data TX: Handle 2 flags 0x00 dlen 21 [hci0] 51.788941 SMP: Pairing Random (0x04) len 16 Random value: a71f0097199364b27fa4753ebc53914f > ACL Data RX: Handle 2 flags 0x02 dlen 24 [hci0] 51.924277 ATT: Read By Group Type Response (0x11) len 19 Attribute data length: 6 Attribute group list: 3 entries Handle range: 0x0001-0x0007 UUID: Generic Access Profile (0x1800) Handle range: 0x0008-0x0008 UUID: Generic Attribute Profile (0x1801) Handle range: 0x0009-0x000d UUID: Device Information (0x180a) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 51.924292 Num handles: 1 Handle: 2 Count: 1 < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 51.924585 ATT: Read By Group Type Request (0x10) len 6 Handle range: 0x000e-0xffff Attribute group type: Primary Service (0x2800) > ACL Data RX: Handle 2 flags 0x02 dlen 21 [hci0] 51.991434 SMP: Pairing Random (0x04) len 16 Random value: 578bb89781435b0e1bb2365d303da298 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 51.991449 Num handles: 1 Handle: 2 Count: 1 < HCI Command: LE Start Encryption (0x08|0x0019) plen 28 [hci0] 51.991561 Handle: 2 Random number: 0x0000000000000000 Encrypted diversifier: 0x0000 Long term key: 5644a3d4171b5b4d6034d00523c717d9 > HCI Event: Command Status (0x0f) plen 4 [hci0] 51.998086 LE Start Encryption (0x08|0x0019) ncmd 1 Status: Success (0x00) > ACL Data RX: Handle 2 flags 0x02 dlen 18 [hci0] 52.057685 ATT: Read By Group Type Response (0x11) len 13 Attribute data length: 6 Attribute group list: 2 entries Handle range: 0x000e-0x0011 UUID: Battery Service (0x180f) Handle range: 0x0012-0xffff UUID: Human Interface Device (0x1812) < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 52.059192 ATT: Read By Group Type Request (0x10) len 6 Handle range: 0x0001-0xffff Attribute group type: Secondary Service (0x2801) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 52.124016 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 9 [hci0] 52.191891 ATT: Error Response (0x01) len 4 Read By Group Type Request (0x10) Handle: 0x0001 Error: Attribute Not Found (0x0a) < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 52.192185 ATT: Read By Type Request (0x08) len 6 Handle range: 0x0001-0x0007 Attribute type: Include (0x2802) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 52.259140 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 9 [hci0] 52.326948 ATT: Error Response (0x01) len 4 Read By Type Request (0x08) Handle: 0x0001 Error: Attribute Not Found (0x0a) < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 52.327248 ATT: Read By Type Request (0x08) len 6 Handle range: 0x0009-0x000d Attribute type: Include (0x2802) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 52.394109 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 9 [hci0] 52.461847 ATT: Error Response (0x01) len 4 Read By Type Request (0x08) Handle: 0x0009 Error: Attribute Not Found (0x0a) < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 52.462156 ATT: Read By Type Request (0x08) len 6 Handle range: 0x000e-0x0011 Attribute type: Include (0x2802) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 52.528989 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 9 [hci0] 52.664309 ATT: Error Response (0x01) len 4 Read By Type Request (0x08) Handle: 0x000e Error: Attribute Not Found (0x0a) < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 52.664624 ATT: Read By Type Request (0x08) len 6 Handle range: 0x0012-0xffff Attribute type: Include (0x2802) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 52.731535 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 9 [hci0] 52.799486 ATT: Error Response (0x01) len 4 Read By Type Request (0x08) Handle: 0x0012 Error: Attribute Not Found (0x0a) < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 52.799778 ATT: Read By Type Request (0x08) len 6 Handle range: 0x0001-0x0007 Attribute type: Characteristic (0x2803) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 52.866517 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 27 [hci0] 53.003647 ATT: Read By Type Response (0x09) len 22 Attribute data length: 7 Attribute data list: 3 entries Handle: 0x0002 Value: 0a0300002a Handle: 0x0004 Value: 020500012a Handle: 0x0006 Value: 020700042a < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 53.003946 ATT: Read By Type Request (0x08) len 6 Handle range: 0x0007-0x0007 Attribute type: Characteristic (0x2803) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 53.068895 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 9 [hci0] 53.137081 ATT: Error Response (0x01) len 4 Read By Type Request (0x08) Handle: 0x0007 Error: Attribute Not Found (0x0a) < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 53.137406 ATT: Read By Type Request (0x08) len 6 Handle range: 0x0009-0x000d Attribute type: Characteristic (0x2803) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 53.271534 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 20 [hci0] 53.340441 ATT: Read By Type Response (0x09) len 15 Attribute data length: 7 Attribute data list: 2 entries Handle: 0x000a Value: 020b00292a Handle: 0x000c Value: 020d00502a < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 53.340827 ATT: Read By Type Request (0x08) len 6 Handle range: 0x000d-0x000d Attribute type: Characteristic (0x2803) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 53.406386 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 9 [hci0] 53.474571 ATT: Error Response (0x01) len 4 Read By Type Request (0x08) Handle: 0x000d Error: Attribute Not Found (0x0a) < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 53.475052 ATT: Read By Type Request (0x08) len 6 Handle range: 0x000e-0x0011 Attribute type: Characteristic (0x2803) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 53.541618 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 13 [hci0] 53.609804 ATT: Read By Type Response (0x09) len 8 Attribute data length: 7 Attribute data list: 1 entry Handle: 0x000f Value: 121000192a < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 53.610123 ATT: Read By Type Request (0x08) len 6 Handle range: 0x0010-0x0011 Attribute type: Characteristic (0x2803) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 53.676514 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 9 [hci0] 53.744308 ATT: Error Response (0x01) len 4 Read By Type Request (0x08) Handle: 0x0010 Error: Attribute Not Found (0x0a) < ACL Data TX: Handle 2 flags 0x00 dlen 9 [hci0] 53.744682 ATT: Find Information Request (0x04) len 4 Handle range: 0x0011-0x0011 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 53.811426 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 10 [hci0] 53.879469 ATT: Find Information Response (0x05) len 5 Format: UUID-16 (0x01) Handle: 0x0011 UUID: Client Characteristic Configuration (0x2902) < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 53.879868 ATT: Read By Type Request (0x08) len 6 Handle range: 0x0012-0xffff Attribute type: Characteristic (0x2803) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 53.946373 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 27 [hci0] 54.016157 ATT: Read By Type Response (0x09) len 22 Attribute data length: 7 Attribute data list: 3 entries Handle: 0x0013 Value: 0614004e2a Handle: 0x0015 Value: 1a16004d2a Handle: 0x0019 Value: 1a1a004d2a < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 54.016510 ATT: Read By Type Request (0x08) len 6 Handle range: 0x001a-0xffff Attribute type: Characteristic (0x2803) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 54.148984 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 27 [hci0] 54.218646 ATT: Read By Type Response (0x09) len 22 Attribute data length: 7 Attribute data list: 3 entries Handle: 0x001d Value: 0a1e004d2a Handle: 0x0020 Value: 0a21004d2a Handle: 0x0023 Value: 0224004b2a < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 54.218967 ATT: Read By Type Request (0x08) len 6 Handle range: 0x0024-0xffff Attribute type: Characteristic (0x2803) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 54.284131 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 27 [hci0] 54.353514 ATT: Read By Type Response (0x09) len 22 Attribute data length: 7 Attribute data list: 3 entries Handle: 0x0025 Value: 1a2600332a Handle: 0x0028 Value: 0229004a2a Handle: 0x002a Value: 042b004c2a < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 54.353873 ATT: Read By Type Request (0x08) len 6 Handle range: 0x002b-0xffff Attribute type: Characteristic (0x2803) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 54.418911 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 9 [hci0] 54.487154 ATT: Error Response (0x01) len 4 Read By Type Request (0x08) Handle: 0x002b Error: Attribute Not Found (0x0a) < ACL Data TX: Handle 2 flags 0x00 dlen 9 [hci0] 54.487600 ATT: Find Information Request (0x04) len 4 Handle range: 0x0017-0x0018 > ACL Data RX: Handle 2 flags 0x02 dlen 14 [hci0] 54.623519 ATT: Find Information Response (0x05) len 9 Format: UUID-16 (0x01) Handle: 0x0017 UUID: Client Characteristic Configuration (0x2902) Handle: 0x0018 UUID: Report Reference (0x2908) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 54.623535 Num handles: 1 Handle: 2 Count: 1 < ACL Data TX: Handle 2 flags 0x00 dlen 9 [hci0] 54.623854 ATT: Find Information Request (0x04) len 4 Handle range: 0x001b-0x001c > ACL Data RX: Handle 2 flags 0x02 dlen 21 [hci0] 55.096893 SMP: Encryption Information (0x06) len 16 Long term key: a2a54ddd7818217ffba02c6523b74e97 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 55.096907 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 14 [hci0] 55.162555 ATT: Find Information Response (0x05) len 9 Format: UUID-16 (0x01) Handle: 0x001b UUID: Client Characteristic Configuration (0x2902) Handle: 0x001c UUID: Report Reference (0x2908) < ACL Data TX: Handle 2 flags 0x00 dlen 9 [hci0] 55.162902 ATT: Find Information Request (0x04) len 4 Handle range: 0x001f-0x001f > ACL Data RX: Handle 2 flags 0x02 dlen 15 [hci0] 55.230975 SMP: Master Identification (0x07) len 10 EDIV: 0x9c1b Rand: 0xfe0ee7ed7698af66 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 55.230990 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 10 [hci0] 55.297075 ATT: Find Information Response (0x05) len 5 Format: UUID-16 (0x01) Handle: 0x001f UUID: Report Reference (0x2908) < ACL Data TX: Handle 2 flags 0x00 dlen 9 [hci0] 55.297414 ATT: Find Information Request (0x04) len 4 Handle range: 0x0022-0x0022 > ACL Data RX: Handle 2 flags 0x02 dlen 21 [hci0] 55.366399 SMP: Identity Information (0x08) len 16 Identity resolving key: ea7102bac3d692626259c72b1613a1ca > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 55.366414 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 10 [hci0] 55.432031 ATT: Find Information Response (0x05) len 5 Format: UUID-16 (0x01) Handle: 0x0022 UUID: Report Reference (0x2908) < ACL Data TX: Handle 2 flags 0x00 dlen 9 [hci0] 55.432387 ATT: Find Information Request (0x04) len 4 Handle range: 0x0027-0x0027 > ACL Data RX: Handle 2 flags 0x02 dlen 12 [hci0] 55.500706 SMP: Identity Address Information (0x09) len 7 Address type: Random (0x01) Address: F5:95:EC:75:0F:D3 (Static) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 55.500721 Num handles: 1 Handle: 2 Count: 1 < ACL Data TX: Handle 2 flags 0x00 dlen 21 [hci0] 55.500874 SMP: Signing Information (0x0a) len 16 Signature key: 76fcf28ce210691a008604b7cf7e3def @ New IRK: F5:95:EC:75:0F:D3 (2) 00:00:00:00:00:00 @ New CSRK: F5:95:EC:75:0F:D3 (2) Local Unauthenticated (0) @ New Long Term Key: F5:95:EC:75:0F:D3 (2) Master (Unauthenticated) 0x00 > ACL Data RX: Handle 2 flags 0x02 dlen 10 [hci0] 55.568053 ATT: Find Information Response (0x05) len 5 Format: UUID-16 (0x01) Handle: 0x0027 UUID: Client Characteristic Configuration (0x2902) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 55.568068 Num handles: 1 Handle: 2 Count: 1 < ACL Data TX: Handle 2 flags 0x00 dlen 9 [hci0] 55.568416 ATT: Find Information Request (0x04) len 4 Handle range: 0x002c-0xffff > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 55.634151 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 9 [hci0] 55.702101 ATT: Error Response (0x01) len 4 Find Information Request (0x04) Handle: 0x002c Error: Attribute Not Found (0x0a) @ Device Added: F5:95:EC:75:0F:D3 (2) 2 < ACL Data TX: Handle 2 flags 0x00 dlen 7 [hci0] 55.721800 ATT: Read Request (0x0a) len 2 Handle: 0x0003 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 55.768866 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 19 [hci0] 55.837793 ATT: Read Response (0x0b) len 14 Value: 44657369676e6572204d6f757365 < ACL Data TX: Handle 2 flags 0x00 dlen 7 [hci0] 55.838123 ATT: Read Request (0x0a) len 2 Handle: 0x0005 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 55.903921 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 7 [hci0] 55.971907 ATT: Read Response (0x0b) len 2 Value: c203 < ACL Data TX: Handle 2 flags 0x00 dlen 7 [hci0] 55.972214 ATT: Read Request (0x0a) len 2 Handle: 0x000d > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 56.038913 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 12 [hci0] 56.107330 ATT: Read Response (0x0b) len 7 Value: 025e0405081001 < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 56.107674 ATT: Read By Type Request (0x08) len 6 Handle range: 0x0012-0xffff Attribute type: Characteristic (0x2803) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 56.173897 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 27 [hci0] 56.243648 ATT: Read By Type Response (0x09) len 22 Attribute data length: 7 Attribute data list: 3 entries Handle: 0x0013 Value: 0614004e2a Handle: 0x0015 Value: 1a16004d2a Handle: 0x0019 Value: 1a1a004d2a < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 56.243991 ATT: Read By Type Request (0x08) len 6 Handle range: 0x001a-0xffff Attribute type: Characteristic (0x2803) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 56.376515 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 27 [hci0] 56.446035 ATT: Read By Type Response (0x09) len 22 Attribute data length: 7 Attribute data list: 3 entries Handle: 0x001d Value: 0a1e004d2a Handle: 0x0020 Value: 0a21004d2a Handle: 0x0023 Value: 0224004b2a < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 56.446643 ATT: Read By Type Request (0x08) len 6 Handle range: 0x0024-0xffff Attribute type: Characteristic (0x2803) > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 56.511473 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 27 [hci0] 56.581195 ATT: Read By Type Response (0x09) len 22 Attribute data length: 7 Attribute data list: 3 entries Handle: 0x0025 Value: 1a2600332a Handle: 0x0028 Value: 0229004a2a Handle: 0x002a Value: 042b004c2a < ACL Data TX: Handle 2 flags 0x00 dlen 11 [hci0] 56.581822 ATT: Read By Type Request (0x08) len 6 Handle range: 0x002b-0xffff Attribute type: Characteristic (0x2803) > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 56.648464 LE L2CAP: Connection Parameter Update Request (0x12) ident 2 len 8 Min interval: 6 Max interval: 6 Slave latency: 60 Timeout multiplier: 300 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 56.648480 Num handles: 1 Handle: 2 Count: 1 < ACL Data TX: Handle 2 flags 0x00 dlen 10 [hci0] 56.648571 LE L2CAP: Connection Parameter Update Response (0x13) ident 2 len 2 Result: Connection Parameters accepted (0x0000) < HCI Command: LE Connection Update (0x08|0x0013) plen 14 [hci0] 56.648584 Handle: 2 Min connection interval: 7.50 msec (0x0006) Max connection interval: 7.50 msec (0x0006) Connection latency: 0x003c Supervision timeout: 3000 msec (0x012c) Min connection length: 0.000 msec (0x0000) Max connection length: 0.000 msec (0x0000) @ New Conn Param: F5:95:EC:75:0F:D3 (2) hint 1 min 0x0006 max 0x0006 latency 0x003c timeout 0x012c > HCI Event: Command Status (0x0f) plen 4 [hci0] 56.655302 LE Connection Update (0x08|0x0013) ncmd 1 Status: Success (0x00) > ACL Data RX: Handle 2 flags 0x02 dlen 9 [hci0] 56.714560 ATT: Error Response (0x01) len 4 Read By Type Request (0x08) Handle: 0x002b Error: Attribute Not Found (0x0a) < ACL Data TX: Handle 2 flags 0x00 dlen 7 [hci0] 56.714908 ATT: Read Request (0x0a) len 2 Handle: 0x0016 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 56.782040 Num handles: 1 Handle: 2 Count: 1 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 56.849127 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 5 [hci0] 56.916579 ATT: Read Response (0x0b) len 0 Value: < ACL Data TX: Handle 2 flags 0x00 dlen 9 [hci0] 56.916870 ATT: Find Information Request (0x04) len 4 Handle range: 0x0017-0x0018 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 56.983895 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 14 [hci0] 57.053457 ATT: Find Information Response (0x05) len 9 Format: UUID-16 (0x01) Handle: 0x0017 UUID: Client Characteristic Configuration (0x2902) Handle: 0x0018 UUID: Report Reference (0x2908) > HCI Event: LE Meta Event (0x3e) plen 10 [hci0] 57.053472 LE Connection Update Complete (0x03) Status: Success (0x00) Handle: 2 Connection interval: 7.50 msec (0x0006) Connection latency: 75.00 msec (0x003c) Supervision timeout: 3000 msec (0x012c) < ACL Data TX: Handle 2 flags 0x00 dlen 7 [hci0] 57.053786 ATT: Read Request (0x0a) len 2 Handle: 0x001a > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.127745 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020000000000000000 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.143330 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 002300ecff00000000 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 57.143345 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.152839 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 000300feff00000000 > ACL Data RX: Handle 2 flags 0x02 dlen 5 [hci0] 57.152854 ATT: Read Response (0x0b) len 0 Value: > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.152856 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 000300feff00000000 < ACL Data TX: Handle 2 flags 0x00 dlen 9 [hci0] 57.153203 ATT: Find Information Request (0x04) len 4 Handle range: 0x001b-0x001c > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.157611 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 000300fdff00000000 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.165798 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 000300feff00000000 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 57.165812 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 14 [hci0] 57.174122 ATT: Find Information Response (0x05) len 9 Format: UUID-16 (0x01) Handle: 0x001b UUID: Client Characteristic Configuration (0x2902) Handle: 0x001c UUID: Report Reference (0x2908) > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.174136 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 000400fdff00000000 < ACL Data TX: Handle 2 flags 0x00 dlen 7 [hci0] 57.174442 ATT: Read Request (0x0a) len 2 Handle: 0x001e > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.179976 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 000400fdff00000000 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.188337 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 000400feff00000000 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 57.188350 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 24 [hci0] 57.197848 ATT: Read Response (0x0b) len 19 Value: 221f0104000000000000000000000000000000 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.197862 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 000200fdff00000000 < ACL Data TX: Handle 2 flags 0x00 dlen 9 [hci0] 57.198132 ATT: Find Information Request (0x04) len 4 Handle range: 0x001f-0x001f > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.202516 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 000200ffff00000000 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.210966 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 000100ffff00000000 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 57.210981 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 10 [hci0] 57.218745 ATT: Find Information Response (0x05) len 5 Format: UUID-16 (0x01) Handle: 0x001f UUID: Report Reference (0x2908) > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.218759 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 000100000000000000 < ACL Data TX: Handle 2 flags 0x00 dlen 7 [hci0] 57.219156 ATT: Read Request (0x0a) len 2 Handle: 0x0021 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.278585 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020000000000000000 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 57.278600 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 5 [hci0] 57.284153 ATT: Read Response (0x0b) len 0 Value: < ACL Data TX: Handle 2 flags 0x00 dlen 9 [hci0] 57.284427 ATT: Find Information Request (0x04) len 4 Handle range: 0x0022-0x0022 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 57.291402 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 10 [hci0] 57.299702 ATT: Find Information Response (0x05) len 5 Format: UUID-16 (0x01) Handle: 0x0022 UUID: Report Reference (0x2908) < ACL Data TX: Handle 2 flags 0x00 dlen 7 [hci0] 57.299977 ATT: Read Request (0x0a) len 2 Handle: 0x0024 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 57.306491 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 27 [hci0] 57.316061 ATT: Read Response (0x0b) len 22 Value: 05010902a10105010902a102851a0901a10005091901 < ACL Data TX: Handle 2 flags 0x00 dlen 7 [hci0] 57.316434 ATT: Read Request (0x0a) len 2 Handle: 0x0014 > ACL Data RX: Handle 2 flags 0x02 dlen 6 [hci0] 57.615841 ATT: Read Response (0x0b) len 1 Value: 01 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.615857 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020100000000000000 < ACL Data TX: Handle 2 flags 0x00 dlen 7 [hci0] 57.616273 ATT: Read Request (0x0a) len 2 Handle: 0x0029 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.622619 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020100000000000000 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 57.628970 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 9 [hci0] 57.636869 ATT: Read Response (0x0b) len 4 Value: 01010003 < ACL Data TX: Handle 2 flags 0x00 dlen 7 [hci0] 57.637119 ATT: Read Request (0x0a) len 2 Handle: 0x0018 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 57.643925 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 7 [hci0] 57.651805 ATT: Read Response (0x0b) len 2 Value: 1a01 < ACL Data TX: Handle 2 flags 0x00 dlen 7 [hci0] 57.652244 ATT: Read Request (0x0a) len 2 Handle: 0x001c > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 57.658852 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 7 [hci0] 57.666751 ATT: Read Response (0x0b) len 2 Value: 2701 < ACL Data TX: Handle 2 flags 0x00 dlen 7 [hci0] 57.667006 ATT: Read Request (0x0a) len 2 Handle: 0x001f > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.675908 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020100000000000000 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 57.675924 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 7 [hci0] 57.683577 ATT: Read Response (0x0b) len 2 Value: 2203 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.683593 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020100000000000000 < ACL Data TX: Handle 2 flags 0x00 dlen 7 [hci0] 57.684037 ATT: Read Request (0x0a) len 2 Handle: 0x0022 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.690032 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020200000000000000 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.698302 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020400000000000000 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 57.698317 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 7 [hci0] 57.706128 ATT: Read Response (0x0b) len 2 Value: 2403 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.706145 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020700000000000000 < ACL Data TX: Handle 2 flags 0x00 dlen 9 [hci0] 57.706549 ATT: Read Blob Request (0x0c) len 4 Handle: 0x0024 Offset: 0x0016 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.712646 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020900000000000000 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.720912 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020800000000000000 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 57.720930 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 27 [hci0] 57.730450 ATT: Read Blob Response (0x0d) len 22 29 05 95 05 75 01 15 00 25 01 81 02 75 03 95 01 )...u...%...u... 81 01 05 01 09 30 .....0 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.730466 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020800000000000000 < ACL Data TX: Handle 2 flags 0x00 dlen 7 [hci0] 57.730892 ATT: Read Request (0x0a) len 2 Handle: 0x0017 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.735069 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020900000000000000 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.743303 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020500000000000000 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 57.743320 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 7 [hci0] 57.751077 ATT: Read Response (0x0b) len 2 Value: 0100 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.751093 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020400000000000000 < ACL Data TX: Handle 2 flags 0x00 dlen 7 [hci0] 57.751558 ATT: Read Request (0x0a) len 2 Handle: 0x001b > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.757730 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020200000000000000 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.766046 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020100000000000000 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 57.766063 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 7 [hci0] 57.773753 ATT: Read Response (0x0b) len 2 Value: 0100 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.773769 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020100000000000000 < ACL Data TX: Handle 2 flags 0x00 dlen 9 [hci0] 57.774198 ATT: Read Blob Request (0x0c) len 4 Handle: 0x0024 Offset: 0x002c > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.938420 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 020000000000000000 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 57.938437 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 27 [hci0] 57.947874 ATT: Read Blob Response (0x0d) len 22 09 31 95 02 75 10 16 01 80 26 ff 7f 81 06 a1 02 .1..u....&...... 85 1a 09 38 35 00 ...85. > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.947890 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 02ffff000000000000 < ACL Data TX: Handle 2 flags 0x00 dlen 9 [hci0] 57.948362 ATT: Write Request (0x12) len 4 Handle: 0x0017 Data: 0100 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.962573 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 02ffff000000000000 > HCI Event: Number of Completed Packets (0x13) plen 5 [hci0] 57.962592 Num handles: 1 Handle: 2 Count: 1 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.962593 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 02ffff000000000000 > ACL Data RX: Handle 2 flags 0x02 dlen 5 [hci0] 57.968509 ATT: Write Response (0x13) len 0 > ACL Data RX: Handle 2 flags 0x02 dlen 16 [hci0] 57.968524 ATT: Handle Value Notification (0x1b) len 11 Handle: 0x0016 Data: 02ffff000000000000 ******************** End ************************************* Thanks, Naveen On Fri, Sep 16, 2016 at 4:02 AM, Szymon Janc <szymon.janc@codecoup.pl> wrote: > > Hi Naveen, > > On 15 September 2016 at 20:58, Naveen Kaje <nckaje@gmail.com> wrote: > > Hi, > > Thanks for all the input so far. I found out that the disconnection is > > coming from Bluetooth SMP in Kernel. After host transmits > > SMP_CMD_PAIRING_RANDOM, > > the HID device sends: > > > > SMP_CMD_ENCRYPT_INFO > > SMP_CMD_MASTER_IDENT > > SMP_CMD_IDENT_ADDR_INFO > > SMP_CMD_IDENT_INFO > > Could you get btmon traces from pairing so that we don't need to speculate? > In general slave should distribute keys only after master enabled encryption. > > There is also a small chance of race between encryption event and ACL data > on USB transport... I've seen this few times on UPFs but have no idea on > how this could be solved without enhancing HCI specification > ^ permalink raw reply [flat|nested] 7+ messages in thread
end of thread, other threads:[~2016-09-16 17:53 UTC | newest]
Thread overview: 7+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2016-09-11 3:06 Issue: Bluez: Bluetooth Mouse Disconnects Soon After Pairing Naveen Kaje
2016-09-11 16:59 ` Luiz Augusto von Dentz
2016-09-12 16:15 ` Naveen Kaje
[not found] ` <CAHFy41-2omfTux6-3VPcv0nHBZEdDu0r0ap2UTrc2z=3c_dtCg@mail.gmail.com>
2016-09-13 9:58 ` Luiz Augusto von Dentz
2016-09-15 18:58 ` Naveen Kaje
2016-09-16 10:02 ` Szymon Janc
2016-09-16 17:53 ` Naveen Kaje
This is an external index of several public inboxes, see mirroring instructions on how to clone and mirror all data and code used by this external index.