#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 _______________________________________________ pulseaudio-tickets mailing list pulseaudio-tickets@mail.0pointer.de https://tango.0pointer.de/mailman/listinfo/pulseaudio-tickets