Re: Changed default configuration in bluez 4.97 and higher?

Linux bluetooth development
 help / color / mirror / Atom feed

From: Karol Babioch <karol@babioch.de>
To: linux-bluetooth@vger.kernel.org
Cc: Luiz Augusto von Dentz <luiz.dentz@gmail.com>
Subject: Re: Changed default configuration in bluez 4.97 and higher?
Date: Thu, 26 Jan 2012 17:22:59 +0100	[thread overview]
Message-ID: <4F217DE3.4020104@babioch.de> (raw)
In-Reply-To: <CABBYNZJ09fo8X69Hqs+d_NiHmq9f2a1JHcSZnot4RXy0fk-u+g@mail.gmail.com>

[-- 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 --]

next prev parent reply	other threads:[~2012-01-26 16:22 UTC|newest]

Thread overview: 10+ messages / expand[flat|nested]  mbox.gz  Atom feed  top
2012-01-25 18:37 Changed default configuration in bluez 4.97 and higher? Karol Babioch
2012-01-26 11:52 ` Luiz Augusto von Dentz
2012-01-26 12:23   ` Karol Babioch
2012-01-26 13:49     ` Karol Babioch
2012-01-26 14:40       ` Szymon Janc
2012-01-26 15:17       ` Luiz Augusto von Dentz
2012-01-26 16:22         ` Karol Babioch [this message]
2012-01-26 17:13           ` Luiz Augusto von Dentz
2012-01-26 18:22             ` Karol Babioch
2012-01-26 19:37               ` Luiz Augusto von Dentz

Reply instructions:

You may reply publicly to this message via plain-text email
using any one of the following methods:

* Save the following mbox file, import it into your mail client,
  and reply-to-all from there: mbox

  Avoid top-posting and favor interleaved quoting:
  https://en.wikipedia.org/wiki/Posting_style#Interleaved_style

* Reply using the --to, --cc, and --in-reply-to
  switches of git-send-email(1):

  git send-email \
    --in-reply-to=4F217DE3.4020104@babioch.de \
    --to=karol@babioch.de \
    --cc=linux-bluetooth@vger.kernel.org \
    --cc=luiz.dentz@gmail.com \
    /path/to/YOUR_REPLY

  https://kernel.org/pub/software/scm/git/docs/git-send-email.html

* If your mail client supports setting the In-Reply-To header
  via mailto: links, try the mailto: link

Be sure your reply has a Subject: header at the top and a blank line before the message body.

This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox