[SOLVED] Bluetooth HSP profile stopped working recently

vtrac · 2020-04-14 22:48:53

Sometime within the last 2-3 weeks, I can't get my HSP profile to work. I can use pavcontrol to switch to the HSP profile and configure my default output/input to my Bose 700 headphones as expected, but no sound plays or is recorded. I can hear my headphones switching profile (more background sounds), and switching profiles works on my phone and under windows just fine. Trying to play music in a Brave tab just hangs the music player (youtube music). This is definitely a regression in something under linux within the last few weeks. It seems like multiple people are having this issue with a recent update, based on some forum searches, too.

I have no idea how bluetooth/pulse works, but here are some logs in case that helps:

$ journalctl -b --user-unit=pulseaudio
-- Logs begin at Mon 2020-02-24 05:52:00 CST, end at Tue 2020-04-14 17:39:42 CDT. --
Apr 14 17:30:22 carbon systemd[1843]: Starting Sound Service...
Apr 14 17:30:22 carbon pulseaudio[1975]: W: [pulseaudio] alsa-ucm.c: UCM file does not specify 'PlaybackChannels' or 'CaptureChannels'for device HDMI3, assuming stereo duplex.
Apr 14 17:30:22 carbon pulseaudio[1975]: W: [pulseaudio] alsa-ucm.c: UCM file does not specify 'PlaybackChannels' or 'CaptureChannels'for device HDMI2, assuming stereo duplex.
Apr 14 17:30:22 carbon pulseaudio[1975]: W: [pulseaudio] alsa-ucm.c: UCM file does not specify 'PlaybackChannels' or 'CaptureChannels'for device HDMI1, assuming stereo duplex.
Apr 14 17:30:22 carbon pulseaudio[1975]: W: [pulseaudio] alsa-ucm.c: UCM file does not specify 'PlaybackChannels' or 'CaptureChannels'for device Headphones2, assuming stereo duplex.
Apr 14 17:30:22 carbon pulseaudio[1975]: W: [pulseaudio] alsa-ucm.c: UCM file does not specify 'PlaybackChannels' or 'CaptureChannels'for device Speaker, assuming stereo duplex.
Apr 14 17:30:22 carbon pulseaudio[1975]: W: [pulseaudio] alsa-ucm.c: UCM file does not specify 'PlaybackChannels' or 'CaptureChannels'for device Headphones1, assuming stereo duplex.

$ systemctl status bluetooth
● bluetooth.service - Bluetooth service
     Loaded: loaded (/usr/lib/systemd/system/bluetooth.service; enabled; vendor preset: disabled)
     Active: active (running) since Tue 2020-04-14 17:30:07 CDT; 12min ago
       Docs: man:bluetoothd(8)
   Main PID: 1101 (bluetoothd)
     Status: "Running"
      Tasks: 1 (limit: 18769)
     Memory: 4.4M
     CGroup: /system.slice/bluetooth.service
             └─1101 /usr/lib/bluetooth/bluetoothd

Apr 14 17:30:07 carbon bluetoothd[1101]: Bluetooth management interface 1.15 initialized
Apr 14 17:30:22 carbon bluetoothd[1101]: Endpoint registered: sender=:1.71 path=/MediaEndpoint/A2DPSink/sbc
Apr 14 17:30:22 carbon bluetoothd[1101]: Endpoint registered: sender=:1.71 path=/MediaEndpoint/A2DPSource/sbc
Apr 14 17:30:30 carbon bluetoothd[1101]: Endpoint unregistered: sender=:1.71 path=/MediaEndpoint/A2DPSink/sbc
Apr 14 17:30:30 carbon bluetoothd[1101]: Endpoint unregistered: sender=:1.71 path=/MediaEndpoint/A2DPSource/sbc
Apr 14 17:30:35 carbon bluetoothd[1101]: Endpoint registered: sender=:1.71 path=/MediaEndpoint/A2DPSink/sbc
Apr 14 17:30:35 carbon bluetoothd[1101]: Endpoint registered: sender=:1.71 path=/MediaEndpoint/A2DPSource/sbc
Apr 14 17:30:42 carbon bluetoothd[1101]: /org/bluez/hci0/dev_4C_87_5D_9F_31_F2/sep1/fd0: fd(36) ready
Apr 14 17:30:43 carbon bluetoothd[1101]: Unable to get io data for Headset Voice gateway: getpeername: Transport endpoint is not connected (107)
Apr 14 17:30:55 carbon bluetoothd[1101]: /org/bluez/hci0/dev_4C_87_5D_9F_31_F2/fd1: fd(36) ready

$ pacmd list-cards | grep -B20 -A40 HSP
				
		[In] Mic: Digital Microphone (priority 100, latency offset 0 usec, available: no)
			properties:
				
    index: 2
	name: <bluez_card.4C_87_5D_9F_31_F2>
	driver: <module-bluez5-device.c>
	owner module: 30
	properties:
		device.description = "Bose NC 700 HP"
		device.string = "4C:87:5D:9F:31:F2"
		device.api = "bluez"
		device.class = "sound"
		device.bus = "bluetooth"
		device.form_factor = "headphone"
		bluez.path = "/org/bluez/hci0/dev_4C_87_5D_9F_31_F2"
		bluez.class = "0x240418"
		bluez.alias = "Bose NC 700 HP"
		device.icon_name = "audio-headphones-bluetooth"
	profiles:
		headset_head_unit: Headset Head Unit (HSP/HFP) (priority 30, available: yes)
		a2dp_sink: High Fidelity Playback (A2DP Sink) (priority 40, available: unknown)
		off: Off (priority 0, available: yes)
	active profile: <headset_head_unit>
	sinks:
		bluez_sink.4C_87_5D_9F_31_F2.headset_head_unit/#6: Bose NC 700 HP
	sources:
		bluez_sink.4C_87_5D_9F_31_F2.headset_head_unit.monitor/#8: Monitor of Bose NC 700 HP
		bluez_source.4C_87_5D_9F_31_F2.headset_head_unit/#9: Bose NC 700 HP
	ports:
		headphone-output: Headphone (priority 0, latency offset 0 usec, available: yes)
			properties:
				
		headphone-input: Bluetooth Input (priority 0, latency offset 0 usec, available: yes)
			properties:

Any suggestions would be appreciated.

Last edited by vtrac (2020-04-27 14:46:51)

V1del · 2020-04-15 07:13:10

Close to the only thing that changed within that timeframe that can have an effect on this on a system level is the kernel, so you might want to try and use LTS or so. But bluetooth is generally a fickle beast, are you sure you didn't pair the headset with another system or so that could lead to issues with this?

YesserLab · 2020-04-16 09:12:39

I've been having the same behavior and it drove me nuts !
Exactly as described by OP. Everything bluetooth-wise, seems to work as expected, profiles are visible, selectable, audio plays normally on A2DP.
Switch to HSP/HFP and everything is silent. no playback, no recording.

Tested on multiple bluetooth speakers including JBL GO, JBL GO 2, BOSE 700

❯ inxi -Fxxxz
System:
  Kernel: 5.6.4-arch1-1 x86_64 bits: 64 compiler: gcc v: 9.3.0 
  Desktop: KDE Plasma 5.18.4 tk: Qt 5.14.2 info: latte-dock wm: kwin_x11 dm: SDDM 
  Distro: Arch Linux 
Machine:
  Type: Laptop System: Dell product: XPS 15 7590 v: N/A serial: <filter> Chassis: 
  type: 10 serial: <filter> 
  Mobo: Dell model: 0VYV0G v: A00 serial: <filter> UEFI: Dell v: 1.5.0 
  date: 12/25/2019

EDIT:
The same setup worked perfectly on kernel 5.5.x
after an update I started noticing this behavior. The issue is, however, that doing a

pacman -Syu

updates quite a number of things (including pulseaudio). So I'm not entirely certain that it's the kernel

Last edited by YesserLab (2020-04-17 09:10:25)

eblau · 2020-04-16 16:48:58

I'm having the same issue. I'm configuring a new bluetooth headset so I can't say that it was all working before, but I see my headset and A2DP output works fine. Switching to HSP/HFP profile in pavucontrol causes audio output to stop and my microphone does not work either. All looks good in "pactl list":

Card #8
	Name: bluez_card.70_BF_92_37_1C_CF
	Driver: module-bluez5-device.c
	Owner Module: 34
	Properties:
		device.description = "Jabra Elite 85h"
		device.string = "70:BF:92:37:1C:CF"
		device.api = "bluez"
		device.class = "sound"
		device.bus = "bluetooth"
		device.form_factor = "headset"
		bluez.path = "/org/bluez/hci0/dev_70_BF_92_37_1C_CF"
		bluez.class = "0x240404"
		bluez.alias = "Jabra Elite 85h"
		device.icon_name = "audio-headset-bluetooth"
		device.intended_roles = "phone"
	Profiles:
		headset_head_unit: Headset Head Unit (HSP/HFP) (sinks: 1, sources: 1, priority: 30, available: yes)
		a2dp_sink: High Fidelity Playback (A2DP Sink) (sinks: 1, sources: 0, priority: 40, available: yes)
		off: Off (sinks: 0, sources: 0, priority: 0, available: yes)
	Active Profile: headset_head_unit
	Ports:
		headset-output: Headset (priority: 0, latency offset: 0 usec, available)
			Part of profile(s): headset_head_unit, a2dp_sink
		headset-input: Headset (priority: 0, latency offset: 0 usec, available)
			Part of profile(s): headset_head_unit

I wish I could have tried this on some earlier kernel version or something where this was last working for others so that I can verify my new headset properly.

Do you folks know when this was last working for you? Any suggestions on package downgrades I can perform to test? Thanks!

NoSync · 2020-04-18 13:15:05

I started having the same problem (also with Bose NC700) and I fixed it by changing

FastConnectable = true

to

FastConnectable = false

in /etc/bluetooth/main.conf

Last edited by NoSync (2020-04-18 13:15:19)

Ram-Z · 2020-04-20 18:52:32

Same (or similar) problem. Discovered this morning when I couldn't join a call over Google Meet. Interestingly enough, the webcam stopped working too until I disconnected the bluetooth headset.

I can reproduce hanging video by changing to HSP profile, setting it as default and trying to play video using mpv. The video will hang on the first frame until the output is moved off the bluetooth device.

I currently have `bluetoothd --noplugin=avrcp` set in bluetoot.service, I think it had the same behaviour beforehand but I'll try again later.

`FastConnectable` was already set to false (the default) for me, changing it in any way had no effect.

So far I've tried this with kernels 5.6.5-arch3 and 5.6.4-arch1. Will reboot into 5.6.3-arch1 and test that one.

