Ubuntu
ubuntu-system-settings package

[bluetooth] can't connect to headset

Bug #1347106 reported by Ricardo Salveti
6
This bug affects 1 person
Affects Status Importance Assigned to Milestone
ubuntu-system-settings (Ubuntu)
Fix Released
High
Mathieu Trudel-Lapierre

Bug Description

Using the current code available at https://code.launchpad.net/~mathieu-tl/ubuntu-system-settings/bluetooth-redesign/+merge/227385, I'm still unable to connect to my bt headset device.

Follows the syslog:
After powering up my device (see that it tries to connect to the host)

Jul 22 18:50:08 ubuntu-phablet bluetoothd[2531]: audio/telephony.c:handle_network_property_changed() in handle_registration_property_changed(), the property is CellId
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:link_key_request() hci0 dba 00:1F:82:05:7D:66
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:get_auth_info() hci0 dba 00:1F:82:05:7D:66
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:link_key_request() kernel auth requirements = 0x04
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:link_key_request() Matching key not found
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:pin_code_request() hci0 PIN request for 00:1F:82:05:7D:66
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: src/adapter.c:adapter_get_device() 00:1F:82:05:7D:66
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: src/adapter.c:adapter_create_device() 00:1F:82:05:7D:66
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: src/device.c:device_create() Creating device /org/bluez/2531/hci0/dev_00_1F_82_05_7D_66
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: src/device.c:btd_device_ref() 0xb71bd100: ref=1
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: src/device.c:device_set_temporary() temporary 1
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: src/device.c:device_request_authentication() Requesting agent authentication for 00:1F:82:05:7D:66
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: No agent available for request type 0
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: PIN code negative reply: Operation not permitted
Jul 22 18:50:32 ubuntu-phablet pulseaudio[1639]: [pulseaudio] bluetooth-util.c: dbus: interface=org.bluez.Adapter, path=/org/bluez/2531/hci0, member=DeviceCreated
Jul 22 18:50:32 ubuntu-phablet pulseaudio[1639]: [pulseaudio] bluetooth-util.c: Device /org/bluez/2531/hci0/dev_00_1F_82_05_7D_66 created
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:conn_complete() status 0x18
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: src/event.c:btd_event_conn_failed() status 0x18
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: src/device.c:device_remove() Removing device /org/bluez/2531/hci0/dev_00_1F_82_05_7D_66
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: src/device.c:btd_device_unref() 0xb71bd100: ref=0
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: src/device.c:device_free() 0xb71bd100
Jul 22 18:50:32 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:bonding_complete() status 0x18
Jul 22 18:50:32 ubuntu-phablet pulseaudio[1639]: [pulseaudio] bluetooth-util.c: dbus: interface=org.bluez.Adapter, path=/org/bluez/2531/hci0, member=DeviceRemoved
Jul 22 18:50:32 ubuntu-phablet pulseaudio[1639]: [pulseaudio] bluetooth-util.c: Device /org/bluez/2531/hci0/dev_00_1F_82_05_7D_66 removed
Jul 22 18:50:33 ubuntu-phablet dbus[870]: [system] Activating service name='com.canonical.applications.Downloader' (using servicehelper)
Jul 22 18:50:33 ubuntu-phablet dbus[870]: [system] Successfully activated service 'com.canonical.applications.Downloader'

When trying to connect from the system-settings UI:

Jul 22 18:50:54 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:hciops_start_discovery() hci0
Jul 22 18:50:54 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:start_inquiry() hci0 length 8
Jul 22 18:50:54 ubuntu-phablet bluetoothd[2531]: Discovery session 0xb71b3b30 with :1.66 activated
Jul 22 18:50:54 ubuntu-phablet bluetoothd[2531]: src/adapter.c:session_ref() 0xb71b3b30: ref=1
Jul 22 18:50:54 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:set_state() hci0: new state 1
Jul 22 18:50:55 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:hciops_confirm_name() hci0 00:1F:82:05:7D:66 name_known 1
Jul 22 18:50:56 ubuntu-phablet kernel: [ 82.068060] healthd: battery l=95 v=4252 t=38.3 h=2 st=2 c=200 chg=u
Jul 22 18:51:02 ubuntu-phablet bluetoothd[2531]: src/adapter.c:session_unref() 0xb71b3b30: ref=0
Jul 22 18:51:02 ubuntu-phablet bluetoothd[2531]: src/adapter.c:session_remove() Discovery session 0xb71b3b30 with :1.66 deactivated
Jul 22 18:51:02 ubuntu-phablet bluetoothd[2531]: src/adapter.c:session_remove() Stopping discovery
Jul 22 18:51:02 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:hciops_stop_discovery() index 0
Jul 22 18:51:02 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:hciops_stop_inquiry() hci0
Jul 22 18:51:02 ubuntu-phablet bluetoothd[2531]: Stopping discovery
Jul 22 18:51:02 ubuntu-phablet bluetoothd[2531]: src/adapter.c:adapter_create_device() 00:1F:82:05:7D:66
Jul 22 18:51:02 ubuntu-phablet bluetoothd[2531]: src/device.c:device_create() Creating device /org/bluez/2531/hci0/dev_00_1F_82_05_7D_66
Jul 22 18:51:02 ubuntu-phablet bluetoothd[2531]: src/device.c:btd_device_ref() 0xb71bf230: ref=1
Jul 22 18:51:02 ubuntu-phablet bluetoothd[2531]: src/device.c:device_set_temporary() temporary 1
Jul 22 18:51:02 ubuntu-phablet bluetoothd[2531]: src/device.c:bonding_request_new() Requesting bonding for 00:1F:82:05:7D:66
Jul 22 18:51:02 ubuntu-phablet bluetoothd[2531]: src/device.c:bonding_request_new() Temporary agent registered for 00:1F:82:05:7D:66 at :1.66:/com/canonical/SettingsBluetoothAgent
Jul 22 18:51:02 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:set_state() hci0: new state 0
Jul 22 18:51:02 ubuntu-phablet kernel: [ 87.840012] l2cap_sock_connect: failed -115
Jul 22 18:51:02 ubuntu-phablet pulseaudio[1639]: [pulseaudio] bluetooth-util.c: dbus: interface=org.bluez.Adapter, path=/org/bluez/2531/hci0, member=DeviceCreated
Jul 22 18:51:02 ubuntu-phablet pulseaudio[1639]: [pulseaudio] bluetooth-util.c: Device /org/bluez/2531/hci0/dev_00_1F_82_05_7D_66 created
Jul 22 18:51:05 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:link_key_request() hci0 dba 00:1F:82:05:7D:66
Jul 22 18:51:05 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:get_auth_info() hci0 dba 00:1F:82:05:7D:66
Jul 22 18:51:05 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:link_key_request() kernel auth requirements = 0x03
Jul 22 18:51:05 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:link_key_request() Matching key not found
Jul 22 18:51:05 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:pin_code_request() hci0 PIN request for 00:1F:82:05:7D:66
Jul 22 18:51:05 ubuntu-phablet bluetoothd[2531]: src/adapter.c:adapter_get_device() 00:1F:82:05:7D:66
Jul 22 18:51:05 ubuntu-phablet bluetoothd[2531]: src/device.c:device_request_authentication() Requesting agent authentication for 00:1F:82:05:7D:66
Jul 22 18:51:06 ubuntu-phablet kernel: [ 92.096078] healthd: battery l=95 v=4276 t=38.2 h=2 st=2 c=43 chg=u
Jul 22 18:51:12 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:hciops_start_discovery() hci0
Jul 22 18:51:12 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:start_inquiry() hci0 length 8
Jul 22 18:51:12 ubuntu-phablet bluetoothd[2531]: Discovery session 0xb71c0248 with :1.66 activated
Jul 22 18:51:12 ubuntu-phablet bluetoothd[2531]: src/adapter.c:session_ref() 0xb71c0248: ref=1
Jul 22 18:51:12 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:set_state() hci0: new state 1
Jul 22 18:51:16 ubuntu-phablet kernel: [ 102.126751] healthd: battery l=95 v=4272 t=38.5 h=2 st=2 c=66 chg=u

Forget and connect:

Jul 22 18:51:22 ubuntu-phablet bluetoothd[2531]: src/adapter.c:session_unref() 0xb71c0248: ref=0
Jul 22 18:51:22 ubuntu-phablet bluetoothd[2531]: src/adapter.c:session_remove() Discovery session 0xb71c0248 with :1.66 deactivated
Jul 22 18:51:22 ubuntu-phablet bluetoothd[2531]: src/adapter.c:session_remove() Stopping discovery
Jul 22 18:51:22 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:hciops_stop_discovery() index 0
Jul 22 18:51:22 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:hciops_stop_inquiry() hci0
Jul 22 18:51:22 ubuntu-phablet bluetoothd[2531]: Stopping discovery
Jul 22 18:51:22 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:set_state() hci0: new state 0
Jul 22 18:51:26 ubuntu-phablet kernel: [ 112.093911] healthd: battery l=95 v=4199 t=38.5 h=2 st=2 c=59 chg=u
Jul 22 18:51:32 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:hciops_start_discovery() hci0
Jul 22 18:51:32 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:start_inquiry() hci0 length 8
Jul 22 18:51:32 ubuntu-phablet bluetoothd[2531]: Discovery session 0xb71b6078 with :1.66 activated
Jul 22 18:51:32 ubuntu-phablet bluetoothd[2531]: src/adapter.c:session_ref() 0xb71b6078: ref=1
Jul 22 18:51:32 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:set_state() hci0: new state 1
Jul 22 18:51:35 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:conn_complete() status 0x08
Jul 22 18:51:35 ubuntu-phablet bluetoothd[2531]: src/event.c:btd_event_conn_failed() status 0x08
Jul 22 18:51:35 ubuntu-phablet bluetoothd[2531]: src/device.c:device_cancel_bonding() Canceling bonding request for 00:1F:82:05:7D:66
Jul 22 18:51:35 ubuntu-phablet bluetoothd[2531]: src/device.c:device_cancel_authentication() Canceling authentication request for 00:1F:82:05:7D:66
Jul 22 18:51:35 ubuntu-phablet bluetoothd[2531]: src/agent.c:send_cancel_request() Sending Cancel request to :1.66, /com/canonical/SettingsBluetoothAgent
Jul 22 18:51:35 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:hciops_pincode_reply() hci0 dba 00:1F:82:05:7D:66
Jul 22 18:51:35 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:hciops_cancel_bonding() hci0
Jul 22 18:51:35 ubuntu-phablet bluetoothd[2531]: src/agent.c:agent_release() Releasing agent :1.66, /com/canonical/SettingsBluetoothAgent
Jul 22 18:51:35 ubuntu-phablet bluetoothd[2531]: src/device.c:device_remove() Removing device /org/bluez/2531/hci0/dev_00_1F_82_05_7D_66
Jul 22 18:51:35 ubuntu-phablet bluetoothd[2531]: src/device.c:btd_device_unref() 0xb71bf230: ref=0
Jul 22 18:51:35 ubuntu-phablet bluetoothd[2531]: src/device.c:device_free() 0xb71bf230
Jul 22 18:51:35 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:bonding_complete() status 0x08
Jul 22 18:51:35 ubuntu-phablet pulseaudio[1639]: [pulseaudio] bluetooth-util.c: dbus: interface=org.bluez.Adapter, path=/org/bluez/2531/hci0, member=DeviceRemoved
Jul 22 18:51:35 ubuntu-phablet pulseaudio[1639]: [pulseaudio] bluetooth-util.c: Device /org/bluez/2531/hci0/dev_00_1F_82_05_7D_66 removed
Jul 22 18:51:36 ubuntu-phablet kernel: [ 122.080909] healthd: battery l=95 v=4205 t=38.5 h=2 st=2 c=83 chg=u
Jul 22 18:51:36 ubuntu-phablet kernel: [ 122.135937] healthd: battery l=95 v=4201 t=38.6 h=2 st=2 c=34 chg=u
Jul 22 18:51:41 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:hciops_confirm_name() hci0 00:1F:82:05:7D:66 name_known 1
Jul 22 18:51:41 ubuntu-phablet bluetoothd[2531]: src/adapter.c:session_unref() 0xb71b6078: ref=0
Jul 22 18:51:41 ubuntu-phablet bluetoothd[2531]: src/adapter.c:session_remove() Discovery session 0xb71b6078 with :1.66 deactivated
Jul 22 18:51:41 ubuntu-phablet bluetoothd[2531]: src/adapter.c:session_remove() Stopping discovery
Jul 22 18:51:41 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:hciops_stop_discovery() index 0
Jul 22 18:51:41 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:hciops_stop_inquiry() hci0
Jul 22 18:51:41 ubuntu-phablet bluetoothd[2531]: Stopping discovery
Jul 22 18:51:41 ubuntu-phablet bluetoothd[2531]: src/adapter.c:adapter_create_device() 00:1F:82:05:7D:66
Jul 22 18:51:41 ubuntu-phablet bluetoothd[2531]: src/device.c:device_create() Creating device /org/bluez/2531/hci0/dev_00_1F_82_05_7D_66
Jul 22 18:51:41 ubuntu-phablet bluetoothd[2531]: src/device.c:btd_device_ref() 0xb71c0248: ref=1
Jul 22 18:51:41 ubuntu-phablet bluetoothd[2531]: src/device.c:device_set_temporary() temporary 1
Jul 22 18:51:41 ubuntu-phablet bluetoothd[2531]: src/device.c:bonding_request_new() Requesting bonding for 00:1F:82:05:7D:66
Jul 22 18:51:41 ubuntu-phablet kernel: [ 126.973050] l2cap_sock_connect: failed -115
Jul 22 18:51:41 ubuntu-phablet bluetoothd[2531]: src/device.c:bonding_request_new() Temporary agent registered for 00:1F:82:05:7D:66 at :1.66:/com/canonical/SettingsBluetoothAgent
Jul 22 18:51:41 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:set_state() hci0: new state 0
Jul 22 18:51:41 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:set_state() hci0: new state 3
Jul 22 18:51:41 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:resolve_names() found_dev 0 need_name 0
Jul 22 18:51:41 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:set_state() hci0: new state 0
Jul 22 18:51:41 ubuntu-phablet pulseaudio[1639]: [pulseaudio] bluetooth-util.c: dbus: interface=org.bluez.Adapter, path=/org/bluez/2531/hci0, member=DeviceCreated
Jul 22 18:51:41 ubuntu-phablet pulseaudio[1639]: [pulseaudio] bluetooth-util.c: Device /org/bluez/2531/hci0/dev_00_1F_82_05_7D_66 created
Jul 22 18:51:42 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:link_key_request() hci0 dba 00:1F:82:05:7D:66
Jul 22 18:51:42 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:get_auth_info() hci0 dba 00:1F:82:05:7D:66
Jul 22 18:51:42 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:link_key_request() kernel auth requirements = 0x03
Jul 22 18:51:42 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:link_key_request() Matching key not found
Jul 22 18:51:42 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:pin_code_request() hci0 PIN request for 00:1F:82:05:7D:66
Jul 22 18:51:42 ubuntu-phablet bluetoothd[2531]: src/adapter.c:adapter_get_device() 00:1F:82:05:7D:66
Jul 22 18:51:42 ubuntu-phablet bluetoothd[2531]: src/device.c:device_request_authentication() Requesting agent authentication for 00:1F:82:05:7D:66
Jul 22 18:51:46 ubuntu-phablet kernel: [ 132.080878] healthd: battery l=95 v=4208 t=38.6 h=2 st=2 c=49 chg=u
Jul 22 18:51:51 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:hciops_start_discovery() hci0
Jul 22 18:51:51 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:start_inquiry() hci0 length 8
Jul 22 18:51:51 ubuntu-phablet bluetoothd[2531]: Discovery session 0xb71c1628 with :1.66 activated
Jul 22 18:51:51 ubuntu-phablet bluetoothd[2531]: src/adapter.c:session_ref() 0xb71c1628: ref=1
Jul 22 18:51:51 ubuntu-phablet bluetoothd[2531]: plugins/hciops.c:set_state() hci0: new state 1
Jul 22 18:51:56 ubuntu-phablet kernel: [ 142.103189] healthd: battery l=95 v=4269 t=38.7 h=2 st=2 c=64 chg=u

