The problem is working with the Bluetooth service [off top]

alexanderzhirov

Hello everyone!
I put together a Linux build with my own hands. I'm trying to make Bluetooth work.
I built and run bluez 5.76. The service started without any problems. The wireless headphones were connected. But when you connect either the mouse or the keyboard, problems occur.
I was looking for similar problems and their solutions - I couldn't find anything. I attach the logs that I receive during the connection process:

[bluetooth]# pair 98:FD:B4:5A:5B:BF
Device 98:FD:B4:5A:5B:BF not available
[bluetooth]# pair 98:FD:B4:5A:5B:BF
Attempting to pair with 98:FD:B4:5A:5B:BF
[bluetooth]# hci0 device_flags_changed: 98:FD:B4:5A:5B:BF (BR/EDR)
[bluetooth]#      supp: 0x00000001  curr: 0x00000000
[bluetooth]# hci0 98:FD:B4:5A:5B:BF type BR/EDR connected eir_len 31
[CHG] Device 98:FD:B4:5A:5B:BF Connected: yes
[HP Bluetooth Mouse Z5000]# hci0 new_link_key 98:FD:B4:5A:5B:BF type 0x04 pin_len 0 store_hint 1
[HP Bluetooth Mouse Z5000]# [CHG] Device 98:FD:B4:5A:5B:BF Bonded: yes
[HP Bluetooth Mouse Z5000]# [CHG] Device 98:FD:B4:5A:5B:BF Modalias: usb:v03F0p084Cd0200
[HP Bluetooth Mouse Z5000]# [CHG] Device 98:FD:B4:5A:5B:BF UUIDs: 00001000-0000-1000-8000-00805f9b34fb
[HP Bluetooth Mouse Z5000]# [CHG] Device 98:FD:B4:5A:5B:BF UUIDs: 00001124-0000-1000-8000-00805f9b34fb
[HP Bluetooth Mouse Z5000]# [CHG] Device 98:FD:B4:5A:5B:BF UUIDs: 00001200-0000-1000-8000-00805f9b34fb
[HP Bluetooth Mouse Z5000]# [CHG] Device 98:FD:B4:5A:5B:BF ServicesResolved: yes
[HP Bluetooth Mouse Z5000]# [CHG] Device 98:FD:B4:5A:5B:BF Paired: yes
[HP Bluetooth Mouse Z5000]# Pairing successful
hci0 98:FD:B4:5A:5B:BF type BR/EDR disconnected with reason 2
[CHG] Device 98:FD:B4:5A:5B:BF ServicesResolved: no
[CHG] Device 98:FD:B4:5A:5B:BF Connected: no
[bluetooth]# trust 98:FD:B4:5A:5B:BF
[bluetooth]# [CHG] Device 98:FD:B4:5A:5B:BF Trusted: yes
[bluetooth]# Changing 98:FD:B4:5A:5B:BF trust succeeded
bluetooth]# trust 98:FD:B4:5A:5B:BF
[bluetooth]# connect 98:FD:B4:5A:5B:BF
Attempting to connect to 98:FD:B4:5A:5B:BF
[bluetooth]# hci0 98:FD:B4:5A:5B:BF type BR/EDR connected eir_len 31
[CHG] Device 98:FD:B4:5A:5B:BF Connected: yes
[HP Bluetooth Mouse Z5000]# [CHG] Device 98:FD:B4:5A:5B:BF ServicesResolved: yes
[HP Bluetooth Mouse Z5000]# Failed to connect: org.bluez.Error.NotAvailable br-connection-profile-unavailable
[HP Bluetooth Mouse Z5000]# hci0 98:FD:B4:5A:5B:BF type BR/EDR disconnected with reason 2
[HP Bluetooth Mouse Z5000]# [CHG] Device 98:FD:B4:5A:5B:BF ServicesResolved: no
[CHG] Device 98:FD:B4:5A:5B:BF Connected: noh

Debugging writes this output:

bluetoothd[5461]: src/device.c:connect_profiles() /org/bluez/hci0/dev_98_FD_B4_5A_5B_BF (all), client :1.69
bluetoothd[5461]: src/device.c:connect_profiles() Resolving services for /org/bluez/hci0/dev_98_FD_B4_5A_5B_BF
bluetoothd[5461]: src/shared/mgmt.c:can_read_data() [0x0000] event 0x000b
bluetoothd[5461]: src/adapter.c:connected_callback() hci0 device 98:FD:B4:5A:5B:BF connected eir_len 26
bluetoothd[5461]: src/device.c:search_cb() 98:FD:B4:5A:5B:BF: No service update
bluetoothd[5461]: src/device.c:device_svc_resolved() /org/bluez/hci0/dev_98_FD_B4_5A_5B_BF err 0
bluetoothd[5461]: src/device.c:connect_profiles() /org/bluez/hci0/dev_98_FD_B4_5A_5B_BF (all), client :1.69
bluetoothd[5461]: src/shared/mgmt.c:can_read_data() [0x0000] event 0x000c
bluetoothd[5461]: src/adapter.c:dev_disconnected() Device 98:FD:B4:5A:5B:BF disconnected, reason 2
bluetoothd[5461]: src/adapter.c:adapter_remove_connection()
bluetoothd[5461]: plugins/policy.c:disconnect_cb() reason 2
bluetoothd[5461]: src/adapter.c:bonding_attempt_complete() hci0 bdaddr 98:FD:B4:5A:5B:BF type 0 status 0xe
bluetoothd[5461]: src/device.c:device_bonding_complete() bonding (nil) status 0x0e
bluetoothd[5461]: src/device.c:device_bonding_failed() status 14
bluetoothd[5461]: src/adapter.c:resume_discovery()

Debugging via btmoni is also available.

I've already started looking for a problem in the kernel, maybe I didn't specify the necessary parameters during the build?

The theme is off top. I am looking for the help of knowledgeable people.

WetGeek

alexanderzhirov The wireless headphones were connected. But when you connect either the mouse or the keyboard, problems occur.

Not that it helps much, but I have a similar problem with Bluetooth on my Xfce laptop. It's not home-built, but simply installed from Solus repository. On that machine, I can connect and use a Logitech Triathlon mouse, and a set of earbuds, but none of three different brands of keyboards will install and operate correctly. I've been trying after every update for months.

Although our issues aren't exactly the same, I'll be following your adventure, so see if a solution that works for you might work for me as well. Good luck with it!

alexanderzhirov

At first I thought that the problem was in the bluez version, I thought to downgrade and check. But in Solus, the same version of bluez and my mouse connects here without any problems. So the problem is something else.