One of the errors I'm getting, usually when I power off the device.

Apr 20 19:40:41 hactar bluetoothd[9526]: Unable to get io data for Headset Voice gateway: getpeername: Transport endpoint is not connected (107)

----

edit: Tested with a few more kernels (also lts) and downgrading bluez (and libs) to 5.53, to no avail. I don't know what else could have changed.

Last edited by Ram-Z (2020-04-20 19:24:36)

jdewinne · 2020-04-20 19:45:38

Confirming, I'm also facing this issue.
Also for me `FastConnectable` is/was having the default value, and didn't resolve the issue.

Below the `journalctl` when switching the profile from A2DP to HSP/HPF

Apr 20 12:40:47 laptopjoris rtkit-daemon[890]: Supervising 6 threads of 3 processes of 1 users.
Apr 20 12:40:47 laptopjoris rtkit-daemon[890]: Successfully made thread 6937 of process 1370 owned by '1000' RT at priority 5.
Apr 20 12:40:47 laptopjoris gsd-media-keys[1549]: Unable to get default sink
Apr 20 12:40:47 laptopjoris rtkit-daemon[890]: Supervising 7 threads of 3 processes of 1 users.

Kernel: 5.6.5-arch3-1
Bluez: 5.54-1
Pulseaudio: 13.0-3

Ram-Z · 2020-04-20 20:02:13

I've found this old report for a similar symptom, comment #4 has a pretty good description on how to get good debug information.

I'll give that a go in either later today or tomorrow. I currently can't get any useful information out of it since my WM is flooding the PA log with regular "connect/give-me-volume/disconnect" calls.

eblau · 2020-04-21 11:06:59

Changing the FastConnectable setting also did not work for me. I hadn't changed it from the default value which was false already.

I tried some of the debug steps in the issue report but nothing stood out. My WM was also flooding the PA log so I had to disable some things. I didn't get any useful information. parecord was able to connect and showed a value record stream with 28000 usec latency while in headset mode. Still, the headset does not work as an output and the microphone does not work as an input.

The only clue I got was that Pulse would shut down the headset as idle after 5 seconds, even while using parecord. I disabled the "module-suspend-on-idle" module but that didn't help either. Something in PA seems to be getting confused with the headset.

Ram-Z · 2020-04-21 21:05:00

I've ran through the debug steps too now.

The thing that struck me was that there were no messages from bluetoothd when switching the profile to hsp. See `#3#`.

$ sudo /usr/lib/bluetooth/bluetoothd --debug --nodetach
bluetoothd[233070]: Bluetooth daemon 5.54
...
### snip
...
bluetoothd[233070]: plugins/hostname.c:property_changed() chassis: desktop
bluetoothd[233070]: plugins/hostname.c:update_class() major: 0x01 minor: 0x01
bluetoothd[233070]: src/adapter.c:local_name_changed_callback() Name: hactar
bluetoothd[233070]: src/adapter.c:local_name_changed_callback() Short name: 
bluetoothd[233070]: src/adapter.c:local_name_changed_callback() Current alias: hactar


#0# starting pulseaudio


bluetoothd[233070]: profiles/audio/avdtp.c:avdtp_register_sep() SEP 0x55c3e23c9170 registered: type:1 codec:0 seid:1
bluetoothd[233070]: src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[233070]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10005
bluetoothd[233070]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000019-0000-1000-8000-00805f9
bluetoothd[233070]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[233070]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[233070]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110b-0000-1000-8000-00805f9
bluetoothd[233070]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110d-0000-1000-8000-00805f9
bluetoothd[233070]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[233070]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[233070]: Endpoint registered: sender=:1.7208 path=/MediaEndpoint/A2DPSink/sbc
...
### snip
...
bluetoothd[233070]: src/profile.c:ext_device_probe() :1.7208/Profile/HSPHSProfile/00001108-0000-1000-8000-00805f9b34fb probed with UUID 00001108-0000-1000-8000-00805f9b34fb
bluetoothd[233070]: src/service.c:change_state() 0x55c3e23e1c20: device 0C:E0:E4:FB:A4:94 profile :1.7208/Profile/HSPHSProfile/00001108-0000-1000-8000-00805f9b34fb state changed: unavailable -> disconnected (0)
bluetoothd[233070]: src/profile.c:ext_device_probe() :1.7208/Profile/HSPHSProfile/00001108-0000-1000-8000-00805f9b34fb probed with UUID 00001108-0000-1000-8000-00805f9b34fb
bluetoothd[233070]: src/service.c:change_state() 0x55c3e23e1b30: device 1C:52:16:9E:D6:CA profile :1.7208/Profile/HSPHSProfile/00001108-0000-1000-8000-00805f9b34fb state changed: unavailable -> disconnected (0)
bluetoothd[233070]: src/profile.c:ext_device_probe() :1.7208/Profile/HSPHSProfile/00001108-0000-1000-8000-00805f9b34fb probed with UUID 00001108-0000-1000-8000-00805f9b34fb
bluetoothd[233070]: src/service.c:change_state() 0x55c3e23e1d20: device C0:EE:FB:9A:8F:99 profile :1.7208/Profile/HSPHSProfile/00001108-0000-1000-8000-00805f9b34fb state changed: unavailable -> disconnected (0)


#1# bluetoothctl power on


bluetoothd[233070]: src/adapter.c:property_set_mode() sending Set Powered command for index 0
bluetoothd[233070]: src/adapter.c:dev_class_changed_callback() Class: 0x0c0104
bluetoothd[233070]: src/adapter.c:property_set_mode_complete() Success (0x00)
bluetoothd[233070]: src/adapter.c:new_settings_callback() Settings: 0x00000ac5
bluetoothd[233070]: src/adapter.c:settings_changed() Changed settings: 0x00000001
bluetoothd[233070]: src/adapter.c:settings_changed() Pending settings: 0x00000000
bluetoothd[233070]: src/adapter.c:adapter_start() adapter /org/bluez/hci0 has been enabled
bluetoothd[233070]: src/adapter.c:trigger_passive_scanning() 


#2# connecting headset


bluetoothd[233070]: src/adapter.c:connected_callback() hci0 device 0C:E0:E4:FB:A4:94 connected eir_len 5
bluetoothd[233070]: src/profile.c:ext_confirm() incoming connect from 0C:E0:E4:FB:A4:94
bluetoothd[233070]: src/service.c:btd_service_ref() 0x55c3e23e0760: ref=2
bluetoothd[233070]: src/profile.c:ext_confirm() Headset Voice gateway authorizing connection from 0C:E0:E4:FB:A4:94
bluetoothd[233070]: src/profile.c:ext_auth() 0C:E0:E4:FB:A4:94 authorized to connect to Headset Voice gateway
bluetoothd[233070]: src/profile.c:ext_connect() Headset Voice gateway connected to 0C:E0:E4:FB:A4:94
...
### snip
...
bluetoothd[233070]: profiles/audio/transport.c:media_owner_remove() Owner :1.7208 Request Release
bluetoothd[233070]: profiles/audio/a2dp.c:a2dp_sep_unlock() SEP 0x55c3e23ca800 unlocked
bluetoothd[233070]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/sep1/fd0: TRANSPORT_STATE_SUSPENDING -> TRANSPORT_STATE_IDLE
bluetoothd[233070]: profiles/audio/transport.c:media_transport_remove_owner() Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/sep1/fd0 Owner :1.7208
bluetoothd[233070]: profiles/audio/transport.c:media_owner_free() Owner :1.7208
bluetoothd[233070]: profiles/audio/a2dp.c:setup_unref() 0x55c3e23e0a70: ref=0
bluetoothd[233070]: profiles/audio/a2dp.c:setup_free() 0x55c3e23e0a70
bluetoothd[233070]: profiles/audio/avdtp.c:avdtp_unref() 0x55c3e23e4c90: ref=2


#3# switching to hsp

### There was nothing here

#4# trying to play something

#5#

#6# switching to a2db
     
bluetoothd[233070]: profiles/audio/transport.c:media_owner_create() Owner created: sender=:1.7208
bluetoothd[233070]: profiles/audio/a2dp.c:a2dp_sep_lock() SEP 0x55c3e23ca800 locked
...
### snip (pretty similar to initial connection)
...
bluetoothd[233070]: profiles/audio/a2dp.c:setup_unref() 0x55c3e23e1e90: ref=0
bluetoothd[233070]: profiles/audio/a2dp.c:setup_free() 0x55c3e23e1e90
bluetoothd[233070]: profiles/audio/avdtp.c:avdtp_unref() 0x55c3e23e4c90: ref=2

#7# switching to hsp

### There was nothing here