And the output from the app:
2014-07-22 18:50:44,226 - WARNING - Bluetooth::agent
2014-07-22 18:50:55,340 - WARNING - file:///usr/lib/arm-linux-gnueabihf/qt5/qml/Ubuntu/Components/ListItems/IconVisual.qml:41:5: QML ImageWithFallback: Failed to get image from provider: image://theme/audio-headset-symbolic
2014-07-22 18:50:59,883 - WARNING - file:///usr/share/ubuntu/settings/system/qml-plugins/bluetooth/PageComponent.qml:331:21: QML QQuickImage: Failed to get image from provider: image://theme/audio-headset-symbolic
2014-07-22 18:51:27,426 - WARNING - Could not create device: "Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken."
2014-07-22 18:51:31,601 - WARNING - Could not remove device: "Device creation in progress"
2014-07-22 18:51:41,228 - WARNING - file:///usr/lib/arm-linux-gnueabihf/qt5/qml/Ubuntu/Components/ListItems/IconVisual.qml:41:5: QML ImageWithFallback: Failed to get image from provider: image://theme/audio-headset-symbolic
2014-07-22 18:52:06,432 - WARNING - Could not create device: "Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken."
2014-07-22 18:52:18,384 - WARNING - file:///usr/lib/arm-linux-gnueabihf/qt5/qml/Ubuntu/Components/ListItems/IconVisual.qml:41:5: QML ImageWithFallback: Failed to get image from provider: image://theme/audio-headset-symbolic

