No such device about bluez-alsa HOT 34 CLOSED

Hi,

Fist of all make sure you are using current master snapshot of bluez-alsa. Compile it with the --enable-debug option (during configuration). Then, start bluealsa server manually, as follows:

./src/bluealsa --disable-hfp

If it complains, about permissions, create /run/bluealsa directory and chmod it to you. Also, make sure, that pulseaudio and other instance of bluealsa (e.g. started by the init.d) is not running.

Then, connect Bluetooth device (e.g. using bluetoothctl). The log from the bluealsa should be as follows (or very similar):

bluealsa: ../../src/ctl.c:464: Starting controller loop
bluealsa: ../../src/bluez.c:688: Registering endpoint: 0000110A-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DP_MPEG24_Source
bluealsa: ../../src/bluez.c:688: Registering endpoint: 0000110A-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DPSource
bluealsa: ../../src/bluez.c:688: Registering endpoint: 0000110B-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DP_MPEG24_Sink
bluealsa: ../../src/bluez.c:688: Registering endpoint: 0000110B-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DPSink
bluealsa: ../../src/bluez.c:924: Registering profile: 00001108-0000-1000-8000-00805F9B34FB: /Profile/HSPHeadset
bluealsa: ../../src/bluez.c:924: Registering profile: 00001112-0000-1000-8000-00805F9B34FB: /Profile/HSPAudioGateway
bluealsa: ../../src/main.c:217: Starting main dispatching loop
bluealsa: ../../src/bluez.c:846: Profile method call: org.bluez.Profile1.NewConnection()
bluealsa: ../../src/transport.c:593: State transition: 0 -> 2
bluealsa: ../../src/transport.c:97: Created new IO thread: HSP Audio Gateway
bluealsa: ../../src/bluez.c:785: HSP Audio Gateway configured for device 1C:48:F9:9D:81:5C
bluealsa: ../../src/transport.c:593: State transition: 0 -> 2
bluealsa: ../../src/io.c:1223: Starting IO loop: HSP Audio Gateway
bluealsa: ../../src/io.c:1074: Starting RFCOMM loop: HSP Audio Gateway
bluealsa: ../../src/transport.c:97: Created new IO thread: HSP Audio Gateway
bluealsa: ../../src/io.c:1134: AT command: +VGS=09
bluealsa: ../../src/io.c:1134: AT command: +XAPL=0000-0000-0100,7
bluealsa: ../../src/io.c:1134: AT command: +IPHONEACCEV=2,1,6,2,0
bluealsa: ../../src/bluez.c:493: Endpoint method call: org.bluez.MediaEndpoint1.SelectConfiguration()
bluealsa: ../../src/bluez.c:493: Endpoint method call: org.bluez.MediaEndpoint1.SetConfiguration()
bluealsa: ../../src/bluez.c:427: A2DP Source (AAC) configured for device 1C:48:F9:9D:81:5C
bluealsa: ../../src/bluez.c:429: Configuration: channels: 2, sampling: 44100
bluealsa: ../../src/transport.c:593: State transition: 0 -> 0

The most important line is (if your device does not support AAC, or bluealsa is compiled without AAC supper, you might see SBC instead):

bluealsa: ../../src/bluez.c:427: A2DP Source (AAC) configured for device 1C:48:F9:9D:81:5C

If the output is somehow "strange", please post it here.

Next, make sure, that the .asoundrc file looks like this:

pcm.!default {
      type plug
      slave.pcm {
              type bluealsa
              device "00:19:7F:98:XX:XX"
              profile "a2dp"
      }
      hint {
              show on
              description "BT Headset"
      }
}

Then, try to play something using aplay (e.g. aplay test.wav). If nothing happens, please send the logs from the bluealsa (from the beginning) and from the aplay.

When the a2dp profile will work, we might try to check the SCO then.

from bluez-alsa.

I've played around with the bluez-alsa quite a bit, and was able to get it to work. I didn't write the code, 'arkq' did so he'd know better than me. I do see a few issues with your current setup.

1. I recommend using only /etc/asoundrc or ~/.asoundrc for alsa configuration.

2. alsa 1.1.2 has a deadlock bug in it so if you can downgrade to 1.1.1 you'll know it isn't failing because of that issue.

3. I don't think your alsa.conf file is necessary because bluez-alsa creates the 20-bluealsa.conf file places it in the /usr/share/alsa/alsa.conf.d/ directory when you use the command 'sudo make install'

4. If you decide to keep 'your' alsa.conf then there are a few things that need to be corrected in it.
   a. Add the interface parameter. It is required by the bluealsa type/code etc.
   b. Add the bluetooth mac address of the destination device (i.e. headset)
   pcm {
   type bluealsa
   --> interface "hci0" // Place your hciX that corresponds to the bluetooth device etc.
   --> device 00:19:7F:98:XX:XX // Place the MAC of the BT headset.
   profile "a2dp"

ctl.!default {
type bluealsa
--> interface "hci0" // Place your hciX etc.
}

5. If you keep 'your' alsa.conf then ~/.asoundrc isn't necessary. If you do 'make install' in the bluez-alsa directory, then you should use ~/.asoundrc. Again, the MAC addr should correspond to the destination device (i.e. your bluetooth headset).

Again, I'm not the author of this code and thus I may have made an error or two. Hopefully this helps.

from bluez-alsa.

OK, so from the logs I can see, that the A2DP profile is not connected. You can try to issue connect command once more and see if the "important" line (A2DP Source (XXX) configured for device) will show up. Without this line, device is not available (from the ALSA point of view). Sometimes profile is not connected - due to signal loss, or other strange "Bluetooth" reasons.

However the Audio Gateway profile is connected, so you can try to use this one. Change "a2dp" profile to "sco" in the .asoundrc and try to play. The quality will be very poor (sampling 8kHz), though. But, there is a possibility that this will not work either. However it is out of the BlueALSA scope. Encoding and data transfer is performed entirely by the Bluetooth kernel module.

In order to debug this problem even further you can dump Bluetooth transfer from the HCI device. See the EDIT of this comment: #7 (comment) You can read the whole comment section of this issue, because it might be somehow related to your problem.

from bluez-alsa.

With JABRA it should work without any troubles - at lest the logs say so. Unfortunately, you are using wrong MAC address when trying to connect.

# bluealsa
A2DP Source (SBC) configured for device 1C:48:F9:40:03:FB
....
# aplay
Getting transport for 00:19:7F:98:FD:29 type 1

Change device in your .asoundrc file to "1C:48:F9:40:03:FB" and it should work.

Could you provide bluetooth dump from the "not working" device (9xxPlantronics)? Also, could you try to use SCO profile with JABRA (recording and playing) and let me know if it works?

from bluez-alsa.

OK, so it seems there is a progress :) that's great!

SCO playback - checked.

SCO recording - It's sad that it's not working... It would require some debugging, however it might be hard to do it on a distance. If I put my hands on a device with a similar problem, I'll try to fix it. But for now I have to put in on hold.

A2DP playback - It looks like a deadlock. Try to use: LIBASOUND_THREAD_SAFE=0 aplay -D bluealsa piano2.wav. However, I'm not able to reproduce this deadlock with aplay... I will have to look at this a little bit longer.

A2DP recording - not supported. A2DP profile is a one-way transport. If you're connecting headset, it won't be possible to capture stream from the build-in microphone. You have to connect a device with A2DP playback capabilities (e.g. smartphone), then it'll be possible to record with A2DP profile. In order to check what "action" is possible with which profile, issue: amixer -D bluealsa. In my case it is:

Simple mixer control 'Jabra MOVE v2.3.0 - A2DP',0
  Capabilities: pvolume pswitch
  Playback channels: Front Left - Front Right
  Limits: Playback 0 - 127
  Mono:
  Front Left: Playback 127 [100%] [on]
  Front Right: Playback 127 [100%] [on]
Simple mixer control 'Jabra MOVE v2.3.0 - SCO',0
  Capabilities: pvolume pvolume-joined cvolume cvolume-joined pswitch pswitch-joined cswitch cswitch-joined
  Playback channels: Mono
  Capture channels: Mono
  Limits: Playback 0 - 15 Capture 0 - 15
  Mono: Playback 10 [67%] [on] Capture 15 [100%] [on]
Simple mixer control 'Jabra MOVE v2.3.0 | Battery',0
  Capabilities: pvolume pvolume-joined
  Playback channels: Mono
  Limits: Playback 0 - 9
  Mono: Playback 6 [67%]

With A2DP it is possible only to playback, with SCO it is possible to capture and playback. And there is a special control, which shows battery level. It is under the "playback" section, though.

I will have some spare time during this weekend (I suppose), so this issue has to wait until then.

from bluez-alsa.

Ad. 1
I will check this, but not until the weekend.

Ad. 2
Any info I've found on the net, is that this earpiece supports: Headset v 1.1 (HSP), Hands-free v. 1.5 (HFP). And this is consistent with the bluetooth.dump you've provided. There is no AVDTP communication. So I guess, that A2DP is not supported.

from bluez-alsa.

Lately I'm rather busy, so I've got no time to tinker around bluealsa. But your problem might be kernel or bluez related. You can try to check two things. First, after connecting to the capture stream of the SCO, check if the hcitop (included with this project) shows incoming data (RX/s). If there is none, you might check with tcpdump and see if the dump file is "big" after some recording. Because, if the IO thread hangs on the poll function, it seems that there is no incoming data and this is beyond bluealsa control. My suggestion is to try to use your headset with other PC (other hardware) or other Linux version.

from bluez-alsa.

Check if code from the sco-hotstart branch resolves this issue.

from bluez-alsa.

It took me a while, but I might know what's going on. I've pushed one debug line to the sco-hotstart branch. Please, check if this line is printed ("FIFO not connected yet") when using arecord only. If yes, then remove the O_NONBLOCK flag from the open call from this line and remove this one. Then try again. (After this changes, bluealsa might be not useable for A2DP recoding, but it will reveal the problem).

from bluez-alsa.

When you say "default audio device", are you attempting to black audio from the bluetooth source -> bluez -> bluez-alsa -> sound card?

from bluez-alsa.

No, I'm attempting to play wav file via BT headset with help of bluez-alsa.

from bluez-alsa.

erikly01, thank you for help!

I was forgotten to add the line interface "hci0" to alsa.conf file. However, it does not help.

So I deleted all files and left ~/.asoundrc only.

If to change a2dp profile to sco, playback begins to work, but only 1 time:

