You are not logged in.
I have two bluetooth keyboards (Apple and Gembird) and both of them have the following issue with bluez v5.30 on Asus ux32vd
Keyboards pairs, trusted and connects fine through bluetoothctl. After it is messaged as `connected` it may work or not. If it works it will break on one of keyboard power save reconnects. And running `power off` `power on` 1-5 times fixes keys to the "working" state.
It's very frustrating and was one of issues that moved me to Arch.
Last edited by c0ffeeartc (2015-05-15 13:34:47)
Offline
I narrowed down problem to bluetoothctl trust command. When I leave keyboard untrusted after each reconnect attempt following prompt pops in bluetoothctl:
[agent] Authorize service 00001124-0000-1000-8000-00805f9b34fb (yes/no): After entering `yes`, before timer runs out, keyboard always works correctly.
Is there a way to automatically answer `yes` to this prompt inside or outside bluetoothctl avoiding trust command?
What could lead to such behavoiur?
UPDATE: manual `yes` with untrusting also appears to be unreliable, and requires `power off`, `power on` once to fix sometimes.
Is there a way to make bluetooth keyboard never sleep/reconnect?
Last edited by c0ffeeartc (2015-05-10 17:29:44)
Offline
I found the pattern through `udevadm monitor`
After keyboard gets disconnected, here is how it connects sucessfully after keypress.
KERNEL[22817.420693] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:34/0005:0A5C:0239.0036/power_supply/hid-00:18:ac:38:94:32-battery (power_supply)
UDEV [22817.422183] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:34/0005:0A5C:0239.0036/power_supply/hid-00:18:ac:38:94:32-battery (power_supply)
KERNEL[22817.458257] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:34/0005:0A5C:0239.0036/input/input78/event14 (input)
UDEV [22817.459018] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:34/0005:0A5C:0239.0036/input/input78/event14 (input)
KERNEL[22817.501540] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:34/0005:0A5C:0239.0036/input/input78 (input)
KERNEL[22817.501720] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:34/0005:0A5C:0239.0036/hidraw/hidraw2 (hidraw)
KERNEL[22817.501834] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:34/0005:0A5C:0239.0036 (hid)
UDEV [22817.503505] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:34/0005:0A5C:0239.0036/input/input78 (input)
UDEV [22817.503559] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:34/0005:0A5C:0239.0036/hidraw/hidraw2 (hidraw)
UDEV [22817.504025] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:34/0005:0A5C:0239.0036 (hid)
KERNEL[22820.225516] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:34 (bluetooth)
UDEV [22820.225985] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:34 (bluetooth)
KERNEL[22824.318132] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35 (bluetooth)
UDEV [22824.320227] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35 (bluetooth)
KERNEL[22824.461528] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037 (hid)
UDEV [22824.463477] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037 (hid)
KERNEL[22824.496768] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/power_supply/hid-00:18:ac:38:94:32-battery (power_supply)
KERNEL[22824.497136] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/input/input79 (input)
KERNEL[22824.497470] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/input/input79/event14 (input)
KERNEL[22824.497551] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/hidraw/hidraw2 (hidraw)
UDEV [22824.510771] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/hidraw/hidraw2 (hidraw)
UDEV [22824.511367] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/input/input79 (input)
KERNEL[22824.531810] change /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/power_supply/hid-00:18:ac:38:94:32-battery (power_supply)
UDEV [22824.541755] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/power_supply/hid-00:18:ac:38:94:32-battery (power_supply)
UDEV [22824.551778] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/input/input79/event14 (input)
UDEV [22824.576912] change /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/power_supply/hid-00:18:ac:38:94:32-battery (power_supply)And here how it connects with nonworking keys, but displayed as connected everywhere in the system (gets fixed through power on, power off)
KERNEL[22828.897380] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/power_supply/hid-00:18:ac:38:94:32-battery (power_supply)
UDEV [22828.898652] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/power_supply/hid-00:18:ac:38:94:32-battery (power_supply)
KERNEL[22828.937703] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/input/input79/event14 (input)
UDEV [22828.938440] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/input/input79/event14 (input)
KERNEL[22828.967294] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/input/input79 (input)
KERNEL[22828.967367] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/hidraw/hidraw2 (hidraw)
KERNEL[22828.967429] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037 (hid)
UDEV [22828.968337] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/input/input79 (input)
UDEV [22828.968477] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/hidraw/hidraw2 (hidraw)
UDEV [22828.968902] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037 (hid)
KERNEL[22831.291568] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35 (bluetooth)
UDEV [22831.292201] remove /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35 (bluetooth)
KERNEL[22833.508035] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:36 (bluetooth)
UDEV [22833.509145] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:36 (bluetooth)
KERNEL[22833.798368] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:36/0005:0A5C:0239.0038 (hid)
UDEV [22833.799439] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:36/0005:0A5C:0239.0038 (hid)
KERNEL[22838.797996] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:36/0005:0A5C:0239.0038/power_supply/hid-00:18:ac:38:94:32-battery (power_supply)
KERNEL[22838.798434] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:36/0005:0A5C:0239.0038/input/input80 (input)
KERNEL[22838.800103] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:36/0005:0A5C:0239.0038/input/input80/event14 (input)
KERNEL[22838.800154] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:36/0005:0A5C:0239.0038/hidraw/hidraw2 (hidraw)
UDEV [22838.800990] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:36/0005:0A5C:0239.0038/hidraw/hidraw2 (hidraw)
UDEV [22838.801614] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:36/0005:0A5C:0239.0038/input/input80 (input)
UDEV [22838.840443] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:36/0005:0A5C:0239.0038/input/input80/event14 (input)
KERNEL[22843.797980] change /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:36/0005:0A5C:0239.0038/power_supply/hid-00:18:ac:38:94:32-battery (power_supply)
UDEV [22853.798151] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:36/0005:0A5C:0239.0038/power_supply/hid-00:18:ac:38:94:32-battery (power_supply)I noticed that when
KERNEL change hid-00:18:ac:38:94:32-battery (power_supply)goes without delay compared to
UDEV add event14 (input) keyboard always works correctly.
But when there is a time delay, keyboard gets displayed as connected but keys won't work
Correct behaviour example:
KERNEL[22824.531810] change /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/power_supply/hid-00:18:ac:38:94:32-battery (power_supply)
UDEV [22824.551778] add /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:35/0005:0A5C:0239.0037/input/input79/event14 (input)How can I remove timing delay of Kernel change compared to Udev add command?
Last edited by c0ffeeartc (2015-05-21 13:49:03)
Offline
Oh my, I was picking straws and did few things, result - there is no ~5 second delay for:
KERNEL[22843.797980] change /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:36/0005:0A5C:0239.0038/power_supply/hid-00:18:ac:38:94:32-battery (power_supply)Previously things broke on ~3 keyboard disconnects, now >100 disconnects in 2 days, and all reconnections went smoothly
But bluetoothd now takes 100% cpu when keyboard disconnects
Here are things I did:
First of all changed bluetooth hci0 device power control from auto to on:
echo on|sudo tee /sys/bus/usb/devices/2-1.6:1.0/bluetooth/hci0/power/controlThings worked well, but changes did not survive the reboot and path to device also changed. So I think this might be not the change that fixed keys.
Another one is after reading https://bbs.archlinux.org/viewtopic.php?id=130995 :
I went to windows8(dual boot), checked Device Manager(there were many unrecognized devices), installed driver for my Bluetooth device, and tested that keyboard works well with it.
Then rebooted to archlinux.
Last edited by c0ffeeartc (2015-05-15 08:04:18)
Offline
Decided to log bluez5 bluetoothd, regarding 100% cpu issue on keyboard disconnect
Stopped bluetooth service
sudo systemctl stop bluetoothStarted bluetoothd manually from fish-shell
sudo /usr/lib/bluetooth/bluetoothd -dn 2>&1 >| tee bluez5.logRan power on in bluetoothctl
Hit keys on bluetooth keyboard, it connected with delay, but keys wouldn't work.
Ran disconnect in bluetoothctl. bluetoothd gets 100% cpu load.
Hit keys on bluetooth keyboard, it connects fine and keys work. (keys will work from now on, but disconnect makes bluetoothd 100% cpu load, and 0% on reconnect)
Stopped bluetoothd
sudo pkill bluetoothdHere is 294 line bluez5.log :
bluetoothd[4069]: Bluetooth daemon 5.30
bluetoothd[4069]: src/main.c:parse_config() parsing main.conf
bluetoothd[4069]: src/main.c:parse_config() Key file does not have key 'DiscoverableTimeout' in group 'General'
bluetoothd[4069]: src/main.c:parse_config() Key file does not have key 'PairableTimeout' in group 'General'
bluetoothd[4069]: src/main.c:parse_config() Key file does not have key 'AutoConnectTimeout' in group 'General'
bluetoothd[4069]: src/main.c:parse_config() Key file does not have key 'Name' in group 'General'
bluetoothd[4069]: src/main.c:parse_config() Key file does not have key 'Class' in group 'General'
bluetoothd[4069]: src/main.c:parse_config() Key file does not have key 'DeviceID' in group 'General'
bluetoothd[4069]: src/main.c:parse_config() Key file does not have key 'ReverseServiceDiscovery' in group 'General'
bluetoothd[4069]: src/adapter.c:adapter_init() sending read version command
bluetoothd[4069]: Starting SDP server
bluetoothd[4069]: src/sdpd-service.c:register_device_id() Adding device id record for 0002:1d6b:0246:051e
bluetoothd[4069]: src/plugin.c:plugin_init() Loading builtin plugins
bluetoothd[4069]: src/plugin.c:add_plugin() Loading hostname plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading wiimote plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading autopair plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading policy plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading neard plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading sap plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading a2dp plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading avrcp plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading network plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading input plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading hog plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading health plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading gap plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading scanparam plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading deviceinfo plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading alert plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading time plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading proximity plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading thermometer plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading heartrate plugin
bluetoothd[4069]: src/plugin.c:add_plugin() Loading cyclingspeed plugin
bluetoothd[4069]: src/plugin.c:plugin_init() Loading plugins /usr/lib/bluetooth/plugins
bluetoothd[4069]: profiles/health/hdp.c:hdp_manager_start() Starting Health manager
bluetoothd[4069]: profiles/input/suspend-dummy.c:suspend_init()
bluetoothd[4069]: profiles/input/suspend-dummy.c:suspend_init() Created suspend-dummy FIFO on /tmp/hogsuspend
bluetoothd[4069]: profiles/network/manager.c:read_config() Config options: Security=true
bluetoothd[4069]: plugins/neard.c:neard_init() Setup neard plugin
bluetoothd[4069]: plugins/hostname.c:read_dmi_fallback() chassis: laptop
bluetoothd[4069]: plugins/hostname.c:read_dmi_fallback() major: 0x01 minor: 0x03
bluetoothd[4069]: src/main.c:main() Entering main loop
bluetoothd[4069]: src/rfkill.c:rfkill_event() RFKILL event idx 0 type 2 op 0 soft 0 hard 0
bluetoothd[4069]: Bluetooth management interface 1.8 initialized
bluetoothd[4069]: src/adapter.c:read_version_complete() sending read supported commands command
bluetoothd[4069]: src/adapter.c:read_version_complete() sending read index list command
bluetoothd[4069]: src/rfkill.c:rfkill_event() RFKILL event idx 1 type 1 op 0 soft 0 hard 0
bluetoothd[4069]: src/rfkill.c:rfkill_event() RFKILL event idx 2 type 1 op 0 soft 0 hard 0
bluetoothd[4069]: src/adapter.c:read_commands_complete() Number of commands: 56
bluetoothd[4069]: src/adapter.c:read_commands_complete() Number of events: 29
bluetoothd[4069]: src/adapter.c:read_commands_complete() enabling kernel-side connection control
bluetoothd[4069]: src/rfkill.c:rfkill_event() RFKILL event idx 3 type 2 op 0 soft 0 hard 0
bluetoothd[4069]: src/adapter.c:read_index_list_complete() Number of controllers: 1
bluetoothd[4069]: src/adapter.c:read_index_list_complete() Found index 0
bluetoothd[4069]: src/adapter.c:index_added() index 0
bluetoothd[4069]: src/adapter.c:btd_adapter_new() System name: BlueZ 5.30
bluetoothd[4069]: src/adapter.c:btd_adapter_new() Major class: 0
bluetoothd[4069]: src/adapter.c:btd_adapter_new() Minor class: 0
bluetoothd[4069]: src/adapter.c:btd_adapter_new() Modalias: usb:v1D6Bp0246d051E
bluetoothd[4069]: src/adapter.c:btd_adapter_new() Discoverable timeout: 180 seconds
bluetoothd[4069]: src/adapter.c:btd_adapter_new() Pairable timeout: 0 seconds
bluetoothd[4069]: src/adapter.c:index_added() sending read info command for index 0
bluetoothd[4069]: src/adapter.c:read_info_complete() index 0 status 0x00
bluetoothd[4069]: src/adapter.c:clear_uuids() sending clear uuids command for index 0
bluetoothd[4069]: src/adapter.c:clear_devices() sending clear devices command for index 0
bluetoothd[4069]: src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10001
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000007-0000-1000-8000-00805f9
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001800-0000-1000-8000-00805f9
bluetoothd[4069]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[4069]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[4069]: src/gatt-database.c:gatt_db_service_added() GATT Service added to local database
bluetoothd[4069]: Failed to obtain handles for "Service Changed" characteristic
bluetoothd[4069]: src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10002
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000007-0000-1000-8000-00805f9
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001801-0000-1000-8000-00805f9
bluetoothd[4069]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[4069]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[4069]: src/gatt-database.c:gatt_db_service_added() GATT Service added to local database
bluetoothd[4069]: Failed to register org.bluez.LEAdvertisingManager1
bluetoothd[4069]: Failed to register LEAdvertisingManager1 interface for adapter
bluetoothd[4069]: profiles/audio/a2dp.c:media_server_probe() path /org/bluez/hci0
bluetoothd[4069]: plugins/hostname.c:hostname_probe()
bluetoothd[4069]: plugins/hostname.c:update_class() major: 0x01 minor: 0x03
bluetoothd[4069]: src/adapter.c:btd_adapter_set_class() class: major 1 minor 3
bluetoothd[4069]: src/adapter.c:set_dev_class() sending set device class command for index 0
bluetoothd[4069]: Not enough free handles to register service
bluetoothd[4069]: Error adding Link Loss service
bluetoothd[4069]: Not enough free handles to register service
bluetoothd[4069]: Not enough free handles to register service
bluetoothd[4069]: profiles/proximity/reporter.c:reporter_adapter_probe() Proximity Reporter for adapter 0x885ef0
bluetoothd[4069]: profiles/time/server.c:time_server_init() path /org/bluez/hci0
bluetoothd[4069]: Not enough free handles to register service
bluetoothd[4069]: Current Time Service could not be registered
bluetoothd[4069]: gatt-time-server: Input/output error (5)
bluetoothd[4069]: Not enough free handles to register service
bluetoothd[4069]: Not enough free handles to register service
bluetoothd[4069]: profiles/network/manager.c:panu_server_probe() path /org/bluez/hci0
bluetoothd[4069]: profiles/network/server.c:server_register() Registered interface org.bluez.NetworkServer1 on path /org/bluez/hci0
bluetoothd[4069]: profiles/network/manager.c:gn_server_probe() path /org/bluez/hci0
bluetoothd[4069]: profiles/network/manager.c:nap_server_probe() path /org/bluez/hci0
bluetoothd[4069]: profiles/audio/avrcp.c:avrcp_controller_server_probe() path /org/bluez/hci0
bluetoothd[4069]: src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10003
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000017-0000-1000-8000-00805f9
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110e-0000-1000-8000-00805f9
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110f-0000-1000-8000-00805f9
bluetoothd[4069]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[4069]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[4069]: profiles/audio/avrcp.c:avrcp_target_server_probe() path /org/bluez/hci0
bluetoothd[4069]: src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10004
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000017-0000-1000-8000-00805f9
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110c-0000-1000-8000-00805f9
bluetoothd[4069]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110e-0000-1000-8000-00805f9
bluetoothd[4069]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[4069]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[4069]: profiles/audio/a2dp.c:a2dp_sink_server_probe() path /org/bluez/hci0
bluetoothd[4069]: profiles/audio/a2dp.c:a2dp_source_server_probe() path /org/bluez/hci0
bluetoothd[4069]: profiles/sap/manager.c:sap_server_probe() path /org/bluez/hci0
bluetoothd[4069]: Sap driver initialization failed.
bluetoothd[4069]: sap-server: Operation not permitted (1)
bluetoothd[4069]: src/adapter.c:btd_adapter_unblock_address() hci0 00:00:00:00:00:00
bluetoothd[4069]: src/adapter.c:get_ltk_info() 00:18:AC:38:94:32
bluetoothd[4069]: src/device.c:device_create_from_storage() address 00:18:AC:38:94:32
bluetoothd[4069]: src/device.c:device_new() address 00:18:AC:38:94:32
bluetoothd[4069]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_00_18_AC_38_94_32
bluetoothd[4069]: src/device.c:btd_device_set_temporary() temporary 0
bluetoothd[4069]: src/device.c:device_probe_profiles() Probing profiles for device 00:18:AC:38:94:32
bluetoothd[4069]: profiles/input/device.c:input_device_register() /org/bluez/hci0/dev_00_18_AC_38_94_32
bluetoothd[4069]: src/service.c:btd_service_ref() 0x894bc0: ref=2
bluetoothd[4069]: src/service.c:change_state() 0x894bc0: device 00:18:AC:38:94:32 profile input-hid state changed: unavailable -> disconnected (0)
bluetoothd[4069]: src/device.c:device_set_bonded()
bluetoothd[4069]: src/adapter.c:get_ltk_info() 6C:70:9F:CE:1B:A9
bluetoothd[4069]: src/device.c:device_create_from_storage() address 6C:70:9F:CE:1B:A9
bluetoothd[4069]: src/device.c:device_new() address 6C:70:9F:CE:1B:A9
bluetoothd[4069]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_6C_70_9F_CE_1B_A9
bluetoothd[4069]: src/device.c:btd_device_set_temporary() temporary 0
bluetoothd[4069]: src/device.c:device_probe_profiles() Probing profiles for device 6C:70:9F:CE:1B:A9
bluetoothd[4069]: profiles/input/device.c:input_device_register() /org/bluez/hci0/dev_6C_70_9F_CE_1B_A9
bluetoothd[4069]: src/service.c:btd_service_ref() 0x8953c0: ref=2
bluetoothd[4069]: src/service.c:change_state() 0x8953c0: device 6C:70:9F:CE:1B:A9 profile input-hid state changed: unavailable -> disconnected (0)
bluetoothd[4069]: src/device.c:device_set_bonded()
bluetoothd[4069]: src/adapter.c:load_link_keys() hci0 keys 2 debug_keys 0
bluetoothd[4069]: src/adapter.c:load_ltks() hci0 keys 0
bluetoothd[4069]: src/adapter.c:load_irks() hci0 irks 0
bluetoothd[4069]: src/adapter.c:load_conn_params() hci0 conn params 0
bluetoothd[4069]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[4069]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[4069]: src/adapter.c:set_did() hci0 source 2 vendor 1d6b product 246 version 51e
bluetoothd[4069]: src/adapter.c:adapter_register() Adapter /org/bluez/hci0 registered
bluetoothd[4069]: src/adapter.c:set_dev_class() sending set device class command for index 0
bluetoothd[4069]: src/adapter.c:set_name() sending set local name command for index 0
bluetoothd[4069]: src/adapter.c:add_whitelist_complete() 00:18:AC:38:94:32 added to kernel whitelist
bluetoothd[4069]: src/adapter.c:add_whitelist_complete() 6C:70:9F:CE:1B:A9 added to kernel whitelist
bluetoothd[4069]: src/adapter.c:load_link_keys_complete() link keys loaded for hci0
bluetoothd[4069]: src/adapter.c:load_ltks_complete() LTKs loaded for hci0
bluetoothd[4069]: src/adapter.c:load_irks_complete() IRKs loaded for hci0
bluetoothd[4069]: src/adapter.c:load_conn_params_complete() Connection Parameters loaded for hci0
bluetoothd[4069]: src/adapter.c:local_name_changed_callback() Name: BlueZ 5.30
bluetoothd[4069]: src/adapter.c:local_name_changed_callback() Short name:
bluetoothd[4069]: src/adapter.c:local_name_changed_callback() Current alias: BlueZ 5.30
bluetoothd[4069]: plugins/hostname.c:property_changed() static hostname: za
bluetoothd[4069]: plugins/hostname.c:property_changed() pretty hostname:
bluetoothd[4069]: plugins/hostname.c:update_name() name: za
bluetoothd[4069]: src/adapter.c:adapter_set_name() name: za
bluetoothd[4069]: src/adapter.c:adapter_set_name() alias: za
bluetoothd[4069]: src/adapter.c:set_name() sending set local name command for index 0
bluetoothd[4069]: plugins/hostname.c:property_changed() chassis: laptop
bluetoothd[4069]: plugins/hostname.c:update_class() major: 0x01 minor: 0x03
bluetoothd[4069]: src/adapter.c:local_name_changed_callback() Name: za
bluetoothd[4069]: src/adapter.c:local_name_changed_callback() Short name:
bluetoothd[4069]: src/adapter.c:local_name_changed_callback() Current alias: za
bluetoothd[4069]: src/adapter.c:property_set_mode() sending Set Powered command for index 0
bluetoothd[4069]: src/adapter.c:dev_class_changed_callback() Class: 0x00010c
bluetoothd[4069]: src/adapter.c:property_set_mode_complete() Success (0x00)
bluetoothd[4069]: src/adapter.c:new_settings_callback() Settings: 0x00000ad1
bluetoothd[4069]: src/adapter.c:settings_changed() Changed settings: 0x00000001
bluetoothd[4069]: src/adapter.c:adapter_start() adapter /org/bluez/hci0 has been enabled
bluetoothd[4069]: src/adapter.c:trigger_passive_scanning()
bluetoothd[4069]: src/adapter.c:connected_callback() hci0 device 00:18:AC:38:94:32 connected eir_len 5
bluetoothd[4069]: profiles/input/server.c:connect_event_cb() Incoming connection from 00:18:AC:38:94:32 on PSM 17
bluetoothd[4069]: profiles/input/device.c:input_device_set_channel() idev 0x895140 psm 17
bluetoothd[4069]: profiles/input/server.c:confirm_event_cb()
bluetoothd[4069]: profiles/input/server.c:connect_event_cb() Incoming connection from 00:18:AC:38:94:32 on PSM 19
bluetoothd[4069]: profiles/input/device.c:input_device_set_channel() idev 0x895140 psm 19
bluetoothd[4069]: src/service.c:change_state() 0x894bc0: device 00:18:AC:38:94:32 profile input-hid state changed: disconnected -> connected (0)
bluetoothd[4069]: src/service.c:btd_service_ref() 0x894bc0: ref=3
bluetoothd[4069]: plugins/policy.c:service_cb() Added input-hid reconnect 0
bluetoothd[4069]: src/adapter.c:bonding_attempt_complete() hci0 bdaddr 00:18:AC:38:94:32 type 0 status 0x5
bluetoothd[4069]: src/device.c:device_bonding_complete() bonding (nil) status 0x05
bluetoothd[4069]: src/device.c:device_bonding_failed() status 5
bluetoothd[4069]: src/adapter.c:resume_discovery()
bluetoothd[4069]: src/service.c:change_state() 0x894bc0: device 00:18:AC:38:94:32 profile input-hid state changed: connected -> disconnecting (0)
bluetoothd[4069]: profiles/input/device.c:input_device_disconnect()
bluetoothd[4069]: Can't get HIDP connection info
bluetoothd[4069]: src/service.c:change_state() 0x894bc0: device 00:18:AC:38:94:32 profile input-hid state changed: disconnecting -> disconnected (0)
bluetoothd[4069]: src/adapter.c:dev_disconnected() Device 00:18:AC:38:94:32 disconnected, reason 2
bluetoothd[4069]: src/adapter.c:adapter_remove_connection()
bluetoothd[4069]: plugins/policy.c:disconnect_cb() reason 2
bluetoothd[4069]: src/adapter.c:bonding_attempt_complete() hci0 bdaddr 00:18:AC:38:94:32 type 0 status 0xe
bluetoothd[4069]: src/device.c:device_bonding_complete() bonding (nil) status 0x0e
bluetoothd[4069]: src/device.c:device_bonding_failed() status 14
bluetoothd[4069]: src/adapter.c:resume_discovery()
bluetoothd[4069]: profiles/input/device.c:ctrl_watch_cb() Device 00:18:AC:38:94:32 disconnected
bluetoothd[4069]: profiles/input/device.c:intr_watch_cb() Device 00:18:AC:38:94:32 disconnected
bluetoothd[4069]: profiles/input/device.c:input_device_enter_reconnect_mode() path=/org/bluez/hci0/dev_00_18_AC_38_94_32 reconnect_mode=device
bluetoothd[4069]: src/adapter.c:connected_callback() hci0 device 00:18:AC:38:94:32 connected eir_len 5
bluetoothd[4069]: profiles/input/server.c:connect_event_cb() Incoming connection from 00:18:AC:38:94:32 on PSM 17
bluetoothd[4069]: profiles/input/device.c:input_device_set_channel() idev 0x895140 psm 17
bluetoothd[4069]: profiles/input/server.c:confirm_event_cb()
bluetoothd[4069]: profiles/input/device.c:encrypt_notify()
bluetoothd[4069]: profiles/input/server.c:connect_event_cb() Incoming connection from 00:18:AC:38:94:32 on PSM 19
bluetoothd[4069]: profiles/input/device.c:input_device_set_channel() idev 0x895140 psm 19
bluetoothd[4069]: src/service.c:change_state() 0x894bc0: device 00:18:AC:38:94:32 profile input-hid state changed: disconnected -> connected (0)
bluetoothd[4069]: plugins/policy.c:service_cb() Added input-hid reconnect 0
bluetoothd[4069]: src/adapter.c:bonding_attempt_complete() hci0 bdaddr 00:18:AC:38:94:32 type 0 status 0x5
bluetoothd[4069]: src/device.c:device_bonding_complete() bonding (nil) status 0x05
bluetoothd[4069]: src/device.c:device_bonding_failed() status 5
bluetoothd[4069]: src/adapter.c:resume_discovery()
bluetoothd[4069]: Terminating
bluetoothd[4069]: src/adapter.c:adapter_shutdown()
bluetoothd[4069]: src/adapter.c:set_mode() sending set mode command for index 0
bluetoothd[4069]: src/adapter.c:dev_disconnected() Device 00:18:AC:38:94:32 disconnected, reason 2
bluetoothd[4069]: src/adapter.c:adapter_remove_connection()
bluetoothd[4069]: plugins/policy.c:disconnect_cb() reason 2
bluetoothd[4069]: src/adapter.c:bonding_attempt_complete() hci0 bdaddr 00:18:AC:38:94:32 type 0 status 0xe
bluetoothd[4069]: src/device.c:device_bonding_complete() bonding (nil) status 0x0e
bluetoothd[4069]: src/device.c:device_bonding_failed() status 14
bluetoothd[4069]: src/adapter.c:resume_discovery()
bluetoothd[4069]: profiles/input/device.c:ctrl_watch_cb() Device 00:18:AC:38:94:32 disconnected
bluetoothd[4069]: profiles/input/device.c:intr_watch_cb() Device 00:18:AC:38:94:32 disconnected
bluetoothd[4069]: src/service.c:change_state() 0x894bc0: device 00:18:AC:38:94:32 profile input-hid state changed: connected -> disconnected (0)
bluetoothd[4069]: profiles/input/device.c:input_device_enter_reconnect_mode() path=/org/bluez/hci0/dev_00_18_AC_38_94_32 reconnect_mode=device
bluetoothd[4069]: src/adapter.c:new_settings_callback() Settings: 0x00000ad0
bluetoothd[4069]: src/adapter.c:settings_changed() Changed settings: 0x00000001
bluetoothd[4069]: src/adapter.c:cancel_passive_scanning()
bluetoothd[4069]: src/adapter.c:adapter_stop() adapter /org/bluez/hci0 has been disabled
bluetoothd[4069]: src/plugin.c:plugin_cleanup() Cleanup plugins
bluetoothd[4069]: profiles/alert/server.c:alert_destroy()
bluetoothd[4069]: profiles/health/hdp.c:hdp_manager_stop() Stopped Health manager
bluetoothd[4069]: profiles/audio/a2dp.c:media_server_remove() path /org/bluez/hci0
bluetoothd[4069]: plugins/neard.c:neard_exit() Cleanup neard plugin
bluetoothd[4069]: src/service.c:btd_service_unref() 0x894bc0: ref=2
bluetoothd[4069]: plugins/hostname.c:hostname_remove()
bluetoothd[4069]: src/adapter.c:adapter_remove() Removing adapter /org/bluez/hci0
bluetoothd[4069]: src/device.c:device_remove() Removing device /org/bluez/hci0/dev_00_18_AC_38_94_32
bluetoothd[4069]: src/service.c:change_state() 0x894bc0: device 00:18:AC:38:94:32 profile input-hid state changed: disconnected -> unavailable (0)
bluetoothd[4069]: profiles/input/device.c:input_device_unregister() /org/bluez/hci0/dev_00_18_AC_38_94_32
bluetoothd[4069]: src/service.c:btd_service_unref() 0x894bc0: ref=1
bluetoothd[4069]: src/service.c:btd_service_unref() 0x894bc0: ref=0
bluetoothd[4069]: src/device.c:btd_device_unref() Freeing device /org/bluez/hci0/dev_00_18_AC_38_94_32
bluetoothd[4069]: src/device.c:device_free() 0x894720
bluetoothd[4069]: src/device.c:device_remove() Removing device /org/bluez/hci0/dev_6C_70_9F_CE_1B_A9
bluetoothd[4069]: src/service.c:change_state() 0x8953c0: device 6C:70:9F:CE:1B:A9 profile input-hid state changed: disconnected -> unavailable (0)
bluetoothd[4069]: profiles/input/device.c:input_device_unregister() /org/bluez/hci0/dev_6C_70_9F_CE_1B_A9
bluetoothd[4069]: src/service.c:btd_service_unref() 0x8953c0: ref=1
bluetoothd[4069]: src/service.c:btd_service_unref() 0x8953c0: ref=0
bluetoothd[4069]: src/device.c:btd_device_unref() Freeing device /org/bluez/hci0/dev_6C_70_9F_CE_1B_A9
bluetoothd[4069]: src/device.c:device_free() 0x894e90
bluetoothd[4069]: profiles/audio/a2dp.c:a2dp_source_server_remove() path /org/bluez/hci0
bluetoothd[4069]: profiles/audio/a2dp.c:a2dp_sink_server_remove() path /org/bluez/hci0
bluetoothd[4069]: profiles/audio/avrcp.c:avrcp_target_server_remove() path /org/bluez/hci0
bluetoothd[4069]: src/adapter.c:adapter_service_remove() /org/bluez/hci0
bluetoothd[4069]: src/adapter.c:remove_uuid() sending remove uuid command for index 0
bluetoothd[4069]: src/sdpd-service.c:remove_record_from_server() Removing record with handle 0x10004
bluetoothd[4069]: profiles/audio/avrcp.c:avrcp_controller_server_remove() path /org/bluez/hci0
bluetoothd[4069]: src/adapter.c:adapter_service_remove() /org/bluez/hci0
bluetoothd[4069]: src/adapter.c:remove_uuid() sending remove uuid command for index 0
bluetoothd[4069]: src/sdpd-service.c:remove_record_from_server() Removing record with handle 0x10003
bluetoothd[4069]: profiles/network/manager.c:nap_server_remove() path /org/bluez/hci0
bluetoothd[4069]: profiles/network/manager.c:gn_server_remove() path /org/bluez/hci0
bluetoothd[4069]: profiles/network/manager.c:panu_server_remove() path /org/bluez/hci0
bluetoothd[4069]: profiles/network/server.c:path_unregister() Unregistered interface org.bluez.NetworkServer1 on path /org/bluez/hci0
bluetoothd[4069]: src/adapter.c:adapter_service_remove() /org/bluez/hci0
bluetoothd[4069]: src/adapter.c:remove_uuid() sending remove uuid command for index 0
bluetoothd[4069]: src/sdpd-service.c:remove_record_from_server() Removing record with handle 0x10002
bluetoothd[4069]: src/adapter.c:adapter_service_remove() /org/bluez/hci0
bluetoothd[4069]: src/adapter.c:remove_uuid() sending remove uuid command for index 0
bluetoothd[4069]: src/sdpd-service.c:remove_record_from_server() Removing record with handle 0x10001
bluetoothd[4069]: src/adapter.c:btd_adapter_unref() Freeing adapter /org/bluez/hci0
bluetoothd[4069]: src/adapter.c:adapter_free() 0x885ef0
bluetoothd[4069]: Stopping SDP server
bluetoothd[4069]: ExitSince my issue changed to high cpu load, and people suggest using bluez4 when such problem occurs, I'll try using bluez4 next
Offline
Bluez4 has same issue where udev and kernel events go with delay.
Switching back to bluez5
Update: on one of reboots bluez5 changed from high cpu to udev events delays, as described previously.
Last edited by c0ffeeartc (2015-05-21 13:52:42)
Offline