This is with a clean 144 image (mako), with the system-settings available from the above MR.

Revision history for this message
Ricardo Salveti (rsalveti) wrote :

This bug is not yet in trunk, opening it here as cyphermox requested it.

Revision history for this message
Ricardo Salveti (rsalveti) wrote :

Worked fine after calling '$ bluetooth-agent --adapter hci0 0000 00:1F:82:05:7D:66' and restarting the device.

Pat McGowan (pat-mcgowan)
tags: added: rtm14
Changed in ubuntu-system-settings (Ubuntu):
assignee: nobody → Mathieu Trudel-Lapierre (mathieu-tl)
importance: Undecided → High
Revision history for this message
Pat McGowan (pat-mcgowan) wrote :

Feature needed to ship

Changed in ubuntu-system-settings (Ubuntu):
importance: High → Critical
status: New → In Progress
Pat McGowan (pat-mcgowan)
Changed in ubuntu-system-settings (Ubuntu):
importance: Critical → High
Pat McGowan (pat-mcgowan)
tags: added: bluetooth
Revision history for this message
Sebastien Bacher (seb128) wrote :

is that still an issue/a duplicate of bug #1351406?

Revision history for this message
Ricardo Salveti (rsalveti) wrote :

Not a duplicate, but fixed on the same upload.

Changed in ubuntu-system-settings (Ubuntu):
status: In Progress → Fix Released
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.
Subscribing...

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.