AnsweredAssumed Answered

Unable to turn on Bluetooth in Android 4.4 on i.MX6 SL

Question asked by Raghu Chaitanya on Nov 20, 2015

Hi

 

We are porting BLE drivers (Murata DA14580 BLE module )to Android 4.4 on i.MX6 Sololite platform on UART interface. We are able to load the firmware successfully onto the module when the Bluetooth is enabled from the Android UI. After loading the firmware , I observed  " E/BluetoothAdapterState( 3104): Error enabling Bluetooth " in the logcat. Can any one help me to understand the probable cause of this error ? Below are the complete logcat messages

 

 

 

I/bt_hci_bdroid( 3104): ...epilog_wait_timeout...

I/bt_hci_bdroid( 3104): bt_hc_worker_thread exiting

W/bt_userial( 3104): select_read return size <=0:-1, exiting userial_read_thread

I/bt_vendor( 3104): AR3002 ::BT_VND_OP_USERIAL_CLOSE

I/bt_userial_vendor( 3104): device fd = 289 close

D/BTSNOOP-DISP( 3104): btsnoop_close

I/bt_vendor( 3104): cleanup

I/bt_vendor( 3104): [//]AR3002 UPIO_BT_POWER_OFF

I/bt_upio ( 3104): is_emulator_context : 0

I/bt_upio ( 3104): check if we have rfkill interface?

I/bt_upio ( 3104): is_rfkill_disabled ? [0]

I/bt_upio ( 3104): Check rfkill_id == -1?

I/bt_upio ( 3104): open set_bluetooth_power success

I/bt_upio ( 3104): write bluetooth_power success

I/GKI_LINUX( 3104): gki_task_entry: gki_task task_id=0 [BTU] terminating

I/GKI_LINUX( 3104): GKI_exit_task: GKI_exit_task 0 done

I/GKI_LINUX( 3104): GKI_destroy_task: GKI_shutdown(): task [BTU] terminated

E/BluetoothServiceJni( 3104): Callback env check fail: env: 0x40cf29c0, callback: 0x68527240

E/BluetoothServiceJni( 3104): Callback: 'adapter_state_change_callback' is not called on the correct thread

I/GKI_LINUX( 3104): gki_task_entry: gki_task task_id=1 [BTIF] terminating

I/GKI_LINUX( 3104): GKI_exit_task: GKI_exit_task 1 done

I/GKI_LINUX( 3104): GKI_destroy_task: GKI_shutdown(): task [BTIF] terminated

I/GKI_LINUX( 3104): gki_task_entry: gki_task task_id=2 [A2DP-MEDIA] terminating

I/GKI_LINUX( 3104): GKI_exit_task: GKI_exit_task 2 done

I/BluetoothServiceJni( 3104): cleanupNative: return from cleanup

D/BluetoothAdapterService( 3104): Done cleaning up adapter native....

D/BluetoothAdapterService(1092270112)( 3104): ****onDestroy()********

D/dalvikvm( 2843): GC_CONCURRENT freed 573K, 46% free 4673K/8596K, paused 9ms+2ms, total 55ms

D/BluetoothManagerService( 2768): enable():  mBluetooth =null mBinding = false

D/BluetoothManagerService( 2768): Message: 1

D/BluetoothManagerService( 2768): MESSAGE_ENABLE: mBluetooth = null

D/BluetoothAdapterService( 3104): REFCOUNT: CREATED. INSTANCE_COUNT3

D/BluetoothAdapterState( 3104): make

I/bluedroid( 3104): init

I/bte_conf( 3104): Attempt to load stack conf from /etc/bluetooth/bt_stack.conf

I/bluedroid( 3104): get_profile_interface socket

D/BluetoothManagerService( 2768): BluetoothServiceConnection: com.android.bluetooth.btservice.AdapterService

D/BluetoothManagerService( 2768): Message: 40

D/BluetoothManagerService( 2768): MESSAGE_BLUETOOTH_SERVICE_CONNECTED: 1

I/bluedroid( 3104): config_hci_snoop_log

D/BluetoothManagerService( 2768): Calling onBluetoothServiceUp callbacks

D/BluetoothManagerService( 2768): Broadcasting onBluetoothServiceUp() to 5 receivers.

I/BluetoothAdapterState( 3104): Entering OffState

D/BluetoothAdapterState( 3104): CURRENT_STATE=OFF, MESSAGE = USER_TURN_ON

D/BluetoothAdapterProperties( 3104): Setting state to 11

I/BluetoothAdapterState( 3104): Bluetooth adapter state changed: 10-> 11

D/BluetoothAdapterService( 3104): Broadcasting updateAdapterState() to 1 receivers.

D/BluetoothBondStateMachine( 3104): make

D/BluetoothManagerService( 2768): Message: 60

D/BluetoothManagerService( 2768): MESSAGE_BLUETOOTH_STATE_CHANGE: prevState = 10, newState=11

D/BluetoothManagerService( 2768): Bluetooth State Change Intent: 10 -> 11

D/BluetoothMapService( 3104): onReceive

I/GKI_LINUX( 3104): gki_task_entry: gki_task_entry task_id=1 [BTIF] starting

I/BluetoothAdapterProperties( 3104): adapterPropertyChangedCallback with type:2 len:6

D/BluetoothAdapterProperties( 3104): Address is:22:22:59:D1:04:07

D/HeadsetService( 3104): Received start request. Starting profile...

D/HeadsetStateMachine( 3104): make

I/BluetoothBondStateMachine( 3104): StableState(): Entering Off State

I/BluetoothAdapterProperties( 3104): adapterPropertyChangedCallback with type:1 len:9

D/BluetoothManagerService( 2768): Bluetooth Adapter name changed to EVK_MX6SL

D/BluetoothManagerService( 2768): Stored Bluetooth name: EVK_MX6SL

D/BluetoothAdapterProperties( 3104): Name is: EVK_MX6SL

I/BluetoothAdapterState( 3104): Entering PendingCommandState State: isTurningOn()=true, isTurningOff()=false

W/BluetoothHeadsetServiceJni( 3104): Cleaning up Bluetooth Handsfree Interface before initializing...

W/BluetoothHeadsetServiceJni( 3104): Cleaning up Bluetooth Handsfree callback object

I/bluedroid( 3104): get_profile_interface handsfree

D/A2dpService( 3104): Received start request. Starting profile...

D/A2dpStateMachine( 3104): make

W/BluetoothA2dpServiceJni( 3104): Cleaning up A2DP Interface before initializing...

W/BluetoothA2dpServiceJni( 3104): Cleaning up A2DP callback object

I/bluedroid( 3104): get_profile_interface a2dp

I/GKI_LINUX( 3104): gki_task_entry: gki_task_entry task_id=2 [A2DP-MEDIA] starting

V/Avrcp   ( 3104): make

W/BluetoothAvrcpServiceJni( 3104): Cleaning up Avrcp Interface before initializing...

W/BluetoothAvrcpServiceJni( 3104): Cleaning up Avrcp callback object

I/bluedroid( 3104): get_profile_interface avrcp

D/A2dpStateMachine( 3104): Enter Disconnected: -2

D/HidService( 3104): Received start request. Starting profile...

W/BluetoothHidServiceJni( 3104): Cleaning up Bluetooth HID Interface before initializing...

W/bt-btif ( 3104): cleanup: HH disabling or disabled already, status = 0

W/BluetoothHidServiceJni( 3104): Cleaning up Bluetooth GID callback object

I/bluedroid( 3104): get_profile_interface hidhost

D/HealthService( 3104): Received start request. Starting profile...

W/BluetoothHealthServiceJni( 3104): Cleaning up Bluetooth Health Interface before initializing...

W/BluetoothHealthServiceJni( 3104): Cleaning up Bluetooth Health callback object

I/bluedroid( 3104): get_profile_interface health

D/PanService( 3104): Received start request. Starting profile...

D/BluetoothPanServiceJni( 3104): initializeNative(L110): pan

D/BtGatt.DebugUtils( 3104): handleDebugAction() action=null

D/BtGatt.GattService( 3104): Received start request. Starting profile...

D/BtGatt.GattService( 3104): start()

D/BluetoothMapService( 3104): Received start request. Starting profile...

D/BluetoothMapService( 3104): start()

D/BluetoothAdapterService( 3104): Profile still not running:com.android.bluetooth.hdp.HealthService

D/HeadsetPhoneState( 3104): sendDeviceStateChanged. mService=0 mSignal=0 mRoam=0 mBatteryCharge=5

D/BluetoothAdapterService( 3104): Profile still not running:com.android.bluetooth.hdp.HealthService

D/BluetoothAdapterService( 3104): Profile still not running:com.android.bluetooth.hdp.HealthService

D/BluetoothAdapterService( 3104): Profile still not running:com.android.bluetooth.map.BluetoothMapService

D/BluetoothAdapterService( 3104): Profile still not running:com.android.bluetooth.map.BluetoothMapService

D/BluetoothAdapterService( 3104): Profile still not running:com.android.bluetooth.map.BluetoothMapService

D/BluetoothAdapterState( 3104): CURRENT_STATE=PENDING, MESSAGE = STARTED, isTurningOn=true, isTurningOff=false

I/bluedroid( 3104): enable

I/bt_hci_bdroid( 3104): init

I/bt_vendor( 3104): bt-vendor : init

I/bt_userial_vendor( 3104): vnd_userial.port_name is /dev/ttymxc1

I/bt_vendor( 3104): AR3002 ::BT_VND_OP_GET_LPM_IDLE_TIMEOUT (timeout_ms = 3000;)

I/bt_vendor( 3104): [//]AR3002 UPIO_BT_POWER_OFF

I/bt_upio ( 3104): is_emulator_context : 0

I/bt_upio ( 3104): check if we have rfkill interface?

I/bt_upio ( 3104): is_rfkill_disabled ? [0]

I/bt_upio ( 3104): Check rfkill_id == -1?

I/bt_upio ( 3104): open set_bluetooth_power success

I/bt_hci_bdroid( 3104): bt_hc_worker_thread started

I/bt_upio ( 3104): write bluetooth_power success

I/bt_vendor( 3104): [//]AR3002 UPIO_BT_POWER_ON

I/bt_upio ( 3104): is_emulator_context : 0

I/bt_upio ( 3104): check if we have rfkill interface?

I/bt_upio ( 3104): is_rfkill_disabled ? [0]

I/bt_upio ( 3104): Check rfkill_id == -1?

I/bt_upio ( 3104): open set_bluetooth_power success

I/bt_upio ( 3104): write bluetooth_power success

I/bt_vendor( 3104): AR3002 ::BT_VND_OP_USERIAL_OPEN

I/bt_userial_vendor( 3104):

I/bt_userial_vendor( 3104): ------ INSIDE userial_vendor_open----

I/bt_hwcfg( 3104): =----------Entering  hw_config function------------

I/bt_hwcfg( 3104):  hw_config

I/bt_hwcfg( 3104):  init_uart

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): ------ INSIDE init_uart ---- /dev/ttymxc1

I/GKI_LINUX( 3104): gki_task_entry: gki_task_entry task_id=0 [BTU] starting

I/bt-btu  ( 3104): btu_task pending for preload complete event

I/bt_hwcfg( 3104): enable flow cotrol

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): ------u->speed =57600 -- u->init_speed = 57600-- u->bdaddr= 80:ea:ca:00:00:12

 

###########################################################################################################################################

Firmware loading .. debug prints included

#############################################################################################################################################

I/bt_hwcfg( 3104): =---------- ath3k_init------------

I/bt_hwcfg( 3104): =----------111111111111111------------

I/bt_hwcfg( 3104): =----------2222222222222222------57600-----

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): =----------set_cntrlr_baud NEWWWWWWWWWWWWWWWWWWWW------------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): =----------After nanosleep rsp -----------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): ----------INSIDE read_hci_event buf = ----------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): ----------INSIDE read_hci_event buf[0] = 0----------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): ----------SUCESSFUL COMMUNICATION----------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): ---------- read_hci_event  r=1---------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): ---------- buf[0] = 6----------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): ------SUCESSFUL /FAILURE IN COMMUNICATION-----

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): =----------sucessssssssssssss----------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): set_cntrlr_baud : ret:0

I/bt_hwcfg( 3104): =----------3333333333333333-----------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): =----------set_speed ----------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): =----------44444444444444444----------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): =----------555555555555555----------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): FILE OPEN SUCESSFULL

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): ----  reading /system/etc/firmware/prod_test.bin-----hcdfile_rd=19916

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): ---- writing /system/etc/firmware/prod_test.bin -----hcdfile_wr=20004

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): ---- Flashing is complete -----

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): ---- Sending ACK complete -----

I/bt_hwcfg( 3104): ath_ps_download is done

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104):  INSIDE bdaddr

I/bt_hwcfg( 3104): ----------INSIDE read_hci_event buf = ----------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): ----------INSIDE read_hci_event buf[0] = 0----------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): ----------SUCESSFUL COMMUNICATION----------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): ---------- read_hci_event  r=1---------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): ---------- buf[0] = 6----------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): ------SUCESSFUL /FAILURE IN COMMUNICATION-----

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): =----------55555555555555555----------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): =----------66666666666666666----------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): =----------DONEEEEEEEEEEEEEEEEE-------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): Device setup complete

#################################################################################################################################################################

I/bt_hwcfg( 3104): =----------set_speed ----------

I/bt_hwcfg( 3104):

I/bt_hwcfg( 3104): =----------IN  hw_config function------------init_urat return :302

I/bt_hwcfg( 3104): =----------Exiting  hw_config function------------

I/bt_userial_vendor( 3104): device fd = 302 open

I/bt_hci_bdroid( 3104): Inside bt_hc_worker_thread function HC_EVENT_PRELOAD

I/bt_vendor( 3104): AR3002 ::BT_VND_OP_FW_CFG

I/bt_vendor( 3104): AR3002 ::Bluetooth Firmware download

I/bt-btu  ( 3104): btu_task received preload complete event

I/        ( 3104): ----------INSIDE btu_init_core function in file btu_init.c gatt_init() success ----------

I/        ( 3104):

I/        ( 3104): BTE_InitTraceLevels -- TRC_HCI

I/        ( 3104): BTE_InitTraceLevels -- TRC_L2CAP

I/        ( 3104): BTE_InitTraceLevels -- TRC_RFCOMM

I/        ( 3104): BTE_InitTraceLevels -- TRC_AVDT

I/        ( 3104): BTE_InitTraceLevels -- TRC_AVRC

I/        ( 3104): BTE_InitTraceLevels -- TRC_A2D

I/        ( 3104): BTE_InitTraceLevels -- TRC_BNEP

I/        ( 3104): BTE_InitTraceLevels -- TRC_BTM

I/        ( 3104): BTE_InitTraceLevels -- TRC_GAP

I/        ( 3104): BTE_InitTraceLevels -- TRC_PAN

I/        ( 3104): BTE_InitTraceLevels -- TRC_SDP

