09-06 02:32:40.460 4239 4266 W bt_osi_thread: run_thread: thread id 4266, thre ad name bt_workqueue started 09-06 02:32:40.460 4239 4266 I bt_btu : btu_task pending for preload complete event 09-06 02:32:40.460 4239 4266 I bt_btu_task: Bluetooth chip preload is complete 09-06 02:32:40.460 4239 4266 I bt_btu : btu_task received preload complete ev ent 09-06 02:32:40.462 4239 4266 I bt_core_module: module_init Initializing module "bte_logmsg_module" 09-06 02:32:40.462 4239 4266 I bt_bte : BTE_InitTraceLevels -- TRC_HCI 09-06 02:32:40.462 4239 4266 I bt_bte : BTE_InitTraceLevels -- TRC_L2CAP 09-06 02:32:40.462 4239 4266 I bt_bte : BTE_InitTraceLevels -- TRC_RFCOMM 09-06 02:32:40.462 4239 4266 I bt_bte : BTE_InitTraceLevels -- TRC_AVRC 09-06 02:32:40.462 4239 4266 I bt_bte : BTE_InitTraceLevels -- TRC_A2D 09-06 02:32:40.462 4239 4266 I bt_bte : BTE_InitTraceLevels -- TRC_BTM 09-06 02:32:40.462 4239 4266 I bt_bte : BTE_InitTraceLevels -- TRC_GAP 09-06 02:32:40.462 4239 4266 I bt_bte : BTE_InitTraceLevels -- TRC_SDP 09-06 02:32:40.462 4239 4266 I bt_bte : BTE_InitTraceLevels -- TRC_GATT 09-06 02:32:40.462 4239 4266 I bt_bte : BTE_InitTraceLevels -- TRC_SMP 09-06 02:32:40.462 4239 4266 I bt_bte : BTE_InitTraceLevels -- TRC_BTAPP 09-06 02:32:40.463 4239 4266 I bt_bte : BTE_InitTraceLevels -- TRC_BTIF 09-06 02:32:40.463 4239 4266 I bt_core_module: module_init Initialized module "bte_logmsg_module" 09-06 02:32:40.465 4239 4258 E bt_hci : command_timed_out hci layer timeout w aiting for response to a command. opcode: 0xc03 09-06 02:32:40.465 4239 4258 E bt_hci : command_timed_out restarting the blue tooth process. 09-06 02:32:40.465 4239 4267 W bt_osi_thread: run_thread: thread id 4267, thre ad name module_wrapper started 09-06 02:32:40.465 4239 4267 I bt_core_module: module_start_up Starting module "controller_module" 09-06 02:32:40.505 473 473 D BluetoothManagerService: BluetoothServiceConnec tion, disconnected: com.android.bluetooth.btservice.AdapterService 09-06 02:32:40.506 473 616 I ActivityManager: Process com.android.bluetooth (pid 4239) has died 09-06 02:32:40.506 473 494 E BluetoothManagerService: MESSAGE_BLUETOOTH_SERV ICE_DISCONNECTED(1) 09-06 02:32:40.506 473 616 D ActivityManager: cleanUpApplicationRecord -- 42 39 09-06 02:32:40.506 473 494 D BluetoothManagerService: Broadcasting onBluetoo thServiceDown() to 5 receivers. 09-06 02:32:40.506 473 494 D BluetoothAdapter: onBluetoothServiceDown: andro id.bluetooth.IBluetooth$Stub$Proxy@3b79896 09-06 02:32:40.506 473 616 W ActivityManager: Scheduling restart of crashed service com.android.bluetooth/.gatt.GattService in 1000ms 09-06 02:32:40.506 876 1257 D BluetoothAdapter: onBluetoothServiceDown: andro id.bluetooth.IBluetooth$Stub$Proxy@ef84b25 09-06 02:32:40.506 660 675 D BluetoothAdapter: onBluetoothServiceDown: andro id.bluetooth.IBluetooth$Stub$Proxy@9b3f1c6 09-06 02:32:40.506 646 659 D BluetoothAdapter: onBluetoothServiceDown: andro id.bluetooth.IBluetooth$Stub$Proxy@798d247 09-06 02:32:40.507 473 616 W ActivityManager: Scheduling restart of crashed service com.android.bluetooth/.btservice.AdapterService in 1000ms 09-06 02:32:40.507 559 590 D BluetoothAdapter: onBluetoothServiceDown: andro id.bluetooth.IBluetooth$Stub$Proxy@803cc36 09-06 02:32:40.706 473 494 D BluetoothManagerService: MESSAGE_RESTART_BLUETO OTH_SERVICE 09-06 02:32:40.723 473 494 I ActivityManager: Start proc 4268:com.android.bl uetooth/1002 for service com.android.bluetooth/.btservice.AdapterService 09-06 02:32:40.855 4268 4268 D AdapterServiceConfig: Adding HeadsetService 09-06 02:32:40.856 4268 4268 D AdapterServiceConfig: Adding A2dpService 09-06 02:32:40.856 4268 4268 D AdapterServiceConfig: Adding HidService 09-06 02:32:40.856 4268 4268 D AdapterServiceConfig: Adding HealthService 09-06 02:32:40.856 4268 4268 D AdapterServiceConfig: Adding PanService 09-06 02:32:40.856 4268 4268 D AdapterServiceConfig: Adding GattService 09-06 02:32:40.856 4268 4268 D AdapterServiceConfig: Adding BluetoothMapServic e 09-06 02:32:40.921 4268 4268 D BluetoothAdapterService: onCreate() 09-06 02:32:40.925 4268 4268 D BluetoothAdapterState: make() - Creating Adapte rState 09-06 02:32:40.930 4268 4268 I bt_btif : init 09-06 02:32:40.930 4268 4283 I BluetoothAdapterState: Entering OffState 09-06 02:32:40.930 4268 4268 D bt_osi_allocation_tracker: canary initialized 09-06 02:32:40.931 4268 4284 W bt_osi_thread: run_thread: thread id 4284, thre ad name stack_manager started 09-06 02:32:40.931 4268 4284 I bt_stack_manager: event_init_stack is initializ ing the stack 09-06 02:32:40.931 4268 4284 I bt_core_module: module_init Initializing module "osi_module" 09-06 02:32:40.931 4268 4284 I bt_core_module: module_init Initialized module "osi_module" 09-06 02:32:40.932 4268 4284 I bt_core_module: module_init Initializing module "bt_utils_module" 09-06 02:32:40.932 4268 4284 I bt_core_module: module_init Initialized module "bt_utils_module" 09-06 02:32:40.932 4268 4284 I bt_core_module: module_init Initializing module "btif_config_module" 09-06 02:32:40.933 4268 4287 W bt_osi_thread: run_thread: thread id 4287, thre ad name alarm_default_ca started 09-06 02:32:40.934 4268 4288 W bt_osi_thread: run_thread: thread id 4288, thre ad name alarm_dispatcher started 09-06 02:32:40.934 4268 4284 I bt_core_module: module_init Initialized module "btif_config_module" 09-06 02:32:40.934 4268 4284 I bt_core_module: module_init Initializing module "interop_module" 09-06 02:32:40.934 4268 4284 I bt_core_module: module_init Initialized module "interop_module" 09-06 02:32:40.935 4268 4284 I bt_core_module: module_init Initializing module "stack_config_module" 09-06 02:32:40.935 4268 4284 I bt_stack_config: init attempt to load stack con f from /etc/bluetooth/bt_stack.conf 09-06 02:32:40.936 4268 4284 I bt_core_module: module_init Initialized module "stack_config_module" 09-06 02:32:40.936 4268 4289 W bt_osi_thread: run_thread: thread id 4289, thre ad name bt_jni_workqueue started 09-06 02:32:40.936 4268 4284 I bt_stack_manager: event_init_stack finished 09-06 02:32:40.937 4268 4268 I bt_osi_wakelock: wakelock_set_os_callouts set t o non-native 09-06 02:32:40.937 4268 4268 I bt_btif : get_profile_interface socket 09-06 02:32:40.939 4268 4289 D BluetoothAdapterProperties: Address is:22:22:0C :37:78:7C 09-06 02:32:40.942 4268 4268 I bt_btif : get_profile_interface sdp 09-06 02:32:40.943 4268 4289 D BluetoothAdapterProperties: Name is: RPi 3 09-06 02:32:40.943 473 473 D BluetoothManagerService: Bluetooth Adapter name changed to RPi 3 09-06 02:32:40.944 473 473 D BluetoothManagerService: Stored Bluetooth name: RPi 3 09-06 02:32:40.948 4268 4268 D BluetoothAdapterService: setAdapterService() - set to: null 09-06 02:32:40.948 4268 4268 D BluetoothAdapterService: onBind() 09-06 02:32:40.950 473 473 D BluetoothManagerService: BluetoothServiceConnec tion: com.android.bluetooth.btservice.AdapterService 09-06 02:32:40.950 473 494 D BluetoothManagerService: MESSAGE_BLUETOOTH_SERV ICE_CONNECTED: 1 09-06 02:32:40.952 4268 4281 I bt_btif : config_hci_snoop_log 09-06 02:32:40.955 473 494 D BluetoothManagerService: Broadcasting onBluetoo thServiceUp() to 6 receivers. 09-06 02:32:40.955 473 494 D BluetoothAdapter: onBluetoothServiceUp: android .bluetooth.IBluetooth$Stub$Proxy@3cd352b 09-06 02:32:40.955 876 1257 D BluetoothAdapter: onBluetoothServiceUp: android .bluetooth.IBluetooth$Stub$Proxy@a3b74fa 09-06 02:32:40.956 4268 4281 D BluetoothAdapter: onBluetoothServiceUp: com.and roid.bluetooth.btservice.AdapterService$AdapterServiceBinder@6f4d51b 09-06 02:32:40.956 660 675 D BluetoothAdapter: onBluetoothServiceUp: android .bluetooth.IBluetooth$Stub$Proxy@a6c7587 09-06 02:32:40.956 646 658 D BluetoothAdapter: onBluetoothServiceUp: android .bluetooth.IBluetooth$Stub$Proxy@60d5a74 09-06 02:32:40.956 559 759 D BluetoothAdapter: onBluetoothServiceUp: android .bluetooth.IBluetooth$Stub$Proxy@99a9737 09-06 02:32:40.957 4268 4281 D BluetoothAdapterService: enable() - Enable call ed with quiet mode status = false 09-06 02:32:40.957 4268 4283 D BluetoothAdapterState: Current state: OFF, mess age: 0 09-06 02:32:40.958 4268 4283 D BluetoothAdapterProperties: Setting state to 14 09-06 02:32:40.958 4268 4283 I BluetoothAdapterState: Bluetooth adapter state changed: 10-> 14 09-06 02:32:40.958 4268 4283 D BluetoothAdapterService: updateAdapterState() - Broadcasting state to 1 receivers. 09-06 02:32:40.958 473 494 D BluetoothManagerService: MESSAGE_BLUETOOTH_STAT E_CHANGE: OFF > BLE_TURNING_ON 09-06 02:32:40.958 473 494 D BluetoothManagerService: Sending BLE State Chan ge: OFF > BLE_TURNING_ON 09-06 02:32:40.959 4268 4283 D BluetoothAdapterService: BleOnProcessStart() 09-06 02:32:40.959 4268 4283 D BluetoothAdapterService: BleOnProcessStart() - Make Bond State Machine 09-06 02:32:40.960 4268 4283 D BluetoothBondStateMachine: make 09-06 02:32:40.962 4268 4290 I BluetoothBondStateMachine: StableState(): Enter ing Off State 09-06 02:32:40.963 4268 4283 D BluetoothAdapterService: setProfileServiceState () - Starting service com.android.bluetooth.gatt.GattService 09-06 02:32:40.967 4268 4283 I BluetoothAdapterState: Entering PendingCommandS tate 09-06 02:32:40.968 4268 4268 I BtGatt.JNI: classInitNative(L922): classInitNat ive: Success! 09-06 02:32:40.974 4268 4268 D BtGatt.DebugUtils: handleDebugAction() action=n ull 09-06 02:32:40.974 4268 4268 D BluetoothAdapterService: getAdapterService() - returning com.android.bluetooth.btservice.AdapterService@81239cc 09-06 02:32:40.975 4268 4268 D BtGatt.GattService: Received start request. Sta rting profile... 09-06 02:32:40.975 4268 4268 D BtGatt.GattService: start() 09-06 02:32:40.975 4268 4268 I bt_btif : get_profile_interface gatt 09-06 02:32:40.977 4268 4268 D BluetoothAdapterService: getAdapterService() - returning com.android.bluetooth.btservice.AdapterService@81239cc 09-06 02:32:40.977 4268 4268 D BtGatt.AdvertiseManager: advertise manager crea ted 09-06 02:32:40.984 4268 4268 D BluetoothAdapterService: getAdapterService() - returning com.android.bluetooth.btservice.AdapterService@81239cc 09-06 02:32:40.985 4268 4268 D BluetoothAdapterService: handleMessage() - Mess age: 1 09-06 02:32:40.985 4268 4268 D BluetoothAdapterService: handleMessage() - MESS AGE_PROFILE_SERVICE_STATE_CHANGED 09-06 02:32:40.985 4268 4268 D BluetoothAdapterService: processProfileServiceS tateChanged() serviceName=com.android.bluetooth.gatt.GattService, state=12, doUp date=true 09-06 02:32:40.985 4268 4268 V BluetoothAdapterState: isTurningOff()=false 09-06 02:32:40.986 4268 4268 V BluetoothAdapterState: isTurningOn()=false 09-06 02:32:40.986 4268 4268 V BluetoothAdapterState: isBleTurningOn()=true 09-06 02:32:40.986 4268 4268 V BluetoothAdapterState: isBleTurningOff()=false 09-06 02:32:40.986 4268 4268 D BluetoothAdapterService: processProfileServiceS tateChanged() - serviceName=com.android.bluetooth.gatt.GattService isTurningOn=f alse isTurningOff=false isBleTurningOn=true isBleTurningOff=false 09-06 02:32:40.986 4268 4268 D BluetoothAdapterService: GattService is started 09-06 02:32:40.987 4268 4283 D BluetoothAdapterState: Current state: PENDING_C OMMAND, message: 4 09-06 02:32:40.989 4268 4283 I bt_btif : enable: start restricted = 0 09-06 02:32:40.989 4268 4284 I bt_stack_manager: event_start_up_stack is bring ing up the stack 09-06 02:32:40.990 4268 4284 I bt_core_module: module_start_up Starting module "btif_config_module" 09-06 02:32:40.990 4268 4284 I bt_core_module: module_start_up Started module "btif_config_module" 09-06 02:32:40.990 4268 4284 I bt_core_module: module_start_up Starting module "btsnoop_module" 09-06 02:32:40.990 4268 4284 I bt_core_module: module_start_up Started module "btsnoop_module" 09-06 02:32:40.990 4268 4284 I bt_core_module: module_start_up Starting module "hci_module" 09-06 02:32:40.990 4268 4284 I bt_hci : start_up 09-06 02:32:40.992 4268 4293 W bt_osi_thread: run_thread: thread id 4293, thre ad name hci_thread started 09-06 02:32:40.996 4268 4284 I bt_vendor: alloc value 0x9d4a29bd 09-06 02:32:40.996 4268 4284 I bt_vendor: init 09-06 02:32:40.996 4268 4284 I bt_vnd_conf: Attempt to load conf from /etc/blu etooth/bt_vendor.conf 09-06 02:32:40.996 4268 4284 D bt_hci : start_up starting async portion 09-06 02:32:40.996 4268 4293 I bt_hci : event_finish_startup 09-06 02:32:40.996 4268 4293 I bt_hci_h4: hal_open 09-06 02:32:40.996 4268 4293 I bt_userial_vendor: userial vendor open: opening /dev/ttyAMA0 09-06 02:32:40.997 4268 4293 I bt_userial_vendor: device fd = 72 open 09-06 02:32:40.997 4268 4294 W bt_osi_thread: run_thread: thread id 4294, thre ad name hci_single_chann started