Bug 1558042 - Bluetooth service stopped right after establishing connection with headset
Summary: Bluetooth service stopped right after establishing connection with headset
Keywords:
Status: CLOSED CURRENTRELEASE
Alias: None
Product: Fedora
Classification: Fedora
Component: bluez
Version: 28
Hardware: x86_64
OS: Linux
unspecified
high
Target Milestone: ---
Assignee: Don Zickus
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2018-03-19 13:40 UTC by Anass Ahmed
Modified: 2018-04-01 07:52 UTC (History)
8 users (show)

Fixed In Version:
Doc Type: If docs needed, set a value
Doc Text:
Clone Of:
Environment:
Last Closed: 2018-04-01 07:52:30 UTC
Type: Bug
Embargoed:


Attachments (Terms of Use)
GNOME Bluetooth trying to connect my headset (2.02 MB, application/octet-stream)
2018-03-19 13:40 UTC, Anass Ahmed
no flags Details

Description Anass Ahmed 2018-03-19 13:40:29 UTC
Created attachment 1409867 [details]
GNOME Bluetooth trying to connect my headset

Description of problem:
-----------------------

I tried to connect my "Creative Aurvana Platinum" headset to my laptop, which used to work with F27 just fine, but as soon as the connection is established; the whole bluetooth service (daemon) is stopped.

To be able to re-establish a connection again, you have to "start" the bluetooth service again with: systemctl start bluetooth.

systemctl status bluetooth after the issue happens gives:
bluetooth.service - Bluetooth service
   Loaded: loaded (/usr/lib/systemd/system/bluetooth.service; enabled; vendor preset: enabled)
   Active: inactive (dead) since Mon 2018-03-19 15:34:28 EET; 2min 47s ago
     Docs: man:bluetoothd(8)
  Process: 12057 ExecStart=/usr/libexec/bluetooth/bluetoothd (code=exited, status=0/SUCCESS)
 Main PID: 12057 (code=exited, status=0/SUCCESS)
   Status: "Quitting"

Mar 19 15:33:57 anass-galago bluetoothd[12057]: Failed to set privacy: Rejected (0x0b)
Mar 19 15:33:57 anass-galago bluetoothd[12057]: Endpoint registered: sender=:1.84 path=/MediaEndpoint/A2DPSource
Mar 19 15:33:57 anass-galago bluetoothd[12057]: Endpoint registered: sender=:1.84 path=/MediaEndpoint/A2DPSink
Mar 19 15:34:28 anass-galago bluetoothd[12057]: /org/bluez/hci0/dev_00_02_3C_4B_D6_08/fd0: fd(37) ready
Mar 19 15:34:28 anass-galago bluetoothd[12057]: Disconnected from D-Bus. Exiting.
Mar 19 15:34:28 anass-galago bluetoothd[12057]: Endpoint unregistered: sender=:1.84 path=/MediaEndpoint/A2DPSource
Mar 19 15:34:28 anass-galago bluetoothd[12057]: No reply to Suspend request
Mar 19 15:34:28 anass-galago bluetoothd[12057]: Endpoint unregistered: sender=:1.84 path=/MediaEndpoint/A2DPSink
Mar 19 15:34:28 anass-galago bluetoothd[12057]: Stopping SDP server
Mar 19 15:34:28 anass-galago bluetoothd[12057]: Exit




Version-Release number of selected component (if applicable):
-------------------------------------------------------------

alsa-plugins-pulseaudio-1.1.5-2.fc28.i686
alsa-plugins-pulseaudio-1.1.5-2.fc28.x86_64
bluez-5.48-4.fc28.x86_64
bluez-cups-5.48-4.fc28.x86_64
bluez-libs-5.48-4.fc28.x86_64
bluez-libs-devel-5.48-4.fc28.x86_64
bluez-obexd-5.48-4.fc28.x86_64
gnome-bluetooth-3.28.0-1.fc28.x86_64
gnome-bluetooth-libs-3.28.0-1.fc28.x86_64
kernel-4.15.9-300.fc27.x86_64
kernel-4.16.0-0.rc4.git0.1.fc28.x86_64
kernel-4.16.0-0.rc5.git0.2.fc28.x86_64
kernel-core-4.15.9-300.fc27.x86_64
kernel-core-4.16.0-0.rc4.git0.1.fc28.x86_64
kernel-core-4.16.0-0.rc5.git0.2.fc28.x86_64
kernel-debug-devel-4.15.9-300.fc27.x86_64
kernel-debug-devel-4.16.0-0.rc4.git0.1.fc28.x86_64
kernel-debug-devel-4.16.0-0.rc5.git0.2.fc28.x86_64
kernel-devel-4.15.9-300.fc27.x86_64
kernel-devel-4.16.0-0.rc4.git0.1.fc28.x86_64
kernel-devel-4.16.0-0.rc5.git0.2.fc28.x86_64
kernel-headers-4.16.0-0.rc5.git0.2.fc28.x86_64
kernel-modules-4.15.9-300.fc27.x86_64
kernel-modules-4.16.0-0.rc4.git0.1.fc28.x86_64
kernel-modules-4.16.0-0.rc5.git0.2.fc28.x86_64
kernel-tools-4.16.0-0.rc5.git0.1.fc28.x86_64
kernel-tools-libs-4.16.0-0.rc5.git0.1.fc28.x86_64
kf5-bluez-qt-5.44.0-1.fc28.x86_64
NetworkManager-1.10.6-1.fc28.x86_64
NetworkManager-bluetooth-1.10.6-1.fc28.x86_64
pulseaudio-11.1-16.fc28.x86_64
pulseaudio-gdm-hooks-11.1-16.fc28.x86_64
pulseaudio-libs-11.1-16.fc28.i686
pulseaudio-libs-11.1-16.fc28.x86_64
pulseaudio-libs-glib2-11.1-16.fc28.x86_64
pulseaudio-module-bluetooth-11.1-16.fc28.x86_64
pulseaudio-module-gconf-11.1-16.fc28.x86_64
pulseaudio-module-x11-11.1-16.fc28.x86_64
pulseaudio-utils-11.1-16.fc28.x86_64




How reproducible:
-----------------

Always



Steps to Reproduce:
-------------------

1. Open GNOME Control Center and switch to Bluetooth panel.
2. Pair or connect to an existing Bluetooth headset.
3. Wait until connection confirmation.



Actual results:
---------------

Bluetooth service goes down.



Expected results:
-----------------
Connection is established in a normal way.



Additional info:
----------------

There's no apparent errors in the logs but there's a clear issue that something stops the bluetoothd service directly.



Logs from Journal:
------------------

# try 1

Mar 19 08:37:00 anass-galago kernel: Bluetooth: hci0: last event is not cmd complete (0x0f)
Mar 19 08:37:17 anass-galago bluetoothd[15044]: Disconnected from D-Bus. Exiting.
Mar 19 08:37:17 anass-galago bluetoothd[15044]: Endpoint unregistered: sender=:1.84 path=/MediaEndpoint/A2DPSource
Mar 19 08:37:17 anass-galago bluetoothd[15044]: Endpoint unregistered: sender=:1.84 path=/MediaEndpoint/A2DPSink
Mar 19 08:37:17 anass-galago bluetoothd[15044]: Stopping SDP server
Mar 19 08:37:17 anass-galago bluetoothd[15044]: Exit
Mar 19 08:37:17 anass-galago NetworkManager[1042]: <info>  [1521441437.6696] device (F0:EE:10:64:06:69): state change: disconnected -> unmanaged (reason 'removed', sys-iface-state: 'removed')
Mar 19 08:37:17 anass-galago dbus-daemon[1282]: [session uid=42 pid=1282] Activating service name='ca.desrt.dconf' requested by ':1.13' (uid=42 pid=1301 comm="/usr/bin/gnome-shell " label="system_u:system_r:xdm_t:s0-s0:c0.c1023")
Mar 19 08:37:17 anass-galago audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=bluetooth comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 19 08:37:17 anass-galago gnome-shell[1301]: Error setting property 'Powered' on interface org.bluez.Adapter1: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.137 was not provided by any .service files (g-dbus-error-quark, 2)
Mar 19 08:37:17 anass-galago gnome-shell[4138]: Error setting property 'Powered' on interface org.bluez.Adapter1: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.137 was not provided by any .service files (g-dbus-error-quark, 2)
Mar 19 08:37:17 anass-galago dbus-daemon[1282]: [session uid=42 pid=1282] Successfully activated service 'ca.desrt.dconf'

