Hi all, I use bluez-5.29+ PA5.0 for A2DP playback function. When I test with iPadMini4, the connection is not stable, the problem is ; while PA enter "Starting playback...", then I can see the message from bluez : "profiles/audio/avdtp.c:connection_lost() Disconnected from 34:12:98:0D:9C:23" immediately the attached is bluez log and hcidump log .
bluetoothd[875]: src/adapter.c:connected_callback() hci0 device 34:12:98:0D:9C:23 connected eir_len 5 bluetoothd[875]: src/device.c:device_create() dst 34:12:98:0D:9C:23 bluetoothd[875]: src/device.c:device_new() address 34:12:98:0D:9C:23 bluetoothd[875]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_34_12_98_0D_9C_23 bluetoothd[875]: src/device.c:device_set_class() /org/bluez/hci0/dev_34_12_98_0D_9C_23 0x6A0110 Device 34:12:98:0D:9C:23 34-12-98-0D-9C-23 bluetoothd[875]: src/adapter.c:dev_disconnected() Device 34:12:98:0D:9C:23 disconnected, reason 3 bluetoothd[875]: src/adapter.c:adapter_remove_connection() bluetoothd[875]: src/adapter.c:adapter_remove_connection() Removing temporary device /org/bluez/hci0/dev_34_12_98_0D_9C_23 bluetoothd[875]: src/device.c:device_remove() Removing device /org/bluez/hci0/dev_34_12_98_0D_9C_23 bluetoothd[875]: src/device.c:btd_device_unref() Freeing device /org/bluez/hci0/dev_34_12_98_0D_9C_23 bluetoothd[875]: src/device.c:device_free() 0x5563f990 Device 34:12:98:0D:9C:23 34-12-98-0D-9C-23 bluetoothd[875]: plugins/policy.c:disconnect_cb() reason 3 bluetoothd[875]: src/adapter.c:bonding_attempt_complete() hci0 bdaddr 34:12:98:0D:9C:23 type 0 status 0xe bluetoothd[875]: src/adapter.c:resume_discovery() bluetoothd[875]: src/adapter.c:connected_callback() hci0 device 34:12:98:0D:9C:23 connected eir_len 13 bluetoothd[875]: src/device.c:device_create() dst 34:12:98:0D:9C:23 bluetoothd[875]: src/device.c:device_new() address 34:12:98:0D:9C:23 bluetoothd[875]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_34_12_98_0D_9C_23 bluetoothd[875]: src/device.c:device_set_class() /org/bluez/hci0/dev_34_12_98_0D_9C_23 0x6A0110 bluetoothd[875]: src/device.c:btd_device_device_set_name() /org/bluez/hci0/dev_34_12_98_0D_9C_23 MyiPad Device 34:12:98:0D:9C:23 MyiPad bluetoothd[875]: src/adapter.c:user_confirm_request_callback() hci0 34:12:98:0D:9C:23 confirm_hint 1 bluetoothd[875]: src/device.c:new_auth() Requesting agent authentication for 34:12:98:0D:9C:23 bluetoothd[875]: src/agent.c:agent_ref() 0x5563a2e0: ref=2 bluetoothd[875]: src/agent.c:agent_request_authorization() Calling Agent.RequestAuthorization: name=:1.2, path=/org/bluez/agent Request authorization bluetoothd[875]: src/agent.c:agent_ref() 0x5563a2e0: ref=3 bluetoothd[875]: src/adapter.c:btd_adapter_confirm_reply() hci0 addr 34:12:98:0D:9C:23 success 1 bluetoothd[875]: src/agent.c:agent_unref() 0x5563a2e0: ref=2 bluetoothd[875]: src/agent.c:agent_unref() 0x5563a2e0: ref=1 bluetoothd[875]: src/adapter.c:new_link_key_callback() hci0 new key for 34:12:98:0D:9C:23 type 4 pin_len 0 store_link_key 6142 Save LinkKey store_link_key 6146 BA ADDR:23:9C:0D:98:12:34 store_link_key 6147 BA ADDR:34:12:98:0D:9C:23 store_link_key 6149 BA ADDR:34:12:98:0D:9C:23 store_link_key 6156 Found Same entry! hci_encrypt_change_evt 1953 link mode 7 b hci_encrypt_change_evt 1976 call hci_encrypt_cfm lu hci_conn_check_link_mode 732 return 1 etoothd[875]: src/device.c:device_set_bonded() bluetoothd[875]: src/device.c:btd_device_set_temporary() temporary 0 bluetoothd[875]: src/device.c:device_bonding_complete() bonding hci_conn_check_link_mode 732 return 1 (nil) status 0x00 bluetoothd[875]: src/device.c:device_bonding_complete() setting timer for reverse service discovery bluetoothd[875]: src/adapter.c:resume_discovery() bluetoothd[875]: profiles/audio/avctp.c:avctp_confirm_cb() AVCTP: incoming connect from 34:12:98:0D:9C:23 bluetoothd[875]: src/device.c:device_probe_profiles() Probing profiles for device 34:12:98:0D:9C:23 bluetoothd[875]: profiles/audio/avrcp.c:avrcp_controller_probe() path /org/bluez/hci0/dev_34_12_98_0D_9C_23 bluetoothd[875]: profiles/audio/control.c:control_init() Registered interface org.bluez.MediaControl1 on path /org/bluez/hci0/dev_34_12_98_0D_9C_23 bluetoothd[875]: src/service.c:btd_service_ref() 0x5563e2a0: ref=2 bluetoothd[875]: src/service.c:change_state() 0x5563e2a0: device 34:12:98:0D:9C:23 profile avrcp-controller state changed: unavailable -> disconnected (0) bluetoothd[875]: src/device.c:device_proinput: 34:12:98:0D:9C:23 as /devices/virtual/input/input0 be_p hci_conn_check_link_mode 732 return 1 rofiles() Probing profiles for device 34:12:98:0D:9C:23 bluetoothd[875]: profiles/audio/avrcp.c:avrcp_targetBluetooth: 8383fd84_<3>Bluetooth: 8383fd84probe() path /org/bluez/hci0/dev_34_12_98_0D_9C_23 bluetoothd[875]: src/service.c:btd_service_ref() 0x5563e400: ref=2 bluetoothd[875]: src/service.c:change_state() 0x5563e400: device 34:12:98:0D:9C:23 profile audio-avrcp-target state changed: unavailable -> disconnected (0) bluetoothd[875]: profiles/audio/avctp.c:avctp_set_state() AVCTP Connecting store_device_info_cb 374 Enter bluetoothd[875]: profiles/audio/a2dp.c:confirm_cb() AVDTP: incoming connect from 34:12:98:0D:9C:23 bluetoothd[875]: src/device.c:device_probe_profiles() Probing profiles for device 34:12:98:0D:9C:23 bluetoothd[875]: profiles/audio/a2dp.c:a2dp_source_probe() path /org/bluez/hci0/dev_34_12_98_0D_9C_23 bluetoothd[875]: profiles/audio/source.c:source_init() /org/bluez/hci0/dev_34_12_98_0D_9C_23 bluetoothd[875]: src/service.c:btd_service_ref() 0x556459a8: ref=2 bluetoothd[875]: src/service.c:change_state() 0x556459a8: device 34:12:98:0D:9C:23 profile a2dp-source state changed: unavailable -> disconnected (0) bluetoothd[875]: src/device.c:device_svc_resolved() /org/bluez/hci0/dev_34_12_98_0D_9C_23 err 0 store_device_info_cb 374 Enter bluetoothd[875]: src/agent.c:agent_ref() 0x5563a2e0: ref=2 Authorize service bluetoothd[875]: src/agent.c:agent_authorize_service() authorize service request was sent for /org/bluez/hci0/dev_34_12_98_0D_9C_23 bluetoothd[875]: src/agent.c:agent_ref() 0x5563a2e0: ref=3 bluetoothd[875]: src/agent.c:agent_unref() 0x5563a2e0: ref=2 bluetoothd[875]: src/agent.c:agent_unref() 0x5563a2e0: ref=1 bluetoothd[875]: src/agent.c:agent_ref() 0x5563a2e0: ref=2 Authorize service bluetoothd[875]: src/agent.c:agent_authorize_service() authorize service request was sent for /org/bluez/hci0/dev_34_12_98_0D_9C_23 bluetoothd[875]: profiles/audio/avctp.c:avctp_connect_cb() AVCTP: connected to 34:12:98:0D:9C:23 bluetoothd[875]: profiles/audio/avctp.c:init_uinput() AVRCP: uinput initialized for 34:12:98:0D:9C:23 bluetoothd[875]: profiles/audio/avrcp.c:controller_init() 0x5563fd70 version 0x0104 bluetoothd[875]: src/service.c:change_state() 0x5563e400: device 34:12:98:0D:9C:23 profile audio-avrcp-target state changed: disconnected -> connected (0) bluetoothd[875]: profiles/audio/player.c:media_player_controller_create() /org/bluez/hci0/dev_34_12_98_0D_9C_23/player0 bluetoothd[875]: profiles/audio/avrcp.c:target_init() 0x55647c58 version 0x0104 bluetoothd[875]: src/service.c:change_state() 0x5563e2a0: device 34:12:98:0D:9C:23 profile avrcp-controller state changed: disconnected -> connected (0) bluetoothd[875]: profiles/audio/avctp.c:avctp_set_state() AVCTP Connected bluetoothd[875]: profiles/audio/avctp.c:avctp_confirm_cb() AVCTP: incoming connect from 34:12:98:0D:9C:23 bluetoothd[875]: profiles/audio/avctp.c:avctp_set_state() AVCTP Browsing Connecting bluetoothd[875]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x10, company 0x001958 len 0x0001 bluetoothd[875]: profiles/audio/avrcp.c:avrcp_handle_get_capabilities() id=3 bluetoothd[875]: src/agent.c:agent_ref() 0x5563a2e0: ref=3 bluetoothd[875]: src/agent.c:agent_unref() 0x5563a2e0: ref=2 bluetoothd[875]: src/agent.c:agent_unref() 0x5563a2e0: ref=1 bluetoothd[875]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x31, company 0x001958 len 0x0005 bluetoothd[875]: profiles/audio/avctp.c:avctp_connect_browsing_cb() AVCTP Browsing: connected to 34:12:98:0D:9C:23 bluetoothd[875]: profiles/audio/avctp.c:avctp_set_state() AVCTP Browsing Connected bluetoothd[875]: profiles/audio/source.c:source_set_state() State changed /org/bluez/hci0/dev_34_12_98_0D_9C_23: SOURCE_STATE_DISCONNECTED -> SOURCE_STATE_CONNECTING bluetoothd[875]: src/device.c:device_probe_profiles() Probing profiles for device 34:12:98:0D:9C:23 bluetoothd[875]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP: connected signaling channel to 34:12:98:0D:9C:23 bluetoothd[875]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP imtu=672, omtu=672 store_device_info_cb 374 Enter bluetoothd[875]: profiles/audio/avdtp.c:session_cb() bluetoothd[875]: profiles/audio/avdtp.c:avdtp_parse_cmd() Received DISCOVER_CMD bluetoothd[875]: profiles/audio/avdtp.c:session_cb() bluetoothd[875]: profiles/audio/avdtp.c:avdtp_parse_cmd() Received GET_ALL_CAPABILITIES_CMD bluetoothd[875]: profiles/audio/a2dp.c:endpoint_getcap_ind() Sink 0x5563ade8: Get_Capability_Ind disconnect_timeout 1144 call source_setup_stream bluetoothd[875]: profiles/audio/avdtp.c:avdtp_ref() 0x55642100: ref=1 source_setup_stream 288 call avdtp_discover avdtp_discover 3201 call send_request AVDTP_DISCOVER bluetoothd[875]: profiles/audio/avdtp.c:session_cb() bluetoothd[875]: profiles/audio/avdtp.c:avdtp_parse_cmd() Received SET_CONFIGURATION_CMD bluetoothd[875]: profiles/audio/a2dp.c:endpoint_setconf_ind() Sink 0x5563ade8: Set_Configuration_Ind bluetoothd[875]: profiles/audio/avdtp.c:avdtp_ref() 0x55642100: ref=2 bluetoothd[875]: profiles/audio/a2dp.c:setup_ref() 0x55642d80: ref=1 bluetoothd[875]: profiles/audio/avrcp.c:avrcp_set_volume() volume=127 bluetoothd[875]: profiles/audio/media.c:media_endpoint_async_call() Calling SetConfiguration: name = :1.1 path = /MediaEndpoint/A2DPSink setconf_cb 1405 set inuse=1 bluetoothd[875]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: IDLE -> CONFIGURED bluetoothd[875]: profiles/audio/a2dp.c:setup_unref() 0x55642d80: ref=0 bluetoothd[875]: profiles/audio/a2dp.c:setup_free() 0x55642d80 hci_conn_check_link_mode 732 return 1 setup_free 161 call avdtp_unref bluetoothd[875]: profiles/audio/avdtp.c:avdtp_unref() 0x55642100: ref=1 bluetoothd[875]: profiles/audio/avdtp.c:session_cb() bluetoothd[875]: profiles/audio/avdtp.c:avdtBluetooth: 8383fd84p_parse_resp() DISCOVER request succeeded bluetoothd[875]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 1 type 0 media 0 in use 0 bluetoothd[875]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 2 type 0 media 0 in use 0 bluetoothd[875]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 3 type 0 media 0 in use 0 bluetoothd[875]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 4 type 0 media 0 in use 0 N: [pulseaudio] 1 SampleRate:44100 bluetoothd[875]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 5 type 0 media 0 in use 0 bluetoothd[875]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 6 type 0 media 0 in use 0 bluetoothd[875]: profiles/audio/avdtp.c:session_cb() bluetoothd[875]: profiles/audio/avdtp.c:avdtp_parse_cmd() Received OPEN_CMD bluetoothd[875]: profiles/audio/a2dp.c:open_ind() Sink 0x5563ade8: Open_Ind bluetoothd[875]: profiles/audio/avdtp.c:avdtp_ref() 0x55642100: ref=2 bluetoothd[875]: profiles/audio/a2dp.c:setup_ref() 0x556478b0: ref=1 bluetoothd[875]: profiles/audio/avdtp.c:session_cb() bluetoothd[875]: profiles/audio/avdtp.c:avdtp_parse_resp() DELAY_REPORT request succeeded bluetoothd[875]: profiles/audio/a2dp.c:delay_report_cfm() Sink 0x5563ade8: DelayReport_Cfm bluetoothd[875]: profiles/audio/a2dp.c:confirm_cb() AVDTP: incoming connect from 34:12:98:0D:9C:23 bluetoothd[875]: profiles/audio/player.c:media_player_set_setting() Repeat: alltracks bluetoothd[875]: profiles/audio/player.c:media_player_set_setting() Shuffle: alltracks N: [alsa-sink-RT5651 rt5651-aif1-0] Resumed successfully... N: [alsa-sink-RT5651 rt5651-aif1-0] Single Starting playback... bluetoothd[875]: profiles/audio/avdtp.c:session_cb() bluetoothd[875]: profiles/audio/avdtp.c:avdtp_parse_resp() GET_ALL_CAPABILITIES request succeeded bluetoothd[875]: profiles/audio/avdtp.c:avdtp_get_capabilities_resp() seid 1 type 0 media 0 handle_transport_connect 817 goto proceed sep->info.type=1 handle_transport_connect 845 set AVDTP_STATE_OPEN bluetoothd[875]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: CONFIGURED -> OPEN bluetoothd[875]: src/service.c:change_state() 0x556459a8: device 34:12:98:0D:9C:23 profile a2dp-source state changed: disconnected -> connected (0) bluetoothd[875]: src/service.c:btd_service_ref() 0x556459a8: ref=3 bluetoothd[875]: plugins/policy.c:service_cb() Added a2dp-source reconnect 1 bluetoothd[875]: profiles/audio/source.c:source_set_state() State changed /org/bluez/hci0/dev_34_12_98_0D_9C_23: SOURCE_STATE_CONNECTING -> SOURCE_STATE_CONNECTED bluetoothd[875]: profiles/audio/transport.c:transport_update_playing() /org/bluez/hci0/dev_34_12_98_0D_9C_23/fd0 State=TRANSPORT_STATE_IDLE Playing=0 bluetoothd[875]: profiles/audio/a2dp.c:setup_unref() 0x556478b0: ref=0 bluetoothd[875]: profiles/audio/a2dp.c:setup_free() 0x556478b0 setup_free 161 call avdtp_unref bluetoothd[875]: profiles/audio/avdtp.c:avdtp_unref() 0x55642100: ref=1 bluetoothd[875]: profiles/audio/avdtp.c:session_cb() bluetoothd[875]: profiles/audio/avdtp.c:avdtp_parse_resp() GET_ALL_CAPABILITIES request succeeded bluetoothd[875]: profiles/audio/avdtp.c:avdtp_get_capabilities_resp() seid 2 type 0 media 0 bluetoothd[875]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x50, company 0x001958 len 0x0001 bluetoothd[875]: profiles/audio/player.c:media_player_set_status() paused bluetoothd[875]: profiles/audio/avdtp.c:session_cb() bluetoothd[875]: profiles/audio/avdtp.c:avdtp_parse_resp() GET_ALL_CAPABILITIES request succeeded bluetoothd[875]: profiles/audio/avdtp.c:avdtp_get_capabilities_resp() seid 3 type 0 media 0 bluetoothd[875]: profiles/audio/avdtp.c:session_cb() bluetoothd[875]: profiles/audio/avdtp.c:avdtp_parse_resp() GET_ALL_CAPABILITIES request succeeded bluetoothd[875]: profiles/audio/avdtp.c:avdtp_get_capabilities_resp() seid 4 type 0 media 0 bluetoothd[875]: profiles/audio/player.c:media_player_set_duration() 241737 bluetoothd[875]: profiles/audio/player.c:media_player_set_position() 22140 bluetoothd[875]: profiles/audio/player.c:media_player_set_status() paused media_player_set_status 1264 Same status return bluetoothd[875]: profiles/audio/avdtp.c:session_cb() bluetoothd[875]: profiles/audio/avdtp.c:avdtp_parse_resp() GET_ALL_CAPABILITIES request succeeded bluetoothd[875]: profiles/audio/avdtp.c:avdtp_get_capabilities_resp() seid 5 type 0 media 0 bluetoothd[875]: profiles/audio/avdtp.c:session_cb() bluetoothd[875]: profiles/audio/avdtp.c:avdtp_parse_resp() GET_ALL_CAPABILITIES request succeeded bluetoothd[875]: profiles/audio/avdtp.c:avdtp_get_capabilities_resp() seid 6 type 0 media 0 bluetoothd[875]: profiles/audio/source.c:discovery_complete() Discovery complete avdtp_find_remote_sep 1208 info inuse a2dp_find_sep 1781 remote sep NULL a2dp_find_sep 1797 return NULL avdtp_find_remote_sep 1208 info inuse a2dp_find_sep 1781 remote sep NULL a2dp_find_sep 1797 return NULL bluetoothd[875]: Unable to select SEP a2dp_select_capabilities 1839 a2dp_select_capabilities err discovery_complete 260 a2dp_select_capabilities err discovery_complete 270 call avdtp_unref bluetoothd[875]: profiles/audio/avdtp.c:avdtp_unref() 0x55642100: ref=0 avdtp_unref 1175 call set_disconnect_timer disconnect_timeout 1148 call connection_lost bluetoothd[875]: profiles/audio/avdtp.c:connection_lost() Disconnected from 34:12:98:0D:9C:23 bluetoothd[875]: profiles/audio/a2dp.c:abort_cfm() Sink 0x5563ade8: Abort_Cfm bluetoothd[875]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: OPEN -> IDLE bluetoothd[875]: src/service.c:change_state() 0x556459a8: device 34:12:98:0D:9C:23 profile a2dp-source state changed: connected -> disconnected (0) bluetoothd[875]: plugins/policy.c:policy_disconnect() /org/bluez/hci0/dev_34_12_98_0D_9C_23 profile audio-avrcp-target bluetoothd[875]: src/service.c:change_state() 0x5563e400: device 34:12:98:0D:9C:23 profile audio-avrcp-target state changed: connected -> disconnecting (0) bluetoothd[875]: profiles/audio/avrcp.c:avrcp_disconnect() path /org/bluez/hci0/dev_34_12_98_0D_9C_23 bluetoothd[875]: profiles/audio/avrcp.c:controller_destroy() 0x5563fd70 bluetoothd[875]: profiles/audio/player.c:media_player_destroy() /org/bluez/hci0/dev_34_12_98_0D_9C_23/player0 bluetoothd[875]: src/service.c:change_state() 0x5563e400: device 34:12:98:0D:9C:23 profile audio-avrcp-target state changed: disconnecting -> disconnected (0) bluetoothd[875]: profiles/audio/avrcp.c:target_destroy() 0x55647c58 bluetoothd[875]: src/service.c:change_state() 0x5563e2a0: device 34:12:98:0D:9C:23 profile avrcp-controller state changed: connected -> disconnected (0) bluetoothd[875]: profiles/audio/avctp.c:avctp_set_state() AVCTP Disconnected N: [alsa-sink-RT5651 rt5651-aif1-0] Device suspended...
Attachment:
bt_hcidump_iPadMini4-0902-1.log
Description: Binary data
