bluez-alsa: Unable to get connect data for Headset Voice gateway: getpeername: Transport endpoint is not connected (107)

I hate to open multiple issues at once, but I’m at the limit of what I can do to troubleshoot.

As part of troubleshooting for issue https://github.com/Arkq/bluez-alsa/issues/337, I upgraded to Ubuntu 20.04. Now when I try to connect my headset, it logs this error and sco profiles do not work to play sounds at all using aplay:

May 29 19:22:48 sen bluetoothd[118979]: Unable to get connect data for Headset Voice gateway: getpeername: Transport endpoint is not connected (107)

I enabled debug mode in bluealsa, and this is what it logged when it connected:

Expand

May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: bluez.c:1121: Called: org.bluez.Profile1.NewConnection()
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:1262: Created new RFCOMM thread [ba-rfcomm]: HFP Audio Gateway
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:880: Starting RFCOMM loop: HFP Audio Gateway
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: sco.c:202: Created SCO dispatcher [ba-sco-dispatch]: hci0
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: bluez.c:1044: HFP Audio Gateway configured for device 00:16:94:47:B6:F4
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-transport.c:701: State transition: 0 -> 2
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: sco.c:79: Starting SCO dispatcher loop: hci0
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-transport.c:975: Created new thread [ba-sco]: HFP Audio Gateway
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: sco.c:243: Starting SCO loop: HFP Audio Gateway
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: at.c:161: AT message: SET: command:+BRSF, value:155
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:107: Sending AT message: RESP: command:+BRSF, value:2784
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:107: Sending AT message: RESP: command:(null), value:OK
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:126: RFCOMM: HFP Audio Gateway state transition: 0 -> 2
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: at.c:161: AT message: SET: command:+BAC, value:1,2
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:107: Sending AT message: RESP: command:(null), value:OK
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:126: RFCOMM: HFP Audio Gateway state transition: 2 -> 3
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: bluez.c:1346: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded()
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: bluez.c:1424: Adding new Stream End-Point: 00:16:94:47:B6:F4: SBC
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: at.c:161: AT message: TEST: command:+CIND, value:(null)
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:107: Sending AT message: RESP: command:+CIND, value:("service",(0-1)),("call",(0,1)),("callsetup",(0-3)),("callheld",(0-2)),("signal",(0-5)),("roam",(0-1)),("battchg",(0-5))
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:107: Sending AT message: RESP: command:(null), value:OK
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:126: RFCOMM: HFP Audio Gateway state transition: 3 -> 5
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: bluez.c:796: Called: org.bluez.MediaEndpoint1.SelectConfiguration()
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: at.c:161: AT message: GET: command:+CIND, value:(null)
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:107: Sending AT message: RESP: command:+CIND, value:0,0,0,0,0,0,5
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:107: Sending AT message: RESP: command:(null), value:OK
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:126: RFCOMM: HFP Audio Gateway state transition: 5 -> 7
May 29 19:22:48 sen bluetoothd[118979]: Unable to get connect data for Headset Voice gateway: getpeername: Transport endpoint is not connected (107)
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: bluez.c:1346: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded()
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: bluez.c:796: Called: org.bluez.MediaEndpoint1.SetConfiguration()
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: bluez.c:565: Configuration: Selected A2DP SBC bit-pool range: [2, 53]
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: bluez.c:715: A2DP Source (SBC) configured for device 00:16:94:47:B6:F4
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: bluez.c:718: Configuration: channels: 2, sampling: 48000
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-transport.c:701: State transition: 0 -> 0
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: bluez.c:842: Creating media endpoint object: /org/bluez/hci0/A2DP/SBC/Source/3
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: bluez.c:871: Registering media endpoint: /org/bluez/hci0/A2DP/SBC/Source/3
May 29 19:22:48 sen bluetoothd[118979]: Endpoint registered: sender=:1.864 path=/org/bluez/hci0/A2DP/SBC/Source/3
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: at.c:161: AT message: SET: command:+CMER, value:3, 0, 0, 1
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:107: Sending AT message: RESP: command:(null), value:OK
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:126: RFCOMM: HFP Audio Gateway state transition: 7 -> 8
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:126: RFCOMM: HFP Audio Gateway state transition: 8 -> 9
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: at.c:161: AT message: SET: command:+BIA, value:0,1,1,1,0,0,0
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:107: Sending AT message: RESP: command:(null), value:OK
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: at.c:161: AT message: SET: command:+XAPL, value:1395-000b-1110,3
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:107: Sending AT message: RESP: command:(null), value:+XAPL=BlueALSA,6
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:107: Sending AT message: RESP: command:(null), value:OK
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: at.c:161: AT message: SET: command:+IPHONEACCEV, value:1,1,9
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:107: Sending AT message: RESP: command:(null), value:OK
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: at.c:161: AT message: SET: command:+IPHONEACCEV, value:1,2,0
May 29 19:22:48 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:107: Sending AT message: RESP: command:(null), value:OK
May 29 19:22:51 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:1085: RFCOMM poll timeout
May 29 19:22:51 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:724: RFCOMM: HFP Audio Gateway setting codec: mSBC
May 29 19:22:51 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:107: Sending AT message: RESP: command:+BCS, value:2
May 29 19:22:51 sen bluealsa[119196]: /usr/bin/bluealsa: D: at.c:161: AT message: SET: command:+BCS, value:2
May 29 19:22:51 sen bluealsa[119196]: /usr/bin/bluealsa: D: ba-rfcomm.c:107: Sending AT message: RESP: command:(null), value:OK

