Hello Bluetooth List,
this is a first post here as I found no other place to report bugs. Please forgive if I do not report according to the expected format or missed any additional information and point me to the respective guidelines - thank you!
==Problem summary:==
Bluetooth pairing with a mouse is lost after system reboot. The pairing has to be removed and a new pairing initiated for being able to use the mouse again.
==System specifications:==
Framework Laptop 16 (Batch 7)
CPU: Ryzen 7 7840HS
GPU: AMD RX 7700S
WiFi/BT controller: Mediatek RZ616
Linux Kernel: 6.9.7-200.fc40.x86_64
Distro: Fedora 40
Bluez version: 5.76-1.fc40
Peripheral device: Cherry Gentix BT
==Reproduction steps:==
1) Pair the Bluetooth mouse via GUI or bluetoothctl
2) The mouse will now work as expected
3) Reboot the system
4) The mouse does not work, the GUI might switch between "connected/disconnected"
5) start btmon
6) clicking the left mouse button produced the attached btmon log
Sometimes btmon also displays the following errors after MGMT Event: Device disconnected (sorry, console cut them, could not capture to file):
bluetoothd[2974]: = profiles/input/hog-lib.c:proto_mode_read_cb() Protocol Mode characteristic read failed: Request attribute has encountered an... 14.720255
bluetoothd[2974]: = profiles/input/hog-lib.c:report_reference_cb() Read Report Reference descriptor failed: Request attribute has encountered an... 14.720368
bluetoothd[2974]: = profiles/input/hog-lib.c:report_reference_cb() Read Report Reference descriptor failed: Request attribute has encountered an... 14.720401
bluetoothd[2974]: = profiles/input/hog-lib.c:info_read_cb() HID Information read failed: Request attribute has encountered an unlikely error 14.720616
bluetoothd[2974]: = profiles/deviceinfo/deviceinfo.c:read_pnpid_cb() Error reading PNP_ID value: Request attribute has encountered an unlikely er.. 14.720675
==Additional Information:==
The mouse also sometimes stops working suddenly. In this case it might come back after a few seconds, but sometimes I have to use "systemctl restart bluetooth" which re-enables the mouse. This might be a different problem though. I have no way to reproduce this well so I also could not produce a log file yet.
Please tell me if I can provide any additional information. Thank you for your work on this piece of software! :)
Cheers,
Jonas
Bluetooth monitor ver 5.76
btmon[6817]: = Note: Linux version 6.9.7-200.fc40.x86_64 (x86_64) 0.119033
btmon[6817]: = Note: Bluetooth subsystem version 2.22 0.119035
= New Index: A8:3B:76:BA:B7:8E (Primary,USB,hci0) [hci0] 0.119036
= Open Index: A8:3B:76:BA:B7:8E [hci0] 0.119037
= Index Info: A8:3B:76:BA:B7:8E (MediaTek, Inc.) [hci0] 0.119037
bluetoothd[2974]: @ MGMT Open: bluetoothd (privileged) version 1.22 {0x0001} 0.119038
> HCI Event: LE Meta Event (0x3e) plen 26 #1 [hci0] 2.027007
LE Extended Advertising Report (0x0d)
Num reports: 1
Entry 0
Event type: 0x0015
Props: 0x0015
Connectable
Directed
Use legacy advertising PDUs
Data status: Complete
Legacy PDU Type: ADV_DIRECT_IND (0x0015)
Address type: Random (0x01)
Address: E0:70:3C:3F:18:BD (Static)
Primary PHY: LE 1M
Secondary PHY: No packets
SID: no ADI field (0xff)
TX power: 127 dBm
RSSI: -38 dBm (0xda)
Periodic advertising interval: 0.00 msec (0x0000)
Direct address type: Public (0x00)
Direct address: A8:3B:76:BA:B7:8E (CLOUD NETWORK TECHNOLOGY SINGAPORE PTE. LTD.)
Data length: 0x00
< HCI Command: LE Set Extended Scan Enable (0x08|0x0042) plen 6 #2 [hci0] 2.027150
Extended scan: Disabled (0x00)
Filter duplicates: Disabled (0x00)
Duration: 0 msec (0x0000)
Period: 0.00 sec (0x0000)
> HCI Event: Command Complete (0x0e) plen 4 #3 [hci0] 2.027805
LE Set Extended Scan Enable (0x08|0x0042) ncmd 1
Status: Success (0x00)
< HCI Command: LE Extended Create Connection (0x08|0x0043) plen 26 #4 [hci0] 2.027986
Filter policy: Accept list is not used (0x00)
Own address type: Public (0x00)
Peer address type: Random (0x01)
Peer address: E0:70:3C:3F:18:BD (Static)
Initiating PHYs: 0x01
Entry 0: LE 1M
Scan interval: 60.000 msec (0x0060)
Scan window: 60.000 msec (0x0060)
Min connection interval: 8.75 msec (0x0007)
Max connection interval: 8.75 msec (0x0007)
Connection latency: 48 (0x0030)
Supervision timeout: 3000 msec (0x012c)
Min connection length: 0.000 msec (0x0000)
Max connection length: 0.000 msec (0x0000)
> HCI Event: Command Status (0x0f) plen 4 #5 [hci0] 2.028674
LE Extended Create Connection (0x08|0x0043) ncmd 1
Status: Success (0x00)
> HCI Event: LE Meta Event (0x3e) plen 31 #6 [hci0] 2.151241
LE Enhanced Connection Complete (0x0a)
Status: Success (0x00)
Handle: 512
Role: Central (0x00)
Peer address type: Random (0x01)
Peer address: E0:70:3C:3F:18:BD (Static)
Local resolvable private address: 00:00:00:00:00:00 (Non-Resolvable)
Peer resolvable private address: 00:00:00:00:00:00 (Non-Resolvable)
Connection interval: 8.75 msec (0x0007)
Connection latency: 48 (0x0030)
Supervision timeout: 3000 msec (0x012c)
Central clock accuracy: 0x00
@ MGMT Event: Device Connected (0x000b) plen 13 {0x0001} [hci0] 2.151317
LE Address: E0:70:3C:3F:18:BD (Static)
Flags: 0x00000008
Connection Locally Initiated
Data length: 0
btmon[6817]: @ RAW Open: btmon (privileged) version 2.22 {0x0002} 2.151474
btmon[6817]: @ RAW Close: btmon {0x0002} 2.151482
> HCI Event: LE Meta Event (0x3e) plen 4 #7 [hci0] 2.151444
LE Channel Selection Algorithm (0x14)
Handle: 512 Address: E0:70:3C:3F:18:BD (Static)
Algorithm: #1 (0x00)
< HCI Command: LE Read Remote Used Features (0x08|0x0016) plen 2 #8 [hci0] 2.170481
Handle: 512 Address: E0:70:3C:3F:18:BD (Static)
> HCI Event: Command Status (0x0f) plen 4 #9 [hci0] 2.170841
LE Read Remote Used Features (0x08|0x0016) ncmd 1
Status: Success (0x00)
> HCI Event: LE Meta Event (0x3e) plen 12 #10 [hci0] 2.185293
LE Read Remote Used Features (0x04)
Status: Success (0x00)
Handle: 512 Address: E0:70:3C:3F:18:BD (Static)
Features: 0x01 0x00 0x00 0x00 0x00 0x00 0x00 0x00
LE Encryption
< ACL Data TX: Handle 512 flags 0x00 dlen 11 #11 [hci0] 2.185573
SMP: Pairing Request (0x01) len 6
IO capability: NoInputNoOutput (0x03)
OOB data: Authentication data not present (0x00)
Authentication requirement: No bonding, No MITM, SC, No Keypresses, CT2 (0x28)
Max encryption key size: 16
Initiator key distribution: <none> (0x08)
Responder key distribution: IdKey LinkKey (0x0a)
> HCI Event: Number of Completed Packets (0x13) plen 5 #12 [hci0] 2.193976
Num handles: 1
Handle: 512 Address: E0:70:3C:3F:18:BD (Static)
Count: 1
#11: len 11 (11 Kb/s)
Latency: 8 msec (8-8 msec ~8 msec)
> ACL Data RX: Handle 512 flags 0x02 dlen 11 #13 [hci0] 2.204412
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: <none> (0x00)
Responder key distribution: IdKey (0x02)
@ MGMT Event: User Confirmation Request (0x000f) plen 12 {0x0001} [hci0] 2.204472
LE Address: E0:70:3C:3F:18:BD (Static)
Confirm hint: 0x01
Value: 0x00000000
bluetoothd[2974]: @ MGMT Command: User Confirmation Negative Reply (0x001d) plen 7 {0x0001} [hci0] 2.204624
LE Address: E0:70:3C:3F:18:BD (Static)
@ MGMT Event: Authentication Failed (0x0011) plen 8 {0x0001} [hci0] 2.204637
LE Address: E0:70:3C:3F:18:BD (Static)
Status: Authentication Failed (0x05)
@ MGMT Event: Command Complete (0x0001) plen 10 {0x0001} [hci0] 2.204647
User Confirmation Negative Reply (0x001d) plen 7
Status: Success (0x00)
LE Address: E0:70:3C:3F:18:BD (Static)
< ACL Data TX: Handle 512 flags 0x00 dlen 6 #14 [hci0] 2.204648
SMP: Pairing Failed (0x05) len 1
Reason: Passkey entry failed (0x01)
> HCI Event: Number of Completed Packets (0x13) plen 5 #15 [hci0] 2.211473
Num handles: 1
Handle: 512 Address: E0:70:3C:3F:18:BD (Static)
Count: 1
#14: len 6 (8 Kb/s)
Latency: 6 msec (6-8 msec ~7 msec)
> HCI Event: Disconnect Complete (0x05) plen 4 #16 [hci0] 14.719679
Status: Success (0x00)
Handle: 512 Address: E0:70:3C:3F:18:BD (Static)
Reason: Connection Timeout (0x08)
@ MGMT Event: Device Disconnected (0x000c) plen 8 {0x0001} [hci0] 14.719767
LE Address: E0:70:3C:3F:18:BD (Static)
Reason: Connection timeout (0x01)
bluetoothd[2974]: = profiles/input/hog-lib.c:proto_mode_read_cb() Protocol Mode characteristic read failed: Request attribute has encountered an... 14.720255
bluetoothd[2974]: = profiles/input/hog-lib.c:report_reference_cb() Read Report Reference descriptor failed: Request attribute has encountered an... 14.720368
bluetoothd[2974]: = profiles/input/hog-lib.c:report_reference_cb() Read Report Reference descriptor failed: Request attribute has encountered an... 14.720401
bluetoothd[2974]: = profiles/input/hog-lib.c:info_read_cb() HID Information read failed: Request attribute has encountered an unlikely error 14.720616
bluetoothd[2974]: = profiles/deviceinfo/deviceinfo.c:read_pnpid_cb() Error reading PNP_ID value: Request attribute has encountered an unlikely er.. 14.720675
> ACL Data RX: Handle 3837 flags 0x02 dlen 141 #17 [hci0] 14.719743
Channel: 2306 len 137 [PSM 0 mode Basic (0x00)] {chan 65535}
00 02 f6 f7 0d 00 00 00 ee 00 00 00 00 00 00 00 ................
00 e4 09 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
00 56 01 00 00 9d 05 00 00 55 01 00 00 00 00 00 .V.......U......
00 00 00 00 00 00 00 00 00 00 00 00 00 65 d2 0d .............e..
00 07 d6 ff ff ff ff 1f 07 00 20 01 06 00 70 19 .......... ...p.
00 00 02 03 00 00 70 19 00 00 02 03 00 00 70 19 ......p.......p.
00 00 02 03 00 00 70 19 00 00 02 03 00 00 70 19 ......p.......p.
00 00 02 03 00 00 70 19 00 00 02 03 00 00 70 00 ......p.......p.
00 00 70 19 00 00 02 03 00 ..p......
< HCI Command: LE Set Extended Scan Parameters (0x08|0x0041) plen 13 #18 [hci0] 14.741570
Own address type: Public (0x00)
Filter policy: Ignore not in accept list (0x01)
PHYs: 0x05
Entry 0: LE 1M
Type: Passive (0x00)
Interval: 60.000 msec (0x0060)
Window: 30.000 msec (0x0030)
Entry 1: LE Coded
Type: Passive (0x00)
Interval: 180.000 msec (0x0120)
Window: 90.000 msec (0x0090)
> HCI Event: Command Complete (0x0e) plen 4 #19 [hci0] 14.743132
LE Set Extended Scan Parameters (0x08|0x0041) ncmd 1
Status: Success (0x00)
< HCI Command: LE Set Extended Scan Enable (0x08|0x0042) plen 6 #20 [hci0] 14.743208
Extended scan: Enabled (0x01)
Filter duplicates: Enabled (0x01)
Duration: 0 msec (0x0000)
Period: 0.00 sec (0x0000)
> HCI Event: Command Complete (0x0e) plen 4 #21 [hci0] 14.744113
LE Set Extended Scan Enable (0x08|0x0042) ncmd 1
Status: Success (0x00)
> HCI Event: LE Meta Event (0x3e) plen 26 #22 [hci0] 14.792088
LE Extended Advertising Report (0x0d)
Num reports: 1
Entry 0
Event type: 0x0015
Props: 0x0015
Connectable
Directed
Use legacy advertising PDUs
Data status: Complete
Legacy PDU Type: ADV_DIRECT_IND (0x0015)
Address type: Random (0x01)
Address: E0:70:3C:3F:18:BD (Static)
Primary PHY: LE 1M
Secondary PHY: No packets
SID: no ADI field (0xff)
TX power: 127 dBm
RSSI: -36 dBm (0xdc)
Periodic advertising interval: 0.00 msec (0x0000)
Direct address type: Public (0x00)
Direct address: A8:3B:76:BA:B7:8E (CLOUD NETWORK TECHNOLOGY SINGAPORE PTE. LTD.)
Data length: 0x00
< HCI Command: LE Set Extended Scan Enable (0x08|0x0042) plen 6 #23 [hci0] 14.792236
Extended scan: Disabled (0x00)
Filter duplicates: Disabled (0x00)
Duration: 0 msec (0x0000)
Period: 0.00 sec (0x0000)
> HCI Event: Command Complete (0x0e) plen 4 #24 [hci0] 14.793130
LE Set Extended Scan Enable (0x08|0x0042) ncmd 1
Status: Success (0x00)
< HCI Command: LE Extended Create Connection (0x08|0x0043) plen 26 #25 [hci0] 14.793270
Filter policy: Accept list is not used (0x00)
Own address type: Public (0x00)
Peer address type: Random (0x01)
Peer address: E0:70:3C:3F:18:BD (Static)
Initiating PHYs: 0x01
Entry 0: LE 1M
Scan interval: 60.000 msec (0x0060)
Scan window: 60.000 msec (0x0060)
Min connection interval: 8.75 msec (0x0007)
Max connection interval: 8.75 msec (0x0007)
Connection latency: 48 (0x0030)
Supervision timeout: 3000 msec (0x012c)
Min connection length: 0.000 msec (0x0000)
Max connection length: 0.000 msec (0x0000)
> HCI Event: Command Status (0x0f) plen 4 #26 [hci0] 14.794739
LE Extended Create Connection (0x08|0x0043) ncmd 1
Status: Success (0x00)
> HCI Event: LE Meta Event (0x3e) plen 31 #27 [hci0] 14.821064
LE Enhanced Connection Complete (0x0a)
Status: Success (0x00)
Handle: 512
Role: Central (0x00)
Peer address type: Random (0x01)
Peer address: E0:70:3C:3F:18:BD (Static)
Local resolvable private address: 00:00:00:00:00:00 (Non-Resolvable)
Peer resolvable private address: 00:00:00:00:00:00 (Non-Resolvable)
Connection interval: 8.75 msec (0x0007)
Connection latency: 48 (0x0030)
Supervision timeout: 3000 msec (0x012c)
Central clock accuracy: 0x00
@ MGMT Event: Device Connected (0x000b) plen 13 {0x0001} [hci0] 14.821161
LE Address: E0:70:3C:3F:18:BD (Static)
Flags: 0x00000008
Connection Locally Initiated
Data length: 0
btmon[6817]: @ RAW Open: btmon (privileged) version 2.22 {0x0002} 14.822206
btmon[6817]: @ RAW Close: btmon {0x0002} 14.822229
> HCI Event: LE Meta Event (0x3e) plen 4 #28 [hci0] 14.821389
LE Channel Selection Algorithm (0x14)
Handle: 512 Address: E0:70:3C:3F:18:BD (Static)
Algorithm: #1 (0x00)
< HCI Command: LE Read Remote Used Features (0x08|0x0016) plen 2 #29 [hci0] 14.831453
Handle: 512 Address: E0:70:3C:3F:18:BD (Static)
> HCI Event: Command Status (0x0f) plen 4 #30 [hci0] 14.831886
LE Read Remote Used Features (0x08|0x0016) ncmd 1
Status: Success (0x00)
> HCI Event: LE Meta Event (0x3e) plen 12 #31 [hci0] 14.846486
LE Read Remote Used Features (0x04)
Status: Success (0x00)
Handle: 512 Address: E0:70:3C:3F:18:BD (Static)
Features: 0x01 0x00 0x00 0x00 0x00 0x00 0x00 0x00
LE Encryption
< ACL Data TX: Handle 512 flags 0x00 dlen 11 #32 [hci0] 14.846766
SMP: Pairing Request (0x01) len 6
IO capability: NoInputNoOutput (0x03)
OOB data: Authentication data not present (0x00)
Authentication requirement: No bonding, No MITM, SC, No Keypresses, CT2 (0x28)
Max encryption key size: 16
Initiator key distribution: <none> (0x08)
Responder key distribution: IdKey LinkKey (0x0a)
> HCI Event: Number of Completed Packets (0x13) plen 5 #33 [hci0] 14.855389
Num handles: 1
Handle: 512 Address: E0:70:3C:3F:18:BD (Static)
Count: 1
#32: len 11 (11 Kb/s)
Latency: 8 msec (8-8 msec ~8 msec)
> ACL Data RX: Handle 512 flags 0x02 dlen 11 #34 [hci0] 14.865415
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: <none> (0x00)
Responder key distribution: IdKey (0x02)
@ MGMT Event: User Confirmation Request (0x000f) plen 12 {0x0001} [hci0] 14.865436
LE Address: E0:70:3C:3F:18:BD (Static)
Confirm hint: 0x01
Value: 0x00000000
bluetoothd[2974]: @ MGMT Command: User Confirmation Negative Reply (0x001d) plen 7 {0x0001} [hci0] 14.865612
LE Address: E0:70:3C:3F:18:BD (Static)
@ MGMT Event: Authentication Failed (0x0011) plen 8 {0x0001} [hci0] 14.865627
LE Address: E0:70:3C:3F:18:BD (Static)
Status: Authentication Failed (0x05)
< ACL Data TX: Handle 512 flags 0x00 dlen 6 #35 [hci0] 14.865629
SMP: Pairing Failed (0x05) len 1
Reason: Passkey entry failed (0x01)
@ MGMT Event: Command Complete (0x0001) plen 10 {0x0001} [hci0] 14.865639
User Confirmation Negative Reply (0x001d) plen 7
Status: Success (0x00)
LE Address: E0:70:3C:3F:18:BD (Static)
> HCI Event: Number of Completed Packets (0x13) plen 5 #36 [hci0] 14.872885
Num handles: 1
Handle: 512 Address: E0:70:3C:3F:18:BD (Static)
Count: 1
#35: len 6 (6 Kb/s)
Latency: 7 msec (7-8 msec ~7 msec)
