Return-Path: MIME-Version: 1.0 In-Reply-To: References: <3004548.VX6QZ5TmgY@leonov> Date: Wed, 17 Sep 2014 15:14:34 -0700 Message-ID: Subject: Re: Create an AVDTP signaling channel From: John Tobias To: Szymon Janc Cc: linux-bluetooth@vger.kernel.org Content-Type: text/plain; charset=UTF-8 Sender: linux-bluetooth-owner@vger.kernel.org List-ID: Addition to that, when I start again the bluetooth daemon, I got these messages: uetoothd[238]: Can't load plugin description: /usr/lib/bluetooth/plugins/audio.a2dp.default.so: undefined symbol: bluetooth_plugin_desc bluetoothd[238]: Can't load plugin description: /usr/lib/bluetooth/plugins/bluetooth.default.so: undefined symbol: bluetooth_plugin_desc bluetoothd[238]: Can't load plugin description: /usr/lib/bluetooth/plugins/audio.sco.default.so: undefined symbol: bluetooth_plugin_desc bluetoothd[238]: Can't listen at channel 8. bluetoothd[238]: sap-server: Operation not permitted (1) bluetoothd[238]: hci0 Load Connection Parameters failed: Unknown Command (0x01) Regards, john On Wed, Sep 17, 2014 at 3:09 PM, John Tobias wrote: > Hi Szymon, > > Here's what happening. > > First, I paired my phone and my device just to make sure I could > stream music - (it works, also, I used PulseAudio 5). After I verified > it's playing, I disconnected it, switched to PTS 5.2 and ran AVDTP > profile. I used bluetoothctl to interact with PTS. Then, when the said > message (posted on previous email) pop'd up, I used the command (under > bluetoothctl) connect , but for some reason the > bluetoothctl doesn't have it in the cache, so I got an message "Device > not available". I ran 'scan on' then, when the > have been found, I ran connect , the PTS got > the message. > > After that, I got the following error messages: > > [bluetooth]# pulseaudio[169]: E: [pulseaudio] bluez5-util.c: Transport > Acquire() failed for transport > /org/bluez/hci0/dev_00_1B_DC_07_32_D3/fd1 (Input/output error) > systemd[1]: bluetooth.service: main process exited, code=killed, status=11/SEGV > systemd[1]: Unit bluetooth.service entered failed state. > Failed to connect: org.freedesktop.DBus.Error.NoReply > > Below are the logs: > > > > Sep 17 21:27:14 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/adapter.c:btd_adapter_confirm_reply() hci0 addr 00:1B:DC:07:32:D3 > success 1 > Sep 17 21:27:14 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/agent.c:agent_unref() 0x78d04128: ref=2 > Sep 17 21:27:14 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/agent.c:agent_unref() 0x78d04128: ref=1 > Sep 17 21:27:14 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/adapter.c:new_link_key_callback() hci0 new key for > 00:1B:DC:07:32:D3 type 4 pin_len 0 > Sep 17 21:27:14 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/device.c:device_bonding_complete() bonding (nil) status 0x00 > Sep 17 21:27:14 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/adapter.c:resume_discovery() > Sep 17 21:27:14 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/adapter.c:trigger_start_discovery() > Sep 17 21:27:14 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/adapter.c:cancel_passive_scanning() > Sep 17 21:27:14 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/adapter.c:device_found_callback() hci0 addr D0:E7:82:ED:AE:B8, > rssi -61 flags 0x0000 eir_len 46 > Sep 17 21:27:14 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/device.c:device_set_legacy() legacy 0 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/device.c:device_probe_profiles() Probing profiles for device > 00:1B:DC:07:32:D3 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/a2dp.c:a2dp_sink_probe() path > /org/bluez/hci0/dev_00_1B_DC_07_32_D3 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/sink.c:sink_init() > /org/bluez/hci0/dev_00_1B_DC_07_32_D3 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/service.c:btd_service_ref() 0x78d067c0: ref=2 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/service.c:change_state() 0x78d067c0: device 00:1B:DC:07:32:D3 > profile a2dp-sink state changed: unavailable -> disconnected (0) > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/device.c:device_svc_resolved() > /org/bluez/hci0/dev_00_1B_DC_07_32_D3 err 0 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/device.c:connect_profiles() /org/bluez/hci0/dev_00_1B_DC_07_32_D3 > (all), client :1.7 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/a2dp.c:a2dp_sink_connect() path > /org/bluez/hci0/dev_00_1B_DC_07_32_D3 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:avdtp_ref() 0x78cec8d8: ref=1 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/sink.c:sink_set_state() State changed > /org/bluez/hci0/dev_00_1B_DC_07_32_D3: SINK_STATE_DISCONNECTED -> > SINK_STATE_CONNECTING > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/sink.c:sink_connect() stream creation in progress > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/service.c:change_state() 0x78d067c0: device 00:1B:DC:07:32:D3 > profile a2dp-sink state changed: disconnected -> connecting (0) > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP: connected signaling > channel to 00:1B:DC:07:32:D3 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP imtu=672, omtu=672 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:session_cb() > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:avdtp_parse_cmd() Received DISCOVER_CMD > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:session_cb() > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:avdtp_parse_resp() DISCOVER request succeeded > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:avdtp_discover_resp() seid 1 type 1 media 0 in > use 0 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:session_cb() > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:avdtp_parse_cmd() Received > GET_CAPABILITIES_CMD > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/a2dp.c:endpoint_getcap_ind() Source 0x78ce0128: > Get_Capability_Ind > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:session_cb() > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:avdtp_parse_resp() GET_CAPABILITIES request > succeeded > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:avdtp_get_capabilities_resp() seid 1 type 1 > media 0 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/sink.c:discovery_complete() Discovery complete > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:avdtp_ref() 0x78cec8d8: ref=2 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/a2dp.c:setup_ref() 0x78ced528: ref=1 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/media.c:media_endpoint_async_call() Calling > SelectConfiguration: name = :1.4 path = /MediaEndpoint/A2DPSource > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/a2dp.c:a2dp_config() a2dp_config: selected SEP > 0x78ce0128 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/a2dp.c:setup_ref() 0x78ced528: ref=2 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:avdtp_set_configuration() 0x78cec8d8: > int_seid=1, acp_seid=1 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/a2dp.c:setup_unref() 0x78ced528: ref=1 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/adapter.c:device_found_callback() hci0 addr D0:E7:82:ED:AE:B8, > rssi -58 flags 0x0000 eir_len 5 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/device.c:device_set_legacy() legacy 0 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/adapter.c:device_found_callback() hci0 addr 68:17:29:58:C9:A0, > rssi -60 flags 0x0000 eir_len 37 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/device.c:device_set_legacy() legacy 0 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:session_cb() > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:avdtp_parse_cmd() Received > SET_CONFIGURATION_CMD > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:session_cb() > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:avdtp_parse_resp() SET_CONFIGURATION request > succeeded > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/a2dp.c:setconf_cfm() Source 0x78ce0128: > Set_Configuration_Cfm > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/media.c:media_endpoint_async_call() Calling > SetConfiguration: name = :1.4 path = /MediaEndpoint/A2DPSource > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: > IDLE -> CONFIGURED > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/transport.c:media_owner_create() Owner created: > sender=:1.4 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:avdtp_ref() 0x78cec8d8: ref=3 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/a2dp.c:a2dp_sep_lock() SEP 0x78ce0128 locked > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/a2dp.c:setup_ref() 0x78ced528: ref=2 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/transport.c:transport_set_state() State changed > /org/bluez/hci0/dev_00_1B_DC_07_32_D3/fd1: TRANSPORT_STATE_IDLE -> > TRANSPORT_STATE_REQUESTING > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/transport.c:media_request_create() Request created: > method=Acquire id=7 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/transport.c:media_owner_add() Owner :1.4 Request > Acquire > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/transport.c:media_transport_set_owner() Transport > /org/bluez/hci0/dev_00_1B_DC_07_32_D3/fd1 Owner :1.4 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/adapter.c:device_found_callback() hci0 addr 68:17:29:58:C9:A0, > rssi -64 flags 0x0000 eir_len 37 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/device.c:device_set_legacy() legacy 0 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/adapter.c:dev_disconnected() Device 00:1B:DC:07:32:D3 > disconnected, reason 3 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/adapter.c:adapter_remove_connection() > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > plugins/policy.c:disconnect_cb() reason 3 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/adapter.c:bonding_attempt_complete() hci0 bdaddr 00:1B:DC:07:32:D3 > type 0 status 0xe > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/device.c:device_bonding_complete() bonding (nil) status 0x0e > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/device.c:device_bonding_failed() status 14 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/adapter.c:resume_discovery() > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/adapter.c:trigger_start_discovery() > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > src/adapter.c:cancel_passive_scanning() > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:session_cb() > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:connection_lost() Disconnected from > 00:1B:DC:07:32:D3 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/a2dp.c:abort_cfm() Source 0x78ce0128: Abort_Cfm > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/a2dp.c:setup_unref() 0x78ced528: ref=1 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: > CONFIGURED -> IDLE > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: No reply to Open > request > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/a2dp.c:open_cfm() Source 0x78ce0128: Open_Cfm > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/transport.c:media_transport_remove_owner() Transport > /org/bluez/hci0/dev_00_1B_DC_07_32_D3/fd1 Owner :1.4 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/transport.c:media_request_reply() Request Acquire Reply > Input/output error > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/transport.c:media_owner_free() Owner :1.4 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/transport.c:media_owner_remove() Owner :1.4 Request > Acquire > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/transport.c:transport_set_state() State changed > /org/bluez/hci0/dev_00_1B_DC_07_32_D3/fd1: TRANSPORT_STATE_REQUESTING > -> TRANSPORT_STATE_IDLE > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/a2dp.c:a2dp_sep_unlock() SEP 0x78ce0128 unlocked > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/a2dp.c:setup_unref() 0x78ced528: ref=0 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/a2dp.c:setup_free() 0x78ced528 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ bluetoothd[156]: > profiles/audio/avdtp.c:avdtp_unref() 0x78cec8d8: ref=2 > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ pulseaudio[169]: E: [pulseaudio] > bluez5-util.c: Transport Acquire() failed for transport > /org/bluez/hci0/dev_00_1B_DC_07_32_D3/fd1 (Input/output error) > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ systemd[1]: bluetooth.service: > main process exited, code=killed, status=11/SEGV > Sep 17 21:27:15 DEVPZZ4CKCFWCY6964WQ systemd[1]: Unit > bluetooth.service entered failed state. > > > Thanks, > > John > > On Wed, Sep 17, 2014 at 12:33 AM, Szymon Janc wrote: >> Hi John, >> >> On Tuesday 16 of September 2014 17:04:46 John Tobias wrote: >>> Hello All, >>> >>> I am using Bluez 5.23 and running PTS 5.2. I am trying to test the >>> AVDTP profile and the PTS software prompt me a dialog box "Create an >>> AVDTP signaling channel" (please see the attached image. >>> >>> I would like to know how to make response via bluetoothctl or any >>> command line to continue PTS testing?. >> >> Connecting A2DP profile (AVDTP is a protocol used by A2DP) should do that (for >> that you would also need PulseAudio 5). So if you have all set up 'connect >> ' should do. >> >> Other option would be to use android/avdtptest tool for AVDTP protocol >> testing. But this would test android/avdtp-lib and not the AVDTP code used in >> Linux daemon (those will be unified, eventually). >> >> -- >> BR >> Szymon Janc