Curiously, so long as I run bluealsa either with no profiles explicitly selected or with a2dp selected, aplay is able to play .wavs just fine. It just seems like the Headset Voice Gateway profile has stopped working.

Hopefully if this issue gets resolved then the other issue will be resolved shortly as well.

About this issue

Original URL
State: closed
Created 4 years ago
Reactions: 7
Comments: 18 (2 by maintainers)

Most upvoted comments

Sep 02 11:15:55 kali bluetoothd[6658]: sap-server: Operation not permitted (1) Sep 02 11:15:55 kali bluetoothd[6658]: Endpoint registered: sender=:1.30 path=/MediaEndpoint/A2DPSink/sbc Sep 02 11:15:55 kali bluetoothd[6658]: Endpoint registered: sender=:1.30 path=/MediaEndpoint/A2DPSource/sbc Sep 02 11:16:18 kali bluetoothd[6658]: profiles/audio/a2dp.c:load_remote_sep() Unable to load LastUsed: lseid 4 not found Sep 02 11:16:18 kali bluetoothd[6658]: /org/bluez/hci1/dev_1C_52_16_14_2B_71/sep1/fd0: fd(75) ready Sep 02 11:32:31 kali bluetoothd[6658]: src/profile.c:ext_io_disconnected() Unable to get io data for Headset Voice gateway: getpeername: Transport endpo…nnected (107) Sep 02 11:32:54 kali bluetoothd[6658]: src/profile.c:ext_io_disconnected() Unable to get io data for Headset Voice gateway: getpeername: Transport endpo…nnected (107) Sep 02 11:35:26 kali bluetoothd[6658]: /org/bluez/hci1/dev_1C_52_16_14_2B_71/sep1/fd1: fd(72) ready Sep 02 12:02:05 kali bluetoothd[6658]: src/profile.c:ext_io_disconnected() Unable to get io data for Headset Voice gateway: getpeername: Transport endpo…nnected (107) Sep 02 14:12:38 kali bluetoothd[6658]: /org/bluez/hci1/dev_1C_52_16_14_2B_71/sep1/fd2: fd(72) ready Hint: Some lines were ellipsized, use -l to show in full. how to fix it someone help me

senthamil333 on Sep 2, 2021