FS#59192 - [pulseaudio] abort when switching to A2DP sink profile

Attached to Project: Arch Linux
Opened by Maciek Borzecki (bboozzoo) - Saturday, 30 June 2018, 17:26 GMT
Last edited by Jan Alexander Steffens (heftig) - Sunday, 19 June 2022, 10:05 GMT
Task Type Bug Report
Category Upstream Bugs
Status Closed
Assigned To Jan Alexander Steffens (heftig)
Architecture All
Severity Low
Priority Normal
Reported Version
Due in Version Undecided
Due Date Undecided
Percent Complete 100%
Votes 3
Private No

Details

Description:

Pulseaudio aborts, when switching audio profile from 'off' to 'a2dp_sink' with SONY MDR-XB950N1 connected over bluetooth.

Relevant log:
cze 30 19:12:28 galeon bluetoothd[655]: /org/bluez/hci0/dev_04_5D_4B_53_F1_EA/fd0: fd(37) ready
cze 30 19:12:28 galeon pulseaudio[1091]: E: [pulseaudio] module-bluez5-device.c: Assertion '!u->thread' failed at modules/bluetooth/module-bluez5-device.c:1731, function start_thread(). Aborting.
cze 30 19:12:28 galeon systemd[1]: Created slice system-systemd\x2dcoredump.slice.
cze 30 19:12:28 galeon systemd[1]: Started Process Core Dump (PID 1802/UID 0).

Additional info:
* package version(s):

- pulseaudio 12.0-1
- bluez 5.50-3

Upstream bug report: https://bugs.freedesktop.org/show_bug.cgi?id=107044
The bug report contains a patch, https://patchwork.freedesktop.org/patch/233880/

This task depends upon

Closed by  Jan Alexander Steffens (heftig)
Sunday, 19 June 2022, 10:05 GMT
Reason for closing:  Fixed
Comment by Maxim (Zeben) - Thursday, 05 July 2018, 19:08 GMT
I can to confirm the issue. Now my headphones don't work anymore.
июл 05 21:57:23 pulseaudio[17238]: E: [pulseaudio] module-bluez5-device.c: Assertion '!u->thread' failed at modules/bluetooth/module-bluez5-device.c:1731, function start_thread(). Aborting.
июл 05 21:57:23 systemd[1]: Started Process Core Dump (PID 17858/UID 0)
...
[Edited]
Downgrading packages (pulseaudio, libpulse, pulseaudio-bluetooth) to 11.1 solves the issue. Waiting for a fix.
Comment by Andreea (andreea660) - Friday, 06 July 2018, 13:47 GMT
I have the same problem. It emerges in the same maner as it does for Maciek Borzecki (bozzo). The problem started aprox 10 days ago, after I made a "pacman -Syu" update.
Logs from journalctl (a larger chunk, hope it has more helpful info):

Jul 06 14:57:48 andreea pulseaudio[2319]: [pulseaudio] main.c: This program is not intended to be run as root (unless --system is specified).
Jul 06 14:57:48 andreea pulseaudio[2322]: [pulseaudio] pid.c: Stale PID file, overwriting.
Jul 06 14:57:48 andreea dbus-daemon[483]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.189' (uid=0 pid=2322 comm="pulseaudio --start --log-target=syslog --st>
Jul 06 14:57:48 andreea bluetoothd[476]: Endpoint registered: sender=:1.188 path=/MediaEndpoint/A2DPSource
Jul 06 14:57:48 andreea bluetoothd[476]: Endpoint registered: sender=:1.188 path=/MediaEndpoint/A2DPSink
Jul 06 14:57:48 andreea pulseaudio[2322]: [pulseaudio] backend-ofono.c: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files
Jul 06 14:57:54 andreea kernel: Bluetooth: hci0: last event is not cmd complete (0x0f)
Jul 06 14:57:57 andreea kernel: input: B8:69:C2:D6:F8:41 as /devices/virtual/input/input15
Jul 06 14:57:57 andreea systemd-logind[480]: Watching system buttons on /dev/input/event7 (B8:69:C2:D6:F8:41)
Jul 06 14:58:04 andreea kernel: Bluetooth: hci0: last event is not cmd complete (0x0f)
Jul 06 14:58:06 andreea bluetoothd[476]: /org/bluez/hci0/dev_B8_69_C2_D6_F8_41/fd1: fd(25) ready
Jul 06 14:58:06 andreea pulseaudio[2322]: [pulseaudio] module-bluez5-device.c: Assertion '!u->thread' failed at modules/bluetooth/module-bluez5-device.c:1731, function start_thread(). Aborting.
Jul 06 14:58:06 andreea systemd[1]: Started Process Core Dump (PID 2345/UID 0).
Jul 06 14:58:07 andreea bluetoothd[476]: Endpoint unregistered: sender=:1.188 path=/MediaEndpoint/A2DPSink
Jul 06 14:58:07 andreea bluetoothd[476]: Endpoint unregistered: sender=:1.188 path=/MediaEndpoint/A2DPSource
Jul 06 14:58:07 andreea systemd-coredump[2346]: Process 2322 (pulseaudio) of user 0 dumped core.

