A2DP reconnections and scanning

Bluez-devel Chris Carlin 2 participants 3 messages May 13, 2007 – May 14, 2007 Bluetooth drivers

Share & tools

Chris Carlin

author

2007-05-13 00:33 UTC #0 lore raw

I'm trying A2DP again with Motorola S805 headphone (gave up on the Creative CB2530 for the moment).

Everything seems to work as expected except that every four seconds A2DP gives the following error message, disconnects from the headphones, reconnects, and continues playing.

a2dp_transfer_raw: Wrote -1 not 613 bytes (sbc.len=100)

An earlier post at http://thread.gmane.org/gmane.linux.bluez.devel/10682
seemed to describe this closely, and he solved it by turning off a program that routinely initiated scans. Before I post reams of output I wanted to ask: is there anything in Bluez 3.10 that initiates scans like this? I have no external programs attempting to scan AFAIK; does Bluez do it itself?

~Chris

-------------------------------------------------------------------------
This SF.net email is sponsored by DB2 Express
Download DB2 Express C - the FREE version of DB2 express and take control of your XML. No limits. Just data. Click to get it now.
http://sourceforge.net/powerbar/db2/
_______________________________________________
Bluez-devel mailing list [email protected]
https://lists.sourceforge.net/lists/listinfo/bluez-devel

Frederic Dalleau

2007-05-13 05:36 UTC #1 lore raw

Quoted 5 lines

Everything seems to work as expected except that every four seconds A2DP gives the following error message, disconnects from the headphones, reconnects, and continues playing.
a2dp_transfer_raw: Wrote -1 not 613 bytes (sbc.len=100)

What bitpool are you using? Try a smaller value.
Are you still using rate=32000?

Quoted 2 lines

An earlier post at http://thread.gmane.org/gmane.linux.bluez.devel/10682
seemed to describe this closely

I don't think it's a scanning: a scan takes can take longer than that. Unfortunately, this sounds a2dpd only.

Frederic

_______________________________________________
Bluez-devel mailing list
[email protected]
https://lists.sourceforge.net/lists/listinfo/bluez-devel

Chris Carlin

author

2007-05-14 13:44 UTC #2 lore raw

Quoted 2 lines · Frederic Dalleau

What bitpool are you using? Try a smaller value.
Are you still using rate=32000?

I've tried it both ways, 32000 and 44100.
I tried various values for bitpool with no change (except sound quality).

Below is the output from one of the loops. This output will repeat as long as the sound is playing as a2dpd disconnects and reconnects. to the headphones.

~Chris