I/        ( 3104): BTE_InitTraceLevels -- TRC_GATT

I/        ( 3104): BTE_InitTraceLevels -- TRC_SMP

I/        ( 3104): BTE_InitTraceLevels -- TRC_BTAPP

I/        ( 3104): BTE_InitTraceLevels -- TRC_BTIF

I/        ( 3104): ---------- INSIDE btu_task function in btu_task.c file wait for events ----------

I/        ( 3104):

I/bt_hci_bdroid( 3104): Inside bt_hc_worker_thread function HC_EVENT_TX

E/bt_h4   ( 3104): [h4] Unknown HCI message type drop this byte 0x0

E/bt_h4   ( 3104): H4: Unable to acquire buffer for incoming HCI message.

D/dalvikvm( 2768): GC_CONCURRENT freed 816K, 39% free 6115K/9912K, paused 21ms+4ms, total 73ms

I/bt_hci_bdroid( 3104): Inside bt_hc_worker_thread function HC_EVENT_TX

D/BluetoothAdapterState( 3104): CURRENT_STATE=PENDING, MESSAGE = ENABLE_TIMEOUT, isTurningOn=true, isTurningOff=false

E/BluetoothAdapterState( 3104): Error enabling Bluetooth

D/BluetoothAdapterProperties( 3104): Setting state to 10

I/BluetoothAdapterState( 3104): Bluetooth adapter state changed: 11-> 10

D/BluetoothAdapterService( 3104): Broadcasting updateAdapterState() to 1 receivers.

D/BluetoothManagerService( 2768): Message: 60

D/BluetoothManagerService( 2768): MESSAGE_BLUETOOTH_STATE_CHANGE: prevState = 11, newState=10

D/BluetoothManagerService( 2768): Broadcasting onBluetoothStateChange(false) to 11 receivers.

D/BluetoothPbap( 3037): onBluetoothStateChange: up=false

I/BluetoothAdapterState( 3104): Entering OffState

E/BluetoothPbap( 3037):

E/BluetoothPbap( 3037): java.lang.IllegalArgumentException: Service not registered: android.bluetooth.BluetoothPbap$2@41198038

E/BluetoothPbap( 3037):         at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:931)

E/BluetoothPbap( 3037):         at android.app.ContextImpl.unbindService(ContextImpl.java:1595)

E/BluetoothPbap( 3037):         at android.content.ContextWrapper.unbindService(ContextWrapper.java:529)

E/BluetoothPbap( 3037):         at android.bluetooth.BluetoothPbap$1.onBluetoothStateChange(BluetoothPbap.java:122)

E/BluetoothPbap( 3037):         at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)

E/BluetoothPbap( 3037):         at android.os.Binder.execTransact(Binder.java:404)

E/BluetoothPbap( 3037):         at dalvik.system.NativeStart.run(Native Method)

E/BluetoothPan( 3037):

E/BluetoothPan( 3037): java.lang.IllegalArgumentException: Service not registered: android.bluetooth.BluetoothPan$2@41223070

E/BluetoothPan( 3037):  at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:931)

E/BluetoothPan( 3037):  at android.app.ContextImpl.unbindService(ContextImpl.java:1595)

E/BluetoothPan( 3037):  at android.content.ContextWrapper.unbindService(ContextWrapper.java:529)

E/BluetoothPan( 3037):  at android.bluetooth.BluetoothPan$1.onBluetoothStateChange(BluetoothPan.java:199)

E/BluetoothPan( 3037):  at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)

E/BluetoothPan( 3037):  at android.os.Binder.execTransact(Binder.java:404)

E/BluetoothPan( 3037):  at dalvik.system.NativeStart.run(Native Method)

D/BluetoothMap( 3037): onBluetoothStateChange: up=false

E/BluetoothMap( 3037):

E/BluetoothMap( 3037): java.lang.IllegalArgumentException: Service not registered: android.bluetooth.BluetoothMap$2@41192860

E/BluetoothMap( 3037):  at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:931)

E/BluetoothMap( 3037):  at android.app.ContextImpl.unbindService(ContextImpl.java:1595)

E/BluetoothMap( 3037):  at android.content.ContextWrapper.unbindService(ContextWrapper.java:529)

E/BluetoothMap( 3037):  at android.bluetooth.BluetoothMap$1.onBluetoothStateChange(BluetoothMap.java:66)

E/BluetoothMap( 3037):  at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)

E/BluetoothMap( 3037):  at android.os.Binder.execTransact(Binder.java:404)

E/BluetoothMap( 3037):  at dalvik.system.NativeStart.run(Native Method)

D/BluetoothA2dp( 2768): onBluetoothStateChange: up=false

E/BluetoothA2dp( 2768):

E/BluetoothA2dp( 2768): java.lang.IllegalArgumentException: Service not registered: android.bluetooth.BluetoothA2dp$2@41774a60

E/BluetoothA2dp( 2768):         at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:931)

E/BluetoothA2dp( 2768):         at android.app.ContextImpl.unbindService(ContextImpl.java:1595)