$ pulseaudio --log-time -vv
(   0.000|   0.000) I: [pulseaudio] main.c: setrlimit(RLIMIT_NICE, (31, 31)) failed: Operation not permitted
(   0.000|   0.000) I: [pulseaudio] main.c: setrlimit(RLIMIT_RTPRIO, (9, 9)) failed: Operation not permitted
(   0.000|   0.000) D: [pulseaudio] core-rtclock.c: Timer slack is set to 50 us.
(   0.002|   0.002) D: [pulseaudio] core-util.c: RealtimeKit worked.
(   0.002|   0.000) I: [pulseaudio] core-util.c: Successfully gained nice level -11.
(   0.002|   0.000) I: [pulseaudio] main.c: This is PulseAudio 13.0
(   0.002|   0.000) D: [pulseaudio] main.c: Compilation host: x86_64
(   0.002|   0.000) D: [pulseaudio] main.c: Compilation CFLAGS: Not yet supported on meson
(   0.002|   0.000) D: [pulseaudio] main.c: Running on host: Linux x86_64 5.4.33-3-lts #1 SMP Sun, 19 Apr 2020 13:14:30 +0000
...
### snip
...
(   2.967|   0.000) D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC0 is accessible: yes
(   2.967|   0.000) D: [pulseaudio] module-udev-detect.c: Resuming all sinks and sources of card alsa_card.pci-0000_0b_00.1.
(   2.967|   0.000) D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC2 is accessible: yes
(   2.967|   0.000) D: [pulseaudio] module-udev-detect.c: Resuming all sinks and sources of card alsa_card.usb-046d_0825_30BE8B60-02.
(   2.967|   0.000) D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC1 is accessible: yes
(   2.967|   0.000) D: [pulseaudio] module-udev-detect.c: Resuming all sinks and sources of card alsa_card.pci-0000_0d_00.4.
(   2.967|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
(   2.967|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.AgentManager1 found, skipping
(   2.967|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.ProfileManager1 found, skipping
(   2.967|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
(   2.967|   0.000) D: [pulseaudio] bluez5-util.c: Adapter /org/bluez/hci0 found
(   2.967|   0.000) D: [pulseaudio] bluez5-util.c: Registering /MediaEndpoint/A2DPSink/sbc on adapter /org/bluez/hci0
(   2.967|   0.000) D: [pulseaudio] bluez5-util.c: Registering /MediaEndpoint/A2DPSource/sbc on adapter /org/bluez/hci0
(   2.967|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
(   2.967|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.GattManager1 found, skipping
(   2.967|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.LEAdvertisingManager1 found, skipping
(   2.967|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.Media1 found, skipping
(   2.967|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.NetworkServer1 found, skipping
(   2.967|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
(   2.967|   0.000) D: [pulseaudio] bluez5-util.c: Device /org/bluez/hci0/dev_C0_EE_FB_9A_8F_99 found
(   2.967|   0.000) D: [pulseaudio] bluez5-util.c: Address: C0:EE:FB:9A:8F:99
(   2.968|   0.000) D: [pulseaudio] bluez5-util.c: Alias: onyx
(   2.968|   0.000) D: [pulseaudio] bluez5-util.c: Class: 5898764
...
### snip ids (this is my phone)
...
(   2.968|   0.000) D: [pulseaudio] bluez5-util.c: Device /org/bluez/hci0/dev_1C_52_16_9E_D6_CA found
(   2.968|   0.000) D: [pulseaudio] bluez5-util.c: Address: 1C:52:16:9E:D6:CA
(   2.968|   0.000) D: [pulseaudio] bluez5-util.c: Alias: SoundBuds Tag
(   2.968|   0.000) D: [pulseaudio] bluez5-util.c: Class: 2360324
...
### a different headset (not tested yet)
...
(   2.968|   0.000) D: [pulseaudio] bluez5-util.c: Device /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94 found
(   2.968|   0.000) D: [pulseaudio] bluez5-util.c: Address: 0C:E0:E4:FB:A4:94
(   2.968|   0.000) D: [pulseaudio] bluez5-util.c: Alias: PLT_BBTPRO
(   2.968|   0.000) D: [pulseaudio] bluez5-util.c: Class: 2360324
...
### this is the headset I was testing with
...
(   2.968|   0.000) D: [pulseaudio] bluez5-util.c: Adapter: /org/bluez/hci0
(   2.968|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
(   2.968|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaControl1 found, skipping
(   2.968|   0.000) D: [pulseaudio] backend-native.c: Bluetooth Headset Backend API support using the native backend
(   2.968|   0.000) D: [pulseaudio] backend-native.c: Registering Profile /Profile/HSPAGProfile 00001112-0000-1000-8000-00805f9b34fb
(   2.968|   0.000) D: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0
(   2.968|   0.000) D: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0
(   2.969|   0.000) I: [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
(   2.969|   0.000) D: [pulseaudio] bluez5-util.c: oFono is running: no
(   2.969|   0.000) D: [pulseaudio] backend-native.c: Registering Profile /Profile/HSPHSProfile 00001108-0000-1000-8000-00805f9b34fb
(   2.969|   0.000) D: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0
(   3.260|   0.291) I: [pulseaudio] client.c: Created 1 "Native client (UNIX socket client)"
(   3.260|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Client added for object /org/pulseaudio/core1/client1
(   3.260|   0.000) D: [pulseaudio] protocol-native.c: Protocol version: remote 33, local 33
(   3.260|   0.000) I: [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
(   3.260|   0.000) D: [pulseaudio] protocol-native.c: SHM possible: yes
(   3.260|   0.000) D: [pulseaudio] protocol-native.c: Negotiated SHM: yes
(   3.260|   0.000) D: [pulseaudio] protocol-native.c: Memfd possible: yes
(   3.260|   0.000) D: [pulseaudio] protocol-native.c: Negotiated SHM type: shared memfd
(   3.260|   0.000) D: [pulseaudio] memblock.c: Using shared memfd memory pool with 1024 slots of size 64.0 KiB each, total size is 64.0 MiB, maximum usable slot size is 65472
(   3.261|   0.000) D: [pulseaudio] srbchannel.c: SHM block is 65472 bytes, ringbuffer capacity is 2 * 32712 bytes
(   3.261|   0.000) D: [pulseaudio] protocol-native.c: Enabling srbchannel...
(   3.261|   0.000) D: [pulseaudio] module-augment-properties.c: Looking for .desktop file for python3.8
(   3.261|   0.000) D: [pulseaudio] protocol-native.c: Client enabled srbchannel.
(   7.969|   4.707) I: [pulseaudio] module-suspend-on-idle.c: Source alsa_input.usb-046d_0825_30BE8B60-02.multichannel-input idle for too long, suspending ...
(   7.972|   0.003) I: [alsa-source-USB Audio] alsa-source.c: Device suspended...
(   7.973|   0.000) D: [pulseaudio] source.c: alsa_input.usb-046d_0825_30BE8B60-02.multichannel-input: suspend_cause: (none) -> IDLE
(   7.973|   0.000) D: [pulseaudio] source.c: alsa_input.usb-046d_0825_30BE8B60-02.multichannel-input: state: IDLE -> SUSPENDED
(   7.973|   0.000) D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
(   7.973|   0.000) I: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_0d_00.4.analog-stereo idle for too long, suspending ...
(   7.973|   0.000) I: [alsa-sink-ALC1220 Analog] alsa-sink.c: Device suspended...
(   7.973|   0.000) D: [pulseaudio] sink.c: alsa_output.pci-0000_0d_00.4.analog-stereo: suspend_cause: (none) -> IDLE
(   7.973|   0.000) D: [pulseaudio] sink.c: alsa_output.pci-0000_0d_00.4.analog-stereo: state: IDLE -> SUSPENDED
(   7.973|   0.000) D: [pulseaudio] source.c: alsa_output.pci-0000_0d_00.4.analog-stereo.monitor: suspend_cause: (none) -> IDLE
(   7.973|   0.000) D: [pulseaudio] source.c: alsa_output.pci-0000_0d_00.4.analog-stereo.monitor: state: IDLE -> SUSPENDED
(   7.973|   0.000) D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
(   7.973|   0.000) D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC2 is accessible: yes
(   7.973|   0.000) D: [pulseaudio] module-udev-detect.c: Resuming all sinks and sources of card alsa_card.usb-046d_0825_30BE8B60-02.
(   7.973|   0.000) D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC1 is accessible: yes
(   7.973|   0.000) D: [pulseaudio] module-udev-detect.c: Resuming all sinks and sources of card alsa_card.pci-0000_0d_00.4.


#1# bluetoothctl power on


(  27.549|  19.575) D: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0
(  27.555|   0.005) D: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0


#2# connecting headset


(  48.154|  20.598) D: [pulseaudio] bluez5-util.c: Properties changed in device /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94
(  48.314|   0.160) D: [pulseaudio] backend-native.c: dbus: path=/Profile/HSPAGProfile, interface=org.bluez.Profile1, member=NewConnection
(  48.314|   0.000) D: [pulseaudio] backend-native.c: dbus: NewConnection path=/org/bluez/hci0/dev_0C_E0_E4_FB_A4_94, fd=27, profile headset_head_unit
(  48.314|   0.000) I: [pulseaudio] backend-native.c: doing listen
(  48.314|   0.000) D: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/fd27 state: disconnected -> idle
(  48.315|   0.000) D: [pulseaudio] backend-native.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/fd27 available for profile headset_head_unit
(  48.329|   0.014) D: [pulseaudio] backend-native.c: RFCOMM << AT+VGS=13
(  48.329|   0.000) D: [pulseaudio] backend-native.c: RFCOMM >> OK
(  48.334|   0.005) D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
(  48.334|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaEndpoint1 found, skipping
(  48.334|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
(  48.334|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
(  48.334|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaEndpoint1 found, skipping
(  48.334|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
(  48.334|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
(  48.334|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaEndpoint1 found, skipping
(  48.334|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
(  48.335|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
(  48.335|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaEndpoint1 found, skipping
(  48.335|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
(  48.344|   0.009) D: [pulseaudio] backend-native.c: RFCOMM << AT+XAPL=047F-011A-0100,3
(  48.346|   0.001) D: [pulseaudio] bluez5-util.c: dbus: path=/MediaEndpoint/A2DPSource/sbc, interface=org.bluez.MediaEndpoint1, member=SelectConfiguration
(  48.355|   0.009) D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
(  48.355|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaTransport1 found, skipping
(  48.355|   0.000) D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
(  48.355|   0.000) D: [pulseaudio] bluez5-util.c: dbus: path=/MediaEndpoint/A2DPSource/sbc, interface=org.bluez.MediaEndpoint1, member=SetConfiguration
(  48.355|   0.000) D: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/sep1/fd0 state: disconnected -> idle
(  48.355|   0.000) D: [pulseaudio] module-bluez5-discover.c: Loading module-bluez5-device path=/org/bluez/hci0/dev_0C_E0_E4_FB_A4_94 autodetect_mtu=0
(  48.356|   0.000) I: [pulseaudio] module-card-restore.c: Restoring port latency offsets for card bluez_card.0C_E0_E4_FB_A4_94.
(  48.356|   0.000) D: [pulseaudio] card.c: Looking for initial profile for card bluez_card.0C_E0_E4_FB_A4_94
(  48.356|   0.000) D: [pulseaudio] card.c: headset_head_unit availability unknown
(  48.356|   0.000) D: [pulseaudio] card.c: a2dp_sink availability unknown
(  48.356|   0.000) D: [pulseaudio] card.c: off availability yes
(  48.356|   0.000) I: [pulseaudio] card.c: bluez_card.0C_E0_E4_FB_A4_94: active_profile: a2dp_sink
(  48.356|   0.000) I: [pulseaudio] card.c: Created 3 "bluez_card.0C_E0_E4_FB_A4_94"
(  48.356|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.CardProfile added for object /org/pulseaudio/core1/card3/profile0
(  48.356|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.CardProfile added for object /org/pulseaudio/core1/card3/profile1
(  48.356|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.CardProfile added for object /org/pulseaudio/core1/card3/profile2
(  48.356|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Card added for object /org/pulseaudio/core1/card3
(  48.356|   0.000) D: [pulseaudio] module-bluez5-device.c: Acquiring transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/sep1/fd0
(  48.409|   0.052) I: [pulseaudio] module-bluez5-device.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/sep1/fd0 acquired: fd 30
(  48.409|   0.000) D: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/sep1/fd0 state: idle -> playing
(  48.409|   0.000) D: [pulseaudio] card.c: Setting card bluez_card.0C_E0_E4_FB_A4_94 profile a2dp_sink to availability status yes
...
### snip a2db initialisation
... 
(  54.136|   0.000) D: [pulseaudio] sink.c: bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink: suspend_cause: (none) -> IDLE
(  54.136|   0.000) D: [pulseaudio] sink.c: bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink: state: IDLE -> SUSPENDED
(  54.136|   0.000) D: [pulseaudio] source.c: bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink.monitor: suspend_cause: (none) -> IDLE
(  54.136|   0.000) D: [pulseaudio] source.c: bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink.monitor: state: IDLE -> SUSPENDED
(  54.136|   0.000) D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
(  54.136|   0.000) D: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/sep1/fd0 state: playing -> idle
(  54.136|   0.000) D: [pulseaudio] card.c: Setting card bluez_card.0C_E0_E4_FB_A4_94 profile a2dp_sink to availability status unknown
(  54.136|   0.000) D: [pulseaudio] device-port.c: Setting port headset-output to status unknown
(  54.136|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
(  54.136|   0.000) D: [pulseaudio] bluez5-util.c: Properties changed in transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/sep1/fd0


#3# switching to hsp


(  94.802|  40.666) I: [pulseaudio] protocol-native.c: Application "pulsemixer" requests card profile change. card = bluez_card.0C_E0_E4_FB_A4_94, profile = headset_head_unit
(  94.802|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device removed from object /org/pulseaudio/core1/sink1
(  94.802|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Sink removed from object /org/pulseaudio/core1/sink1
(  94.802|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.DevicePort removed from object /org/pulseaudio/core1/sink1/port0
(  94.802|   0.000) D: [pulseaudio] module-rescue-streams.c: No sink inputs to move away.
(  94.802|   0.000) I: [pulseaudio] core.c: default_sink: bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink -> alsa_output.pci-0000_0d_00.4.analog-stereo
(  94.802|   0.000) D: [pulseaudio] sink.c: bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink: state: SUSPENDED -> UNLINKED
(  94.802|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device removed from object /org/pulseaudio/core1/source2
(  94.802|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Source removed from object /org/pulseaudio/core1/source2
(  94.802|   0.000) D: [pulseaudio] module-rescue-streams.c: No source outputs to move away.
(  94.802|   0.000) D: [pulseaudio] source.c: bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink.monitor: state: SUSPENDED -> UNLINKED
(  94.802|   0.000) D: [bluetooth] module-bluez5-device.c: IO thread shutdown requested, stopping cleanly
(  94.803|   0.000) D: [bluetooth] module-bluez5-device.c: IO thread shutting down
(  94.803|   0.000) I: [pulseaudio] sink.c: Freeing sink 1 "bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink"
(  94.803|   0.000) I: [pulseaudio] source.c: Freeing source 2 "bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink.monitor"
(  94.803|   0.000) D: [pulseaudio] module-bluez5-device.c: Acquiring transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/fd27
(  94.803|   0.000) I: [pulseaudio] backend-native.c: doing connect
(  95.267|   0.464) I: [pulseaudio] module-bluez5-device.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/fd27 acquired: fd 21
(  95.267|   0.000) D: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/fd27 state: idle -> playing
(  95.267|   0.000) D: [pulseaudio] card.c: Setting card bluez_card.0C_E0_E4_FB_A4_94 profile headset_head_unit to availability status yes
(  95.267|   0.000) D: [pulseaudio] device-port.c: Setting port headset-output to status yes
(  95.267|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
(  95.267|   0.000) D: [pulseaudio] device-port.c: Setting port headset-input to status yes
(  95.267|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
(  95.268|   0.000) I: [pulseaudio] module-device-restore.c: Restoring volume for sink bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit: mono: 56798 /  87%
(  95.268|   0.000) I: [pulseaudio] module-device-restore.c: Restoring mute state for sink bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit: unmuted
(  95.268|   0.000) I: [pulseaudio] sink.c: Created sink 2 "bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit" with sample spec s16le 1ch 8000Hz and channel map mono
(  95.268|   0.000) I: [pulseaudio] sink.c:     bluetooth.protocol = "headset_head_unit"
(  95.268|   0.000) I: [pulseaudio] sink.c:     device.intended_roles = "phone"
(  95.268|   0.000) I: [pulseaudio] sink.c:     device.description = "PLT_BBTPRO"
(  95.268|   0.000) I: [pulseaudio] sink.c:     device.string = "0C:E0:E4:FB:A4:94"
(  95.268|   0.000) I: [pulseaudio] sink.c:     device.api = "bluez"
(  95.268|   0.000) I: [pulseaudio] sink.c:     device.class = "sound"
(  95.268|   0.000) I: [pulseaudio] sink.c:     device.bus = "bluetooth"
(  95.268|   0.000) I: [pulseaudio] sink.c:     device.form_factor = "headset"
(  95.268|   0.000) I: [pulseaudio] sink.c:     bluez.path = "/org/bluez/hci0/dev_0C_E0_E4_FB_A4_94"
(  95.268|   0.000) I: [pulseaudio] sink.c:     bluez.class = "0x240404"
(  95.268|   0.000) I: [pulseaudio] sink.c:     bluez.alias = "PLT_BBTPRO"
(  95.268|   0.000) I: [pulseaudio] sink.c:     device.icon_name = "audio-headset-bluetooth"
(  95.268|   0.000) I: [pulseaudio] module-device-restore.c: Restoring mute state for source bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit.monitor: unmuted
(  95.268|   0.000) I: [pulseaudio] source.c: Created source 3 "bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit.monitor" with sample spec s16le 1ch 8000Hz and channel map mono
(  95.268|   0.000) I: [pulseaudio] source.c:     device.description = "Monitor of PLT_BBTPRO"
(  95.268|   0.000) I: [pulseaudio] source.c:     device.class = "monitor"
(  95.268|   0.000) I: [pulseaudio] source.c:     device.string = "0C:E0:E4:FB:A4:94"
(  95.268|   0.000) I: [pulseaudio] source.c:     device.api = "bluez"
(  95.268|   0.000) I: [pulseaudio] source.c:     device.bus = "bluetooth"
(  95.268|   0.000) I: [pulseaudio] source.c:     device.form_factor = "headset"
(  95.268|   0.000) I: [pulseaudio] source.c:     bluez.path = "/org/bluez/hci0/dev_0C_E0_E4_FB_A4_94"
(  95.268|   0.000) I: [pulseaudio] source.c:     bluez.class = "0x240404"
(  95.268|   0.000) I: [pulseaudio] source.c:     bluez.alias = "PLT_BBTPRO"
(  95.268|   0.000) I: [pulseaudio] source.c:     device.icon_name = "audio-headset-bluetooth"
(  95.268|   0.000) I: [pulseaudio] source.c:     device.intended_roles = "phone"
(  95.268|   0.000) I: [pulseaudio] module-device-restore.c: Restoring volume for source bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit: mono: 43691 /  67%
(  95.268|   0.000) I: [pulseaudio] module-device-restore.c: Restoring mute state for source bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit: unmuted
(  95.268|   0.000) I: [pulseaudio] source.c: Created source 4 "bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit" with sample spec s16le 1ch 8000Hz and channel map mono
(  95.268|   0.000) I: [pulseaudio] source.c:     bluetooth.protocol = "headset_head_unit"
(  95.268|   0.000) I: [pulseaudio] source.c:     device.intended_roles = "phone"
(  95.268|   0.000) I: [pulseaudio] source.c:     device.description = "PLT_BBTPRO"
(  95.268|   0.000) I: [pulseaudio] source.c:     device.string = "0C:E0:E4:FB:A4:94"
(  95.268|   0.000) I: [pulseaudio] source.c:     device.api = "bluez"
(  95.268|   0.000) I: [pulseaudio] source.c:     device.class = "sound"
(  95.268|   0.000) I: [pulseaudio] source.c:     device.bus = "bluetooth"
(  95.268|   0.000) I: [pulseaudio] source.c:     device.form_factor = "headset"
(  95.268|   0.000) I: [pulseaudio] source.c:     bluez.path = "/org/bluez/hci0/dev_0C_E0_E4_FB_A4_94"
(  95.268|   0.000) I: [pulseaudio] source.c:     bluez.class = "0x240404"
(  95.268|   0.000) I: [pulseaudio] source.c:     bluez.alias = "PLT_BBTPRO"
(  95.268|   0.000) I: [pulseaudio] source.c:     device.icon_name = "audio-headset-bluetooth"
(  95.268|   0.000) D: [bluetooth] module-bluez5-device.c: IO Thread starting up
(  95.272|   0.003) D: [bluetooth] util.c: RealtimeKit worked.
(  95.272|   0.000) I: [bluetooth] util.c: Successfully enabled SCHED_RR scheduling for thread, with priority 5.
(  95.272|   0.000) I: [bluetooth] module-bluez5-device.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/fd27 resuming
(  95.272|   0.000) I: [bluetooth] module-bluez5-device.c: Changing bluetooth buffer size: Changed from 106496 to 1056
(  95.272|   0.000) D: [bluetooth] module-bluez5-device.c: Stream properly set up, we're ready to roll!
(  95.272|   0.000) D: [pulseaudio] sink.c: bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit: state: INIT -> IDLE
(  95.272|   0.000) D: [pulseaudio] source.c: bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit.monitor: state: INIT -> IDLE
(  95.272|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device added for object /org/pulseaudio/core1/source3
(  95.272|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Source added for object /org/pulseaudio/core1/source3
(  95.272|   0.000) D: [pulseaudio] module-device-restore.c: Could not set format on sink bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit
(  95.272|   0.000) D: [pulseaudio] module-bluetooth-policy.c: Profile headset_head_unit cannot be selected for loopback
(  95.272|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.DevicePort added for object /org/pulseaudio/core1/sink2/port0
(  95.272|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device added for object /org/pulseaudio/core1/sink2
(  95.272|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Sink added for object /org/pulseaudio/core1/sink2
(  95.272|   0.000) D: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit becomes idle, timeout in 5 seconds.
(  95.272|   0.000) I: [pulseaudio] core.c: default_sink: alsa_output.pci-0000_0d_00.4.analog-stereo -> bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit
(  95.272|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
(  95.272|   0.000) D: [pulseaudio] source.c: bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit: state: INIT -> IDLE
(  95.272|   0.000) D: [pulseaudio] module-bluetooth-policy.c: Profile headset_head_unit cannot be selected for loopback
(  95.272|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.DevicePort added for object /org/pulseaudio/core1/source4/port0
(  95.272|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device added for object /org/pulseaudio/core1/source4
(  95.272|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Source added for object /org/pulseaudio/core1/source4
(  95.272|   0.000) D: [pulseaudio] module-suspend-on-idle.c: Source bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit becomes idle, timeout in 5 seconds.
(  95.272|   0.000) I: [pulseaudio] core.c: default_source: alsa_input.usb-046d_0825_30BE8B60-02.multichannel-input -> bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit
(  95.272|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
(  95.272|   0.000) D: [pulseaudio] backend-native.c: RFCOMM >> +VGM=10
(  95.272|   0.000) D: [pulseaudio] card.c: bluez_card.0C_E0_E4_FB_A4_94: active_profile: a2dp_sink -> headset_head_unit
(  95.272|   0.000) I: [pulseaudio] module-card-restore.c: Storing card profile for card bluez_card.0C_E0_E4_FB_A4_94.
(  95.272|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
(  95.276|   0.003) D: [pulseaudio] backend-native.c: RFCOMM << 
(  95.276|   0.003) D: [pulseaudio] backend-native.c: AT+VGM=10
( 100.277|   5.001) I: [pulseaudio] module-suspend-on-idle.c: Source bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit idle for too long, suspending ...
( 100.277|   0.000) D: [pulseaudio] source.c: bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit: suspend_cause: (none) -> IDLE
( 100.277|   0.000) D: [pulseaudio] source.c: bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit: state: IDLE -> SUSPENDED
( 100.277|   0.000) D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
( 100.277|   0.000) I: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit idle for too long, suspending ...
( 100.277|   0.000) D: [bluetooth] module-bluez5-device.c: Releasing transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/fd27
( 100.278|   0.000) I: [bluetooth] backend-native.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/fd27 released
( 100.278|   0.000) D: [bluetooth] module-bluez5-device.c: Audio stream torn down
( 100.278|   0.000) D: [pulseaudio] sink.c: bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit: suspend_cause: (none) -> IDLE
( 100.278|   0.000) D: [pulseaudio] sink.c: bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit: state: IDLE -> SUSPENDED
( 100.278|   0.000) D: [pulseaudio] source.c: bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit.monitor: suspend_cause: (none) -> IDLE
( 100.278|   0.000) D: [pulseaudio] source.c: bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit.monitor: state: IDLE -> SUSPENDED
( 100.278|   0.000) D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
( 100.278|   0.000) D: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/fd27 state: playing -> idle
( 100.278|   0.000) D: [pulseaudio] card.c: Setting card bluez_card.0C_E0_E4_FB_A4_94 profile headset_head_unit to availability status unknown
( 100.278|   0.000) D: [pulseaudio] device-port.c: Setting port headset-output to status unknown
( 100.278|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
( 100.278|   0.000) D: [pulseaudio] device-port.c: Setting port headset-input to status unknown
( 100.278|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
( 105.273|   4.995) I: [pulseaudio] module-card-restore.c: Synced.


#4# trying to play something


( 133.422|  28.148) I: [pulseaudio] client.c: Created 2 "Native client (UNIX socket client)"
( 133.422|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Client added for object /org/pulseaudio/core1/client2
( 133.422|   0.000) D: [pulseaudio] protocol-native.c: Protocol version: remote 33, local 33
( 133.422|   0.000) I: [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
( 133.422|   0.000) D: [pulseaudio] protocol-native.c: SHM possible: yes
( 133.422|   0.000) D: [pulseaudio] protocol-native.c: Negotiated SHM: yes
( 133.422|   0.000) D: [pulseaudio] protocol-native.c: Memfd possible: yes
( 133.422|   0.000) D: [pulseaudio] protocol-native.c: Negotiated SHM type: shared memfd
( 133.422|   0.000) D: [pulseaudio] memblock.c: Using shared memfd memory pool with 1024 slots of size 64.0 KiB each, total size is 64.0 MiB, maximum usable slot size is 65472
( 133.422|   0.000) D: [pulseaudio] srbchannel.c: SHM block is 65472 bytes, ringbuffer capacity is 2 * 32712 bytes
( 133.422|   0.000) D: [pulseaudio] protocol-native.c: Enabling srbchannel...
( 133.422|   0.000) D: [pulseaudio] module-augment-properties.c: Looking for .desktop file for mpv
( 133.422|   0.000) D: [pulseaudio] module-augment-properties.c: Found /usr/share/applications/mpv.desktop.
( 133.422|   0.000) D: [pulseaudio] conf-parser.c: Parsing configuration file '/usr/share/applications/mpv.desktop'
( 133.422|   0.000) D: [pulseaudio] protocol-native.c: Client enabled srbchannel.
( 133.422|   0.000) I: [pulseaudio] module-stream-restore.c: Restoring device for stream sink-input-by-application-name:mpv Media Player.
( 133.422|   0.000) D: [pulseaudio] module-intended-roles.c: Not setting device for stream audio stream, because already set.
( 133.422|   0.000) D: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"float32le\""  format.rate = "48000"  format.channels = "6"  format.channel_map = "\"front-left,front-right,front-center,lfe,side-left,side-right\""
( 133.422|   0.000) I: [pulseaudio] sink-input.c: Trying to change sample spec
( 133.422|   0.000) I: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-application-name:mpv Media Player.
( 133.422|   0.000) I: [pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-application-name:mpv Media Player.
( 133.422|   0.000) D: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit becomes busy, resuming.
( 133.422|   0.000) D: [bluetooth] module-bluez5-device.c: Acquiring transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/fd27
( 133.422|   0.000) I: [bluetooth] backend-native.c: doing connect
( 133.884|   0.461) I: [bluetooth] module-bluez5-device.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/fd27 acquired: fd 41
( 133.884|   0.000) I: [bluetooth] module-bluez5-device.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/fd27 resuming
( 133.884|   0.000) I: [bluetooth] module-bluez5-device.c: Changing bluetooth buffer size: Changed from 106496 to 1056
( 133.884|   0.000) D: [bluetooth] module-bluez5-device.c: Stream properly set up, we're ready to roll!
( 133.884|   0.000) D: [pulseaudio] sink.c: bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit: suspend_cause: IDLE -> (none)
( 133.884|   0.000) D: [pulseaudio] sink.c: bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit: state: SUSPENDED -> IDLE
( 133.884|   0.000) D: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit becomes idle, timeout in 5 seconds.
( 133.884|   0.000) D: [pulseaudio] source.c: bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit.monitor: suspend_cause: IDLE -> (none)
( 133.884|   0.000) D: [pulseaudio] source.c: bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit.monitor: state: SUSPENDED -> IDLE
( 133.884|   0.000) D: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit becomes idle, timeout in 5 seconds.
( 133.884|   0.000) D: [pulseaudio] resampler.c: Resampler:
( 133.884|   0.000) D: [pulseaudio] resampler.c:   rate 48000 -> 8000 (method speex-float-1)
( 133.884|   0.000) D: [pulseaudio] resampler.c:   format float32le -> s16le (intermediate float32le)
( 133.884|   0.000) D: [pulseaudio] resampler.c:   channels 6 -> 1 (resampling 1)
( 133.884|   0.000) D: [pulseaudio] resampler.c: Channel matrix:
( 133.884|   0.000) D: [pulseaudio] resampler.c:        I00   I01   I02   I03   I04   I05 
( 133.884|   0.000) D: [pulseaudio] resampler.c:     +------------------------------------
( 133.884|   0.000) D: [pulseaudio] resampler.c: O00 | 0.167 0.167 0.167 0.167 0.167 0.167
( 133.884|   0.000) I: [pulseaudio] remap.c: Using generic matrix remapping
( 133.884|   0.000) I: [pulseaudio] speex.c: Choosing speex quality setting 1.
( 133.884|   0.000) D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=2, prebuf=0, minreq=1 maxrewind=0
( 133.884|   0.000) D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=2, prebuf=0, minreq=2 maxrewind=0
( 133.884|   0.000) I: [pulseaudio] sink-input.c: Created input 0 "audio stream" on bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit with sample spec float32le 6ch 48000Hz and channel map front-left,front-right,front-center,lfe,side-left,side-right
( 133.884|   0.000) I: [pulseaudio] sink-input.c:     media.icon_name = "mpv"
( 133.884|   0.000) I: [pulseaudio] sink-input.c:     media.name = "audio stream"
( 133.884|   0.000) I: [pulseaudio] sink-input.c:     application.name = "mpv Media Player"
( 133.884|   0.000) I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
( 133.884|   0.000) I: [pulseaudio] sink-input.c:     native-protocol.version = "33"
( 133.884|   0.000) I: [pulseaudio] sink-input.c:     application.process.id = "233360"
( 133.884|   0.000) I: [pulseaudio] sink-input.c:     application.process.user = "ramsi"
( 133.884|   0.000) I: [pulseaudio] sink-input.c:     application.process.host = "hactar"
( 133.884|   0.000) I: [pulseaudio] sink-input.c:     application.process.binary = "mpv"
( 133.884|   0.000) I: [pulseaudio] sink-input.c:     application.language = "C"
( 133.884|   0.000) I: [pulseaudio] sink-input.c:     window.x11.display = ":0"
( 133.884|   0.000) I: [pulseaudio] sink-input.c:     application.process.machine_id = "3f1aaafe2f4948d880b0198a0b7fd105"
( 133.884|   0.000) I: [pulseaudio] sink-input.c:     application.process.session_id = "1"
( 133.884|   0.000) I: [pulseaudio] sink-input.c:     application.icon_name = "mpv"
( 133.884|   0.000) I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-name:mpv Media Player"
( 133.884|   0.000) I: [pulseaudio] protocol-native.c: Requested tlength=100.00 ms, minreq=20.00 ms
( 133.884|   0.000) D: [pulseaudio] protocol-native.c: Traditional mode enabled, modifying sink usec only for compat with minreq.
( 133.884|   0.000) D: [pulseaudio] protocol-native.c: Requested latency=60.00 ms, Received latency=28.00 ms
( 133.884|   0.000) D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=115200, base=24, prebuf=92184, minreq=23040 maxrewind=0
( 133.884|   0.000) D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194312, tlength=115200, base=24, prebuf=92184, minreq=23040 maxrewind=0
( 133.884|   0.000) I: [pulseaudio] protocol-native.c: Final latency 128.00 ms = 60.00 ms + 2*20.00 ms + 28.00 ms
( 133.885|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream added for object /org/pulseaudio/core1/playback_stream0
( 133.885|   0.000) D: [pulseaudio] sink.c: bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit: state: IDLE -> RUNNING
( 133.885|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
( 133.885|   0.000) D: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/fd27 state: idle -> playing
( 133.885|   0.000) D: [pulseaudio] card.c: Setting card bluez_card.0C_E0_E4_FB_A4_94 profile headset_head_unit to availability status yes
( 133.885|   0.000) D: [pulseaudio] device-port.c: Setting port headset-output to status yes
( 133.885|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
( 133.885|   0.000) D: [pulseaudio] device-port.c: Setting port headset-input to status yes
( 133.885|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
( 133.885|   0.000) D: [pulseaudio] module-bluez5-device.c: Resuming source bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit because its transport state changed to playing
( 133.885|   0.000) D: [pulseaudio] source.c: bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit: suspend_cause: IDLE -> (none)
( 133.885|   0.000) D: [pulseaudio] source.c: bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit: state: SUSPENDED -> IDLE
( 133.885|   0.000) D: [pulseaudio] module-suspend-on-idle.c: Source bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit becomes idle, timeout in 5 seconds.
( 133.885|   0.000) D: [pulseaudio] module-bluez5-device.c: Resuming sink bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit because its transport state changed to playing
( 133.885|   0.000) D: [pulseaudio] sink-input.c: Sink input 0: proplist[media.name]: (data) -> (data)
( 133.893|   0.007) D: [pulseaudio] backend-native.c: RFCOMM << AT+VGM=10
( 133.893|   0.000) D: [pulseaudio] backend-native.c: RFCOMM >> OK
( 133.920|   0.027) D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
( 138.885|   4.964) I: [pulseaudio] module-suspend-on-idle.c: Source bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit idle for too long, suspending ...
( 138.885|   0.000) D: [pulseaudio] source.c: bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit: suspend_cause: (none) -> IDLE
( 138.885|   0.000) D: [pulseaudio] source.c: bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit: state: IDLE -> SUSPENDED


#5# video is stuck, I played something using mpv in interaction #4#

#6# switching to a2db

( 589.913| 451.027) I: [pulseaudio] protocol-native.c: Application "pulsemixer" requests card profile change. card = bluez_card.0C_E0_E4_FB_A4_94, profile = a2dp_sink
( 589.913|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device removed from object /org/pulseaudio/core1/sink2
( 589.913|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Sink removed from object /org/pulseaudio/core1/sink2
( 589.913|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.DevicePort removed from object /org/pulseaudio/core1/sink2/port0
( 589.913|   0.000) D: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit becomes idle, timeout in 5 seconds.
( 589.913|   0.000) D: [pulseaudio] sink-input.c: Starting to move sink input 0 from 'bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit'
( 589.913|   0.000) D: [bluetooth] sink-input.c: Have to rewind 592 bytes on render memblockq.
( 589.913|   0.000) D: [bluetooth] sink-input.c: Have to rewind 42624 bytes on implementor.
( 589.913|   0.000) D: [bluetooth] sink.c: Requesting rewind due to started move
( 589.913|   0.000) D: [pulseaudio] sink.c: bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit: state: RUNNING -> IDLE
( 589.913|   0.000) D: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit becomes idle, timeout in 5 seconds.
( 589.913|   0.000) I: [pulseaudio] sink-input.c: Trying to change sample spec
( 589.913|   0.000) D: [pulseaudio] sink.c: Suspending sink alsa_output.pci-0000_0d_00.4.analog-stereo due to changing format, desired format = s16le rate = 48000
( 589.913|   0.000) D: [pulseaudio] sink.c: alsa_output.pci-0000_0d_00.4.analog-stereo: suspend_cause: IDLE -> IDLE|INTERNAL
( 589.913|   0.000) D: [pulseaudio] source.c: alsa_output.pci-0000_0d_00.4.analog-stereo.monitor: suspend_cause: IDLE -> IDLE|INTERNAL
( 589.913|   0.000) I: [pulseaudio] sink.c: alsa_output.pci-0000_0d_00.4.analog-stereo: rate: 44100 -> 48000
( 589.913|   0.000) D: [pulseaudio] source.c: Suspending source alsa_output.pci-0000_0d_00.4.analog-stereo.monitor due to changing format, desired format = s16le rate = 48000
( 589.913|   0.000) I: [pulseaudio] source.c: Reconfigured successfully
( 589.913|   0.000) I: [pulseaudio] sink.c: Reconfigured successfully
( 589.913|   0.000) D: [pulseaudio] sink.c: alsa_output.pci-0000_0d_00.4.analog-stereo: suspend_cause: IDLE|INTERNAL -> IDLE
( 589.913|   0.000) D: [pulseaudio] source.c: alsa_output.pci-0000_0d_00.4.analog-stereo.monitor: suspend_cause: IDLE|INTERNAL -> IDLE
( 589.913|   0.000) I: [pulseaudio] protocol-native.c: Requested tlength=100.00 ms, minreq=20.00 ms
( 589.913|   0.000) D: [pulseaudio] protocol-native.c: Traditional mode enabled, modifying sink usec only for compat with minreq.
( 589.913|   0.000) D: [pulseaudio] protocol-native.c: Requested latency=60.00 ms, Received latency=60.00 ms
( 589.913|   0.000) I: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
( 589.913|   0.000) D: [pulseaudio] resampler.c: Resampler:
( 589.913|   0.000) D: [pulseaudio] resampler.c:   rate 48000 -> 48000 (method copy)
( 589.913|   0.000) D: [pulseaudio] resampler.c:   format float32le -> s16le (intermediate s16le)
( 589.913|   0.000) D: [pulseaudio] resampler.c:   channels 6 -> 2 (resampling 2)
( 589.913|   0.000) D: [pulseaudio] resampler.c: Channel matrix:
( 589.913|   0.000) D: [pulseaudio] resampler.c:        I00   I01   I02   I03   I04   I05 
( 589.913|   0.000) D: [pulseaudio] resampler.c:     +------------------------------------
( 589.913|   0.000) D: [pulseaudio] resampler.c: O00 | 0.621 0.000 0.310 0.000 0.069 0.000
( 589.913|   0.000) D: [pulseaudio] resampler.c: O01 | 0.000 0.621 0.310 0.000 0.000 0.069
( 589.913|   0.000) I: [pulseaudio] remap.c: Using generic matrix remapping
( 589.913|   0.000) D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
( 589.913|   0.000) D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
( 589.913|   0.000) D: [pulseaudio] sink-input.c: Updated resampler for sink input 0
( 589.913|   0.000) D: [alsa-sink-ALC1220 Analog] sink.c: Requesting rewind due to finished move
( 589.913|   0.000) D: [alsa-sink-ALC1220 Analog] alsa-sink.c: Requested volume: front-left: 49152 /  75% / -7.50 dB,   front-right: 49152 /  75% / -7.50 dB
( 589.913|   0.000) D: [alsa-sink-ALC1220 Analog] alsa-sink.c: Got hardware volume: front-left: 49145 /  75% / -7.50 dB,   front-right: 49145 /  75% / -7.50 dB
( 589.913|   0.000) D: [alsa-sink-ALC1220 Analog] alsa-sink.c: Calculated software volume: front-left: 65545 / 100% / 0.00 dB,   front-right: 65545 / 100% / 0.00 dB (accurate-enough=yes)
( 589.913|   0.000) D: [alsa-sink-ALC1220 Analog] sink.c: Volume not changing
( 589.913|   0.000) D: [pulseaudio] sink-input.c: Successfully moved sink input 0 to alsa_output.pci-0000_0d_00.4.analog-stereo.
( 589.913|   0.000) D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_0d_00.4.analog-stereo becomes busy, resuming.
( 589.914|   0.000) D: [pulseaudio] reserve-wrap.c: Successfully acquired reservation lock on device 'Audio1'
( 589.914|   0.000) I: [alsa-sink-ALC1220 Analog] alsa-sink.c: Trying resume...
( 589.914|   0.000) I: [alsa-sink-ALC1220 Analog] alsa-util.c: Disabling tsched mode since BATCH flag is set
( 589.914|   0.000) D: [alsa-sink-ALC1220 Analog] alsa-util.c: Maximum hw buffer size is 21845 ms
( 590.034|   0.119) D: [alsa-sink-ALC1220 Analog] alsa-util.c: Set buffer size first (to 4416 samples), period size second (to 736 samples).
( 590.034|   0.000) D: [alsa-sink-ALC1220 Analog] alsa-sink.c: hwbuf_unused=0
( 590.034|   0.000) D: [alsa-sink-ALC1220 Analog] alsa-sink.c: setting avail_min=1
( 590.034|   0.000) D: [alsa-sink-ALC1220 Analog] protocol-native.c: max_request changed, trying to update from 115200 to 152064.
( 590.034|   0.000) D: [alsa-sink-ALC1220 Analog] protocol-native.c: Notifying client about increased tlength
( 590.034|   0.000) I: [alsa-sink-ALC1220 Analog] alsa-sink.c: Resumed successfully...
( 590.034|   0.000) D: [pulseaudio] sink.c: alsa_output.pci-0000_0d_00.4.analog-stereo: suspend_cause: IDLE -> (none)
( 590.034|   0.000) D: [pulseaudio] sink.c: alsa_output.pci-0000_0d_00.4.analog-stereo: state: SUSPENDED -> RUNNING
( 590.034|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
( 590.034|   0.000) I: [alsa-sink-ALC1220 Analog] alsa-sink.c: Starting playback.
( 590.034|   0.000) D: [pulseaudio] source.c: alsa_output.pci-0000_0d_00.4.analog-stereo.monitor: suspend_cause: IDLE -> (none)
( 590.034|   0.000) D: [pulseaudio] source.c: alsa_output.pci-0000_0d_00.4.analog-stereo.monitor: state: SUSPENDED -> IDLE
( 590.034|   0.000) I: [pulseaudio] module-rescue-streams.c: Successfully moved sink input 0 "mpv Media Player" to alsa_output.pci-0000_0d_00.4.analog-stereo.
( 590.034|   0.000) I: [pulseaudio] core.c: default_sink: bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit -> alsa_output.pci-0000_0d_00.4.analog-stereo
( 590.034|   0.000) D: [pulseaudio] sink.c: bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit: state: IDLE -> UNLINKED
( 590.034|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device removed from object /org/pulseaudio/core1/source3
( 590.034|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Source removed from object /org/pulseaudio/core1/source3
( 590.034|   0.000) D: [pulseaudio] module-rescue-streams.c: No source outputs to move away.
( 590.034|   0.000) D: [pulseaudio] source.c: bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit.monitor: state: IDLE -> UNLINKED
( 590.034|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to remove event.
( 590.034|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device removed from object /org/pulseaudio/core1/source4
( 590.034|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Source removed from object /org/pulseaudio/core1/source4
( 590.034|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.DevicePort removed from object /org/pulseaudio/core1/source4/port0
( 590.034|   0.000) D: [pulseaudio] module-rescue-streams.c: No source outputs to move away.
( 590.034|   0.000) I: [pulseaudio] core.c: default_source: bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit -> alsa_input.usb-046d_0825_30BE8B60-02.multichannel-input
( 590.034|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
( 590.034|   0.000) D: [pulseaudio] source.c: bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit: state: SUSPENDED -> UNLINKED
( 590.034|   0.000) D: [bluetooth] module-bluez5-device.c: IO thread shutdown requested, stopping cleanly
( 590.034|   0.000) D: [bluetooth] module-bluez5-device.c: Releasing transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/fd27
( 590.034|   0.000) I: [bluetooth] backend-native.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/fd27 released
( 590.034|   0.000) D: [bluetooth] module-bluez5-device.c: Audio stream torn down
( 590.034|   0.000) D: [bluetooth] module-bluez5-device.c: IO thread shutting down
( 590.035|   0.000) D: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/fd27 state: playing -> idle
( 590.035|   0.000) D: [pulseaudio] card.c: Setting card bluez_card.0C_E0_E4_FB_A4_94 profile headset_head_unit to availability status unknown
( 590.035|   0.000) D: [pulseaudio] device-port.c: Setting port headset-output to status unknown
( 590.035|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
( 590.035|   0.000) D: [pulseaudio] device-port.c: Setting port headset-input to status unknown
( 590.035|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
( 590.035|   0.000) I: [pulseaudio] sink.c: Freeing sink 2 "bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit"
( 590.035|   0.000) I: [pulseaudio] source.c: Freeing source 3 "bluez_sink.0C_E0_E4_FB_A4_94.headset_head_unit.monitor"
( 590.035|   0.000) I: [pulseaudio] source.c: Freeing source 4 "bluez_source.0C_E0_E4_FB_A4_94.headset_head_unit"
( 590.035|   0.000) D: [pulseaudio] module-bluez5-device.c: Acquiring transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/sep1/fd0
( 590.050|   0.014) D: [alsa-sink-ALC1220 Analog] protocol-native.c: Implicit underrun of '... - mpv'
( 590.050|   0.000) D: [alsa-sink-ALC1220 Analog] sink.c: alsa_output.pci-0000_0d_00.4.analog-stereo: Found underrun 1448 bytes ago (16208 bytes ahead in playback buffer)
( 590.065|   0.015) D: [alsa-sink-ALC1220 Analog] sink.c: alsa_output.pci-0000_0d_00.4.analog-stereo: Found underrun 4368 bytes ago (13292 bytes ahead in playback buffer)
( 590.080|   0.015) D: [alsa-sink-ALC1220 Analog] sink.c: alsa_output.pci-0000_0d_00.4.analog-stereo: Found underrun 7312 bytes ago (10348 bytes ahead in playback buffer)
( 590.096|   0.015) D: [alsa-sink-ALC1220 Analog] sink.c: alsa_output.pci-0000_0d_00.4.analog-stereo: Found underrun 10256 bytes ago (7404 bytes ahead in playback buffer)
( 590.111|   0.015) D: [alsa-sink-ALC1220 Analog] sink.c: alsa_output.pci-0000_0d_00.4.analog-stereo: Found underrun 13200 bytes ago (4460 bytes ahead in playback buffer)
( 590.126|   0.015) D: [alsa-sink-ALC1220 Analog] sink.c: alsa_output.pci-0000_0d_00.4.analog-stereo: Found underrun 16144 bytes ago (1516 bytes ahead in playback buffer)
( 590.344|   0.217) I: [pulseaudio] module-bluez5-device.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/sep1/fd0 acquired: fd 36
( 590.344|   0.000) D: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/sep1/fd0 state: idle -> playing
( 590.344|   0.000) D: [pulseaudio] card.c: Setting card bluez_card.0C_E0_E4_FB_A4_94 profile a2dp_sink to availability status yes
( 590.344|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
( 590.344|   0.000) D: [pulseaudio] device-port.c: Setting port headset-output to status yes
( 590.344|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
( 590.344|   0.000) I: [pulseaudio] a2dp-codec-sbc.c: SBC parameters: allocation=Loudness, subbands=8, blocks=16, mode=JointStereo bitpool=53 codesize=512 frame_length=119
( 590.344|   0.000) I: [pulseaudio] module-device-restore.c: Restoring volume for sink bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink: front-left: 30801 /  47%,   front-right: 30801 /  47%
( 590.344|   0.000) I: [pulseaudio] module-device-restore.c: Restoring mute state for sink bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink: unmuted
( 590.344|   0.000) I: [pulseaudio] sink.c: Created sink 3 "bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
( 590.344|   0.000) I: [pulseaudio] sink.c:     bluetooth.protocol = "a2dp_sink"
( 590.344|   0.000) I: [pulseaudio] sink.c:     device.description = "PLT_BBTPRO"
( 590.344|   0.000) I: [pulseaudio] sink.c:     device.string = "0C:E0:E4:FB:A4:94"
( 590.344|   0.000) I: [pulseaudio] sink.c:     device.api = "bluez"
( 590.344|   0.000) I: [pulseaudio] sink.c:     device.class = "sound"
( 590.344|   0.000) I: [pulseaudio] sink.c:     device.bus = "bluetooth"
( 590.344|   0.000) I: [pulseaudio] sink.c:     device.form_factor = "headset"
( 590.344|   0.000) I: [pulseaudio] sink.c:     bluez.path = "/org/bluez/hci0/dev_0C_E0_E4_FB_A4_94"
( 590.344|   0.000) I: [pulseaudio] sink.c:     bluez.class = "0x240404"
( 590.344|   0.000) I: [pulseaudio] sink.c:     bluez.alias = "PLT_BBTPRO"
( 590.344|   0.000) I: [pulseaudio] sink.c:     device.icon_name = "audio-headset-bluetooth"
( 590.344|   0.000) I: [pulseaudio] sink.c:     device.intended_roles = "phone"
( 590.344|   0.000) I: [pulseaudio] module-device-restore.c: Restoring mute state for source bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink.monitor: unmuted
( 590.344|   0.000) I: [pulseaudio] source.c: Created source 5 "bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink.monitor" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
( 590.344|   0.000) I: [pulseaudio] source.c:     device.description = "Monitor of PLT_BBTPRO"
( 590.344|   0.000) I: [pulseaudio] source.c:     device.class = "monitor"
( 590.344|   0.000) I: [pulseaudio] source.c:     device.string = "0C:E0:E4:FB:A4:94"
( 590.344|   0.000) I: [pulseaudio] source.c:     device.api = "bluez"
( 590.344|   0.000) I: [pulseaudio] source.c:     device.bus = "bluetooth"
( 590.344|   0.000) I: [pulseaudio] source.c:     device.form_factor = "headset"
( 590.344|   0.000) I: [pulseaudio] source.c:     bluez.path = "/org/bluez/hci0/dev_0C_E0_E4_FB_A4_94"
( 590.344|   0.000) I: [pulseaudio] source.c:     bluez.class = "0x240404"
( 590.344|   0.000) I: [pulseaudio] source.c:     bluez.alias = "PLT_BBTPRO"
( 590.344|   0.000) I: [pulseaudio] source.c:     device.icon_name = "audio-headset-bluetooth"
( 590.344|   0.000) I: [pulseaudio] source.c:     device.intended_roles = "phone"
( 590.344|   0.000) D: [bluetooth] module-bluez5-device.c: IO Thread starting up
( 590.348|   0.003) D: [bluetooth] util.c: RealtimeKit worked.
( 590.348|   0.000) I: [bluetooth] util.c: Successfully enabled SCHED_RR scheduling for thread, with priority 5.
( 590.348|   0.000) I: [bluetooth] module-bluez5-device.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/sep1/fd0 resuming
( 590.348|   0.000) I: [bluetooth] module-bluez5-device.c: Changing bluetooth buffer size: Changed from 8192 to 7168
( 590.348|   0.000) D: [bluetooth] module-bluez5-device.c: Stream properly set up, we're ready to roll!
( 590.348|   0.000) D: [pulseaudio] sink.c: bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink: state: INIT -> IDLE
( 590.348|   0.000) D: [pulseaudio] source.c: bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink.monitor: state: INIT -> IDLE
( 590.348|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device added for object /org/pulseaudio/core1/source5
( 590.348|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Source added for object /org/pulseaudio/core1/source5
( 590.348|   0.000) D: [pulseaudio] module-device-restore.c: Could not set format on sink bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink
( 590.348|   0.000) D: [pulseaudio] module-bluetooth-policy.c: Profile a2dp_sink cannot be selected for loopback
( 590.348|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.DevicePort added for object /org/pulseaudio/core1/sink3/port0
( 590.348|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device added for object /org/pulseaudio/core1/sink3
( 590.348|   0.000) D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Sink added for object /org/pulseaudio/core1/sink3
( 590.348|   0.000) D: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink becomes idle, timeout in 5 seconds.
( 590.348|   0.000) I: [pulseaudio] core.c: default_sink: alsa_output.pci-0000_0d_00.4.analog-stereo -> bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink
( 590.348|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
( 590.348|   0.000) D: [pulseaudio] card.c: bluez_card.0C_E0_E4_FB_A4_94: active_profile: headset_head_unit -> a2dp_sink
( 590.348|   0.000) I: [pulseaudio] module-card-restore.c: Storing card profile for card bluez_card.0C_E0_E4_FB_A4_94.
( 590.348|   0.000) I: [pulseaudio] module-card-restore.c: Storing profile a2dp_sink for port headset-output on card bluez_card.0C_E0_E4_FB_A4_94.
( 590.348|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
( 590.348|   0.000) D: [pulseaudio] bluez5-util.c: Properties changed in transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/sep1/fd0
( 590.349|   0.000) D: [alsa-sink-ALC1220 Analog] protocol-native.c: Requesting rewind due to end of underrun.
( 590.349|   0.000) D: [alsa-sink-ALC1220 Analog] alsa-sink.c: Requested to rewind 17664 bytes.
( 590.349|   0.000) D: [alsa-sink-ALC1220 Analog] alsa-sink.c: Limited to 17404 bytes.
( 590.349|   0.000) D: [alsa-sink-ALC1220 Analog] alsa-sink.c: before: 4351
( 590.349|   0.000) D: [alsa-sink-ALC1220 Analog] alsa-sink.c: after: 4351
( 590.349|   0.000) D: [alsa-sink-ALC1220 Analog] alsa-sink.c: Rewound 17404 bytes.
( 590.349|   0.000) D: [alsa-sink-ALC1220 Analog] sink.c: Processing rewind...
( 590.349|   0.000) D: [alsa-sink-ALC1220 Analog] sink.c: latency = 1623
( 590.349|   0.000) D: [alsa-sink-ALC1220 Analog] sink-input.c: Have to rewind 17404 bytes on render memblockq.
( 590.349|   0.000) D: [alsa-sink-ALC1220 Analog] source.c: Processing rewind...
( 595.348|   4.999) I: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink idle for too long, suspending ...
( 595.348|   0.000) D: [bluetooth] module-bluez5-device.c: Releasing transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/sep1/fd0
( 595.378|   0.029) I: [bluetooth] bluez5-util.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/sep1/fd0 released
( 595.378|   0.000) D: [bluetooth] module-bluez5-device.c: Audio stream torn down
( 595.378|   0.000) D: [pulseaudio] sink.c: bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink: suspend_cause: (none) -> IDLE
( 595.378|   0.000) D: [pulseaudio] sink.c: bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink: state: IDLE -> SUSPENDED
( 595.378|   0.000) D: [pulseaudio] source.c: bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink.monitor: suspend_cause: (none) -> IDLE
( 595.378|   0.000) D: [pulseaudio] source.c: bluez_sink.0C_E0_E4_FB_A4_94.a2dp_sink.monitor: state: IDLE -> SUSPENDED
( 595.378|   0.000) D: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/sep1/fd0 state: playing -> idle
( 595.378|   0.000) D: [pulseaudio] card.c: Setting card bluez_card.0C_E0_E4_FB_A4_94 profile a2dp_sink to availability status unknown
( 595.378|   0.000) D: [pulseaudio] device-port.c: Setting port headset-output to status unknown
( 595.378|   0.000) D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
( 595.378|   0.000) D: [pulseaudio] bluez5-util.c: Properties changed in transport /org/bluez/hci0/dev_0C_E0_E4_FB_A4_94/sep1/fd0

vtrac · 2020-04-27 14:46:10

FWIW, I just upgraded to 5.6.7-arch1-1 and the issue has resolved itself. My bluetooth headphones can switch to the HSP profile and works just fine (like it used to).

eblau · 2020-04-27 15:27:12

Thanks for letting us know, vtrac. Likewise, after upgrading it works for me too with linux-5.6.7-arch1-1.

Ram-Z · 2020-04-28 00:17:43

No luck for me unfortunately.

I saw that there was a linux-firmware upgrade recently too which I thought might have brought in the fix for you rather than linux-5.6.7. So also tried downgrading that to some older version of that. But also no luck.

NoSync · 2020-04-28 10:05:19

After the latest kernel update, my headphones started having trouble with HSP/HFP again. This time I fixed it by setting

ControllerMode = bredr

in main.conf

Ram-Z · 2020-04-28 11:45:54

Thanks, no luck with that either though.

jdewinne · 2020-04-28 15:39:08

I got it working again, with kernel 5.6.7-arch1-1 and below /etc/bluetooth/main.conf. Only change being the AutoEnable I think. I tried other suggestions like ControllerMode and FastConnectable, but those did not work for me.

[General]

# Default adapter name
# Defaults to 'BlueZ X.YZ'
#Name = BlueZ

# Default device class. Only the major and minor device class bits are
# considered. Defaults to '0x000000'.
#Class = 0x000100

# How long to stay in discoverable mode before going back to non-discoverable
# The value is in seconds. Default is 180, i.e. 3 minutes.
# 0 = disable timer, i.e. stay discoverable forever
#DiscoverableTimeout = 0

# Always allow pairing even if there are no agent registered
# Possible values: true, false
# Default: false
#AlwaysPairable = false

# How long to stay in pairable mode before going back to non-discoverable
# The value is in seconds. Default is 0.
# 0 = disable timer, i.e. stay pairable forever
#PairableTimeout = 0

# Use vendor id source (assigner), vendor, product and version information for
# DID profile support. The values are separated by ":" and assigner, VID, PID
# and version.
# Possible vendor id source values: bluetooth, usb (defaults to usb)
#DeviceID = bluetooth:1234:5678:abcd

# Do reverse service discovery for previously unknown devices that connect to
# us. For BR/EDR this option is really only needed for qualification since the
# BITE tester doesn't like us doing reverse SDP for some test cases, for LE
# this disables the GATT client functionally so it can be used in system which
# can only operate as peripheral.
# Defaults to 'true'.
#ReverseServiceDiscovery = true

# Enable name resolving after inquiry. Set it to 'false' if you don't need
# remote devices name and want shorter discovery cycle. Defaults to 'true'.
#NameResolving = true

# Enable runtime persistency of debug link keys. Default is false which
# makes debug link keys valid only for the duration of the connection
# that they were created for.
#DebugKeys = false

# Restricts all controllers to the specified transport. Default value
# is "dual", i.e. both BR/EDR and LE enabled (when supported by the HW).
# Possible values: "dual", "bredr", "le"
# ControllerMode = dual

# Enables Multi Profile Specification support. This allows to specify if
# system supports only Multiple Profiles Single Device (MPSD) configuration
# or both Multiple Profiles Single Device (MPSD) and Multiple Profiles Multiple
# Devices (MPMD) configurations.
# Possible values: "off", "single", "multiple"
#MultiProfile = off

# Permanently enables the Fast Connectable setting for adapters that
# support it. When enabled other devices can connect faster to us,
# however the tradeoff is increased power consumptions. This feature
# will fully work only on kernel version 4.1 and newer. Defaults to
# 'false'.
# FastConnectable = false

# Default privacy setting.
# Enables use of private address.
# Possible values: "off", "device", "network"
# "network" option not supported currently
# Defaults to "off"
# Privacy = off

[GATT]
# GATT attribute cache.
# Possible values:
# always: Always cache attributes even for devices not paired, this is
# recommended as it is best for interoperability, with more consistent
# reconnection times and enables proper tracking of notifications for all
# devices.
# yes: Only cache attributes of paired devices.
# no: Never cache attributes
# Default: always
#Cache = always

# Minimum required Encryption Key Size for accessing secured characteristics.
# Possible values: 0 and 7-16. 0 means don't care.
# Defaults to 0
#KeySize = 0

# Exchange MTU size.
# Possible values: 23-517
# Defaults to 517
#ExchangeMTU = 517

[Policy]
#
# The ReconnectUUIDs defines the set of remote services that should try
# to be reconnected to in case of a link loss (link supervision
# timeout). The policy plugin should contain a sane set of values by
# default, but this list can be overridden here. By setting the list to
# empty the reconnection feature gets disabled.
#ReconnectUUIDs=00001112-0000-1000-8000-00805f9b34fb,0000111f-0000-1000-8000-00805f9b34fb,0000110a-0000-1000-8000-00805f9b34fb

# ReconnectAttempts define the number of attempts to reconnect after a link
# lost. Setting the value to 0 disables reconnecting feature.
#ReconnectAttempts=7

# ReconnectIntervals define the set of intervals in seconds to use in between
# attempts.
# If the number of attempts defined in ReconnectAttempts is bigger than the
# set of intervals the last interval is repeated until the last attempt.
#ReconnectIntervals=1,2,4,8,16,32,64

# AutoEnable defines option to enable all controllers when they are found.
# This includes adapters present on start as well as adapters that are plugged
# in later on. Defaults to 'false'.
AutoEnable=true

Ram-Z · 2020-05-06 20:16:35

I'm still not having any luck. Also tried with a different headset. Same behaviour. I never tried this headset before though, so unlike the other headset I can cannot say that it used to work for sure.

Ram-Z · 2020-05-06 23:40:21

Tried it on a different Arch machine now and it is working there with same headset and same kernel/PA/BlueZ/firmware combination. The only other difference I can think of is the chipset. So I'm leaning towards a driver regression for the bluetooth controller on my desktop.

Not working:

    |__ Port 2: Dev 3, If 0, Class=Wireless, Driver=btusb, 12M
        ID 8087:0029 Intel Corp. 
    |__ Port 2: Dev 3, If 1, Class=Wireless, Driver=btusb, 12M
        ID 8087:0029 Intel Corp.

Working:

        |__ Port 6: Dev 4, If 0, Class=Wireless, Driver=btusb, 12M
            ID 8087:07da Intel Corp. 
        |__ Port 6: Dev 4, If 1, Class=Wireless, Driver=btusb, 12M
            ID 8087:07da Intel Corp.

Arch Linux

#1 2020-04-14 22:48:53

[SOLVED] Bluetooth HSP profile stopped working recently

#2 2020-04-15 07:13:10

Re: [SOLVED] Bluetooth HSP profile stopped working recently

#3 2020-04-16 09:12:39

Re: [SOLVED] Bluetooth HSP profile stopped working recently

#4 2020-04-16 16:48:58

Re: [SOLVED] Bluetooth HSP profile stopped working recently

#5 2020-04-18 13:15:05

Re: [SOLVED] Bluetooth HSP profile stopped working recently

#6 2020-04-20 18:52:32

Re: [SOLVED] Bluetooth HSP profile stopped working recently

#7 2020-04-20 19:45:38

Re: [SOLVED] Bluetooth HSP profile stopped working recently

#8 2020-04-20 20:02:13

Re: [SOLVED] Bluetooth HSP profile stopped working recently

#9 2020-04-21 11:06:59

Re: [SOLVED] Bluetooth HSP profile stopped working recently

#10 2020-04-21 21:05:00

Re: [SOLVED] Bluetooth HSP profile stopped working recently

#11 2020-04-27 14:46:10

Re: [SOLVED] Bluetooth HSP profile stopped working recently

#12 2020-04-27 15:27:12

Re: [SOLVED] Bluetooth HSP profile stopped working recently

#13 2020-04-28 00:17:43

Re: [SOLVED] Bluetooth HSP profile stopped working recently

#14 2020-04-28 10:05:19

Re: [SOLVED] Bluetooth HSP profile stopped working recently

#15 2020-04-28 11:45:54

Re: [SOLVED] Bluetooth HSP profile stopped working recently

#16 2020-04-28 15:39:08

Re: [SOLVED] Bluetooth HSP profile stopped working recently

#17 2020-05-06 20:16:35

Re: [SOLVED] Bluetooth HSP profile stopped working recently

#18 2020-05-06 23:40:21

Re: [SOLVED] Bluetooth HSP profile stopped working recently

Board footer