[bluetooth]# scan on
[bluetooth]# SetDiscoveryFilter success
[bluetooth]# Discovery started
[bluetooth]# [CHG] Controller E8:48:B8:C8:20:00 Discovering: yes
[bluetooth]# [NEW] Device 98:FD:B4:5A:5B:BF HP Bluetooth Mouse Z5000
[bluetooth]# [CHG] Device 98:FD:B4:5A:5B:BF RSSI: 0xffffffb4 (-76)
[bluetooth]# [CHG] Device 98:FD:B4:5A:5B:BF RSSI: 0xffffffc0 (-64)
[bluet98:FD:B4:5A:5B:BFD:B4:5A:5B:BF
[bluetooth]# [CHG] Device 98:FD:B4:5A:5B:BF Trusted: yes
[bluetooth]# Changing 98:FD:B4:5A:5B:BF trust succeeded
[blue98:FD:B4:5A:5B:BFD:B4:5A:5B:BF
Attempting to pair with 98:FD:B4:5A:5B:BF
[CHG] Device 98:FD:B4:5A:5B:BF Connected: yes
[HP Bluetooth Mouse Z5000]# [CHG] Device 98:FD:B4:5A:5B:BF Bonded: yes
[CHG] Device 98:FD:B4:5A:5B:BF Modalias: usb:v03F0p084Cd0200
[CHG] Device 98:FD:B4:5A:5B:BF UUIDs: 00001000-0000-1000-8000-00805f9b34fb
[CHG] Device 98:FD:B4:5A:5B:BF UUIDs: 00001124-0000-1000-8000-00805f9b34fb
[CHG] Device 98:FD:B4:5A:5B:BF UUIDs: 00001200-0000-1000-8000-00805f9b34fb
[CHG] Device 98:FD:B4:5A:5B:BF ServicesResolved: yes
[CHG] Device 98:FD:B4:5A:5B:BF Paired: yes
Pairing successful
[CHG] Device 98:FD:B4:5A:5B:BF WakeAllowed: yes
[HP Blue98:FD:B4:5A:5B:BF]# connect 98:FD:B4:5A:5B:BF
Attempting to connect to 98:FD:B4:5A:5B:BF
[HP Bluetooth Mouse Z5000]# Connection successful

P.S. There is a suspicion that it is necessary to dig towards the D-Bus. I use version 1.12.16 in my build.

alexanderzhirov

I updated D-Bud to version 1.14.10, but it didn't do anything...
Judging by the debugging of the Bluetooth service, there is nothing terrible. Although, I may not see something.

P.S. Although there are some parts of the log that talk about errors

мая 28 20:30:43 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: profiles/input/device.c:input_device_register() /org/bluez/hci0/dev_98_FD_B4_5A_5B_BF
мая 28 20:30:43 ts-54e1ad8d94da bluetoothd[1641]: profiles/input/device.c:input_device_register() /org/bluez/hci0/dev_98_FD_B4_5A_5B_BF
мая 28 20:30:43 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/service.c:btd_service_ref() 0x1575eb0: ref=2
мая 28 20:30:43 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: profiles/input/device.c:input_device_register() bt_uhid_new_default: failed
мая 28 20:30:43 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/service.c:btd_service_unref() 0x1575eb0: ref=1
мая 28 20:30:43 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/service.c:service_probe() input-hid profile probe failed for 98:FD:B4:5A:5B:BF
мая 28 20:30:43 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/service.c:btd_service_unref() 0x1575eb0: ref=0
мая 28 20:30:43 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/device.c:device_svc_resolved() /org/bluez/hci0/dev_98_FD_B4_5A_5B_BF err 0
мая 28 20:30:43 ts-54e1ad8d94da bluetoothd[1641]: src/service.c:btd_service_ref() 0x1575eb0: ref=2
мая 28 20:30:43 ts-54e1ad8d94da bluetoothd[1641]: profiles/input/device.c:input_device_register() bt_uhid_new_default: failed
мая 28 20:30:43 ts-54e1ad8d94da bluetoothd[1641]: src/service.c:btd_service_unref() 0x1575eb0: ref=1
мая 28 20:30:43 ts-54e1ad8d94da bluetoothd[1641]: src/service.c:service_probe() input-hid profile probe failed for 98:FD:B4:5A:5B:BF
мая 28 20:30:43 ts-54e1ad8d94da bluetoothd[1641]: src/service.c:btd_service_unref() 0x1575eb0: ref=0
мая 28 20:30:43 ts-54e1ad8d94da bluetoothd[1641]: src/device.c:device_svc_resolved() /org/bluez/hci0/dev_98_FD_B4_5A_5B_BF err 0
мая 28 20:30:46 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/shared/mgmt.c:can_read_data() [0x0000] event 0x000c
мая 28 20:30:46 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/adapter.c:dev_disconnected() Device 98:FD:B4:5A:5B:BF disconnected, reason 2
мая 28 20:30:46 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/adapter.c:adapter_remove_connection()
мая 28 20:30:46 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: plugins/policy.c:disconnect_cb() reason 2
мая 28 20:30:46 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/adapter.c:bonding_attempt_complete() hci0 bdaddr 98:FD:B4:5A:5B:BF type 0 status 0xe
мая 28 20:30:46 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/device.c:device_bonding_complete() bonding (nil) status 0x0e
мая 28 20:30:46 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/device.c:device_bonding_failed() status 14
мая 28 20:30:46 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/adapter.c:resume_discovery()
мая 28 20:30:46 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/adapter.c:trigger_start_discovery()
мая 28 20:30:46 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/adapter.c:cancel_passive_scanning()
мая 28 20:30:46 ts-54e1ad8d94da bluetoothd[1641]: src/shared/mgmt.c:can_read_data() [0x0000] event 0x000c
мая 28 20:30:46 ts-54e1ad8d94da bluetoothd[1641]: src/adapter.c:dev_disconnected() Device 98:FD:B4:5A:5B:BF disconnected, reason 2
мая 28 20:30:46 ts-54e1ad8d94da bluetoothd[1641]: src/adapter.c:adapter_remove_connection()
мая 28 20:30:46 ts-54e1ad8d94da bluetoothd[1641]: plugins/policy.c:disconnect_cb() reason 2
мая 28 20:30:46 ts-54e1ad8d94da bluetoothd[1641]: src/adapter.c:bonding_attempt_complete() hci0 bdaddr 98:FD:B4:5A:5B:BF type 0 status 0xe
мая 28 20:30:46 ts-54e1ad8d94da bluetoothd[1641]: src/device.c:device_bonding_complete() bonding (nil) status 0x0e
мая 28 20:30:46 ts-54e1ad8d94da bluetoothd[1641]: src/device.c:device_bonding_failed() status 14
мая 28 20:30:46 ts-54e1ad8d94da bluetoothd[1641]: src/adapter.c:resume_discovery()

мая 28 20:31:05 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/shared/mgmt.c:can_read_data() [0x0000] event 0x000c
мая 28 20:31:05 ts-54e1ad8d94da bluetoothd[1641]: src/shared/mgmt.c:can_read_data() [0x0000] event 0x000c
мая 28 20:31:05 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/adapter.c:dev_disconnected() Device 98:FD:B4:5A:5B:BF disconnected, reason 2
мая 28 20:31:05 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/adapter.c:adapter_remove_connection()
мая 28 20:31:05 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: plugins/policy.c:disconnect_cb() reason 2
мая 28 20:31:05 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/adapter.c:bonding_attempt_complete() hci0 bdaddr 98:FD:B4:5A:5B:BF type 0 status 0xe
мая 28 20:31:05 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/device.c:device_bonding_complete() bonding (nil) status 0x0e
мая 28 20:31:05 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/device.c:device_bonding_failed() status 14
мая 28 20:31:05 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/adapter.c:resume_discovery()
мая 28 20:31:05 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/adapter.c:trigger_start_discovery()
мая 28 20:31:05 ts-54e1ad8d94da bluetoothd[1641]: bluetoothd[1641]: src/adapter.c:cancel_passive_scanning()
мая 28 20:31:05 ts-54e1ad8d94da bluetoothd[1641]: src/adapter.c:dev_disconnected() Device 98:FD:B4:5A:5B:BF disconnected, reason 2
мая 28 20:31:05 ts-54e1ad8d94da bluetoothd[1641]: src/adapter.c:adapter_remove_connection()
мая 28 20:31:05 ts-54e1ad8d94da bluetoothd[1641]: plugins/policy.c:disconnect_cb() reason 2
мая 28 20:31:05 ts-54e1ad8d94da bluetoothd[1641]: src/adapter.c:bonding_attempt_complete() hci0 bdaddr 98:FD:B4:5A:5B:BF type 0 status 0xe
мая 28 20:31:05 ts-54e1ad8d94da bluetoothd[1641]: src/device.c:device_bonding_complete() bonding (nil) status 0x0e
мая 28 20:31:05 ts-54e1ad8d94da bluetoothd[1641]: src/device.c:device_bonding_failed() status 14
мая 28 20:31:05 ts-54e1ad8d94da bluetoothd[1641]: src/adapter.c:resume_discovery()

alexanderzhirov

The problem was solved. The problem was that the uhid module was not loaded. I tested it on several computers under different distributions and the lsmod check showed that the loaded uhid module was missing from my build. After rebuilding the kernel with the inclusion of the uhid module and loading it in the booted modprobe uhid distribution, my devices began to connect without problems.