[pulseaudio-tickets] [PulseAudio] #636: pulseaudio aborts with assertion decoded == a2dp->frame_length in function a2dp_process_push()

PulseAudio trac-noreply at tango.0pointer.de
Tue Aug 25 07:49:08 PDT 2009 

#636: pulseaudio aborts with assertion decoded == a2dp->frame_length in function
a2dp_process_push()
--------------------+-------------------------------------------------------
 Reporter:  walmis  |       Owner:  lennart
     Type:  defect  |      Status:  new    
Milestone:          |   Component:  daemon 
 Keywords:          |  
--------------------+-------------------------------------------------------
 Steps to reproduce:[[BR]]
 1. start pulseaudio daemon[[BR]]
 2. issue a bluez AudioSource.Connect() dbus call[[BR]]
 3. daemon aborts with [[BR]]
 {{{
 Assertion '(size_t) decoded == a2dp->frame_length' failed at
 modules/bluetooth/module-bluetooth-device.c:1361, function
 a2dp_process_push(). Aborting.
 }}}

 device used: Nokia 3120 classic[[BR]]
 system: archlinux, kernel 2.6.30.4, bluez 4.48, pulseaudio git

 Here are the logs:

 pulseaudio:
 log starts just after Connect() was issued.
 {{{
 D: bluetooth-util.c: dbus: interface=org.bluez.AudioSource,
 path=/org/bluez/14821/hci0/dev_00_24_03_78_4A_5C, member=PropertyChanged
 E: module-bluetooth-discover.c: dead: 0, device_connected: 0, audio_state:
 0, audio_source_state: 1
 D: module-console-kit.c: dbus: interface=org.bluez.AudioSource,
 path=/org/bluez/14821/hci0/dev_00_24_03_78_4A_5C, member=PropertyChanged
 D: bluetooth-util.c: dbus: interface=org.bluez.Device,
 path=/org/bluez/14821/hci0/dev_00_24_03_78_4A_5C, member=PropertyChanged
 E: module-bluetooth-discover.c: dead: 0, device_connected: 1, audio_state:
 0, audio_source_state: 1
 D: module-console-kit.c: dbus: interface=org.bluez.Device,
 path=/org/bluez/14821/hci0/dev_00_24_03_78_4A_5C, member=PropertyChanged
 D: bluetooth-util.c: dbus: interface=org.bluez.AudioSource,
 path=/org/bluez/14821/hci0/dev_00_24_03_78_4A_5C, member=PropertyChanged
 E: module-bluetooth-discover.c: dead: 0, device_connected: 1, audio_state:
 0, audio_source_state: 2
 D: module-bluetooth-discover.c: Loading module-bluetooth-device
 address="00:24:03:78:4A:5C"
 path="/org/bluez/14821/hci0/dev_00_24_03_78_4A_5C" profile="a2dp_source"
 I: card.c: Created 0 "bluez_card.00_24_03_78_4A_5C"
 D: module-bluetooth-device.c: Connected to the bluetooth audio service
 D: module-bluetooth-device.c: Sending BT_REQUEST -> BT_GET_CAPABILITIES
 D: module-bluetooth-device.c: Trying to receive message from audio
 service...
 D: module-bluetooth-device.c: Received BT_RESPONSE <- BT_GET_CAPABILITIES
 D: module-bluetooth-device.c: Payload size is 13 6
 D: module-bluetooth-device.c: Sending BT_REQUEST -> BT_GET_CAPABILITIES
 D: module-bluetooth-device.c: Trying to receive message from audio
 service...
 D: module-bluetooth-device.c: Received BT_RESPONSE <- BT_GET_CAPABILITIES
 D: module-bluetooth-device.c: Payload size is 13 6
 D: module-bluetooth-device.c: Got device capabilities
 D: module-bluetooth-device.c: Sending BT_REQUEST -> BT_OPEN
 D: module-bluetooth-device.c: Trying to receive message from audio
 service...
 D: module-bluetooth-device.c: Received BT_RESPONSE <- BT_OPEN
 D: module-bluetooth-device.c: Sending BT_REQUEST -> BT_SET_CONFIGURATION
 D: module-bluetooth-device.c: Trying to receive message from audio
 service...
 D: module-bluetooth-device.c: Received BT_RESPONSE <- BT_SET_CONFIGURATION
 I: module-bluetooth-device.c: SBC parameters:
 I: module-bluetooth-device.c:   allocation=0
 I: module-bluetooth-device.c:   subbands=1
 I: module-bluetooth-device.c:   blocks=3
 I: module-bluetooth-device.c:   bitpool=48
 D: module-bluetooth-device.c: Connection to the device configured
 D: module-bluetooth-device.c: Got the stream socket
 I: source.c: Created source 1 "bluez_source.00_24_03_78_4A_5C" with sample
 spec s16le 2ch 48000Hz and channel map front-left,front-right
 I: source.c:     bluetooth.protocol = "a2dp_source"
 I: source.c:     device.description = "Nokia 3120 classic"
 I: source.c:     device.string = "00:24:03:78:4A:5C"
 I: source.c:     device.api = "bluez"
 I: source.c:     device.class = "sound"
 I: source.c:     device.bus = "bluetooth"
 I: source.c:     bluez.path =
 "/org/bluez/14821/hci0/dev_00_24_03_78_4A_5C"
 I: source.c:     bluez.class = "0x5a0204"
 I: source.c:     bluez.name = "Nokia 3120 classic"
 I: source.c:     device.icon_name = "audio-card-bluetooth"
 D: module-bluetooth-device.c: IO Thread starting up
 D: core-util.c: SCHED_RR worked.
 I: core-util.c: Successfully enabled SCHED_RR scheduling for thread, with
 priority 5.
 D: module-bluetooth-device.c: Sending BT_REQUEST -> BT_START_STREAM
 D: module-bluetooth-device.c: Trying to receive message from audio
 service...
 D: module-bluetooth-device.c: Received BT_RESPONSE <- BT_START_STREAM
 D: module-bluetooth-device.c: Trying to receive message from audio
 service...
 D: module-bluetooth-device.c: Received BT_RESPONSE <- BT_NEW_STREAM
 D: module-bluetooth-device.c: Stream properly set up, we're ready to roll!
 D: module-suspend-on-idle.c: Source bluez_source.00_24_03_78_4A_5C becomes
 idle, timeout in 5 seconds.
 I: module.c: Loaded "module-bluetooth-device" (index: #17; argument:
 "address="00:24:03:78:4A:5C"
 path="/org/bluez/14821/hci0/dev_00_24_03_78_4A_5C"
 profile="a2dp_source"").
 D: module-console-kit.c: dbus: interface=org.bluez.AudioSource,
 path=/org/bluez/14821/hci0/dev_00_24_03_78_4A_5C, member=PropertyChanged
 D: bluetooth-util.c: dbus: interface=org.bluez.AudioSource,
 path=/org/bluez/14821/hci0/dev_00_24_03_78_4A_5C, member=PropertyChanged
 E: module-bluetooth-discover.c: dead: 0, device_connected: 1, audio_state:
 0, audio_source_state: 3
 D: module-console-kit.c: dbus: interface=org.bluez.AudioSource,
 path=/org/bluez/14821/hci0/dev_00_24_03_78_4A_5C, member=PropertyChanged
 D: module-bluetooth-device.c: dbus: interface=org.bluez.AudioSource,
 path=/org/bluez/14821/hci0/dev_00_24_03_78_4A_5C, member=PropertyChanged
 D: module-bluetooth-device.c: SBC: decoded: 71; written: 512
 D: module-bluetooth-device.c: SBC: frame_length: 109; codesize: 512
 E: module-bluetooth-device.c: Assertion '(size_t) decoded ==
 a2dp->frame_length' failed at modules/bluetooth/module-bluetooth-
 device.c:1361, function a2dp_process_push(). Aborting.
 Aborted

 }}}

 bluetoothd, just after Connect() was issued
 {{{
 bluetoothd[12812]: avdtp_ref(0x7fcc7a7d2b90): ref=2
 bluetoothd[12812]: stream creation in progress
 bluetoothd[12812]: adapter_get_device(00:24:03:78:4A:5C)
 bluetoothd[12812]: link_key_request (sba=00:02:5B:00:6C:1D,
 dba=00:24:03:78:4A:5C)
 bluetoothd[12812]: kernel auth requirements = 0x00
 bluetoothd[12812]: stored link key type = 0x00
 bluetoothd[12812]: AVDTP: connected signaling channel to 00:24:03:78:4A:5C
 bluetoothd[12812]: AVDTP imtu=672, omtu=672
 bluetoothd[12812]: session_cb
 bluetoothd[12812]: DISCOVER request succeeded
 bluetoothd[12812]: seid 1 type 0 media 0 in use 0
 bluetoothd[12812]: session_cb
 bluetoothd[12812]: GET_CAPABILITIES request succeeded
 bluetoothd[12812]: seid 1 type 0 media 0
 bluetoothd[12812]: Discovery complete
 bluetoothd[12812]: a2dp_config: selected SEP 0x7fcc7a79c180
 bluetoothd[12812]: avdtp_ref(0x7fcc7a7d2b90): ref=3
 bluetoothd[12812]: setup_ref(0x7fcc7a7d7aa0): ref=1
 bluetoothd[12812]: avdtp_set_configuration(0x7fcc7a7d2b90): int_seid=2,
 acp_seid=1
 bluetoothd[12812]: session_cb
 bluetoothd[12812]: SET_CONFIGURATION request succeeded
 bluetoothd[12812]: Sink 0x7fcc7a79c180: Set_Configuration_Cfm
 bluetoothd[12812]: stream state changed: IDLE -> CONFIGURED
 bluetoothd[12812]: session_cb
 bluetoothd[12812]: OPEN request succeeded
 bluetoothd[12812]: AVDTP: connected transport channel to 00:24:03:78:4A:5C
 bluetoothd[12812]: Sink 0x7fcc7a79c180: Open_Cfm
 bluetoothd[12812]: setup_ref(0x7fcc7a7d7aa0): ref=2
 bluetoothd[12812]: Stream successfully created
 bluetoothd[12812]: setup_unref(0x7fcc7a7d7aa0): ref=1
 bluetoothd[12812]: setup_unref(0x7fcc7a7d7aa0): ref=0
 bluetoothd[12812]: setup_free(0x7fcc7a7d7aa0)
 bluetoothd[12812]: avdtp_unref(0x7fcc7a7d2b90): ref=2
 bluetoothd[12812]: stream state changed: CONFIGURED -> OPEN
 bluetoothd[12812]: Accepted new client connection on unix socket (fd=25)
 bluetoothd[12812]: Audio API: BT_REQUEST <- BT_GET_CAPABILITIES
 bluetoothd[12812]: avdtp_ref(0x7fcc7a7d2b90): ref=3
 bluetoothd[12812]: Media Codec: SBC Channel Modes: Mono JointStereo
 Frequencies: 48Khz  Subbands: 8  Blocks: 4 8 12 16  Bitpool: 8-48
 bluetoothd[12812]: Append configured seid 1 - length 13 - total 181
 bluetoothd[12812]: Audio API: BT_RESPONSE -> BT_GET_CAPABILITIES
 bluetoothd[12812]: Audio API: BT_REQUEST <- BT_GET_CAPABILITIES
 bluetoothd[12812]: Media Codec: SBC Channel Modes: JointStereo
 Frequencies: 48Khz  Subbands: 8  Blocks: 16  Bitpool: 8-48
 bluetoothd[12812]: Append configured seid 1 - length 13 - total 181
 bluetoothd[12812]: Audio API: BT_RESPONSE -> BT_GET_CAPABILITIES
 bluetoothd[12812]: Audio API: BT_REQUEST <- BT_OPEN
 bluetoothd[12812]: open a2dp -
 object=/org/bluez/12812/hci0/dev_00_24_03_78_4A_5C source=ANY
 destination=ANY lock=readwrite
 bluetoothd[12812]: SEP 0x7fcc7a79c180 locked
 bluetoothd[12812]: Audio API: BT_RESPONSE -> BT_OPEN
 bluetoothd[12812]: Audio API: BT_REQUEST <- BT_SET_CONFIGURATION
 bluetoothd[12812]: Media Codec: SBC Channel Modes: JointStereo
 Frequencies: 48Khz  Subbands: 8  Blocks: 16  Bitpool: 8-48
 bluetoothd[12812]: a2dp_config: selected SEP 0x7fcc7a79c180
 bluetoothd[12812]: avdtp_ref(0x7fcc7a7d2b90): ref=4
 bluetoothd[12812]: setup_ref(0x7fcc7a7d7b10): ref=1
 bluetoothd[12812]: Configuration match: resuming
 bluetoothd[12812]: setup_ref(0x7fcc7a7d7b10): ref=2
 bluetoothd[12812]: Audio API: BT_RESPONSE -> BT_SET_CONFIGURATION
 bluetoothd[12812]: setup_unref(0x7fcc7a7d7b10): ref=1
 bluetoothd[12812]: setup_unref(0x7fcc7a7d7b10): ref=0
 bluetoothd[12812]: setup_free(0x7fcc7a7d7b10)
 bluetoothd[12812]: avdtp_unref(0x7fcc7a7d2b90): ref=3
 bluetoothd[12812]: Audio API: BT_REQUEST <- BT_START_STREAM
 bluetoothd[12812]: avdtp_ref(0x7fcc7a7d2b90): ref=4
 bluetoothd[12812]: setup_ref(0x7fcc7a7d7b10): ref=1
 bluetoothd[12812]: session_cb
 bluetoothd[12812]: START request succeeded
 bluetoothd[12812]: Sink 0x7fcc7a79c180: Start_Cfm
 bluetoothd[12812]: setup_ref(0x7fcc7a7d7b10): ref=2
 bluetoothd[12812]: Audio API: BT_RESPONSE -> BT_START_STREAM
 bluetoothd[12812]: Audio API: BT_RESPONSE -> BT_NEW_STREAM
 bluetoothd[12812]: setup_unref(0x7fcc7a7d7b10): ref=1
 bluetoothd[12812]: setup_unref(0x7fcc7a7d7b10): ref=0
 bluetoothd[12812]: setup_free(0x7fcc7a7d7b10)
 bluetoothd[12812]: avdtp_unref(0x7fcc7a7d2b90): ref=3
 bluetoothd[12812]: stream state changed: OPEN -> STREAMING
 bluetoothd[12812]: Unix client disconnected (fd=25)
 bluetoothd[12812]: SEP 0x7fcc7a79c180 unlocked
 bluetoothd[12812]: avdtp_unref(0x7fcc7a7d2b90): ref=2
 bluetoothd[12812]: client_free(0x7fcc7a7d7aa0)
 bluetoothd[12812]: a2dp_cancel()
 bluetoothd[12812]: session_cb
 bluetoothd[12812]: SUSPEND request succeeded
 bluetoothd[12812]: stream state changed: STREAMING -> OPEN
 bluetoothd[12812]: Sink 0x7fcc7a79c180: Suspend_Cfm

 }}}

-- 
Ticket URL: <http://pulseaudio.org/ticket/636>
PulseAudio <http://pulseaudio.org/>
The PulseAudio Sound Server

More information about the pulseaudio-bugs mailing list