E/BluetoothA2dp( 2768):         at android.bluetooth.BluetoothA2dp$1.onBluetoothStateChange(BluetoothA2dp.java:121)

E/BluetoothA2dp( 2768):         at com.android.server.BluetoothManagerService.sendBluetoothStateCallback(BluetoothManagerService.java:484)

E/BluetoothA2dp( 2768):         at com.android.server.BluetoothManagerService.bluetoothStateChangeHandler(BluetoothManagerService.java:1129)

E/BluetoothA2dp( 2768):         at com.android.server.BluetoothManagerService.access$2800(BluetoothManagerService.java:47)

E/BluetoothA2dp( 2768):         at com.android.server.BluetoothManagerService$BluetoothHandler.handleMessage(BluetoothManagerService.java:864)

E/BluetoothA2dp( 2768):         at android.os.Handler.dispatchMessage(Handler.java:102)

E/BluetoothA2dp( 2768):         at android.os.Looper.loop(Looper.java:136)

E/BluetoothA2dp( 2768):         at android.os.HandlerThread.run(HandlerThread.java:61)

E/BluetoothPan( 3037):

E/BluetoothPan( 3037): java.lang.IllegalArgumentException: Service not registered: android.bluetooth.BluetoothPan$2@4118d0f8

E/BluetoothPan( 3037):  at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:931)

E/BluetoothPan( 3037):  at android.app.ContextImpl.unbindService(ContextImpl.java:1595)

E/BluetoothPan( 3037):  at android.content.ContextWrapper.unbindService(ContextWrapper.java:529)

E/BluetoothPan( 3037):  at android.bluetooth.BluetoothPan$1.onBluetoothStateChange(BluetoothPan.java:199)

E/BluetoothPan( 3037):  at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)

E/BluetoothPan( 3037):  at android.os.Binder.execTransact(Binder.java:404)

E/BluetoothPan( 3037):  at dalvik.system.NativeStart.run(Native Method)

D/BluetoothHeadset( 2953): onBluetoothStateChange: up=false

E/BluetoothHeadset( 2953):

E/BluetoothHeadset( 2953): java.lang.IllegalArgumentException: Service not registered: android.bluetooth.BluetoothHeadset$2@411941e0

E/BluetoothHeadset( 2953):      at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:931)

E/BluetoothHeadset( 2953):      at android.app.ContextImpl.unbindService(ContextImpl.java:1595)

E/BluetoothHeadset( 2953):      at android.content.ContextWrapper.unbindService(ContextWrapper.java:529)

E/BluetoothHeadset( 2953):      at android.content.ContextWrapper.unbindService(ContextWrapper.java:529)

E/BluetoothHeadset( 2953):      at android.bluetooth.BluetoothHeadset$1.onBluetoothStateChange(BluetoothHeadset.java:239)

E/BluetoothHeadset( 2953):      at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)

E/BluetoothHeadset( 2953):      at android.os.Binder.execTransact(Binder.java:404)

E/BluetoothHeadset( 2953):      at dalvik.system.NativeStart.run(Native Method)

D/BluetoothHeadset( 2953): onBluetoothStateChange: up=false

E/BluetoothHeadset( 2953):

E/BluetoothHeadset( 2953): java.lang.IllegalArgumentException: Service not registered: android.bluetooth.BluetoothHeadset$2@411b8828

E/BluetoothHeadset( 2953):      at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:931)

E/BluetoothHeadset( 2953):      at android.app.ContextImpl.unbindService(ContextImpl.java:1595)

E/BluetoothHeadset( 2953):      at android.content.ContextWrapper.unbindService(ContextWrapper.java:529)

E/BluetoothHeadset( 2953):      at android.bluetooth.BluetoothHeadset$1.onBluetoothStateChange(BluetoothHeadset.java:239)

E/BluetoothHeadset( 2953):      at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)

E/BluetoothHeadset( 2953):      at android.os.Binder.execTransact(Binder.java:404)

E/BluetoothHeadset( 2953):      at dalvik.system.NativeStart.run(Native Method)

D/BluetoothInputDevice( 3037): onBluetoothStateChange: up=false

E/BluetoothInputDevice( 3037):

E/BluetoothInputDevice( 3037): java.lang.IllegalArgumentException: Service not registered: android.bluetooth.BluetoothInputDevice$2@41187568

E/BluetoothInputDevice( 3037):  at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:931)

E/BluetoothInputDevice( 3037):  at android.app.ContextImpl.unbindService(ContextImpl.java:1595)

E/BluetoothInputDevice( 3037):  at android.content.ContextWrapper.unbindService(ContextWrapper.java:529)

E/BluetoothInputDevice( 3037):  at android.bluetooth.BluetoothInputDevice$1.onBluetoothStateChange(BluetoothInputDevice.java:199)

E/BluetoothInputDevice( 3037):  at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)

E/BluetoothInputDevice( 3037):  at android.os.Binder.execTransact(Binder.java:404)

E/BluetoothInputDevice( 3037):  at dalvik.system.NativeStart.run(Native Method)

D/BluetoothHeadset( 2768): onBluetoothStateChange: up=false

