android-rpi/device_brcm_rpi3

Audio not coming out of BT Speaker

pinayour opened this issue · 0 comments

pinayour commented

Hi,

I am able to pair and connect to my BT speaker. However, audio samples are not getting rendered on to it.

When i took a logcat, i see the following :

--------- beginning of main
02-02 17:19:40.945 I/BluetoothDevice( 559): createBond() for device 04:52:C7:76:10:B9 called by pid: 559 tid: 559
02-02 17:19:40.949 I/BluetoothBondStateMachine( 8131): Bond address is:04:52:C7:76:10:B9
02-02 17:19:40.949 D/bt_btif_config( 8131): btif_get_device_type: Device [04:52:c7:76:10:b9] type 1
02-02 17:19:40.950 I/bt_btif_dm( 8131): get_cod remote_cod = 0x00240418
02-02 17:19:40.951 I/bt_btif_dm( 8131): get_cod remote_cod = 0x00240418
02-02 17:19:40.952 D/bt_btif_config( 8131): btif_get_device_type: Device [04:52:c7:76:10:b9] type 1
02-02 17:19:40.952 I/bt_btif_dm( 8131): get_cod remote_cod = 0x00240418
02-02 17:19:40.953 I/BluetoothBondStateMachine( 8131): bondStateChangeCallback: Status: 0 Address: 04:52:C7:76:10:B9 newState: 1
02-02 17:19:40.954 I/BluetoothBondStateMachine( 8131): Entering PendingCommandState State
02-02 17:19:40.958 I/BluetoothBondStateMachine( 8131): Bond State Change Intent:04:52:C7:76:10:B9 OldState: 10 NewState: 11
02-02 17:19:40.967 D/Index ( 559): Indexing locale 'en' took 3 millis
02-02 17:19:40.995 D/AudioFlinger( 136): mixer(0xa751e140) throttle end: throttle time(29)
02-02 17:19:43.197 W/bt_btif ( 8131): bta_dm_acl_change info: 0x10
02-02 17:19:43.197 D/bt_btif_dm( 8131): remote version info [04:52:c7:76:10:b9]: 6, a, 263a
02-02 17:19:43.199 E/BluetoothRemoteDevices( 8131): state12newState0
02-02 17:19:43.521 D/bt_btif_config( 8131): btif_get_device_type: Device [04:52:c7:76:10:b9] type 1
02-02 17:19:43.563 D/bt_btif_config( 8131): btif_get_device_type: Device [04:52:c7:76:10:b9] type 1
02-02 17:19:43.563 I/bt_btif_dm( 8131): get_cod remote_cod = 0x00240418
02-02 17:19:43.682 I/bt_bta_dm( 8131): bta_dm_find_services services_to_search=3fffffff
02-02 17:19:43.682 I/bt_bta_dm( 8131): bta_dm_find_services search UUID = 1200
02-02 17:19:43.785 W/bt_sdp ( 8131): process_service_search_attr_rsp
02-02 17:19:43.785 W/bt_sdp ( 8131): sdp_copy_raw_data: list_len:54 cpy_len:54 p:0x9afc800b p_ccb:0x9cf36050 p_db:0x9afb1008 raw_size:4096 raw_used:0 raw_data:0x9cf4f674
02-02 17:19:43.841 I/bt_bta_dm( 8131): bta_dm_find_services services_to_search=3ffffffe
02-02 17:19:43.841 I/bt_bta_dm( 8131): bta_dm_find_services search UUID = 0100
02-02 17:19:44.032 W/bt_sdp ( 8131): process_service_search_attr_rsp
02-02 17:19:44.032 W/bt_sdp ( 8131): sdp_copy_raw_data: list_len:180 cpy_len:180 p:0x9afca80b p_ccb:0x9cf36050 p_db:0x9afb1008 raw_size:4096 raw_used:0 raw_data:0x9cf4f674
02-02 17:19:44.039 I/bt_btif_dm( 8131): btif_dm_search_services_evt index:0 uuid:0000110b-0000-1000-8000-00805f9b34fb
02-02 17:19:44.039 I/bt_btif_dm( 8131): btif_dm_search_services_evt index:1 uuid:0000110e-0000-1000-8000-00805f9b34fb
02-02 17:19:44.039 I/bt_btif_dm( 8131): btif_dm_search_services_evt index:2 uuid:00000000-0000-1000-8000-00805f9b34fb
02-02 17:19:44.039 D/bt_btif_config( 8131): btif_get_device_type: Device [04:52:c7:76:10:b9] type 1
02-02 17:19:44.039 I/bt_btif_dm( 8131): get_cod remote_cod = 0x00240418
02-02 17:19:44.039 I/BluetoothBondStateMachine( 8131): bondStateChangeCallback: Status: 0 Address: 04:52:C7:76:10:B9 newState: 2
02-02 17:19:44.041 D/BluetoothAdapterService( 8131): handleMessage() - Message: 40
02-02 17:19:44.042 D/BluetoothAdapterService( 8131): handleMessage() - MESSAGE_PROFILE_INIT_PRIORITIES
02-02 17:19:44.042 D/HidService( 8131): getHidService(): returning com.android.bluetooth.hid.HidService@ad50404
02-02 17:19:44.042 D/A2dpSinkService( 8131): getA2dpSinkService(): service is NULL
02-02 17:19:44.052 D/BluetoothAdapterProperties( 8131): Adding bonded device:04:52:C7:76:10:B9
02-02 17:19:44.055 I/BluetoothBondStateMachine( 8131): Bond State Change Intent:04:52:C7:76:10:B9 OldState: 11 NewState: 12
02-02 17:19:44.055 I/BluetoothBondStateMachine( 8131): StableState(): Entering Off State
02-02 17:19:44.063 D/LocalBluetoothProfileManager( 559): Current Profiles[]
02-02 17:19:44.063 D/BluetoothMap( 559): getConnectedDevices()
02-02 17:19:44.065 D/BluetoothMap( 559): getConnectionState(04:52:C7:76:10:B9)
02-02 17:19:44.067 D/MapProfile( 559): getConnectionStatus: status is: 0
02-02 17:19:44.067 D/LocalBluetoothProfileManager( 559): New Profiles[A2DP]
02-02 17:19:44.111 D/BluetoothA2dp( 559): connect(04:52:C7:76:10:B9)
02-02 17:19:44.118 D/A2dpStateMachine( 8131): Disconnected process message: 1
02-02 17:19:44.118 D/CachedBluetoothDevice( 559): Command sent successfully:CONNECT Address:04:52:C7:76:10:B9 Profile:A2DP
02-02 17:19:44.120 I/BluetoothA2dpServiceJni( 8131): connectA2dpNative: sBluetoothA2dpInterface: 0x9ce771e0
02-02 17:19:44.121 I/BluetoothA2dpServiceJni( 8131): bta2dp_connection_state_callback
02-02 17:19:44.121 E/bt_btif ( 8131): reset flags
02-02 17:19:44.121 D/A2dpStateMachine( 8131): Exit Disconnected: 1
02-02 17:19:44.121 D/A2dpStateMachine( 8131): Enter Pending: 1
02-02 17:19:44.122 D/A2dpStateMachine( 8131): Pending process message: 101
02-02 17:19:44.122 D/A2dpStateMachine( 8131): Stack and target device are connecting
02-02 17:19:44.123 D/A2dpStateMachine( 8131): Connection state 04:52:C7:76:10:B9: 0->1
02-02 17:19:44.123 D/BluetoothAdapterService( 8131): getAdapterService() - returning com.android.bluetooth.btservice.AdapterService@59634e0
02-02 17:19:44.125 D/BluetoothAdapterService( 8131): handleMessage() - Message: 20
02-02 17:19:44.125 D/BluetoothAdapterService( 8131): handleMessage() - MESSAGE_PROFILE_CONNECTION_STATE_CHANGED
02-02 17:19:44.127 D/BluetoothAdapterProperties( 8131): CONNECTION_STATE_CHANGE: 04:52:C7:76:10:B9: 0 -> 1
02-02 17:19:44.129 E/wpa_supplicant( 8945): wpa_driver_nl80211_driver_cmd: failed to issue private command: BTCOEXSCAN-START
02-02 17:19:44.154 D/CachedBluetoothDevice( 559): onProfileStateChanged: profile A2DP newProfileState 1
02-02 17:19:44.218 W/bt_sdp ( 8131): process_service_search_attr_rsp
02-02 17:19:44.405 E/bt_btif ( 8131): bta_av_rc_create ACP handle exist for shdl:0
02-02 17:19:44.437 I/VysorMain(12074): creating sync frame
02-02 17:19:44.602 W/bt_btif ( 8131): bta_dm_rm_cback:0, status:0
02-02 17:19:44.602 W/bt_btif ( 8131): new conn_srvc id:18, app_id:0
02-02 17:19:44.602 W/bt_btif ( 8131): bta_dm_pm_ssr conn_srvc id:18, app_id:0
02-02 17:19:44.602 W/bt_btif ( 8131): bta_dm_pm_ssr ssr:2, lat:1200
02-02 17:19:44.602 I/BluetoothA2dpServiceJni( 8131): bta2dp_connection_state_callback
02-02 17:19:44.603 D/A2dpStateMachine( 8131): Pending process message: 101
02-02 17:19:44.607 D/A2dpStateMachine( 8131): Enter Connected: 101
02-02 17:19:44.610 D/A2dpStateMachine( 8131): Connection state 04:52:C7:76:10:B9: 1->2
02-02 17:19:44.610 D/BluetoothAdapterService( 8131): getAdapterService() - returning com.android.bluetooth.btservice.AdapterService@59634e0
02-02 17:19:44.610 D/APM_AudioPolicyManager( 136): setDeviceConnectionState() could not find HW module for device 00000080
02-02 17:19:44.612 D/A2dpStateMachine( 8131): A2DP Playing state : device: 04:52:C7:76:10:B9 State:10->11
02-02 17:19:44.612 D/CachedBluetoothDevice( 559): onProfileStateChanged: profile A2DP newProfileState 2
02-02 17:19:44.612 D/BluetoothAdapterService( 8131): handleMessage() - Message: 20
02-02 17:19:44.612 D/BluetoothAdapterService( 8131): handleMessage() - MESSAGE_PROFILE_CONNECTION_STATE_CHANGED
02-02 17:19:44.612 D/BluetoothAdapterService( 8131): Profile connected. Schedule missing profile connection if any
02-02 17:19:44.612 D/BluetoothAdapterService( 8131): isQuetModeEnabled() - Enabled = false
02-02 17:19:44.630 V/MediaRouter( 415): Updating audio routes: AudioRoutesInfo{ type=SPEAKER, bluetoothName=Bose Colour SoundLink }
02-02 17:19:44.630 V/MediaRouter( 492): Updating audio routes: AudioRoutesInfo{ type=SPEAKER, bluetoothName=Bose Colour SoundLink }
02-02 17:19:44.630 V/MediaRouter( 415): Adding route: RouteInfo{ name=Bose Colour SoundLink, description=Bluetooth audio, status=null, category=RouteCategory{ name=null types=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO groupable=false }, supportedTypes=ROUTE_TYPE_LIVE_AUDIO , presentationDisplay=null }
02-02 17:19:44.630 V/MediaRouter( 492): Adding route: RouteInfo{ name=Bose Colour SoundLink, description=Bluetooth audio, status=null, category=RouteCategory{ name=null types=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO groupable=false }, supportedTypes=ROUTE_TYPE_LIVE_AUDIO , presentationDisplay=null }
02-02 17:19:44.631 V/MediaRouter( 415): Selecting route: RouteInfo{ name=Bose Colour SoundLink, description=Bluetooth audio, status=null, category=RouteCategory{ name=null types=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO groupable=false }, supportedTypes=ROUTE_TYPE_LIVE_AUDIO , presentationDisplay=null }
02-02 17:19:44.632 V/MediaRouter( 492): Selecting route: RouteInfo{ name=Bose Colour SoundLink, description=Bluetooth audio, status=null, category=RouteCategory{ name=null types=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO groupable=false }, supportedTypes=ROUTE_TYPE_LIVE_AUDIO , presentationDisplay=null }
02-02 17:19:44.632 V/MediaRouter( 415): Updating audio routes: AudioRoutesInfo{ type=SPEAKER, bluetoothName=Bose Colour SoundLink }
02-02 17:19:44.634 V/MediaRouter( 492): Updating audio routes: AudioRoutesInfo{ type=SPEAKER, bluetoothName=Bose Colour SoundLink }
02-02 17:19:44.635 V/MediaRouter( 492): Updating audio routes: AudioRoutesInfo{ type=SPEAKER, bluetoothName=Bose Colour SoundLink }
02-02 17:19:44.635 V/MediaRouter( 415): Updating audio routes: AudioRoutesInfo{ type=SPEAKER, bluetoothName=Bose Colour SoundLink }
02-02 17:19:44.637 E/wpa_supplicant( 8945): wpa_driver_nl80211_driver_cmd: failed to issue private command: BTCOEXSCAN-START
02-02 17:19:44.637 I/wpa_supplicant( 8945): wlan0: CTRL-EVENT-DRIVER-STATE HANGED
02-02 17:19:44.639 D/BluetoothAdapterProperties( 8131): CONNECTION_STATE_CHANGE: 04:52:C7:76:10:B9: 1 -> 2
02-02 17:19:44.640 D/WifiStateMachine( 415): WifiStateMachine: Leaving Connected state
02-02 17:19:44.658 D/WifiNative-HAL( 415): stopRssiMonitoring, cmdId 0
02-02 17:19:44.672 D/DhcpClient( 415): doQuit
--------- beginning of system
02-02 17:19:44.678 D/ConnectivityService( 415): NetworkAgentInfo [WIFI () - 102] EVENT_NETWORK_INFO_CHANGED, going from CONNECTED to DISCONNECTED
02-02 17:19:44.678 D/ConnectivityService( 415): NetworkAgentInfo [WIFI () - 102] got DISCONNECTED, was satisfying 4
02-02 17:19:44.682 I/WifiConnectivityManager( 415): Set WiFi disabled
02-02 17:19:44.692 D/DhcpClient( 415): Receive thread stopped

Please help