wget http://www.kozco.com/tch/piano2.wav
aplay -D bluealsa piano2.wav 
Playing WAVE 'piano2.wav' : Signed 16 bit Little Endian, Rate 48000 Hz, Stereo

After some seconds, headset disconnected in bluetoothctl. Moreover, can not be connected within several seconds.

The system logs in this moment:

Jan 09 10:51:24 pi bluetoothd[300]: Unable to get connect data for Headset Voice gateway: getpeername: Transport endpoint is not connected (107)
Jan 09 10:53:26 pi bluetoothd[300]: Unable to get io data for Hands-Free Voice gateway: getpeername: Transport endpoint is not connected (107)
Jan 09 10:53:57 pi bluetoothd[300]: Unable to get connect data for Hands-Free Voice gateway: getpeername: Transport endpoint is not connected (107)
Jan 09 10:54:02 pi bluetoothd[300]: Unable to get connect data for Headset Voice gateway: getpeername: Transport endpoint is not connected (107)

Why does it happen? Moreover, why playback does not work with a2dp profile? What device can not be opened?

By the way, recording with sco profile also not work. The command arecord -d 3 -D bluealsa test.wav is never finished, test.wav is 44 bytes.

Downgrading alsa-lib to 1.1.1 does not help.

from bluez-alsa.

Hello, Arkq!

Thank you for help!

Here is my logs:

bluetoothctl

[bluetooth]# connect 00:19:7F:98:FD:29
Attempting to connect to 00:19:7F:98:FD:29
[CHG] Device 00:19:7F:98:FD:29 Connected: yes
Connection successful
[CHG] Device 00:19:7F:98:FD:29 ServicesResolved: yes
[9xxPlantronics]#

bluealsa

bluealsa --disable-hfp
bluealsa: ../../git/src/ctl.c:464: Starting controller loop
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110A-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DP_MPEG24_Source
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110A-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DPSource
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110B-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DP_MPEG24_Sink
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110B-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DPSink
bluealsa: ../../git/src/bluez.c:924: Registering profile: 00001108-0000-1000-8000-00805F9B34FB: /Profile/HSPHeadset
bluealsa: ../../git/src/bluez.c:924: Registering profile: 00001112-0000-1000-8000-00805F9B34FB: /Profile/HSPAudioGateway
bluealsa: ../../git/src/main.c:217: Starting main dispatching loop
bluealsa: ../../git/src/bluez.c:846: Profile method call: org.bluez.Profile1.NewConnection()
bluealsa: ../../git/src/transport.c:590: State transition: 0 -> 2
bluealsa: ../../git/src/transport.c:94: Created new IO thread: HSP Audio Gateway
bluealsa: ../../git/src/bluez.c:785: HSP Audio Gateway configured for device 00:19:7F:98:FD:29
bluealsa: ../../git/src/transport.c:590: State transition: 0 -> 2
bluealsa: ../../git/src/transport.c:94: Created new IO thread: HSP Audio Gateway
bluealsa: ../../git/src/io.c:1068: Starting RFCOMM loop: HSP Audio Gateway
bluealsa: ../../git/src/io.c:1217: Starting IO loop: HSP Audio Gateway
bluealsa: ../../git/src/ctl.c:524: New client accepted: 8
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/ctl.c:496: Client closed connection: 8
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/ctl.c:524: New client accepted: 8
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/ctl.c:496: Client closed connection: 8
bluealsa: ../../git/src/ctl.c:527: +-+-

aplay

aplay piano2.wav
../../../git/src/asound/bluealsa-pcm.c:103: Getting transport for 00:19:7F:98:FD:29 type 1
ALSA lib ../../../git/src/asound/bluealsa-pcm.c:707:(_snd_pcm_bluealsa_open) Couldn't get BlueALSA transport: No such device
aplay: main:786: audio open error: No such device

from bluez-alsa.

I tried another headset with A2DP profile (Jabra instead of 9xxPlantronics), but get "no such" device anyway. May be some problems with the configuration of the operation system? But with PulseAudio no any problems on the same system.

New logs:

# bluetoothctl 
[NEW] Controller 00:19:86:00:0A:D8 tpp [default]
[bluetooth]# power on
Changing power on succeeded
[bluetooth]# scan on
Discovery started
[CHG] Controller 00:19:86:00:0A:D8 Discovering: yes
[bluetooth]# agent on
Agent registered
[bluetooth]# default-agent 
Default agent request successful
[NEW] Device 1C:48:F9:40:03:FB JABRA TALK
[bluetooth]# pair 1C:48:F9:40:03:FB
Attempting to pair with 1C:48:F9:40:03:FB
[CHG] Device 1C:48:F9:40:03:FB Connected: yes
[CHG] Device 1C:48:F9:40:03:FB UUIDs: 00001108-0000-1000-8000-00805f9b34fb
[CHG] Device 1C:48:F9:40:03:FB UUIDs: 0000110b-0000-1000-8000-00805f9b34fb
[CHG] Device 1C:48:F9:40:03:FB UUIDs: 0000111e-0000-1000-8000-00805f9b34fb
[CHG] Device 1C:48:F9:40:03:FB ServicesResolved: yes
[CHG] Device 1C:48:F9:40:03:FB Paired: yes
Pairing successful
[CHG] Device 1C:48:F9:40:03:FB ServicesResolved: no
[CHG] Device 1C:48:F9:40:03:FB Connected: no
[CHG] Controller 00:19:86:00:0A:D8 Class: 0x080000
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 00001801-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 0000110a-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 0000110e-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 00001200-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 00001800-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 0000110c-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 Class: 0x0c0000
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 0000110b-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 00001801-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 0000110a-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 0000110e-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 00001200-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 00001800-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 0000110c-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 00001112-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 00001801-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 0000110e-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 00001800-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 00001200-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 0000110c-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 0000110b-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 0000110a-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 00001108-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 00001112-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 00001801-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 0000110e-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 00001800-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 00001200-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 0000110c-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 0000110b-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 0000110a-0000-1000-8000-00805f9b34fb
[CHG] Controller 00:19:86:00:0A:D8 UUIDs: 00001108-0000-1000-8000-00805f9b34fb
[bluetooth]# connect 1C:48:F9:40:03:FB
Attempting to connect to 1C:48:F9:40:03:FB
[CHG] Device 1C:48:F9:40:03:FB Connected: yes
Connection successful
[CHG] Device 1C:48:F9:40:03:FB ServicesResolved: yes
[JABRA TALK]# 

# bluealsa --disable-hfp
bluealsa: ../../git/src/ctl.c:464: Starting controller loop
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110A-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DP_MPEG24_Source
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110A-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DPSource
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110B-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DP_MPEG24_Sink
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110B-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DPSink
bluealsa: ../../git/src/bluez.c:924: Registering profile: 00001108-0000-1000-8000-00805F9B34FB: /Profile/HSPHeadset
bluealsa: ../../git/src/bluez.c:924: Registering profile: 00001112-0000-1000-8000-00805F9B34FB: /Profile/HSPAudioGateway
bluealsa: ../../git/src/main.c:217: Starting main dispatching loop
bluealsa: ../../git/src/bluez.c:846: Profile method call: org.bluez.Profile1.NewConnection()
bluealsa: ../../git/src/transport.c:590: State transition: 0 -> 2
bluealsa: ../../git/src/transport.c:94: Created new IO thread: HSP Audio Gateway
bluealsa: ../../git/src/bluez.c:785: HSP Audio Gateway configured for device 1C:48:F9:40:03:FB
bluealsa: ../../git/src/transport.c:590: State transition: 0 -> 2
bluealsa: ../../git/src/transport.c:94: Created new IO thread: HSP Audio Gateway
bluealsa: ../../git/src/io.c:1217: Starting IO loop: HSP Audio Gateway
bluealsa: ../../git/src/io.c:1068: Starting RFCOMM loop: HSP Audio Gateway
bluealsa: ../../git/src/bluez.c:493: Endpoint method call: org.bluez.MediaEndpoint1.SelectConfiguration()
bluealsa: ../../git/src/bluez.c:493: Endpoint method call: org.bluez.MediaEndpoint1.SetConfiguration()
bluealsa: ../../git/src/bluez.c:427: A2DP Source (SBC) configured for device 1C:48:F9:40:03:FB
bluealsa: ../../git/src/bluez.c:429: Configuration: channels: 2, sampling: 48000
bluealsa: ../../git/src/transport.c:590: State transition: 0 -> 0
bluealsa: ../../git/src/ctl.c:524: New client accepted: 12
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/ctl.c:496: Client closed connection: 12
bluealsa: ../../git/src/ctl.c:527: +-+-

# aplay piano2.wav 
../../../git/src/asound/bluealsa-pcm.c:103: Getting transport for 00:19:7F:98:FD:29 type 1
ALSA lib ../../../git/src/asound/bluealsa-pcm.c:707:(_snd_pcm_bluealsa_open) Couldn't get BlueALSA transport: No such device
aplay: main:786: audio open error: No such device

Bluetooth dump here.

Thank you for help!

from bluez-alsa.

Sorry, forgot to change the MAC address.

The playback works fine with both Jabra and 9xxPlantronics BT headsets on SCO profile.

The recording does not work with both Jabra and 9xxPlantronics on SCO profile (the output file is always 44 bytes, the problem like here).

May be the deadlock bug with the recording (used alsa-lib 1.1.2)?

Some logs.

Jabra SCO playback (ok, 9xxPlantronics the same, but have not got A2DP profile detected):

# bluealsa --disable-hfp
bluealsa: ../../git/src/ctl.c:464: Starting controller loop
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110A-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DP_MPEG24_Source
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110A-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DPSource
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110B-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DP_MPEG24_Sink
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110B-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DPSink
bluealsa: ../../git/src/bluez.c:924: Registering profile: 00001108-0000-1000-8000-00805F9B34FB: /Profile/HSPHeadset
bluealsa: ../../git/src/bluez.c:924: Registering profile: 00001112-0000-1000-8000-00805F9B34FB: /Profile/HSPAudioGateway
bluealsa: ../../git/src/main.c:217: Starting main dispatching loop
bluealsa: ../../git/src/bluez.c:846: Profile method call: org.bluez.Profile1.NewConnection()
bluealsa: ../../git/src/transport.c:590: State transition: 0 -> 2
bluealsa: ../../git/src/transport.c:94: Created new IO thread: HSP Audio Gateway
bluealsa: ../../git/src/bluez.c:785: HSP Audio Gateway configured for device 1C:48:F9:40:03:FB
bluealsa: ../../git/src/transport.c:590: State transition: 0 -> 2
bluealsa: ../../git/src/transport.c:94: Created new IO thread: HSP Audio Gateway
bluealsa: ../../git/src/io.c:1068: Starting RFCOMM loop: HSP Audio Gateway
bluealsa: ../../git/src/io.c:1217: Starting IO loop: HSP Audio Gateway
bluealsa: ../../git/src/bluez.c:493: Endpoint method call: org.bluez.MediaEndpoint1.SelectConfiguration()
bluealsa: ../../git/src/bluez.c:493: Endpoint method call: org.bluez.MediaEndpoint1.SetConfiguration()
bluealsa: ../../git/src/bluez.c:427: A2DP Source (SBC) configured for device 1C:48:F9:40:03:FB
bluealsa: ../../git/src/bluez.c:429: Configuration: channels: 2, sampling: 48000
bluealsa: ../../git/src/transport.c:590: State transition: 0 -> 0
bluealsa: ../../git/src/ctl.c:524: New client accepted: 10
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/ctl.c:306: PCM requested for 1C:48:F9:40:03:FB type 2 stream 0
bluealsa: ../../git/src/io.c:75: Opening FIFO for reading: /var/run/bluealsa/hci0-1C:48:F9:40:03:FB-2-0
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/transport.c:794: New SCO link: 14 (MTU: R:48 W:48)
bluealsa: ../../git/src/ctl.c:381: PCM close for 1C:48:F9:40:03:FB type 2 stream 0
bluealsa: ../../git/src/transport.c:827: Cleaning PCM FIFO: /var/run/bluealsa/hci0-1C:48:F9:40:03:FB-2-0
bluealsa: ../../git/src/transport.c:834: Closing PCM: 13
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/io.c:75: Opening FIFO for reading: (null)
bluealsa: ../../git/src/transport.c:804: Closing SCO: 14
bluealsa: ../../git/src/ctl.c:496: Client closed connection: 10
bluealsa: ../../git/src/ctl.c:527: +-+-

# aplay -D bluealsa piano2.wav 
../../../git/src/asound/bluealsa-pcm.c:103: Getting transport for 1C:48:F9:40:03:FB type 2
../../../git/src/asound/bluealsa-pcm.c:587: Setting constraints
Playing WAVE 'piano2.wav' : Signed 16 bit Little Endian, Rate 48000 Hz, Stereo
../../../git/src/asound/bluealsa-pcm.c:366: Initializing HW
../../../git/src/asound/bluealsa-pcm.c:140: Requesting PCM open for 1C:48:F9:40:03:FB
../../../git/src/asound/bluealsa-pcm.c:157: Opening PCM FIFO (mode: WR): /var/run/bluealsa/hci0-1C:48:F9:40:03:FB-2-0
../../../git/src/asound/bluealsa-pcm.c:389: FIFO buffer size: 4096
../../../git/src/asound/bluealsa-pcm.c:394: Selected HW buffer: 8000 ?= 4 periods x 2000 bytes
../../../git/src/asound/bluealsa-pcm.c:425: Prepared
../../../git/src/asound/bluealsa-pcm.c:319: Starting
../../../git/src/asound/bluealsa-pcm.c:243: Starting IO loop
../../../git/src/asound/bluealsa-pcm.c:340: Stopping
../../../git/src/asound/bluealsa-pcm.c:340: Stopping
../../../git/src/asound/bluealsa-pcm.c:401: Freeing HW
../../../git/src/asound/bluealsa-pcm.c:186: Closing PCM for 1C:48:F9:40:03:FB
../../../git/src/asound/bluealsa-pcm.c:356: Closing plugin

Jabra SCO recording (failed with 44 bytes, 9xxPlantronics the same, but have not got A2DP profile detected):

# bluealsa --disable-hfp
bluealsa: ../../git/src/ctl.c:464: Starting controller loop
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110A-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DP_MPEG24_Source
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110A-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DPSource
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110B-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DP_MPEG24_Sink
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110B-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DPSink
bluealsa: ../../git/src/bluez.c:924: Registering profile: 00001108-0000-1000-8000-00805F9B34FB: /Profile/HSPHeadset
bluealsa: ../../git/src/bluez.c:924: Registering profile: 00001112-0000-1000-8000-00805F9B34FB: /Profile/HSPAudioGateway
bluealsa: ../../git/src/main.c:217: Starting main dispatching loop
bluealsa: ../../git/src/bluez.c:846: Profile method call: org.bluez.Profile1.NewConnection()
bluealsa: ../../git/src/transport.c:590: State transition: 0 -> 2
bluealsa: ../../git/src/transport.c:94: Created new IO thread: HSP Audio Gateway
bluealsa: ../../git/src/bluez.c:785: HSP Audio Gateway configured for device 1C:48:F9:40:03:FB
bluealsa: ../../git/src/transport.c:590: State transition: 0 -> 2
bluealsa: ../../git/src/transport.c:94: Created new IO thread: HSP Audio Gateway
bluealsa: ../../git/src/io.c:1217: Starting IO loop: HSP Audio Gateway
bluealsa: ../../git/src/io.c:1068: Starting RFCOMM loop: HSP Audio Gateway
bluealsa: ../../git/src/bluez.c:493: Endpoint method call: org.bluez.MediaEndpoint1.SelectConfiguration()
bluealsa: ../../git/src/bluez.c:493: Endpoint method call: org.bluez.MediaEndpoint1.SetConfiguration()
bluealsa: ../../git/src/bluez.c:427: A2DP Source (SBC) configured for device 1C:48:F9:40:03:FB
bluealsa: ../../git/src/bluez.c:429: Configuration: channels: 2, sampling: 48000
bluealsa: ../../git/src/transport.c:590: State transition: 0 -> 0
bluealsa: ../../git/src/ctl.c:524: New client accepted: 10
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/ctl.c:306: PCM requested for 1C:48:F9:40:03:FB type 2 stream 1
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/io.c:75: Opening FIFO for reading: (null)
bluealsa: ../../git/src/io.c:96: Opening FIFO for writing: /var/run/bluealsa/hci0-1C:48:F9:40:03:FB-2-1

root@tpp:~# arecord -d 5 test.wav
../../../git/src/asound/bluealsa-pcm.c:103: Getting transport for 1C:48:F9:40:03:FB type 2
../../../git/src/asound/bluealsa-pcm.c:587: Setting constraints
Recording WAVE 'test.wav' : Unsigned 8 bit, Rate 8000 Hz, Mono
../../../git/src/asound/bluealsa-pcm.c:366: Initializing HW
../../../git/src/asound/bluealsa-pcm.c:140: Requesting PCM open for 1C:48:F9:40:03:FB
../../../git/src/asound/bluealsa-pcm.c:157: Opening PCM FIFO (mode: RO): /var/run/bluealsa/hci0-1C:48:F9:40:03:FB-2-1
../../../git/src/asound/bluealsa-pcm.c:394: Selected HW buffer: 8000 ?= 4 periods x 2000 bytes
../../../git/src/asound/bluealsa-pcm.c:425: Prepared
../../../git/src/asound/bluealsa-pcm.c:319: Starting

Jabra A2DP playback (failed):

root@tpp:~# bluealsa --disable-hfp
bluealsa: ../../git/src/ctl.c:464: Starting controller loop
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110A-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DP_MPEG24_Source
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110A-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DPSource
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110B-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DP_MPEG24_Sink
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110B-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DPSink
bluealsa: ../../git/src/bluez.c:924: Registering profile: 00001108-0000-1000-8000-00805F9B34FB: /Profile/HSPHeadset
bluealsa: ../../git/src/bluez.c:924: Registering profile: 00001112-0000-1000-8000-00805F9B34FB: /Profile/HSPAudioGateway
bluealsa: ../../git/src/main.c:217: Starting main dispatching loop
bluealsa: ../../git/src/bluez.c:846: Profile method call: org.bluez.Profile1.NewConnection()
bluealsa: ../../git/src/transport.c:590: State transition: 0 -> 2
bluealsa: ../../git/src/transport.c:94: Created new IO thread: HSP Audio Gateway
bluealsa: ../../git/src/bluez.c:785: HSP Audio Gateway configured for device 1C:48:F9:40:03:FB
bluealsa: ../../git/src/transport.c:590: State transition: 0 -> 2
bluealsa: ../../git/src/transport.c:94: Created new IO thread: HSP Audio Gateway
bluealsa: ../../git/src/io.c:1068: Starting RFCOMM loop: HSP Audio Gateway
bluealsa: ../../git/src/io.c:1217: Starting IO loop: HSP Audio Gateway
bluealsa: ../../git/src/bluez.c:493: Endpoint method call: org.bluez.MediaEndpoint1.SelectConfiguration()
bluealsa: ../../git/src/bluez.c:493: Endpoint method call: org.bluez.MediaEndpoint1.SetConfiguration()
bluealsa: ../../git/src/bluez.c:427: A2DP Source (SBC) configured for device 1C:48:F9:40:03:FB
bluealsa: ../../git/src/bluez.c:429: Configuration: channels: 2, sampling: 48000
bluealsa: ../../git/src/transport.c:590: State transition: 0 -> 0
bluealsa: ../../git/src/ctl.c:524: New client accepted: 10
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/ctl.c:306: PCM requested for 1C:48:F9:40:03:FB type 1 stream 0
bluealsa: ../../git/src/transport.c:674: New transport: 13 (MTU: R:672 W:895)
bluealsa: ../../git/src/bluez.c:1028: Signal: PropertiesChanged: org.bluez.MediaTransport1
bluealsa: ../../git/src/transport.c:590: State transition: 0 -> 2
bluealsa: ../../git/src/transport.c:94: Created new IO thread: A2DP Source (SBC)
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/io.c:75: Opening FIFO for reading: /var/run/bluealsa/hci0-1C:48:F9:40:03:FB-1-0
bluealsa: ../../git/src/io.c:499: Starting IO loop: A2DP Source (SBC)

root@tpp:~# aplay -D bluealsa piano2.wav 
../../../git/src/asound/bluealsa-pcm.c:103: Getting transport for 1C:48:F9:40:03:FB type 1
../../../git/src/asound/bluealsa-pcm.c:587: Setting constraints
Playing WAVE 'piano2.wav' : Signed 16 bit Little Endian, Rate 48000 Hz, Stereo
../../../git/src/asound/bluealsa-pcm.c:366: Initializing HW
../../../git/src/asound/bluealsa-pcm.c:140: Requesting PCM open for 1C:48:F9:40:03:FB
../../../git/src/asound/bluealsa-pcm.c:157: Opening PCM FIFO (mode: WR): /var/run/bluealsa/hci0-1C:48:F9:40:03:FB-1-0
../../../git/src/asound/bluealsa-pcm.c:389: FIFO buffer size: 4096
../../../git/src/asound/bluealsa-pcm.c:394: Selected HW buffer: 94208 ?= 23 periods x 4096 bytes
../../../git/src/asound/bluealsa-pcm.c:425: Prepared

Jabra A2DP recording (failed):

root@tpp:~# bluealsa --disable-hfp
bluealsa: ../../git/src/ctl.c:464: Starting controller loop
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110A-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DP_MPEG24_Source
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110A-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DPSource
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110B-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DP_MPEG24_Sink
bluealsa: ../../git/src/bluez.c:688: Registering endpoint: 0000110B-0000-1000-8000-00805F9B34FB: /MediaEndpoint/A2DPSink
bluealsa: ../../git/src/bluez.c:924: Registering profile: 00001108-0000-1000-8000-00805F9B34FB: /Profile/HSPHeadset
bluealsa: ../../git/src/bluez.c:924: Registering profile: 00001112-0000-1000-8000-00805F9B34FB: /Profile/HSPAudioGateway
bluealsa: ../../git/src/main.c:217: Starting main dispatching loop
bluealsa: ../../git/src/bluez.c:846: Profile method call: org.bluez.Profile1.NewConnection()
bluealsa: ../../git/src/transport.c:590: State transition: 0 -> 2
bluealsa: ../../git/src/transport.c:94: Created new IO thread: HSP Audio Gateway
bluealsa: ../../git/src/bluez.c:785: HSP Audio Gateway configured for device 1C:48:F9:40:03:FB
bluealsa: ../../git/src/transport.c:590: State transition: 0 -> 2
bluealsa: ../../git/src/transport.c:94: Created new IO thread: HSP Audio Gateway
bluealsa: ../../git/src/io.c:1068: Starting RFCOMM loop: HSP Audio Gateway
bluealsa: ../../git/src/io.c:1217: Starting IO loop: HSP Audio Gateway
bluealsa: ../../git/src/bluez.c:493: Endpoint method call: org.bluez.MediaEndpoint1.SelectConfiguration()
bluealsa: ../../git/src/bluez.c:493: Endpoint method call: org.bluez.MediaEndpoint1.SetConfiguration()
bluealsa: ../../git/src/bluez.c:427: A2DP Source (SBC) configured for device 1C:48:F9:40:03:FB
bluealsa: ../../git/src/bluez.c:429: Configuration: channels: 2, sampling: 48000
bluealsa: ../../git/src/transport.c:590: State transition: 0 -> 0
bluealsa: ../../git/src/ctl.c:524: New client accepted: 10
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/ctl.c:527: +-+-
bluealsa: ../../git/src/ctl.c:496: Client closed connection: 10
bluealsa: ../../git/src/ctl.c:527: +-+-


root@tpp:~# arecord -d 5 test.wav
../../../git/src/asound/bluealsa-pcm.c:103: Getting transport for 1C:48:F9:40:03:FB type 1
ALSA lib ../../../git/src/asound/bluealsa-pcm.c:707:(_snd_pcm_bluealsa_open) Couldn't get BlueALSA transport: No such device
arecord: main:786: audio open error: No such device

On the 9xxPlantronics with A2DP profile - recording and playback the error "no such device" (the logs above).

Full dump for 9xxPlantronics: here.

from bluez-alsa.

Good news! An A2DP playback works fine with the LIBASOUND_THREAD_SAFE=0 flag.

So it remains to solve the two problems - thread issue on SCO recording and determine the cause of the absence A2DP profile for 9xxPlantronics BT headset.

1. I see function bluealsa_start called from the logs, but io_thread thread not started. The wrong condition on 324 string (is variable io_started init?) or some thread created call issue? May be bad cast in io_thread function? Have not got any ideas. Here system calls logs, perhaps they can be useful.
2. Can't to find the datasheet for Plantronics Discovery 925. Not sure if it A2DP support.

P.S. I see in bluealsa.4653 file error in io.c (function io_thread_open_pcm_write return an error:

[b6b5b9c8] open("/var/run/bluealsa/hci0-1C:48:F9:40:03:FB-2-1", O_WRONLY|O_NONBLOCK) = -1 ENXIO (No such device or address)

It's strange. Also I do not get an exception.

from bluez-alsa.

Hello, Arkq!

Any good news?

I debugged the eternal cycle on the SCO recording (44 bytes output file).

See the problem in static void *io_thread(void *arg) function - the poll function never ending. If to add the timeout 10 seconds, it finished after 10 second with logs from arecord as below (string number are different from git, because some debug logs added):

../../../src/asound/bluealsa-pcm.c:252: Starting IO loop
../../../src/asound/bluealsa-pcm.c:321: Exiting IO thread
arecord: pcm_read:2096: read error: No such device

What device can not be found? May be some additional logs is required?

If remove reading condition in io_thread, get the error: "ALSA lib ../../../src/asound/bluealsa-pcm.c:280:(io_thread) PCM FIFO write error: Bad file descriptor".

from bluez-alsa.

Yes, no any activity in RX/s or TX/s when recording and about 15000-17000 bytes/s when playing (both on SCO). Get about 1 packet for 3 second in tcpdump. It's strange, but with PulseAudio recording works fine.

May be some dbus configuration issue? Or some kernel option? I will continue to research...

from bluez-alsa.

from bluez-alsa.

from bluez-alsa.

It was about 15 kbytes/s for TX and the same for RX during playback.

from bluez-alsa.

By the way. Is blue-alsa required some Linux kernel specific build flags? I have a light weight kernel for the equipment with low performance.

from bluez-alsa.

from bluez-alsa.

Arkq, thanks in advance for a good idea!

Start arecord without aplay, get a string Starting in the log and waiting for a file descriptor in the poll function.

Start aplay, in arecord log appears Starting IO loop and recording works fine...

My be some AT commands needed in blue-alsa?

from bluez-alsa.

What happens when you stop aplay? Does arecord still work? Because I might have some idea how to "fix" this issue :)

It is not AT command issue (I think). The problem is, that I'm using HSP/HFP profile in a non-standard way. This profile should be used to stream audio to the headset and receive one from it - standard usage as a handsfree setup for a phone. However, I thought, that receiving audio and not streaming one should be also possible - save CPU and bandwidth. But it might not be as easy as it seems.

from bluez-alsa.

Yes... :) I just made tests... When play astop, arecord continues to record the sound while I don not interrupt the recording manually.

from bluez-alsa.

Recording does not work yet. :-(

The debug string "Hot-starting SCO for reading" not appear.

If to start arecord only, t->sco.spk_pcm.fd == -1 and t->sco.mic_pcm.fd == -1.

If to start aplay only, t->sco.spk_pcm.fd > 0 and t->sco.mic_pcm.fd == -1.

If to start arecord (at this moment t->sco.spk_pcm.fd == -1 and t->sco.mic_pcm.fd == -1), then aplay - t->sco.spk_pcm.fd > 0 and t->sco.mic_pcm.fd > 0.

from bluez-alsa.

Yes, I get the line "FIFO not connected yet". And... SCO recording works fine with removed O_NONBLOCK flag from 97 line and deleted 107 line. :-)

from bluez-alsa.

from bluez-alsa.

@Vitaliy69, please check if the current master snapshot works for you.

from bluez-alsa.

Works fine! Thank you!

from bluez-alsa.

Hi Arkq!

First of all, I really appreciate the work you've done so far. This is really a very good project unlike pa which "... breaks your audio" (sic)

Unfortunately I've run into a similar issue with my bluetooth device. The playback works just fine, aplay works, mplayer works, chromium works, I can listen to YouTunbe audio. But the recording doesn't. And unfortunately I have no more ideas nor clues how to proceed.

I have alsa-1.1.2
bluz 5.47
bluealsa built against the commit #9736a05

Please take a look at the logs.

asound.conf:

defaults.bluealsa.interface "hci0"
defaults.bluealsa.device "04:5D:4B:53:8F:1D"
defaults.bluealsa.profile "a2dp"
defaults.bluealsa.delay 0

pcm.duplex {
type asym;
playback.pcm "bluz";
capture.pcm "bluzmic"
}

pcm.!default {
type plug;
slave.pcm "duplex";
}

pcm.bluzmic {
type bluealsa
device "04:5D:4B:53:8F:1D"
profile "sco"
"delay" 0
}

pcm.bluz {
type plug
slave.pcm {
type bluealsa
device "04:5D:4B:53:8F:1D"
profile "a2dp"
"delay" 0
}
hint {
show on
description "BT Headset"
}
}

=====
sudo bluetoothd -d -n

bluetoothd[18792]: Bluetooth daemon 5.47
bluetoothd[18792]: src/adapter.c:adapter_init() sending read version command
bluetoothd[18792]: Starting SDP server
bluetoothd[18792]: src/sdpd-service.c:register_device_id() Adding device id record for 0002:1d6b:0246:052f
bluetoothd[18792]: src/plugin.c:plugin_init() Loading builtin plugins
bluetoothd[18792]: src/plugin.c:add_plugin() Loading hostname plugin
bluetoothd[18792]: src/plugin.c:add_plugin() Loading wiimote plugin
bluetoothd[18792]: src/plugin.c:add_plugin() Loading autopair plugin
bluetoothd[18792]: src/plugin.c:add_plugin() Loading policy plugin
bluetoothd[18792]: src/plugin.c:add_plugin() Loading a2dp plugin
bluetoothd[18792]: src/plugin.c:add_plugin() Loading avrcp plugin
bluetoothd[18792]: src/plugin.c:add_plugin() Loading network plugin
bluetoothd[18792]: src/plugin.c:add_plugin() Loading input plugin
bluetoothd[18792]: src/plugin.c:add_plugin() Loading hog plugin
bluetoothd[18792]: src/plugin.c:add_plugin() Loading gap plugin
bluetoothd[18792]: src/plugin.c:add_plugin() Loading scanparam plugin
bluetoothd[18792]: src/plugin.c:add_plugin() Loading deviceinfo plugin
bluetoothd[18792]: src/plugin.c:plugin_init() Loading plugins /usr/lib64/bluetooth/plugins
bluetoothd[18792]: profiles/input/suspend-none.c:suspend_init()
bluetoothd[18792]: profiles/network/manager.c:read_config() Config options: Security=true
bluetoothd[18792]: plugins/hostname.c:read_dmi_fallback() chassis: laptop
bluetoothd[18792]: plugins/hostname.c:read_dmi_fallback() major: 0x01 minor: 0x03
bluetoothd[18792]: src/main.c:main() Entering main loop
bluetoothd[18792]: src/rfkill.c:rfkill_event() RFKILL event idx 0 type 1 op 0 soft 0 hard 0
bluetoothd[18792]: Bluetooth management interface 1.10 initialized
bluetoothd[18792]: src/adapter.c:read_version_complete() sending read supported commands command
bluetoothd[18792]: src/adapter.c:read_version_complete() sending read index list command
bluetoothd[18792]: src/rfkill.c:rfkill_event() RFKILL event idx 1 type 2 op 0 soft 0 hard 0
bluetoothd[18792]: src/rfkill.c:rfkill_event() RFKILL event idx 2 type 1 op 0 soft 0 hard 0
bluetoothd[18792]: src/adapter.c:read_commands_complete() Number of commands: 61
bluetoothd[18792]: src/adapter.c:read_commands_complete() Number of events: 34
bluetoothd[18792]: src/adapter.c:read_commands_complete() enabling kernel-side connection control
bluetoothd[18792]: src/rfkill.c:rfkill_event() RFKILL event idx 3 type 2 op 0 soft 0 hard 0
bluetoothd[18792]: src/adapter.c:read_index_list_complete() Number of controllers: 1
bluetoothd[18792]: src/adapter.c:read_index_list_complete() Found index 0
bluetoothd[18792]: src/adapter.c:index_added() index 0
bluetoothd[18792]: src/adapter.c:btd_adapter_new() System name: BlueZ 5.47
bluetoothd[18792]: src/adapter.c:btd_adapter_new() Major class: 0
bluetoothd[18792]: src/adapter.c:btd_adapter_new() Minor class: 0
bluetoothd[18792]: src/adapter.c:btd_adapter_new() Modalias: usb:v1D6Bp0246d052F
bluetoothd[18792]: src/adapter.c:btd_adapter_new() Discoverable timeout: 180 seconds
bluetoothd[18792]: src/adapter.c:btd_adapter_new() Pairable timeout: 0 seconds
bluetoothd[18792]: src/adapter.c:index_added() sending read info command for index 0
bluetoothd[18792]: src/adapter.c:read_info_complete() index 0 status 0x00
bluetoothd[18792]: src/adapter.c:clear_uuids() sending clear uuids command for index 0
bluetoothd[18792]: src/adapter.c:clear_devices() sending clear devices command for index 0
bluetoothd[18792]: src/adapter.c:set_privacy() sending set privacy command for index 0
bluetoothd[18792]: src/adapter.c:set_privacy() setting privacy mode 0x00 for index 0
bluetoothd[18792]: src/gatt-database.c:btd_gatt_database_new() GATT Manager registered for adapter: /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10001
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000007-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001800-0000-1000-8000-00805f9
bluetoothd[18792]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[18792]: src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10002
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000007-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001801-0000-1000-8000-00805f9
bluetoothd[18792]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[18792]: profiles/audio/a2dp.c:media_server_probe() path /org/bluez/hci0
bluetoothd[18792]: plugins/hostname.c:hostname_probe()
bluetoothd[18792]: plugins/hostname.c:update_class() major: 0x01 minor: 0x03
bluetoothd[18792]: src/adapter.c:btd_adapter_set_class() class: major 1 minor 3
bluetoothd[18792]: src/adapter.c:set_dev_class() sending set device class command for index 0
bluetoothd[18792]: profiles/network/manager.c:panu_server_probe() path /org/bluez/hci0
bluetoothd[18792]: profiles/network/server.c:server_register() Registered interface org.bluez.NetworkServer1 on path /org/bluez/hci0
bluetoothd[18792]: profiles/network/manager.c:gn_server_probe() path /org/bluez/hci0
bluetoothd[18792]: profiles/network/manager.c:nap_server_probe() path /org/bluez/hci0
bluetoothd[18792]: profiles/audio/avrcp.c:avrcp_controller_server_probe() path /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10003
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000017-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110e-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110f-0000-1000-8000-00805f9
bluetoothd[18792]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[18792]: profiles/audio/avrcp.c:avrcp_target_server_probe() path /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10004
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000017-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110c-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110e-0000-1000-8000-00805f9
bluetoothd[18792]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[18792]: profiles/audio/a2dp.c:a2dp_sink_server_probe() path /org/bluez/hci0
bluetoothd[18792]: profiles/audio/a2dp.c:a2dp_source_server_probe() path /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:btd_adapter_unblock_address() hci0 00:00:00:00:00:00
bluetoothd[18792]: src/adapter.c:get_ltk_info() C8:69:CD:72:8D:72
bluetoothd[18792]: src/device.c:device_create_from_storage() address C8:69:CD:72:8D:72
bluetoothd[18792]: src/device.c:device_new() address C8:69:CD:72:8D:72
bluetoothd[18792]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_C8_69_CD_72_8D_72
bluetoothd[18792]: src/device.c:btd_device_set_temporary() temporary 0
bluetoothd[18792]: src/adapter.c:get_ltk_info() 78:C3:E9:B0:3A:5D
bluetoothd[18792]: src/device.c:device_create_from_storage() address 78:C3:E9:B0:3A:5D
bluetoothd[18792]: src/device.c:device_new() address 78:C3:E9:B0:3A:5D
bluetoothd[18792]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_78_C3_E9_B0_3A_5D
bluetoothd[18792]: src/device.c:btd_device_set_temporary() temporary 0
bluetoothd[18792]: src/device.c:device_set_bonded()
bluetoothd[18792]: src/adapter.c:get_ltk_info() 04:5D:4B:53:8F:1D
bluetoothd[18792]: src/device.c:device_create_from_storage() address 04:5D:4B:53:8F:1D
bluetoothd[18792]: src/device.c:device_new() address 04:5D:4B:53:8F:1D
bluetoothd[18792]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_04_5D_4B_53_8F_1D
bluetoothd[18792]: src/device.c:btd_device_set_temporary() temporary 0
bluetoothd[18792]: src/device.c:device_set_bonded()
bluetoothd[18792]: src/adapter.c:get_ltk_info() CC:79:4A:E3:25:4A
bluetoothd[18792]: src/device.c:device_create_from_storage() address CC:79:4A:E3:25:4A
bluetoothd[18792]: src/device.c:device_new() address CC:79:4A:E3:25:4A
bluetoothd[18792]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_CC_79_4A_E3_25_4A
bluetoothd[18792]: src/device.c:btd_device_set_temporary() temporary 0
bluetoothd[18792]: src/device.c:device_set_bonded()
bluetoothd[18792]: src/adapter.c:load_link_keys() hci0 keys 3 debug_keys 0
bluetoothd[18792]: src/adapter.c:load_ltks() hci0 keys 0
bluetoothd[18792]: src/adapter.c:load_irks() hci0 irks 0
bluetoothd[18792]: src/adapter.c:load_conn_params() hci0 conn params 0
bluetoothd[18792]: src/device.c:device_probe_profiles() Probing profiles for device C8:69:CD:72:8D:72
bluetoothd[18792]: src/device.c:device_probe_profiles() Probing profiles for device 78:C3:E9:B0:3A:5D
bluetoothd[18792]: profiles/gap/gas.c:gap_probe() GAP profile probe (78:C3:E9:B0:3A:5D)
bluetoothd[18792]: src/service.c:change_state() 0x717360: device 78:C3:E9:B0:3A:5D profile gap-profile state changed: unavailable -> disconnected (0)
bluetoothd[18792]: profiles/network/connection.c:connection_register() /org/bluez/hci0/dev_78_C3_E9_B0_3A_5D id 4373
bluetoothd[18792]: profiles/network/connection.c:create_peer() Registered interface org.bluez.Network1 on path /org/bluez/hci0/dev_78_C3_E9_B0_3A_5D
bluetoothd[18792]: src/service.c:btd_service_ref() 0x7173c0: ref=2
bluetoothd[18792]: profiles/network/connection.c:connection_register() id 4373 registered
bluetoothd[18792]: src/service.c:change_state() 0x7173c0: device 78:C3:E9:B0:3A:5D profile network-panu state changed: unavailable -> disconnected (0)
bluetoothd[18792]: profiles/network/connection.c:connection_register() /org/bluez/hci0/dev_78_C3_E9_B0_3A_5D id 4374
bluetoothd[18792]: src/service.c:btd_service_ref() 0x721970: ref=2
bluetoothd[18792]: profiles/network/connection.c:connection_register() id 4374 registered
bluetoothd[18792]: src/service.c:change_state() 0x721970: device 78:C3:E9:B0:3A:5D profile network-nap state changed: unavailable -> disconnected (0)
bluetoothd[18792]: profiles/audio/avrcp.c:avrcp_target_probe() path /org/bluez/hci0/dev_78_C3_E9_B0_3A_5D
bluetoothd[18792]: profiles/audio/control.c:control_init() Registered interface org.bluez.MediaControl1 on path /org/bluez/hci0/dev_78_C3_E9_B0_3A_5D
bluetoothd[18792]: src/service.c:btd_service_ref() 0x71fd10: ref=2
bluetoothd[18792]: src/service.c:change_state() 0x71fd10: device 78:C3:E9:B0:3A:5D profile audio-avrcp-target state changed: unavailable -> disconnected (0)
bluetoothd[18792]: profiles/audio/a2dp.c:a2dp_source_probe() path /org/bluez/hci0/dev_78_C3_E9_B0_3A_5D
bluetoothd[18792]: profiles/audio/source.c:source_init() /org/bluez/hci0/dev_78_C3_E9_B0_3A_5D
bluetoothd[18792]: src/service.c:btd_service_ref() 0x7234e0: ref=2
bluetoothd[18792]: src/service.c:change_state() 0x7234e0: device 78:C3:E9:B0:3A:5D profile a2dp-source state changed: unavailable -> disconnected (0)
bluetoothd[18792]: src/device.c:device_probe_profiles() Probing profiles for device 04:5D:4B:53:8F:1D
bluetoothd[18792]: profiles/audio/avrcp.c:avrcp_controller_probe() path /org/bluez/hci0/dev_04_5D_4B_53_8F_1D
bluetoothd[18792]: profiles/audio/control.c:control_init() Registered interface org.bluez.MediaControl1 on path /org/bluez/hci0/dev_04_5D_4B_53_8F_1D
bluetoothd[18792]: src/service.c:btd_service_ref() 0x723690: ref=2
bluetoothd[18792]: src/service.c:change_state() 0x723690: device 04:5D:4B:53:8F:1D profile avrcp-controller state changed: unavailable -> disconnected (0)
bluetoothd[18792]: profiles/audio/avrcp.c:avrcp_target_probe() path /org/bluez/hci0/dev_04_5D_4B_53_8F_1D
bluetoothd[18792]: src/service.c:btd_service_ref() 0x722760: ref=2
bluetoothd[18792]: src/service.c:change_state() 0x722760: device 04:5D:4B:53:8F:1D profile audio-avrcp-target state changed: unavailable -> disconnected (0)
bluetoothd[18792]: profiles/audio/a2dp.c:a2dp_sink_probe() path /org/bluez/hci0/dev_04_5D_4B_53_8F_1D
bluetoothd[18792]: profiles/audio/sink.c:sink_init() /org/bluez/hci0/dev_04_5D_4B_53_8F_1D
bluetoothd[18792]: src/service.c:btd_service_ref() 0x722790: ref=2
bluetoothd[18792]: src/service.c:change_state() 0x722790: device 04:5D:4B:53:8F:1D profile a2dp-sink state changed: unavailable -> disconnected (0)
bluetoothd[18792]: src/device.c:device_probe_profiles() Probing profiles for device CC:79:4A:E3:25:4A
bluetoothd[18792]: profiles/gap/gas.c:gap_probe() GAP profile probe (CC:79:4A:E3:25:4A)
bluetoothd[18792]: src/service.c:change_state() 0x714e60: device CC:79:4A:E3:25:4A profile gap-profile state changed: unavailable -> disconnected (0)
bluetoothd[18792]: profiles/network/connection.c:connection_register() /org/bluez/hci0/dev_CC_79_4A_E3_25_4A id 4373
bluetoothd[18792]: profiles/network/connection.c:create_peer() Registered interface org.bluez.Network1 on path /org/bluez/hci0/dev_CC_79_4A_E3_25_4A
bluetoothd[18792]: src/service.c:btd_service_ref() 0x714c20: ref=2
bluetoothd[18792]: profiles/network/connection.c:connection_register() id 4373 registered
bluetoothd[18792]: src/service.c:change_state() 0x714c20: device CC:79:4A:E3:25:4A profile network-panu state changed: unavailable -> disconnected (0)
bluetoothd[18792]: profiles/network/connection.c:connection_register() /org/bluez/hci0/dev_CC_79_4A_E3_25_4A id 4374
bluetoothd[18792]: src/service.c:btd_service_ref() 0x7235a0: ref=2
bluetoothd[18792]: profiles/network/connection.c:connection_register() id 4374 registered
bluetoothd[18792]: src/service.c:change_state() 0x7235a0: device CC:79:4A:E3:25:4A profile network-nap state changed: unavailable -> disconnected (0)
bluetoothd[18792]: profiles/audio/avrcp.c:avrcp_target_probe() path /org/bluez/hci0/dev_CC_79_4A_E3_25_4A
bluetoothd[18792]: profiles/audio/control.c:control_init() Registered interface org.bluez.MediaControl1 on path /org/bluez/hci0/dev_CC_79_4A_E3_25_4A
bluetoothd[18792]: src/service.c:btd_service_ref() 0x723bc0: ref=2
bluetoothd[18792]: src/service.c:change_state() 0x723bc0: device CC:79:4A:E3:25:4A profile audio-avrcp-target state changed: unavailable -> disconnected (0)
bluetoothd[18792]: profiles/audio/a2dp.c:a2dp_source_probe() path /org/bluez/hci0/dev_CC_79_4A_E3_25_4A
bluetoothd[18792]: profiles/audio/source.c:source_init() /org/bluez/hci0/dev_CC_79_4A_E3_25_4A
bluetoothd[18792]: src/service.c:btd_service_ref() 0x714c50: ref=2
bluetoothd[18792]: src/service.c:change_state() 0x714c50: device CC:79:4A:E3:25:4A profile a2dp-source state changed: unavailable -> disconnected (0)
bluetoothd[18792]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[18792]: src/adapter.c:set_did() hci0 source 2 vendor 1d6b product 246 version 52f
bluetoothd[18792]: src/adapter.c:adapter_register() Adapter /org/bluez/hci0 registered
bluetoothd[18792]: src/adapter.c:set_dev_class() sending set device class command for index 0
bluetoothd[18792]: src/adapter.c:set_name() sending set local name command for index 0
bluetoothd[18792]: src/adapter.c:set_privacy_complete() Successfuly set privacy for index 0
bluetoothd[18792]: src/adapter.c:add_whitelist_complete() C8:69:CD:72:8D:72 added to kernel whitelist
bluetoothd[18792]: src/adapter.c:add_whitelist_complete() 78:C3:E9:B0:3A:5D added to kernel whitelist
bluetoothd[18792]: src/adapter.c:add_whitelist_complete() 04:5D:4B:53:8F:1D added to kernel whitelist
bluetoothd[18792]: src/adapter.c:add_whitelist_complete() CC:79:4A:E3:25:4A added to kernel whitelist
bluetoothd[18792]: src/adapter.c:load_link_keys_complete() link keys loaded for hci0
bluetoothd[18792]: src/adapter.c:load_ltks_complete() LTKs loaded for hci0
bluetoothd[18792]: src/adapter.c:load_irks_complete() IRKs loaded for hci0
bluetoothd[18792]: src/adapter.c:load_conn_params_complete() Connection Parameters loaded for hci0
bluetoothd[18792]: src/profile.c:register_profile() sender :1.120
bluetoothd[18792]: src/profile.c:create_ext() Created ":1.120/HSP/Headset/00001108-0000-1000-8000-00805F9B34FB"
bluetoothd[18792]: src/profile.c:ext_adapter_probe() ":1.120/HSP/Headset/00001108-0000-1000-8000-00805F9B34FB" probed
bluetoothd[18792]: src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10005
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001108-0000-1000-8000-00805f9
bluetoothd[18792]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[18792]: src/profile.c:ext_device_probe() :1.120/HSP/Headset/00001108-0000-1000-8000-00805F9B34FB probed with UUID 00001108-0000-1000-8000-00805F9B34FB
bluetoothd[18792]: src/service.c:change_state() 0x717590: device 04:5D:4B:53:8F:1D profile :1.120/HSP/Headset/00001108-0000-1000-8000-00805F9B34FB state changed: unavailable -> disconnected (0)
bluetoothd[18792]: src/agent.c:agent_ref() 0x713af0: ref=1
bluetoothd[18792]: src/agent.c:register_agent() agent :1.121
bluetoothd[18792]: src/profile.c:register_profile() sender :1.120
bluetoothd[18792]: src/profile.c:create_ext() Created "Headset Voice gateway"
bluetoothd[18792]: src/profile.c:ext_adapter_probe() "Headset Voice gateway" probed
bluetoothd[18792]: src/profile.c:ext_start_servers() Headset Voice gateway listening on chan 12
bluetoothd[18792]: src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10006
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000003-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001108-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001112-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001203-0000-1000-8000-00805f9
bluetoothd[18792]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[18792]: src/profile.c:ext_device_probe() Headset Voice gateway probed with UUID 00001108-0000-1000-8000-00805f9b34fb
bluetoothd[18792]: src/service.c:change_state() 0x70b2a0: device 04:5D:4B:53:8F:1D profile Headset Voice gateway state changed: unavailable -> disconnected (0)
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_register_sep() SEP 0x70d490 registered: type:0 codec:0 seid:1
bluetoothd[18792]: src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10007
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000019-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110a-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110d-0000-1000-8000-00805f9
bluetoothd[18792]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[18792]: Endpoint registered: sender=:1.120 path=/A2DP/SBC/Source/1
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_register_sep() SEP 0x70bec0 registered: type:1 codec:0 seid:2
bluetoothd[18792]: src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10008
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000019-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110b-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110d-0000-1000-8000-00805f9
bluetoothd[18792]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[18792]: Endpoint registered: sender=:1.120 path=/A2DP/SBC/Sink/1
bluetoothd[18792]: src/profile.c:ext_exited() ":1.120/HSP/Headset/00001108-0000-1000-8000-00805F9B34FB" exited
bluetoothd[18792]: src/service.c:change_state() 0x717590: device 04:5D:4B:53:8F:1D profile :1.120/HSP/Headset/00001108-0000-1000-8000-00805F9B34FB state changed: disconnected -> unavailable (0)
bluetoothd[18792]: src/profile.c:ext_device_remove() :1.120/HSP/Headset/00001108-0000-1000-8000-00805F9B34FB
bluetoothd[18792]: src/service.c:btd_service_unref() 0x717590: ref=0
bluetoothd[18792]: src/profile.c:ext_adapter_remove() ":1.120/HSP/Headset/00001108-0000-1000-8000-00805F9B34FB" removed
bluetoothd[18792]: src/adapter.c:adapter_service_remove() /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:remove_uuid() sending remove uuid command for index 0
bluetoothd[18792]: src/sdpd-service.c:remove_record_from_server() Removing record with handle 0x10005
bluetoothd[18792]: src/profile.c:remove_ext() Removed ":1.120/HSP/Headset/00001108-0000-1000-8000-00805F9B34FB"
bluetoothd[18792]: src/profile.c:ext_exited() "Headset Voice gateway" exited
bluetoothd[18792]: src/service.c:change_state() 0x70b2a0: device 04:5D:4B:53:8F:1D profile Headset Voice gateway state changed: disconnected -> unavailable (0)
bluetoothd[18792]: src/profile.c:ext_device_remove() Headset Voice gateway
bluetoothd[18792]: src/service.c:btd_service_unref() 0x70b2a0: ref=0
bluetoothd[18792]: src/profile.c:ext_adapter_remove() "Headset Voice gateway" removed
bluetoothd[18792]: src/adapter.c:adapter_service_remove() /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:remove_uuid() sending remove uuid command for index 0
bluetoothd[18792]: src/sdpd-service.c:remove_record_from_server() Removing record with handle 0x10006
bluetoothd[18792]: src/profile.c:remove_ext() Removed "Headset Voice gateway"
bluetoothd[18792]: src/adapter.c:adapter_service_remove() /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:remove_uuid() sending remove uuid command for index 0
bluetoothd[18792]: src/sdpd-service.c:remove_record_from_server() Removing record with handle 0x10007
bluetoothd[18792]: profiles/audio/media.c:release_endpoint() sender=:1.120 path=/A2DP/SBC/Source/1
bluetoothd[18792]: Endpoint unregistered: sender=:1.120 path=/A2DP/SBC/Source/1
bluetoothd[18792]: profiles/audio/media.c:media_endpoint_destroy() sender=:1.120 path=/A2DP/SBC/Source/1
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_unregister_sep() SEP 0x70d490 unregistered: type:0 codec:0 seid:1
bluetoothd[18792]: src/adapter.c:adapter_service_remove() /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:remove_uuid() sending remove uuid command for index 0
bluetoothd[18792]: src/sdpd-service.c:remove_record_from_server() Removing record with handle 0x10008
bluetoothd[18792]: profiles/audio/media.c:release_endpoint() sender=:1.120 path=/A2DP/SBC/Sink/1
bluetoothd[18792]: Endpoint unregistered: sender=:1.120 path=/A2DP/SBC/Sink/1
bluetoothd[18792]: profiles/audio/media.c:media_endpoint_destroy() sender=:1.120 path=/A2DP/SBC/Sink/1
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_unregister_sep() SEP 0x70bec0 unregistered: type:1 codec:0 seid:2
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_register_sep() SEP 0x720210 registered: type:0 codec:0 seid:1
bluetoothd[18792]: src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10005
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000019-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110a-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110d-0000-1000-8000-00805f9
bluetoothd[18792]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[18792]: Endpoint registered: sender=:1.124 path=/A2DP/SBC/Source/1
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_register_sep() SEP 0x7220a0 registered: type:1 codec:0 seid:2
bluetoothd[18792]: src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10006
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000019-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110b-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110d-0000-1000-8000-00805f9
bluetoothd[18792]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[18792]: Endpoint registered: sender=:1.124 path=/A2DP/SBC/Sink/1
bluetoothd[18792]: src/profile.c:register_profile() sender :1.124
bluetoothd[18792]: src/profile.c:create_ext() Created ":1.124/HSP/Headset/00001108-0000-1000-8000-00805F9B34FB"
bluetoothd[18792]: src/profile.c:ext_adapter_probe() ":1.124/HSP/Headset/00001108-0000-1000-8000-00805F9B34FB" probed
bluetoothd[18792]: src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10007
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001108-0000-1000-8000-00805f9
bluetoothd[18792]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[18792]: src/profile.c:ext_device_probe() :1.124/HSP/Headset/00001108-0000-1000-8000-00805F9B34FB probed with UUID 00001108-0000-1000-8000-00805F9B34FB
bluetoothd[18792]: src/service.c:change_state() 0x711360: device 04:5D:4B:53:8F:1D profile :1.124/HSP/Headset/00001108-0000-1000-8000-00805F9B34FB state changed: unavailable -> disconnected (0)
bluetoothd[18792]: src/profile.c:register_profile() sender :1.124
bluetoothd[18792]: src/profile.c:create_ext() Created "Headset Voice gateway"
bluetoothd[18792]: src/profile.c:ext_adapter_probe() "Headset Voice gateway" probed
bluetoothd[18792]: src/profile.c:ext_start_servers() Headset Voice gateway listening on chan 12
bluetoothd[18792]: src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10008
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000003-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001108-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001112-0000-1000-8000-00805f9
bluetoothd[18792]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001203-0000-1000-8000-00805f9
bluetoothd[18792]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[18792]: src/adapter.c:add_uuid() sending add uuid command for index 0
bluetoothd[18792]: src/profile.c:ext_device_probe() Headset Voice gateway probed with UUID 00001108-0000-1000-8000-00805f9b34fb
bluetoothd[18792]: src/service.c:change_state() 0x720c20: device 04:5D:4B:53:8F:1D profile Headset Voice gateway state changed: unavailable -> disconnected (0)
bluetoothd[18792]: src/agent.c:agent_disconnect() Agent :1.121 disconnected
bluetoothd[18792]: src/agent.c:agent_destroy() agent :1.121
bluetoothd[18792]: src/agent.c:agent_unref() 0x713af0: ref=0
bluetoothd[18792]: src/agent.c:agent_ref() 0x713af0: ref=1
bluetoothd[18792]: src/agent.c:register_agent() agent :1.125
bluetoothd[18792]: src/adapter.c:property_set_mode() sending Set Powered command for index 0
bluetoothd[18792]: src/adapter.c:dev_class_changed_callback() Class: 0x0c010c
bluetoothd[18792]: src/adapter.c:property_set_mode_complete() Success (0x00)
bluetoothd[18792]: src/adapter.c:new_settings_callback() Settings: 0x00000ad1
bluetoothd[18792]: src/adapter.c:settings_changed() Changed settings: 0x00000001
bluetoothd[18792]: src/adapter.c:adapter_start() adapter /org/bluez/hci0 has been enabled
bluetoothd[18792]: src/adapter.c:trigger_passive_scanning()
bluetoothd[18792]: src/device.c:connect_profiles() /org/bluez/hci0/dev_04_5D_4B_53_8F_1D (all), client :1.125
bluetoothd[18792]: src/service.c:btd_service_ref() 0x720c20: ref=2
bluetoothd[18792]: src/service.c:change_state() 0x720c20: device 04:5D:4B:53:8F:1D profile Headset Voice gateway state changed: disconnected -> connecting (0)
bluetoothd[18792]: src/adapter.c:connected_callback() hci0 device 04:5D:4B:53:8F:1D connected eir_len 13
bluetoothd[18792]: src/profile.c:ext_connect() Headset Voice gateway connected to 04:5D:4B:53:8F:1D
bluetoothd[18792]: src/service.c:change_state() 0x720c20: device 04:5D:4B:53:8F:1D profile Headset Voice gateway state changed: connecting -> connected (0)
bluetoothd[18792]: src/device.c:device_profile_connected() Headset Voice gateway Success (0)
bluetoothd[18792]: profiles/audio/a2dp.c:a2dp_sink_connect() path /org/bluez/hci0/dev_04_5D_4B_53_8F_1D
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_ref() 0x727270: ref=1
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_ref() 0x727270: ref=2
bluetoothd[18792]: profiles/audio/a2dp.c:setup_ref() 0x727700: ref=1
bluetoothd[18792]: profiles/audio/sink.c:sink_set_state() State changed /org/bluez/hci0/dev_04_5D_4B_53_8F_1D: SINK_STATE_DISCONNECTED -> SINK_STATE_CONNECTING
bluetoothd[18792]: profiles/audio/sink.c:sink_connect() stream creation in progress
bluetoothd[18792]: src/service.c:change_state() 0x722790: device 04:5D:4B:53:8F:1D profile a2dp-sink state changed: disconnected -> connecting (0)
bluetoothd[18792]: src/service.c:btd_service_ref() 0x720c20: ref=3
bluetoothd[18792]: plugins/policy.c:service_cb() Added Headset Voice gateway reconnect 0
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP: connected signaling channel to 04:5D:4B:53:8F:1D
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP imtu=672, omtu=895
bluetoothd[18792]: profiles/audio/avdtp.c:session_cb()
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_parse_resp() DISCOVER request succeeded
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 1 type 1 media 0 in use 0
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 3 type 1 media 0 in use 0
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 2 type 1 media 0 in use 0
bluetoothd[18792]: profiles/audio/avdtp.c:session_cb()
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_parse_resp() GET_ALL_CAPABILITIES request succeeded
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_get_capabilities_resp() seid 1 type 1 media 0
bluetoothd[18792]: profiles/audio/avdtp.c:session_cb()
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_parse_resp() GET_ALL_CAPABILITIES request succeeded
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_get_capabilities_resp() seid 3 type 1 media 0
bluetoothd[18792]: profiles/audio/avdtp.c:session_cb()
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_parse_resp() GET_ALL_CAPABILITIES request succeeded
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_get_capabilities_resp() seid 2 type 1 media 0
bluetoothd[18792]: profiles/audio/a2dp.c:discover_cb() err (nil)
bluetoothd[18792]: profiles/audio/sink.c:discovery_complete() Discovery complete
bluetoothd[18792]: profiles/audio/a2dp.c:setup_ref() 0x727700: ref=2
bluetoothd[18792]: profiles/audio/a2dp.c:setup_ref() 0x727700: ref=3
bluetoothd[18792]: profiles/audio/media.c:media_endpoint_async_call() Calling SelectConfiguration: name = :1.124 path = /A2DP/SBC/Source/1
bluetoothd[18792]: profiles/audio/a2dp.c:setup_unref() 0x727700: ref=2
bluetoothd[18792]: profiles/audio/a2dp.c:a2dp_config() a2dp_config: selected SEP 0x720210
bluetoothd[18792]: profiles/audio/a2dp.c:setup_ref() 0x727700: ref=3
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_set_configuration() 0x727270: int_seid=1, acp_seid=1
bluetoothd[18792]: profiles/audio/a2dp.c:setup_unref() 0x727700: ref=2
bluetoothd[18792]: profiles/audio/a2dp.c:setup_unref() 0x727700: ref=1
bluetoothd[18792]: profiles/audio/avdtp.c:session_cb()
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_parse_resp() SET_CONFIGURATION request succeeded
bluetoothd[18792]: profiles/audio/a2dp.c:setconf_cfm() Source 0x720210: Set_Configuration_Cfm
bluetoothd[18792]: profiles/audio/a2dp.c:setup_ref() 0x727700: ref=2
bluetoothd[18792]: profiles/audio/media.c:media_endpoint_async_call() Calling SetConfiguration: name = :1.124 path = /A2DP/SBC/Source/1
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: IDLE -> CONFIGURED
bluetoothd[18792]: profiles/audio/a2dp.c:setup_unref() 0x727700: ref=1
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_register_sep() SEP 0x71be10 registered: type:0 codec:0 seid:3
bluetoothd[18792]: Endpoint registered: sender=:1.124 path=/A2DP/SBC/Source/2
bluetoothd[18792]: profiles/audio/avdtp.c:session_cb()
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_parse_resp() OPEN request succeeded
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP: connected transport channel to 04:5D:4B:53:8F:1D
bluetoothd[18792]: profiles/audio/avdtp.c:handle_transport_connect() Flushable packets enabled
bluetoothd[18792]: profiles/audio/avdtp.c:handle_transport_connect() sk 23, omtu 895, send buffer size 106496
bluetoothd[18792]: profiles/audio/a2dp.c:open_cfm() Source 0x720210: Open_Cfm
bluetoothd[18792]: profiles/audio/a2dp.c:setup_unref() 0x727700: ref=0
bluetoothd[18792]: profiles/audio/a2dp.c:setup_free() 0x727700
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_unref() 0x727270: ref=1
bluetoothd[18792]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: CONFIGURED -> OPEN
bluetoothd[18792]: src/service.c:change_state() 0x722790: device 04:5D:4B:53:8F:1D profile a2dp-sink state changed: connecting -> connected (0)
bluetoothd[18792]: src/device.c:device_profile_connected() a2dp-sink Success (0)
bluetoothd[18792]: src/device.c:device_profile_connected() returning response to :1.125
bluetoothd[18792]: plugins/policy.c:policy_connect() /org/bluez/hci0/dev_04_5D_4B_53_8F_1D profile avrcp-controller
bluetoothd[18792]: profiles/audio/avrcp.c:avrcp_connect() path /org/bluez/hci0/dev_04_5D_4B_53_8F_1D
bluetoothd[18792]: profiles/audio/avctp.c:avctp_set_state() AVCTP Connecting
bluetoothd[18792]: src/service.c:change_state() 0x723690: device 04:5D:4B:53:8F:1D profile avrcp-controller state changed: disconnected -> connecting (0)
bluetoothd[18792]: src/service.c:btd_service_ref() 0x722790: ref=3
bluetoothd[18792]: plugins/policy.c:service_cb() Added a2dp-sink reconnect 0
bluetoothd[18792]: profiles/audio/sink.c:sink_set_state() State changed /org/bluez/hci0/dev_04_5D_4B_53_8F_1D: SINK_STATE_CONNECTING -> SINK_STATE_CONNECTED
bluetoothd[18792]: profiles/audio/transport.c:transport_update_playing() /org/bluez/hci0/dev_04_5D_4B_53_8F_1D/fd0 State=TRANSPORT_STATE_IDLE Playing=0
bluetoothd[18792]: profiles/audio/avctp.c:avctp_connect_cb() AVCTP: connected to 04:5D:4B:53:8F:1D
bluetoothd[18792]: profiles/audio/avctp.c:init_uinput() AVRCP: uinput initialized for 04:5D:4B:53:8F:1D
bluetoothd[18792]: profiles/audio/avrcp.c:controller_init() 0x7180e0 version 0x0105
bluetoothd[18792]: src/service.c:change_state() 0x722760: device 04:5D:4B:53:8F:1D profile audio-avrcp-target state changed: disconnected -> connected (0)
bluetoothd[18792]: profiles/audio/avrcp.c:target_init() 0x71c0e0 version 0x0105
bluetoothd[18792]: src/service.c:change_state() 0x723690: device 04:5D:4B:53:8F:1D profile avrcp-controller state changed: connecting -> connected (0)
bluetoothd[18792]: src/device.c:device_profile_connected() avrcp-controller Success (0)
bluetoothd[18792]: profiles/audio/avctp.c:avctp_set_state() AVCTP Connected
bluetoothd[18792]: src/device.c:search_cb() 04:5D:4B:53:8F:1D: No service update
bluetoothd[18792]: src/device.c:device_svc_resolved() /org/bluez/hci0/dev_04_5D_4B_53_8F_1D err 0

