Changed default configuration in bluez 4.97 and higher?

Changed default configuration in bluez 4.97 and higher?

[Karol Babioch]

[-- Attachment #1: Type: text/plain, Size: 1398 bytes --]

Hi,

I'm wondering whether there has been made any change to the default
configuration of bluez 4.97 and/or 4.98? I've looked at the changelog,
but combined with the release notes it looks to me as they were "simple"
bug fixing releases. At least there is no mention of a change of the
default configuration or something like that.

However I'm experiencing trouble with these releases using my bluetooth
headset (A2DP). To be honest I'm using gnome-bluetooth and not that
experienced with the command line tools, but starting with 4.97 it is no
longer possible to use my headset as an audio output device, although it
is possible to discover it and to connect to it. But after the
connection has been established it doesn't even get recognized by
PulseAudio.

I now got it working again by adding "Enable=Socket" in the audio.conf
file. However I'm not sure whether this was changed intentional, because
it worked out-of-the-box beforehand and I guess a basic A2DP setup is
supposed to do so.

There are at least two other guys affected by this, see [1]. So before
filing a bug in the bug tracking system of my distribution and asking
for inclusion of the above option, I would like to know why this was
changed and whether this is intentional or not.

[1]
https://mailman.archlinux.org/pipermail/arch-general/2012-January/024386.html

Best regards,
Karol Babioch


[-- Attachment #2: OpenPGP digital signature --]
[-- Type: application/pgp-signature, Size: 900 bytes --]

Re: Changed default configuration in bluez 4.97 and higher?

[Luiz Augusto von Dentz]

Hi Karol,

On Wed, Jan 25, 2012 at 8:37 PM, Karol Babioch <karol@babioch.de> wrote:
> Hi,
>
> I'm wondering whether there has been made any change to the default
> configuration of bluez 4.97 and/or 4.98? I've looked at the changelog,
> but combined with the release notes it looks to me as they were "simple"
> bug fixing releases. At least there is no mention of a change of the
> default configuration or something like that.
>
> However I'm experiencing trouble with these releases using my bluetooth
> headset (A2DP). To be honest I'm using gnome-bluetooth and not that
> experienced with the command line tools, but starting with 4.97 it is no
> longer possible to use my headset as an audio output device, although it
> is possible to discover it and to connect to it. But after the
> connection has been established it doesn't even get recognized by
> PulseAudio.
>
> I now got it working again by adding "Enable=Socket" in the audio.conf
> file. However I'm not sure whether this was changed intentional, because
> it worked out-of-the-box beforehand and I guess a basic A2DP setup is
> supposed to do so.
>
> There are at least two other guys affected by this, see [1]. So before
> filing a bug in the bug tracking system of my distribution and asking
> for inclusion of the above option, I would like to know why this was
> changed and whether this is intentional or not.

This is intentional as unix socket is considered deprecated now,
probably PA should be updated in your system as it seems it is lacking
support for Media D-Bus API which is now the default mechanism to
control A2DP stream. Note that Media API was introduced one year ago
and it is currently used in production in systems such as MeeGo
Harmattan aka Nokia N9.

-- 
Luiz Augusto von Dentz

Re: Changed default configuration in bluez 4.97 and higher?

[Karol Babioch]

[-- Attachment #1: Type: text/plain, Size: 394 bytes --]

Hi,

Am 26.01.2012 12:52, schrieb Luiz Augusto von Dentz:
> probably PA should be updated in your system
Well, I'm running Arch with the latest and greatest PulseAudio (version
1.1).

Is there any configuration that can be changed for it to work on side of
PulseAudio?

Thanks for your reply anyway, as I'm now knowing that it is intentional.

Best regards,
Karol Babioch





[-- Attachment #2: OpenPGP digital signature --]
[-- Type: application/pgp-signature, Size: 900 bytes --]

Re: Changed default configuration in bluez 4.97 and higher?

[Karol Babioch]

[-- Attachment #1: Type: text/plain, Size: 1158 bytes --]

Hi,

Am 26.01.2012 13:29, schrieb Luiz Augusto von Dentz:
> do you have any logs when Socket is disabled?

Jan 26 14:28:10 localhost bluetoothd[813]: Discovery session
0x7f5a22e73c50 with :1.41 activated
Jan 26 14:28:13 localhost bluetoothd[813]: Stopping discovery
Jan 26 14:28:24 localhost kernel: [  349.752870] input:
00:16:94:0B:XX:XX as /devices/virtual/input/input17
Jan 26 14:28:24 localhost pulseaudio[1072]: [pulseaudio]
module-bluetooth-device.c: Bluetooth audio service not available
Jan 26 14:28:24 localhost pulseaudio[1072]: [pulseaudio]
module-bluetooth-device.c: Service not connected
Jan 26 14:28:24 localhost pulseaudio[1072]: [pulseaudio] module.c:
Failed to load module "module-bluetooth-device" (argument:
"address="00:16:94:0B:XX:XX"
path="/org/bluez/813/hci0/dev_00_16_94_0B_XX_XX""): initialization failed.

So you were right, it seems that PulseAudio is the culprit here. However
it works with bluez-4.96, although sockets are not enabled explicitly?
So the question stays whether bluez 4.96 uses sockets implicitly, or
whether PulseAudio doesn't work with bluez-4.98.

Best regards,
Karol Babioch





[-- Attachment #2: OpenPGP digital signature --]
[-- Type: application/pgp-signature, Size: 900 bytes --]

Re: Changed default configuration in bluez 4.97 and higher?

[Szymon Janc]

Hi Karol,

> So you were right, it seems that PulseAudio is the culprit here. However
> it works with bluez-4.96, although sockets are not enabled explicitly?
> So the question stays whether bluez 4.96 uses sockets implicitly, or
> whether PulseAudio doesn't work with bluez-4.98.

I guess it is distribution responsibility to provide proper configuration files
for software it includes. You should ask Arch people to fix it (either by
fixing PA or providing BT config that is usable with currently shipped PA).

-- 
BR
Szymon Janc

Re: Changed default configuration in bluez 4.97 and higher?

[Luiz Augusto von Dentz]

Hi Karol,

On Thu, Jan 26, 2012 at 3:49 PM, Karol Babioch <karol@babioch.de> wrote:
> Hi,
>
> Am 26.01.2012 13:29, schrieb Luiz Augusto von Dentz:
>> do you have any logs when Socket is disabled?
>
> Jan 26 14:28:10 localhost bluetoothd[813]: Discovery session
> 0x7f5a22e73c50 with :1.41 activated
> Jan 26 14:28:13 localhost bluetoothd[813]: Stopping discovery
> Jan 26 14:28:24 localhost kernel: [  349.752870] input:
> 00:16:94:0B:XX:XX as /devices/virtual/input/input17
> Jan 26 14:28:24 localhost pulseaudio[1072]: [pulseaudio]
> module-bluetooth-device.c: Bluetooth audio service not available
> Jan 26 14:28:24 localhost pulseaudio[1072]: [pulseaudio]
> module-bluetooth-device.c: Service not connected
> Jan 26 14:28:24 localhost pulseaudio[1072]: [pulseaudio] module.c:
> Failed to load module "module-bluetooth-device" (argument:
> "address="00:16:94:0B:XX:XX"
> path="/org/bluez/813/hci0/dev_00_16_94_0B_XX_XX""): initialization failed.
>
> So you were right, it seems that PulseAudio is the culprit here. However
> it works with bluez-4.96, although sockets are not enabled explicitly?
> So the question stays whether bluez 4.96 uses sockets implicitly, or
> whether PulseAudio doesn't work with bluez-4.98.

4.96 uses Socket by default, now it seems from the logs that it did
not configure a transport for some reason, can you check if you get
anything on bluetoothd logs like this:

bluetoothd[17465]: Endpoint registered: sender=:1.250
path=/MediaEndpoint/A2DPSource

Btw, to enable debug logs just run bluetoothd with -d option

Regards

-- 
Luiz Augusto von Dentz

Re: Changed default configuration in bluez 4.97 and higher?

[Karol Babioch]

[-- Attachment #1: Type: text/plain, Size: 15594 bytes --]

Hi,

Am 26.01.2012 16:17, schrieb Luiz Augusto von Dentz:
> can you check if you get
> anything on bluetoothd logs like this:

I'm attaching my logs (with -d enabled) here. Hopefully they are not too
long. As you can see, the discovery works just fine, but I can't see any
registration of an endpoint, like you proposed.


Jan 26 17:15:23 localhost bluetoothd[21970]:
plugins/hciops.c:hciops_start_discovery() hci0
Jan 26 17:15:23 localhost bluetoothd[21970]:
plugins/hciops.c:start_inquiry() hci0 length 8
Jan 26 17:15:23 localhost bluetoothd[21970]: Discovery session
0x7f83904256c0 with :1.65 activated
Jan 26 17:15:23 localhost bluetoothd[21970]: src/adapter.c:session_ref()
0x7f83904256c0: ref=1
Jan 26 17:15:23 localhost bluetoothd[21970]:
plugins/hciops.c:set_state() hci0: new state 1
Jan 26 17:15:23 localhost bluetoothd[21970]:
plugins/hciops.c:hciops_confirm_name() hci0 00:16:94:0B:XX:XX name_known 1
Jan 26 17:15:25 localhost bluetoothd[21970]:
src/adapter.c:session_unref() 0x7f83904256c0: ref=0
Jan 26 17:15:25 localhost bluetoothd[21970]:
src/adapter.c:session_remove() Discovery session 0x7f83904256c0 with
:1.65 deactivated
Jan 26 17:15:25 localhost bluetoothd[21970]:
src/adapter.c:session_remove() Stopping discovery
Jan 26 17:15:25 localhost bluetoothd[21970]:
plugins/hciops.c:hciops_stop_discovery() index 0
Jan 26 17:15:25 localhost bluetoothd[21970]:
plugins/hciops.c:hciops_stop_inquiry() hci0
Jan 26 17:15:25 localhost bluetoothd[21970]: Stopping discovery
Jan 26 17:15:25 localhost bluetoothd[21970]:
plugins/hciops.c:set_state() hci0: new state 0
Jan 26 17:15:25 localhost bluetoothd[21970]:
src/adapter.c:adapter_create_device() 00:16:94:0B:XX:XX
Jan 26 17:15:25 localhost bluetoothd[21970]:
src/device.c:device_create() Creating device
/org/bluez/21970/hci0/dev_00_16_94_0B_XX_XX
Jan 26 17:15:25 localhost bluetoothd[21970]:
src/device.c:btd_device_ref() 0x7f8390425340: ref=1
Jan 26 17:15:25 localhost bluetoothd[21970]:
src/device.c:device_set_temporary() temporary 1
Jan 26 17:15:25 localhost bluetoothd[21970]:
src/device.c:bonding_request_new() Requesting bonding for 00:16:94:0B:XX:XX
Jan 26 17:15:25 localhost bluetoothd[21970]:
src/device.c:bonding_request_new() Temporary agent registered for
00:16:94:0B:XX:XX at :1.65:/org/bluez/agent/wizard
Jan 26 17:15:26 localhost bluetoothd[21970]:
plugins/hciops.c:conn_complete() status 0x00
Jan 26 17:15:26 localhost bluetoothd[21970]:
src/adapter.c:adapter_get_device() 00:16:94:0B:XX:XX
Jan 26 17:15:26 localhost bluetoothd[21970]:
plugins/hciops.c:remote_features_information() hci0 status 0
Jan 26 17:15:26 localhost bluetoothd[21970]:
plugins/hciops.c:remote_name_information() hci0 status 0
Jan 26 17:15:26 localhost bluetoothd[21970]:
plugins/hciops.c:link_key_request() hci0 dba 00:16:94:0B:XX:XX
Jan 26 17:15:26 localhost bluetoothd[21970]:
plugins/hciops.c:get_auth_info() hci0 dba 00:16:94:0B:XX:XX
Jan 26 17:15:26 localhost bluetoothd[21970]:
plugins/hciops.c:link_key_request() kernel auth requirements = 0x03
Jan 26 17:15:26 localhost bluetoothd[21970]:
plugins/hciops.c:link_key_request() Matching key not found
Jan 26 17:15:26 localhost bluetoothd[21970]:
plugins/hciops.c:io_capa_request() hci0 IO capability request for
00:16:94:0B:XX:XX
Jan 26 17:15:26 localhost bluetoothd[21970]:
plugins/hciops.c:get_auth_info() hci0 dba 00:16:94:0B:XX:XX
Jan 26 17:15:26 localhost bluetoothd[21970]:
plugins/hciops.c:get_io_cap() initial authentication requirement is 0x03
Jan 26 17:15:26 localhost bluetoothd[21970]:
plugins/hciops.c:get_io_cap() final authentication requirement is 0x03
Jan 26 17:15:26 localhost bluetoothd[21970]:
plugins/hciops.c:io_capa_response() hci0 IO capability response from
00:16:94:0B:XX:XX
Jan 26 17:15:29 localhost bluetoothd[21970]:
plugins/hciops.c:user_confirm_request() hci0
Jan 26 17:15:29 localhost bluetoothd[21970]:
plugins/hciops.c:user_confirm_request() auto accept of confirmation
Jan 26 17:15:29 localhost bluetoothd[21970]:
plugins/hciops.c:hciops_confirm_reply() hci0 dba 00:16:94:0B:XX:XX success 1
Jan 26 17:15:32 localhost bluetoothd[21970]:
plugins/hciops.c:simple_pairing_complete() hci0 status 0
Jan 26 17:15:32 localhost bluetoothd[21970]:
src/event.c:btd_event_simple_pairing_complete() status=00
Jan 26 17:15:32 localhost bluetoothd[21970]:
src/adapter.c:adapter_get_device() 00:16:94:0B:XX:XX
Jan 26 17:15:32 localhost bluetoothd[21970]:
plugins/hciops.c:link_key_notify() hci0 dba 00:16:94:0B:XX:XX type 4
Jan 26 17:15:32 localhost bluetoothd[21970]:
plugins/hciops.c:link_key_notify() key type 0x04 old key type 0xff
Jan 26 17:15:32 localhost bluetoothd[21970]:
plugins/hciops.c:link_key_notify() local auth 0x03 and remote auth 0x02
Jan 26 17:15:32 localhost bluetoothd[21970]:
src/adapter.c:adapter_get_device() 00:16:94:0B:XX:XX
Jan 26 17:15:32 localhost bluetoothd[21970]:
src/event.c:btd_event_link_key_notify() storing link key of type 0x04
Jan 26 17:15:32 localhost bluetoothd[21970]:
src/device.c:device_set_bonded() bonded 1
Jan 26 17:15:32 localhost bluetoothd[21970]:
src/device.c:device_set_temporary() temporary 0
Jan 26 17:15:32 localhost bluetoothd[21970]:
plugins/hciops.c:auth_complete() hci0 status 0
Jan 26 17:15:32 localhost bluetoothd[21970]:
plugins/hciops.c:bonding_complete() status 0x00
Jan 26 17:15:32 localhost bluetoothd[21970]:
src/adapter.c:adapter_get_device() 00:16:94:0B:XX:XX
Jan 26 17:15:32 localhost bluetoothd[21970]:
src/device.c:device_bonding_complete() bonding 0x7f8390456ba0 status 0x00
Jan 26 17:15:32 localhost bluetoothd[21970]:
src/device.c:device_bonding_complete() Proceeding with service discovery
Jan 26 17:15:32 localhost bluetoothd[21970]:
src/device.c:btd_device_ref() 0x7f8390425340: ref=2
Jan 26 17:15:32 localhost bluetoothd[21970]: src/agent.c:agent_release()
Releasing agent :1.65, /org/bluez/agent/wizard
Jan 26 17:15:32 localhost bluetoothd[21970]:
src/device.c:device_probe_drivers() Probing drivers for 00:16:94:0B:XX:XX
Jan 26 17:15:32 localhost bluetoothd[21970]:
serial/manager.c:serial_probe() path
/org/bluez/21970/hci0/dev_00_16_94_0B_XX_XX:
0000111e-0000-1000-8000-00805f9b34fb
Jan 26 17:15:32 localhost bluetoothd[21970]:
serial/port.c:create_serial_device() Registered interface
org.bluez.Serial on path /org/bluez/21970/hci0/dev_00_16_94_0B_XX_XX
Jan 26 17:15:32 localhost bluetoothd[21970]:
serial/manager.c:serial_probe() path
/org/bluez/21970/hci0/dev_00_16_94_0B_XX_XX:
00001108-0000-1000-8000-00805f9b34fb
Jan 26 17:15:32 localhost bluetoothd[21970]:
input/manager.c:headset_probe() path
/org/bluez/21970/hci0/dev_00_16_94_0B_XX_XX
Jan 26 17:15:32 localhost bluetoothd[21970]:
src/device.c:btd_device_ref() 0x7f8390425340: ref=3
Jan 26 17:15:32 localhost bluetoothd[21970]:
input/device.c:input_device_new() Registered interface org.bluez.Input
on path /org/bluez/21970/hci0/dev_00_16_94_0B_XX_XX
Jan 26 17:15:32 localhost bluetoothd[21970]:
src/adapter.c:adapter_get_device() 00:16:94:0B:XX:XX
Jan 26 17:15:32 localhost bluetoothd[21970]:
src/device.c:btd_device_ref() 0x7f8390425340: ref=4
Jan 26 17:15:32 localhost bluetoothd[21970]:
audio/device.c:audio_device_register() Registered interface
org.bluez.Audio on path /org/bluez/21970/hci0/dev_00_16_94_0B_XX_XX
Jan 26 17:15:32 localhost bluetoothd[21970]:
audio/manager.c:handle_uuid() Found Headset record
Jan 26 17:15:32 localhost bluetoothd[21970]:
audio/headset.c:headset_init() Registered interface org.bluez.Headset on
path /org/bluez/21970/hci0/dev_00_16_94_0B_XX_XX
Jan 26 17:15:32 localhost bluetoothd[21970]:
audio/manager.c:handle_uuid() Found Handsfree record
Jan 26 17:15:32 localhost bluetoothd[21970]:
audio/manager.c:handle_uuid() Found Audio Sink
Jan 26 17:15:32 localhost bluetoothd[21970]: audio/sink.c:sink_init()
Registered interface org.bluez.AudioSink on path
/org/bluez/21970/hci0/dev_00_16_94_0B_XX_XX
Jan 26 17:15:32 localhost bluetoothd[21970]:
audio/manager.c:handle_uuid() Found AV Remote
Jan 26 17:15:32 localhost bluetoothd[21970]:
audio/control.c:control_init() Registered interface org.bluez.Control on
path /org/bluez/21970/hci0/dev_00_16_94_0B_XX_XX
Jan 26 17:15:32 localhost bluetoothd[21970]:
src/device.c:btd_device_unref() 0x7f8390425340: ref=3
Jan 26 17:15:32 localhost bluetoothd[21970]:
audio/headset.c:headset_set_state() State changed
/org/bluez/21970/hci0/dev_00_16_94_0B_XX_XX: HEADSET_STATE_DISCONNECTED
-> HEADSET_STATE_CONNECTING
Jan 26 17:15:32 localhost bluetoothd[21970]:
audio/media.c:headset_state_changed()
Jan 26 17:15:32 localhost bluetoothd[21970]:
audio/media.c:media_endpoint_async_call() Calling SetConfiguration: name
= :1.59 path = /MediaEndpoint/HFPAG
Jan 26 17:15:33 localhost bluetoothd[21970]:
audio/headset.c:headset_set_channel() Discovered Handsfree service on
channel 1
Jan 26 17:15:33 localhost bluetoothd[21970]:
audio/headset.c:rfcomm_connect()
/org/bluez/21970/hci0/dev_00_16_94_0B_XX_XX: Connecting to
00:16:94:0B:XX:XX channel 1
Jan 26 17:15:33 localhost bluetoothd[21970]:
audio/headset.c:headset_connect_cb()
/org/bluez/21970/hci0/dev_00_16_94_0B_XX_XX: Connected to 00:16:94:0B:XX:XX
Jan 26 17:15:33 localhost bluetoothd[21970]:
audio/headset.c:handle_event() Received AT+BRSF=25
Jan 26 17:15:33 localhost bluetoothd[21970]:
audio/headset.c:print_hf_features() HFP HF features: "EC and/or NR
function" "Voice recognition activation" "Remote volume control"
Jan 26 17:15:33 localhost bluetoothd[21970]:
audio/headset.c:handle_event() Received AT+CIND=?
Jan 26 17:15:34 localhost bluetoothd[21970]:
audio/headset.c:handle_event() Received AT+CIND?
Jan 26 17:15:34 localhost bluetoothd[21970]:
audio/headset.c:handle_event() Received AT+CMER=3, 0, 0, 1
Jan 26 17:15:34 localhost bluetoothd[21970]:
audio/headset.c:event_reporting() Event reporting (CMER): mode=3, ind=1
Jan 26 17:15:34 localhost bluetoothd[21970]:
audio/headset.c:hfp_slc_complete() HFP Service Level Connection established
Jan 26 17:15:34 localhost bluetoothd[21970]:
audio/telephony.c:telephony_device_connected() telephony-dummy: device
0x7f839046caf0 connected
Jan 26 17:15:34 localhost bluetoothd[21970]:
audio/headset.c:headset_set_state() State changed
/org/bluez/21970/hci0/dev_00_16_94_0B_XX_XX: HEADSET_STATE_CONNECTING ->
HEADSET_STATE_CONNECTED
Jan 26 17:15:34 localhost bluetoothd[21970]:
audio/media.c:headset_state_changed()
Jan 26 17:15:34 localhost bluetoothd[21970]:
audio/headset.c:handle_event() Received AT+VGS=15
Jan 26 17:15:34 localhost bluetoothd[21970]:
audio/headset.c:headset_set_gain() Ignoring no-change in speaker gain
Jan 26 17:15:35 localhost bluetoothd[21970]: audio/avdtp.c:avdtp_ref()
0x7f8390453b60: ref=2
Jan 26 17:15:35 localhost bluetoothd[21970]: audio/avdtp.c:avdtp_ref()
0x7f8390453b60: ref=3
Jan 26 17:15:35 localhost bluetoothd[21970]:
audio/sink.c:sink_set_state() State changed
/org/bluez/21970/hci0/dev_00_16_94_0B_XX_XX: SINK_STATE_DISCONNECTED ->
SINK_STATE_CONNECTING
Jan 26 17:15:35 localhost bluetoothd[21970]: audio/avdtp.c:avdtp_unref()
0x7f8390453b60: ref=2
Jan 26 17:15:35 localhost bluetoothd[21970]:
audio/avdtp.c:avdtp_connect_cb() AVDTP: connected signaling channel to
00:16:94:0B:XX:XX
Jan 26 17:15:35 localhost bluetoothd[21970]:
audio/avdtp.c:avdtp_connect_cb() AVDTP imtu=672, omtu=895
Jan 26 17:15:35 localhost bluetoothd[21970]:
audio/avctp.c:avctp_set_state() AVCTP Connecting
Jan 26 17:15:35 localhost bluetoothd[21970]: audio/avdtp.c:session_cb()
Jan 26 17:15:35 localhost bluetoothd[21970]:
audio/avdtp.c:avdtp_parse_resp() DISCOVER request succeeded
Jan 26 17:15:35 localhost bluetoothd[21970]:
audio/avdtp.c:avdtp_discover_resp() seid 1 type 1 media 0 in use 0
Jan 26 17:15:35 localhost bluetoothd[21970]: audio/avdtp.c:session_cb()
Jan 26 17:15:35 localhost bluetoothd[21970]:
audio/avdtp.c:avdtp_parse_resp() GET_CAPABILITIES request succeeded
Jan 26 17:15:35 localhost bluetoothd[21970]:
audio/avdtp.c:avdtp_get_capabilities_resp() seid 1 type 1 media 0
Jan 26 17:15:35 localhost bluetoothd[21970]:
audio/sink.c:discovery_complete() Discovery complete
Jan 26 17:15:35 localhost bluetoothd[21970]: audio/avdtp.c:avdtp_ref()
0x7f8390453b60: ref=3
Jan 26 17:15:35 localhost bluetoothd[21970]: audio/a2dp.c:setup_ref()
0x7f8390457980: ref=1
Jan 26 17:15:35 localhost bluetoothd[21970]: audio/a2dp.c:a2dp_config()
a2dp_config: selected SEP 0x7f8390429bb0
Jan 26 17:15:35 localhost bluetoothd[21970]: audio/a2dp.c:setup_ref()
0x7f8390457980: ref=2
Jan 26 17:15:35 localhost bluetoothd[21970]:
audio/avdtp.c:avdtp_set_configuration() 0x7f8390453b60: int_seid=1,
acp_seid=1
Jan 26 17:15:35 localhost bluetoothd[21970]: audio/a2dp.c:setup_unref()
0x7f8390457980: ref=1
Jan 26 17:15:35 localhost bluetoothd[21970]: audio/avdtp.c:session_cb()
Jan 26 17:15:35 localhost bluetoothd[21970]:
audio/avdtp.c:avdtp_parse_resp() SET_CONFIGURATION request succeeded
Jan 26 17:15:35 localhost bluetoothd[21970]: audio/a2dp.c:setconf_cfm()
Source 0x7f8390429bb0: Set_Configuration_Cfm
Jan 26 17:15:35 localhost bluetoothd[21970]:
audio/avdtp.c:avdtp_sep_set_state() stream state changed: IDLE -> CONFIGURED
Jan 26 17:15:35 localhost bluetoothd[21970]: audio/avdtp.c:session_cb()
Jan 26 17:15:35 localhost bluetoothd[21970]:
audio/avdtp.c:avdtp_parse_resp() OPEN request succeeded
Jan 26 17:15:36 localhost bluetoothd[21970]:
audio/avctp.c:avctp_connect_cb() AVCTP: connected to 00:16:94:0B:XX:XX
Jan 26 17:15:36 localhost bluetoothd[21970]: audio/avctp.c:init_uinput()
AVRCP: uinput initialized for 00:16:94:0B:XX:XX
Jan 26 17:15:36 localhost bluetoothd[21970]:
audio/avctp.c:avctp_set_state() AVCTP Connected
Jan 26 17:15:36 localhost kernel: [10376.086611] input:
00:16:94:0B:XX:XX as /devices/virtual/input/input21
Jan 26 17:15:36 localhost bluetoothd[21970]:
audio/avdtp.c:avdtp_connect_cb() AVDTP: connected transport channel to
00:16:94:0B:XX:XX
Jan 26 17:15:36 localhost bluetoothd[21970]:
audio/avdtp.c:handle_transport_connect() Flushable packets enabled
Jan 26 17:15:36 localhost bluetoothd[21970]:
audio/avdtp.c:handle_transport_connect() sk 28, omtu 895, send buffer
size 114688
Jan 26 17:15:36 localhost bluetoothd[21970]: audio/a2dp.c:open_cfm()
Source 0x7f8390429bb0: Open_Cfm
Jan 26 17:15:36 localhost bluetoothd[21970]:
audio/sink.c:stream_setup_complete() Stream successfully created
Jan 26 17:15:36 localhost bluetoothd[21970]: audio/a2dp.c:setup_unref()
0x7f8390457980: ref=0
Jan 26 17:15:36 localhost bluetoothd[21970]: audio/a2dp.c:setup_free()
0x7f8390457980
Jan 26 17:15:36 localhost bluetoothd[21970]: audio/avdtp.c:avdtp_unref()
0x7f8390453b60: ref=2
Jan 26 17:15:36 localhost bluetoothd[21970]:
audio/avdtp.c:avdtp_sep_set_state() stream state changed: CONFIGURED -> OPEN
Jan 26 17:15:36 localhost bluetoothd[21970]:
audio/sink.c:sink_set_state() State changed
/org/bluez/21970/hci0/dev_00_16_94_0B_XX_XX: SINK_STATE_CONNECTING ->
SINK_STATE_CONNECTED
Jan 26 17:15:36 localhost pulseaudio[20926]: [pulseaudio]
module-bluetooth-device.c: Bluetooth audio service not available
Jan 26 17:15:36 localhost pulseaudio[20926]: [pulseaudio]
module-bluetooth-device.c: Service not connected
Jan 26 17:15:36 localhost pulseaudio[20926]: [pulseaudio] module.c:
Failed to load module "module-bluetooth-device" (argument:
"address="00:16:94:0B:XX:XX"
path="/org/bluez/21970/hci0/dev_00_16_94_0B_XX_XX""): initialization failed.

Does it tell you anything useful?

Best regards,
Karol Babioch


[-- Attachment #2: OpenPGP digital signature --]
[-- Type: application/pgp-signature, Size: 900 bytes --]

Re: Changed default configuration in bluez 4.97 and higher?

[Luiz Augusto von Dentz]

[-- Attachment #1: Type: text/plain, Size: 1973 bytes --]

Hi Karol,

On Thu, Jan 26, 2012 at 6:22 PM, Karol Babioch <karol@babioch.de> wrote:
> Hi,
>
> Am 26.01.2012 16:17, schrieb Luiz Augusto von Dentz:
>> can you check if you get
>> anything on bluetoothd logs like this:
>
> I'm attaching my logs (with -d enabled) here. Hopefully they are not too
> long. As you can see, the discovery works just fine, but I can't see any
> registration of an endpoint, like you proposed.

Thanks for the log, Ive figure out the problem, here is a patch that
might fix it (also attached):

diff --git a/audio/a2dp.c b/audio/a2dp.c
index c4cdc62..2eebe6b 100644
--- a/audio/a2dp.c
+++ b/audio/a2dp.c
@@ -1441,9 +1441,9 @@ static struct a2dp_server *find_server(GSList
*list, const bdaddr_t *src)

 int a2dp_register(DBusConnection *conn, const bdaddr_t *src, GKeyFile *config)
 {
-       int sbc_srcs = 1, sbc_sinks = 1;
+       int sbc_srcs = 0, sbc_sinks = 0;
        int mpeg12_srcs = 0, mpeg12_sinks = 0;
-       gboolean source = TRUE, sink = FALSE, socket = TRUE;
+       gboolean source = TRUE, sink = FALSE, socket = FALSE;
        gboolean delay_reporting = FALSE;
        char *str;
        GError *err = NULL;
@@ -1463,6 +1463,8 @@ int a2dp_register(DBusConnection *conn, const
bdaddr_t *src, GKeyFile *config)
                        source = TRUE;
                if (strstr(str, "Source"))
                        sink = TRUE;
+               if (strstr(str, "Socket"))
+                       socket = TRUE;
                g_free(str);
        }

@@ -1482,18 +1484,14 @@ int a2dp_register(DBusConnection *conn, const
bdaddr_t *src, GKeyFile *config)
        }

        /* Don't register any local sep if Socket is disabled */
-       if (socket == FALSE) {
-               sbc_srcs = 0;
-               sbc_sinks = 0;
-               mpeg12_srcs = 0;
-               mpeg12_sinks = 0;
:

Basically the problem is that the code is assuming that Socket is
enabled by default which is not true anymore.

-- 
Luiz Augusto von Dentz

[-- Attachment #2: diff.patch --]
[-- Type: text/x-patch, Size: 1598 bytes --]

diff --git a/audio/a2dp.c b/audio/a2dp.c
index c4cdc62..2eebe6b 100644
--- a/audio/a2dp.c
+++ b/audio/a2dp.c
@@ -1441,9 +1441,9 @@ static struct a2dp_server *find_server(GSList *list, const bdaddr_t *src)
 
 int a2dp_register(DBusConnection *conn, const bdaddr_t *src, GKeyFile *config)
 {
-	int sbc_srcs = 1, sbc_sinks = 1;
+	int sbc_srcs = 0, sbc_sinks = 0;
 	int mpeg12_srcs = 0, mpeg12_sinks = 0;
-	gboolean source = TRUE, sink = FALSE, socket = TRUE;
+	gboolean source = TRUE, sink = FALSE, socket = FALSE;
 	gboolean delay_reporting = FALSE;
 	char *str;
 	GError *err = NULL;
@@ -1463,6 +1463,8 @@ int a2dp_register(DBusConnection *conn, const bdaddr_t *src, GKeyFile *config)
 			source = TRUE;
 		if (strstr(str, "Source"))
 			sink = TRUE;
+		if (strstr(str, "Socket"))
+			socket = TRUE;
 		g_free(str);
 	}
 
@@ -1482,18 +1484,14 @@ int a2dp_register(DBusConnection *conn, const bdaddr_t *src, GKeyFile *config)
 	}
 
 	/* Don't register any local sep if Socket is disabled */
-	if (socket == FALSE) {
-		sbc_srcs = 0;
-		sbc_sinks = 0;
-		mpeg12_srcs = 0;
-		mpeg12_sinks = 0;
+	if (socket == FALSE)
 		goto proceed;
-	}
 
 	str = g_key_file_get_string(config, "A2DP", "SBCSources", &err);
 	if (err) {
 		DBG("audio.conf: %s", err->message);
 		g_clear_error(&err);
+		sbc_srcs = 1;
 	} else {
 		sbc_srcs = atoi(str);
 		g_free(str);
@@ -1512,6 +1510,7 @@ int a2dp_register(DBusConnection *conn, const bdaddr_t *src, GKeyFile *config)
 	if (err) {
 		DBG("audio.conf: %s", err->message);
 		g_clear_error(&err);
+		sbc_sinks = 1;
 	} else {
 		sbc_sinks = atoi(str);
 		g_free(str);

Re: Changed default configuration in bluez 4.97 and higher?

[Karol Babioch]

[-- Attachment #1: Type: text/plain, Size: 369 bytes --]

Hi,

Am 26.01.2012 18:13, schrieb Luiz Augusto von Dentz:
> Thanks for the log, Ive figure out the problem, here is a patch that
> might fix it (also attached):

It does indeed fix the issue. Do you need any kind of log? Because the
patch seems to be quite straightforward. Will this be included in the
next release of bluez?

Best regards,
Karol Babioch


[-- Attachment #2: OpenPGP digital signature --]
[-- Type: application/pgp-signature, Size: 900 bytes --]

Re: Changed default configuration in bluez 4.97 and higher?

[Luiz Augusto von Dentz]

Hi Karol,

On Thu, Jan 26, 2012 at 8:22 PM, Karol Babioch <karol@babioch.de> wrote:
> Hi,
>
> Am 26.01.2012 18:13, schrieb Luiz Augusto von Dentz:
>> Thanks for the log, Ive figure out the problem, here is a patch that
>> might fix it (also attached):
>
> It does indeed fix the issue. Do you need any kind of log? Because the
> patch seems to be quite straightforward. Will this be included in the
> next release of bluez?

Gonna send a patch asap, so yes it will probably be in the next
release, thanks for quick testing it.


-- 
Luiz Augusto von Dentz