# try 2

Mar 19 15:06:29 anass-galago kernel: Bluetooth: hci0: last event is not cmd complete (0x0f)
Mar 19 15:06:38 anass-galago kernel: input: 00:02:3C:4B:D6:08 as /devices/virtual/input/input24
Mar 19 15:06:39 anass-galago systemd-logind[1077]: Watching system buttons on /dev/input/event15 (00:02:3C:4B:D6:08)
Mar 19 15:06:41 anass-galago bluetoothd[24877]: /org/bluez/hci0/dev_00_02_3C_4B_D6_08/fd0: fd(37) ready
Mar 19 15:06:41 anass-galago bluetoothd[24877]: Disconnected from D-Bus. Exiting.
Mar 19 15:06:41 anass-galago bluetoothd[24877]: Endpoint unregistered: sender=:1.85 path=/MediaEndpoint/A2DPSource
Mar 19 15:06:41 anass-galago bluetoothd[24877]: No reply to Suspend request
Mar 19 15:06:41 anass-galago NetworkManager[1050]: <info>  [1521464801.4563] device (F0:EE:10:64:06:69): state change: disconnected -> unmanaged (reason 'removed', sys-iface-state: 'removed')
Mar 19 15:06:41 anass-galago pulseaudio[4271]: Transport Acquire() failed for transport /org/bluez/hci0/dev_00_02_3C_4B_D6_08/fd0 (Message recipient disconnected from message bus without replying)
Mar 19 15:06:41 anass-galago gnome-shell[1280]: Error setting property 'Powered' on interface org.bluez.Adapter1: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.159 was not provided by any .service files (g-dbus-error-quark, 2)
Mar 19 15:06:41 anass-galago gnome-control-c[24710]: Error setting property 'Powered' on interface org.bluez.Adapter1: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.159 was not provided by any .service files (g-dbus-error-quark, 2)
Mar 19 15:06:41 anass-galago gnome-control-c[24710]: Error setting property 'Discoverable' on interface org.bluez.Adapter1: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.159 was not provided by any .service files (g-dbus-error-quark, 2)
Mar 19 15:06:41 anass-galago gnome-control-c[24710]: Error setting property 'DiscoverableTimeout' on interface org.bluez.Adapter1: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.159 was not provided by any .service files (g-dbus-error-quark, 2)
Mar 19 15:06:41 anass-galago gnome-control-c[24710]: Error setting property 'Discoverable' on interface org.bluez.Adapter1: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.159 was not provided by any .service files (g-dbus-error-quark, 2)
Mar 19 15:06:41 anass-galago gnome-control-c[24710]: Error setting property 'DiscoverableTimeout' on interface org.bluez.Adapter1: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.159 was not provided by any .service files (g-dbus-error-quark, 2)
Mar 19 15:06:41 anass-galago bluetoothd[24877]: Endpoint unregistered: sender=:1.85 path=/MediaEndpoint/A2DPSink
Mar 19 15:06:41 anass-galago bluetoothd[24877]: Stopping SDP server
Mar 19 15:06:41 anass-galago bluetoothd[24877]: Exit
Mar 19 15:06:41 anass-galago gnome-shell[3980]: Error setting property 'Powered' on interface org.bluez.Adapter1: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.159 was not provided by any .service files (g-dbus-error-quark, 2)
Mar 19 15:06:41 anass-galago audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=bluetooth comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'