A2DPD[09:37:40.090]: a2dp_transfer_raw: Wrote -1 not 613 bytes (sbc.len=100) A2DPD[09:37:40.091]: transfer_outgoing_packets: Error in transfer (transfer=-1)
A2DPD[09:37:40.091]: a2dp_state_disconnect: State AVDTP_STATEX_STREAM_CLOSING
A2DPD[09:37:40.091]: a2dp_state_machine: State AVDTP_STATEX_STREAM_CLOSING_WAIT
A2DPD[09:37:40.091]: a2dpd_signal_state: Connecting 00:19:1F:00:0A:4D A2DPD[09:37:42.962]: get_socket_omtu: Socket 8 imtu=672, omtu=0, flush_to=65535
A2DPD[09:37:42.962]: pollfd_cb_avrcpfd: socket 12: Connection from 4D:0A:00:1F:19:00, mtu=610 accepted at index 0 A2DPD[09:37:42.987]: a2dp_handle_avdtp_message: Read non command packet (msgtype=MESSAGE_TYPE_ACCEPT,signal=AVDTP_CLOSE) A2DPD[09:37:42.987]: a2dp_state_machine: AVDTP_CLOSE success A2DPD[09:37:42.987]: a2dp_state_machine: State AVDTP_STATEX_DISCONNECTING A2DPD[09:37:42.988]: a2dp_disconnect:
A2DPD[09:37:42.988]: a2dp_stream_stop: Closing stream socket 10 A2DPD[09:37:42.988]: a2dp_stream_stop: Closed A2DPD[09:37:42.988]: a2dp_stream_stop: State AVDTP_STATE_IDLE A2DPD[09:37:42.988]: a2dp_disconnect: Closing ctl socket 11 A2DPD[09:37:42.988]: a2dp_disconnect: Closed A2DPD[09:37:42.988]: a2dp_disconnect: State AVDTP_STATE_DISCONNECTED A2DPD[09:37:42.989]: a2dpd_signal_state: Disconnected A2DPD[09:37:42.991]: a2dp_state_connect: State AVDTP_STATEX_SDP_CONNECTING A2DPD[09:37:42.991]: a2dp_state_machine: Role is INITIATOR A2DPD[09:37:42.991]: a2dp_state_machine: (errno=115:Operation now in progress)SDP connection on socket 10
A2DPD[09:37:42.991]: a2dp_state_machine: State AVDTP_STATEX_SDP_CONNECTING_WAIT
A2DPD[09:37:42.991]: a2dpd_signal_state: Connecting 00:19:1F:00:0A:4D A2DPD[09:37:43.129]: a2dp_state_machine: SDP connection terminated A2DPD[09:37:43.200]: get_avdtp_psm: Parsing results A2DPD[09:37:43.200]: get_avdtp_psm: Record
A2DPD[09:37:43.200]: get_avdtp_psm: Service Search OK A2DPD[09:37:43.216]: get_avdtp_psm: Checking non spec audio A2DPD[09:37:43.216]: a2dp_state_machine: Found psm 25 A2DPD[09:37:43.216]: a2dp_state_machine: State AVDTP_STATEX_CTL_CONNECTING A2DPD[09:37:43.216]: a2dp_state_machine: Socket is 10 A2DPD[09:37:43.216]: a2dp_state_machine: (errno=115:Operation now in progress)State AVDTP_STATEX_CTL_CONNECTING_WAIT A2DPD[09:37:43.396]: avrcp_handle_message: AVRCP Receive failed A2DPD[09:37:43.495]: a2dp_state_machine: State AVDTP_STATE_IDLE A2DPD[09:37:43.496]: a2dp_state_machine: State AVDTP_STATEX_DISCOVERING A2DPD[09:37:43.496]: a2dp_state_machine: State AVDTP_STATEX_DISCOVERING_RESP A2DPD[09:37:43.590]: a2dp_handle_avdtp_message: Read non command packet (msgtype=MESSAGE_TYPE_ACCEPT,signal=AVDTP_DISCOVER) A2DPD[09:37:43.590]: a2dp_state_machine: AVDTP_DISCOVER success A2DPD[09:37:43.590]: a2dp_state_machine: State AVDTP_STATEX_GETTING_CAPABILITIES
A2DPD[09:37:43.591]: a2dp_state_machine: Getting capabilities for SEID=1 A2DPD[09:37:43.591]: a2dp_state_machine: State AVDTP_STATEX_GETTING_CAPABILITIES_RESP
A2DPD[09:37:43.634]: a2dp_handle_avdtp_message: Read non command packet (msgtype=MESSAGE_TYPE_ACCEPT,signal=AVDTP_GET_CAPABILITIES) A2DPD[09:37:43.634]: a2dp_state_machine: AVDTP_GET_CAPABILITIES success for seid 1
A2DPD[09:37:43.634]: a2dp_state_machine: servcap_cap=1, servcap_len=0, A2DPD[09:37:43.634]: a2dp_state_machine: cap_type=7, length=6 A2DPD[09:37:43.634]: a2dp_state_machine: media_type=0, codec=0 A2DPD[09:37:43.634]: a2dp_state_machine: A2DPD Prefered bitpool 53 A2DPD[09:37:43.634]: a2dp_state_machine: Device Prefered bitpool 2 -> 44 A2DPD[09:37:43.634]: a2dp_state_machine: A2DPD Selected bitpool 44 A2DPD[09:37:43.634]: a2dp_state_machine: State AVDTP_STATEX_SETTING_CONFIGURATION
A2DPD[09:37:43.635]: a2dp_state_machine: State AVDTP_STATEX_SETTING_CONFIGURATION_RESP
A2DPD[09:37:43.710]: a2dp_handle_avdtp_message: Read non command packet (msgtype=MESSAGE_TYPE_ACCEPT,signal=AVDTP_SET_CONFIGURATION) A2DPD[09:37:43.710]: a2dp_state_machine: AVDTP_SET_CONFIGURATION success A2DPD[09:37:43.710]: a2dp_state_machine: State AVDTP_STATEX_STREAM_OPENING A2DPD[09:37:43.711]: a2dp_state_machine: State AVDTP_STATEX_STREAM_OPENING_RESP
A2DPD[09:37:43.762]: a2dp_handle_avdtp_message: Read non command packet (msgtype=MESSAGE_TYPE_ACCEPT,signal=AVDTP_OPEN) A2DPD[09:37:43.762]: a2dp_state_machine: AVDTP_OPEN success A2DPD[09:37:43.762]: a2dp_state_machine: State AVDTP_STATE_CONFIGURED A2DPD[09:37:43.763]: a2dp_state_machine: State AVDTP_STATEX_STREAM_CONNECTING
A2DPD[09:37:43.764]: a2dp_state_machine: Socket is 11 A2DPD[09:37:43.764]: a2dp_state_machine: (errno=115:Operation now in progress)State AVDTP_STATEX_STREAM_CONNECTING_WAIT A2DPD[09:37:43.986]: a2dp_state_machine: Stream connected A2DPD[09:37:43.986]: get_socket_omtu: Socket 11 imtu=672, omtu=895, flush_to=65535
A2DPD[09:37:43.986]: a2dp_state_machine: State AVDTP_STATE_OPEN_START_STREAMING
A2DPD[09:37:43.987]: a2dp_state_machine: State AVDTP_STATEX_STREAM_STARTING A2DPD[09:37:43.987]: a2dpd_signal_state: Connected 00:19:1F:00:0A:4D A2DPD[09:37:43.988]: a2dp_state_machine: State AVDTP_STATEX_STREAM_STARTING_WAIT
A2DPD[09:37:44.089]: a2dp_handle_avdtp_message: Read non command packet (msgtype=MESSAGE_TYPE_ACCEPT,signal=AVDTP_START) A2DPD[09:37:44.089]: a2dp_state_machine: AVDTP_START success A2DPD[09:37:44.089]: a2dp_state_machine: State AVDTP_STATE_STREAMING A2DPD[09:37:44.935]: a2dp_transfer_raw: Wrote -1 not 613 bytes (sbc.len=100)