* 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
* 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.