# try 3 (with btusb.enable_autosuspend=n)

Mar 19 15:17:23 anass-galago kernel: input: 00:02:3C:4B:D6:08 as /devices/virtual/input/input22
Mar 19 15:17:23 anass-galago systemd-logind[1071]: Watching system buttons on /dev/input/event15 (00:02:3C:4B:D6:08)
Mar 19 15:17:24 anass-galago kernel: Bluetooth: hci0: last event is not cmd complete (0x0f)
Mar 19 15:17:26 anass-galago bluetoothd[4061]: /org/bluez/hci0/dev_00_02_3C_4B_D6_08/fd0: fd(37) ready
Mar 19 15:17:26 anass-galago bluetoothd[4061]: Disconnected from D-Bus. Exiting.
Mar 19 15:17:26 anass-galago bluetoothd[4061]: Endpoint unregistered: sender=:1.84 path=/MediaEndpoint/A2DPSource
Mar 19 15:17:26 anass-galago bluetoothd[4061]: No reply to Suspend request
Mar 19 15:17:26 anass-galago pulseaudio[3990]: Transport Acquire() failed for transport /org/bluez/hci0/dev_00_02_3C_4B_D6_08/fd0 (Message recipient disconnected from message bus without replying)
Mar 19 15:17:26 anass-galago NetworkManager[1053]: <info>  [1521465446.2697] device (F0:EE:10:64:06:69): state change: disconnected -> unmanaged (reason 'removed', sys-iface-state: 'removed')
Mar 19 15:17:26 anass-galago gnome-shell[1303]: Error setting property 'Powered' on interface org.bluez.Adapter1: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.86 was not provided by any .service files (g-dbus-error-quark, 2)
Mar 19 15:17:26 anass-galago gnome-shell[3701]: Error setting property 'Powered' on interface org.bluez.Adapter1: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.86 was not provided by any .service files (g-dbus-error-quark, 2)
Mar 19 15:17:26 anass-galago bluetoothd[4061]: Endpoint unregistered: sender=:1.84 path=/MediaEndpoint/A2DPSink
Mar 19 15:17:26 anass-galago bluetoothd[4061]: Stopping SDP server
Mar 19 15:17:26 anass-galago bluetoothd[4061]: Exit
Mar 19 15:17:26 anass-galago audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=bluetooth comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'

Maybe related to Bug 1514836 but I'm not sure.

Comment 1 Anass Ahmed 2018-03-24 19:33:29 UTC
I've downloaded Fedora 28 Workstation Compose (Fedora-28-20180324.n.0) from https://kojipkgs.fedoraproject.org/compose/branched/Fedora-28-20180324.n.0/compose/Workstation/x86_64/iso/Fedora-Workstation-Live-x86_64-28-20180324.n.0.iso because I wanted to test something clean (maybe after the upgrade, there was some dirty config files).

I flashed to a USB thumb drive, and booted and tried to pair the headset, but as soon as the connection has been established, the bluetooth(d) service went down (stopped)!

Comment 2 Anass Ahmed 2018-04-01 07:52:30 UTC
Looks like something around these versions has fixed the issue:

bluez-libs-5.49-2.fc28.x86_64
bluez-cups-5.49-2.fc28.x86_64
pulseaudio-module-bluetooth-11.1-18.fc28.x86_64
gnome-bluetooth-libs-3.28.0-1.fc28.x86_64
bluez-5.49-2.fc28.x86_64
bluez-libs-devel-5.49-2.fc28.x86_64
gnome-bluetooth-3.28.0-1.fc28.x86_64
NetworkManager-bluetooth-1.10.6-1.fc28.x86_64
bluez-obexd-5.49-2.fc28.x86_64


Note You need to log in before you can comment on or make changes to this bug.