=====
sudo bluealsa --disable-hfp

bluealsa: ctl.c:489: Starting controller loop
bluealsa: bluez.c:680: Registering endpoint: /A2DP/SBC/Source/1
bluealsa: bluez.c:680: Registering endpoint: /A2DP/SBC/Sink/1
bluealsa: bluez.c:910: Registering profile: /HSP/Headset
bluealsa: bluez.c:910: Registering profile: /HSP/AudioGateway
bluealsa: main.c:225: Starting main dispatching loop
bluealsa: bluez.c:857: Profile method call: org.bluez.Profile1.NewConnection()
bluealsa: transport.c:655: State transition: 0 -> 2
bluealsa: transport.c:104: Created new IO thread: HSP Audio Gateway
bluealsa: bluez.c:797: HSP Audio Gateway configured for device 04:5D:4B:53:8F:1D
bluealsa: io.c:1172: Starting IO loop: HSP Audio Gateway
bluealsa: transport.c:655: State transition: 0 -> 2
bluealsa: ctl.c:571: +-+-
bluealsa: transport.c:104: Created new IO thread: HSP Audio Gateway
bluealsa: rfcomm.c:553: Starting RFCOMM loop: HSP Audio Gateway
bluealsa: bluez.c:609: Endpoint method call: org.bluez.MediaEndpoint1.SelectConfiguration()
bluealsa: bluez.c:609: Endpoint method call: org.bluez.MediaEndpoint1.SetConfiguration()
bluealsa: bluez.c:541: A2DP Source (SBC) configured for device 04:5D:4B:53:8F:1D
bluealsa: bluez.c:543: Configuration: channels: 2, sampling: 48000
bluealsa: transport.c:655: State transition: 0 -> 0
bluealsa: ctl.c:571: +-+-
bluealsa: bluez.c:680: Registering endpoint: /A2DP/SBC/Source/2
bluealsa: bluez.c:663: Endpoint already registered: /A2DP/SBC/Sink/1
bluealsa: ctl.c:550: New client accepted: 13
bluealsa: ctl.c:571: +-+-
bluealsa: ctl.c:571: +-+-
bluealsa: ctl.c:326: PCM requested for 04:5D:4B:53:8F:1D type 2 stream 1
bluealsa: ctl.c:571: +-+-
bluealsa: io.c:54: Opening FIFO for reading: (null)
bluealsa: io.c:75: Opening FIFO for writing: /var/run/bluealsa/hci0-04:5D:4B:53:8F:1D-2-1
bluealsa: ctl.c:571: +-+-
bluealsa: transport.c:655: State transition: 2 -> 2
bluealsa: ctl.c:571: +-+-
bluealsa: transport.c:873: New SCO link: 15 (MTU: R:48 W:48)
bluealsa: io.c:54: Opening FIFO for reading: (null)
bluealsa: at.c:156: AT message: SET: command:+VGS, value:15
bluealsa: rfcomm.c:95: Sending AT message: RESP: command:(null), value:OK
bluealsa: ctl.c:571: +-+-
bluealsa: ctl.c:401: PCM close for 04:5D:4B:53:8F:1D type 2 stream 1
bluealsa: transport.c:906: Cleaning PCM FIFO: /var/run/bluealsa/hci0-04:5D:4B:53:8F:1D-2-1
bluealsa: transport.c:913: Closing PCM: 14
bluealsa: ctl.c:571: +-+-
bluealsa: io.c:54: Opening FIFO for reading: (null)
bluealsa: transport.c:883: Closing SCO: 15
bluealsa: ctl.c:521: Client closed connection: 13
bluealsa: ctl.c:571: +-+-