Stack trace of thread 2322:
#0 0x00007f45a28d086b raise (libc.so.6)
#1 0x00007f45a28bb40e abort (libc.so.6)
#2 0x00007f4581783d17 n/a (module-bluez5-device.so)
#3 0x00007f4581786c3f n/a (module-bluez5-device.so)
#4 0x00007f45a3e57c4a pa_card_set_profile (libpulsecore-12.0.so)
#5 0x00007f459e6b9481 n/a (libprotocol-native.so)
#6 0x00007f45a399cf48 pa_pdispatch_run (libpulsecommon-12.0.so)
#7 0x00007f459e6c20d6 n/a (libprotocol-native.so)
#8 0x00007f45a399fa9f n/a (libpulsecommon-12.0.so)
#9 0x00007f45a39a290b n/a (libpulsecommon-12.0.so)
#10 0x00007f45a39a2cba n/a (libpulsecommon-12.0.so)
#11 0x00007f45a39a354a n/a (libpulsecommon-12.0.so)
#12 0x00007f45a3c05d18 pa_mainloop_dispatch (libpulse.so.0)
#13 0x00007f45a3c05fef pa_mainloop_iterate (libpulse.so.0)
#14 0x00007f45a3c06091 pa_mainloop_run (libpulse.so.0)
#15 0x00005568daa613b3 main (pulseaudio)
#16 0x00007f45a28bd06b __libc_start_main (libc.so.6)
#17 0x00005568daa6209a _start (pulseaudio)

Stack trace of thread 2344:
#0 0x00007f45a2987fa6 ppoll (libc.so.6)
#1 0x00007f45a3e67e10 pa_rtpoll_run (libpulsecore-12.0.so)
#2 0x00007f4581787338 n/a (module-bluez5-device.so)
#3 0x00007f45a39b39fc n/a (libpulsecommon-12.0.so)
#4 0x00007f45a2e61075 start_thread (libpthread.so.0)
#5 0x00007f45a299253f __clone (libc.so.6)

Stack trace of thread 2328:
#0 0x00007f45a2987fa6 ppoll (libc.so.6)
#1 0x00007f45a3e67e10 pa_rtpoll_run (libpulsecore-12.0.so)
#2 0x00007f45929b4a21 n/a (module-null-sink.so)
#3 0x00007f45a39b39fc n/a (libpulsecommon-12.0.so)
#4 0x00007f45a2e61075 start_thread (libpthread.so.0)
#5 0x00007f45a299253f __clone (libc.so.6)

And this:

> systemctl status bluetooth
* bluetooth.service - Bluetooth service
Loaded: loaded (/usr/lib/systemd/system/bluetooth.service; enabled; vendor preset: disabled)
Active: active (running) since Fri 2018-07-06 14:40:04 EEST; 2h 4min ago
Docs: man:bluetoothd(8)
Main PID: 476 (bluetoothd)
Status: "Running"
Tasks: 1 (limit: 4915)
Memory: 5.5M
CGroup: /system.slice/bluetooth.service
`-476 /usr/lib/bluetooth/bluetoothd

Jul 06 14:56:58 andreea bluetoothd[476]: a2dp-sink profile connect failed for B8:69:C2:D6:F8:41: Protocol not available
Jul 06 14:56:58 andreea bluetoothd[476]: a2dp-sink profile connect failed for B8:69:C2:D6:F8:41: Protocol not available
Jul 06 14:56:59 andreea bluetoothd[476]: a2dp-sink profile connect failed for B8:69:C2:D6:F8:41: Protocol not available
Jul 06 14:56:59 andreea bluetoothd[476]: a2dp-sink profile connect failed for B8:69:C2:D6:F8:41: Protocol not available
Jul 06 14:57:00 andreea bluetoothd[476]: a2dp-sink profile connect failed for B8:69:C2:D6:F8:41: Protocol not available
Jul 06 14:57:48 andreea bluetoothd[476]: Endpoint registered: sender=:1.188 path=/MediaEndpoint/A2DPSource
Jul 06 14:57:48 andreea bluetoothd[476]: Endpoint registered: sender=:1.188 path=/MediaEndpoint/A2DPSink
Jul 06 14:58:06 andreea bluetoothd[476]: /org/bluez/hci0/dev_B8_69_C2_D6_F8_41/fd1: fd(25) ready
Jul 06 14:58:07 andreea bluetoothd[476]: Endpoint unregistered: sender=:1.188 path=/MediaEndpoint/A2DPSink
Jul 06 14:58:07 andreea bluetoothd[476]: Endpoint unregistered: sender=:1.188 path=/MediaEndpoint/A2DPSource

Loading...