E/BluetoothHeadset( 2768):

E/BluetoothHeadset( 2768): java.lang.IllegalArgumentException: Service not registered: android.bluetooth.BluetoothHeadset$2@41558bc8

E/BluetoothHeadset( 2768):      at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:931)

E/BluetoothHeadset( 2768):      at android.app.ContextImpl.unbindService(ContextImpl.java:1595)

E/BluetoothHeadset( 2768):      at android.bluetooth.BluetoothHeadset$1.onBluetoothStateChange(BluetoothHeadset.java:239)

E/BluetoothHeadset( 2768):      at com.android.server.BluetoothManagerService.sendBluetoothStateCallback(BluetoothManagerService.java:484)

E/BluetoothHeadset( 2768):      at com.android.server.BluetoothManagerService.bluetoothStateChangeHandler(BluetoothManagerService.java:1129)

E/BluetoothHeadset( 2768):      at com.android.server.BluetoothManagerService.access$2800(BluetoothManagerService.java:47)

E/BluetoothHeadset( 2768):      at com.android.server.BluetoothManagerService$BluetoothHandler.handleMessage(BluetoothManagerService.java:864)

E/BluetoothHeadset( 2768):      at android.os.Handler.dispatchMessage(Handler.java:102)

E/BluetoothHeadset( 2768):      at android.os.Looper.loop(Looper.java:136)

E/BluetoothHeadset( 2768):      at android.os.HandlerThread.run(HandlerThread.java:61)

D/BluetoothHeadset( 2953): onBluetoothStateChange: up=false

E/BluetoothHeadset( 2953):

E/BluetoothHeadset( 2953): java.lang.IllegalArgumentException: Service not registered: android.bluetooth.BluetoothHeadset$2@411ee208

E/BluetoothHeadset( 2953):      at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:931)

E/BluetoothHeadset( 2953):      at android.app.ContextImpl.unbindService(ContextImpl.java:1595)

E/BluetoothHeadset( 2953):      at android.content.ContextWrapper.unbindService(ContextWrapper.java:529)

E/BluetoothHeadset( 2953):      at android.bluetooth.BluetoothHeadset$1.onBluetoothStateChange(BluetoothHeadset.java:239)

E/BluetoothHeadset( 2953):      at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)

E/BluetoothHeadset( 2953):      at android.os.Binder.execTransact(Binder.java:404)

E/BluetoothHeadset( 2953):      at dalvik.system.NativeStart.run(Native Method)

E/BluetoothPan( 3037):

E/BluetoothPan( 3037): java.lang.IllegalArgumentException: Service not registered: android.bluetooth.BluetoothPan$2@412ea9b0

E/BluetoothPan( 3037):  at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:931)

E/BluetoothPan( 3037):  at android.app.ContextImpl.unbindService(ContextImpl.java:1595)

E/BluetoothPan( 3037):  at android.content.ContextWrapper.unbindService(ContextWrapper.java:529)

E/BluetoothPan( 3037):  at android.bluetooth.BluetoothPan$1.onBluetoothStateChange(BluetoothPan.java:199)

E/BluetoothPan( 3037):  at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)

E/BluetoothPan( 3037):  at android.os.Binder.execTransact(Binder.java:404)

E/BluetoothPan( 3037):  at dalvik.system.NativeStart.run(Native Method)

D/BluetoothManagerService( 2768): Bluetooth State Change Intent: 11 -> 10

D/BluetoothMapService( 3104): onReceive

W/ContextImpl( 3037): Calling a method in the system process without a qualified user: android.app.ContextImpl.startService:1479 android.content.ContextWrapper.startService:494 android.content.ContextWrapper.startService:494 com.android.settings.bluetooth.DockEventReceiver.beginStartingService:134 com.android.settings.bluetooth.DockEventReceiver.onReceive:115

E/BluetoothManagerService( 2768): recoverBluetoothServiceFromError

D/DockEventReceiver( 3037): finishStartingService: stopping service

D/dalvikvm( 2953): GC_CONCURRENT freed 394K, 14% free 3052K/3520K, paused 7ms+2ms, total 106ms

D/BluetoothManagerService( 2768): Sending off request.

D/BluetoothAdapterState( 3104): CURRENT_STATE=OFF, MESSAGE = USER_TURN_OFF

D/BluetoothManagerService( 2768): Calling onBluetoothServiceDown callbacks

D/BluetoothManagerService( 2768): Broadcasting onBluetoothServiceDown() to 5 receivers.

D/BluetoothAdapterService( 3104): Cleaning up adapter native....

I/bt_hwcfg( 3104): =----------Entering  hw_epilog_process function------------

I/bt_hwcfg( 3104): hw_epilog_process

I/bt_hwcfg( 3104): =----------Exiting  hw_epilog_process function------------

I/bt_hci_bdroid( 3104): Inside bt_hc_worker_thread function HC_EVENT_TX

I/bt_hci_bdroid( 3104): ...epilog_wait_timeout...

I/bt_hci_bdroid( 3104): bt_hc_worker_thread exiting