====
LIBASOUND_THREAD_SAFE=0 arecord -f S16_LE -d 3 ~/test.wav

../shared/ctl-client.c:102: Connecting to socket: /var/run/bluealsa/hci0
../shared/ctl-client.c:213: Getting transport for 04:5D:4B:53:8F:1D type 2
bluealsa-pcm.c:526: Setting constraints
Recording WAVE '/home/echo/test.wav' : Signed 16 bit Little Endian, Rate 8000 Hz, Mono
bluealsa-pcm.c:272: Initializing HW
../shared/ctl-client.c:302: Requesting PCM open for 04:5D:4B:53:8F:1D
../shared/ctl-client.c:321: Opening PCM FIFO (mode: RO): /var/run/bluealsa/hci0-04:5D:4B:53:8F:1D-2-1
bluealsa-pcm.c:301: Selected HW buffer: 4 periods x 2000 bytes == 8000 bytes
bluealsa-pcm.c:324: Initializing SW
bluealsa-pcm.c:324: Initializing SW
bluealsa-pcm.c:340: Prepared
bluealsa-pcm.c:324: Initializing SW
bluealsa-pcm.c:205: Starting
../shared/ctl-client.c:390: Requesting PCM resume for 04:5D:4B:53:8F:1D
^CAborted by signal Interrupción...
arecord: pcm_read:2096: read error: Llamada al sistema interrumpida
bluealsa-pcm.c:245: Stopping
bluealsa-pcm.c:308: Freeing HW
../shared/ctl-client.c:364: Closing PCM for 04:5D:4B:53:8F:1D
bluealsa-pcm.c:261: Closing plugin