W/bt_userial( 3104): select_read return size <=0:-1, exiting userial_read_thread

I/bt_vendor( 3104): AR3002 ::BT_VND_OP_USERIAL_CLOSE

I/bt_userial_vendor( 3104): device fd = 302 close

D/BTSNOOP-DISP( 3104): btsnoop_close

I/bt_vendor( 3104): cleanup

I/bt_vendor( 3104): [//]AR3002 UPIO_BT_POWER_OFF

I/bt_upio ( 3104): is_emulator_context : 0

I/bt_upio ( 3104): check if we have rfkill interface?

I/bt_upio ( 3104): is_rfkill_disabled ? [0]

I/bt_upio ( 3104): Check rfkill_id == -1?

I/bt_upio ( 3104): open set_bluetooth_power success

I/bt_upio ( 3104): write bluetooth_power success

I/GKI_LINUX( 3104): gki_task_entry: gki_task task_id=0 [BTU] terminating

I/GKI_LINUX( 3104): GKI_exit_task: GKI_exit_task 0 done

I/GKI_LINUX( 3104): GKI_destroy_task: GKI_shutdown(): task [BTU] terminated

E/BluetoothServiceJni( 3104): Callback env check fail: env: 0x40cf29c0, callback: 0x686b6530

E/BluetoothServiceJni( 3104): Callback: 'adapter_state_change_callback' is not called on the correct thread

I/GKI_LINUX( 3104): gki_task_entry: gki_task task_id=1 [BTIF] terminating

I/GKI_LINUX( 3104): GKI_exit_task: GKI_exit_task 1 done

I/GKI_LINUX( 3104): GKI_destroy_task: GKI_shutdown(): task [BTIF] terminated

I/GKI_LINUX( 3104): gki_task_entry: gki_task task_id=2 [A2DP-MEDIA] terminating

I/GKI_LINUX( 3104): GKI_exit_task: GKI_exit_task 2 done

I/BluetoothServiceJni( 3104): cleanupNative: return from cleanup

D/BluetoothAdapterService( 3104): Done cleaning up adapter native....

D/BluetoothAdapterService(1092297800)( 3104): ****onDestroy()********

D/ConnectivityService( 2768): Sampling interval elapsed, updating statistics ..

D/ConnectivityService( 2768): Done.

I/ProcessStatsService( 2768): Prepared write state in 22ms

I/ProcessStatsService( 2768): Prepared write state in 3ms

D/ConnectivityService( 2768): Setting timer for 720seconds

W/SocketClient( 2422): write error (Broken pipe)

I/ActivityManager( 2768): Killing 3087:com.android.music/u0a33 (adj 15): empty for 1802s

I/ActivityManager( 2768): Killing 2992:com.android.printspooler/u0a38 (adj 15): empty for 1802s

I/ActivityManager( 2768): Killing 3010:android.process.acore/u0a2 (adj 15): empty for 1805s

W/MediaFocusControl( 2768):   RemoteControlClient died

V/Avrcp   ( 3104): New genId = 4, clearing = 1

V/Avrcp   ( 3104): New genId = 4, clearing = 1

V/Avrcp   ( 3104): New genId = 4, clearing = 1

D/dalvikvm( 2843): GC_CONCURRENT freed 538K, 46% free 4671K/8596K, paused 6ms+2ms, total 50ms

W/BluetoothAdapterService( 3104): *************service already starting to cleanup... Ignoring cleanup request.........

D/dalvikvm( 3104): GC_CONCURRENT freed 619K, 22% free 2935K/3740K, paused 9ms+2ms, total 46ms

D/BluetoothAdapterService( 3104): REFCOUNT: FINALIZED. INSTANCE_COUNT= 2

W/BluetoothAdapterService( 3104): *************service already starting to cleanup... Ignoring cleanup request.........

D/BluetoothAdapterService( 3104): REFCOUNT: FINALIZED. INSTANCE_COUNT= 1

D/BluetoothManagerService( 2768): enable():  mBluetooth =null mBinding = false

D/BluetoothManagerService( 2768): Message: 1

D/BluetoothManagerService( 2768): MESSAGE_ENABLE: mBluetooth = null

D/BluetoothAdapterService( 3104): REFCOUNT: CREATED. INSTANCE_COUNT2

D/BluetoothAdapterState( 3104): make

I/bluedroid( 3104): init

I/bte_conf( 3104): Attempt to load stack conf from /etc/bluetooth/bt_stack.conf

I/bluedroid( 3104): get_profile_interface socket

D/BluetoothManagerService( 2768): BluetoothServiceConnection: com.android.bluetooth.btservice.AdapterService

D/BluetoothManagerService( 2768): Message: 40

D/BluetoothManagerService( 2768): MESSAGE_BLUETOOTH_SERVICE_CONNECTED: 1

I/bluedroid( 3104): config_hci_snoop_log

D/BluetoothManagerService( 2768): Calling onBluetoothServiceUp callbacks

D/BluetoothManagerService( 2768): Broadcasting onBluetoothServiceUp() to 5 receivers.

I/BluetoothAdapterState( 3104): Entering OffState

I/GKI_LINUX( 3104): gki_task_entry: gki_task_entry task_id=1 [BTIF] starting

I/BluetoothAdapterProperties( 3104): adapterPropertyChangedCallback with type:2 len:6

D/BluetoothAdapterState( 3104): CURRENT_STATE=OFF, MESSAGE = USER_TURN_ON

D/BluetoothAdapterProperties( 3104): Address is:22:22:59:D1:04:07

I/BluetoothAdapterProperties( 3104): adapterPropertyChangedCallback with type:1 len:9

D/BluetoothManagerService( 2768): Bluetooth Adapter name changed to EVK_MX6SL

D/BluetoothManagerService( 2768): Stored Bluetooth name: EVK_MX6SL

D/BluetoothAdapterProperties( 3104): Name is: EVK_MX6SL

D/BluetoothAdapterProperties( 3104): Setting state to 11

I/BluetoothAdapterState( 3104): Bluetooth adapter state changed: 10-> 11

D/BluetoothAdapterService( 3104): Broadcasting updateAdapterState() to 1 receivers.

D/BluetoothBondStateMachine( 3104): make

D/BluetoothManagerService( 2768): Message: 60

D/BluetoothManagerService( 2768): MESSAGE_BLUETOOTH_STATE_CHANGE: prevState = 10, newState=11

D/BluetoothManagerService( 2768): Bluetooth State Change Intent: 10 -> 11

D/BluetoothMapService( 3104): onReceive

D/HeadsetService( 3104): Received start request. Starting profile...

D/HeadsetStateMachine( 3104): make

I/BluetoothAdapterState( 3104): Entering PendingCommandState State: isTurningOn()=true, isTurningOff()=false

I/BluetoothBondStateMachine( 3104): StableState(): Entering Off State

W/BluetoothHeadsetServiceJni( 3104): Cleaning up Bluetooth Handsfree Interface before initializing...

W/BluetoothHeadsetServiceJni( 3104): Cleaning up Bluetooth Handsfree callback object

I/bluedroid( 3104): get_profile_interface handsfree

D/A2dpService( 3104): Received start request. Starting profile...

D/A2dpStateMachine( 3104): make

W/BluetoothA2dpServiceJni( 3104): Cleaning up A2DP Interface before initializing...

W/BluetoothA2dpServiceJni( 3104): Cleaning up A2DP callback object

I/bluedroid( 3104): get_profile_interface a2dp

I/GKI_LINUX( 3104): gki_task_entry: gki_task_entry task_id=2 [A2DP-MEDIA] starting

V/Avrcp   ( 3104): make

D/A2dpStateMachine( 3104): Enter Disconnected: -2

W/BluetoothAvrcpServiceJni( 3104): Cleaning up Avrcp Interface before initializing...

W/BluetoothAvrcpServiceJni( 3104): Cleaning up Avrcp callback object

I/bluedroid( 3104): get_profile_interface avrcp

D/HidService( 3104): Received start request. Starting profile...

W/BluetoothHidServiceJni( 3104): Cleaning up Bluetooth HID Interface before initializing...

W/bt-btif ( 3104): cleanup: HH disabling or disabled already, status = 0

W/BluetoothHidServiceJni( 3104): Cleaning up Bluetooth GID callback object

I/bluedroid( 3104): get_profile_interface hidhost

D/HealthService( 3104): Received start request. Starting profile...

W/BluetoothHealthServiceJni( 3104): Cleaning up Bluetooth Health Interface before initializing...

W/BluetoothHealthServiceJni( 3104): Cleaning up Bluetooth Health callback object

I/bluedroid( 3104): get_profile_interface health

D/PanService( 3104): Received start request. Starting profile...

D/BluetoothPanServiceJni( 3104): initializeNative(L110): pan

D/BtGatt.DebugUtils( 3104): handleDebugAction() action=null

D/BtGatt.GattService( 3104): Received start request. Starting profile...

D/BtGatt.GattService( 3104): start()

D/BluetoothMapService( 3104): Received start request. Starting profile...

D/BluetoothMapService( 3104): start()

D/BluetoothAdapterService( 3104): Profile still not running:com.android.bluetooth.hdp.HealthService

D/BluetoothAdapterService( 3104): Profile still not running:com.android.bluetooth.hdp.HealthService

D/HeadsetPhoneState( 3104): sendDeviceStateChanged. mService=0 mSignal=0 mRoam=0 mBatteryCharge=5

D/BluetoothAdapterService( 3104): Profile still not running:com.android.bluetooth.hdp.HealthService

D/BluetoothAdapterService( 3104): Profile still not running:com.android.bluetooth.map.BluetoothMapService

D/BluetoothAdapterService( 3104): Profile still not running:com.android.bluetooth.map.BluetoothMapService

D/BluetoothAdapterService( 3104): Profile still not running:com.android.bluetooth.map.BluetoothMapService

D/BluetoothAdapterState( 3104): CURRENT_STATE=PENDING, MESSAGE = STARTED, isTurningOn=true, isTurningOff=false

I/bluedroid( 3104): enable

Outcomes