====

ls -la test.wav
-rw-r--r-- 1 echo users 44 nov 8 23:34 test.wav

As you see I have that 44-byte issue, meaning that the .wav is empty. Even more, despite I say to record the audio only for 3 seconds, it doesn't stop it. Mind the

^CAborted by signal Interrupción...

If you could only give me a hint where to dig further that would be really great. Thanks!

from bluez-alsa.

I am sorry for the mess here. I hope you haven't lost your time trying to analyse the issue reported. It turns out the issue has nothing to do with BlueAlsa. I found a solution here:

https://forums.gentoo.org/viewtopic-p-7846088.html?sid=e055c02e2ca9e8edb99b75d9e24c97b8#7846088

The guy mentions another thread with the solution

https://forums.gentoo.org/viewtopic-p-6910768.html#6910768

which comes from a long and a more general discussion on the Arch forum

https://bbs.archlinux.org/viewtopic.php?id=91496

So, just for the record, just in case if somebody else struggling with the same issue. I had to enable CONFIG_USB_EHCI_TT_NEWSCHED in the kernel.

But anyway, thanks for a good software.

from bluez-alsa.

@keyoffecka
I am following the above configuration you got
defaults.bluealsa.interface "hci0"
defaults.bluealsa.device "00:1D:DF:F8:95:85"
defaults.bluealsa.profile "a2dp"
defaults.bluealsa.delay 10000

pcm.duplex {
type asym;
playback.pcm "bluz2";
capture.pcm "bluzmic"
}

pcm.!default {
type plug;
slave.pcm "duplex";
}

pcm.bluzmic {
type bluealsa
device "00:1D:DF:F8:95:85"
profile "sco"
"delay" 1000
}

pcm.bluz {
type plug
slave.pcm {
type bluealsa
device "00:1D:DF:F8:95:85"
profile "sco"
"delay" 1000
}
hint {
show on
description "BT Headset"
}
}

to use it with google assistant that is a full duplex audio application.

But although it can record being on profile sco, it does not automatically turn to a2dp when playing audio and nothing is heard

from bluez-alsa.