Anybody seen/solved this Bluetooth Admin issue?

kimble01

Member
Licensed User
Longtime User
All,
I've run into a strange issue perhaps someone can help me with:

I define a BluetoothAdmin object and a Serial object in the ProcessGlobals of my activity, then I initialize those objects in the FirstTime condition in ActivityCreate:
B4X:
If FirstTime Then
   clsErrorsEvents.Initialize
   objphone.SetRingerMode(objphone.RINGER_SILENT)
   
   If Not(admin.IsInitialized) Then
      admin.Initialize("btAdmin")
   End If
   If Not(admin.IsEnabled) Then
      admin.Enable
   End If
   Serial1.Initialize("btSerial")

Then I call into a webservice to check for a valid user in the ActivityCreate:

B4X:
Activity.LoadLayout("InitialScreen")
   ProgressDialogShow("Checking for valid user...")
   Log("Checking for valid user with " & objPhoneID.GetLine1Number & " and " & mapDeviceInfo.Get("edtreceivingnumber"))
   mapMessage.Put("DeviceNumber", objPhoneID.GetLine1Number)
   mapMessage.Put("RemoteNumber", mapDeviceInfo.Get("edtreceivingnumber"))
   mapMessage.Put("EventCode", clsErrorsEvents.EventCodes.CheckForServiceEvent)
   mapMessage.Put("BlueToothMac", strBTMac)   
   objJSONGen.Initialize(mapMessage)
   Log("About to call CheckForService with the message:" & objJSONGen.ToString)
   Dim objJob As HttpJob
   objJob.Initialize("ValidUser", Me)
   Log("About to check valid user with: " & objJSONGen.ToString)
   Dim tmpString As String: tmpString = strWebServiceURL & "ProcessMessage"
   Try
      objJob.PostString(tmpString, objJSONGen.ToString)
      objJob.GetRequest.SetContentType("application/json")
   Catch
      Log("An Error was raised trying to contact the webservice: " & LastException.Message)
      BuildSupportScreen
   End Try

I am handling the serialconnect and admin statechanged events although I am only logging the results, not taking any action.

Here's the issue:
When Bluetooth is off and this code executes, I get the following:

SBG_Data.s3db


/mnt/sdcard/Android/data/com.seatbeltguard/files


DB File Existed...
Forced Update was: true


Forced update!


ExecuteMap: Select * from DeviceInfo
Saving this DeviceInfo: (MyMap) {edtthisphonenumber=4793532095, edtthisuser=Gary, edtreceivingnumber=14793532095, serviceenddate=1.37957e+12, bluetoothmac=}


ExecuteMap: Select * from DeviceInfo


New DeviceInfo: (MyMap) {edtthisphonenumber=4793532095, edtthisuser=Gary, edtreceivingnumber=14793532095, serviceenddate=1.37957e+12, bluetoothmac=}


SBG_Data.s3db
/mnt/sdcard/Android/data/com.seatbeltguard/files
DB File Existed...
** Activity (main) Create, isFirst = true **


Checking to see whether we need to run setup...result was: 1


ExecuteMap: Select * from DeviceInfo


About to enable bluetooth...


Bluetooth enabled


strBTMac is CC:05:1B:77:0E:FC


Checking for valid user with 4793532095 and 14793532095


About to call CheckForService with the message:{"TimeStamp":"String content","RemoteNumber":"14793532095","BlueToothMac":"CC:05:1B:77:0E:FC","ServiceEndDate":"String content","RemoteName":"String content","DeviceNumber":"4793532095","EventCode":200}


About to check valid user with: {"TimeStamp":"String content","RemoteNumber":"14793532095","BlueToothMac":"CC:05:1B:77:0E:FC","ServiceEndDate":"String content","RemoteName":"String content","DeviceNumber":"4793532095","EventCode":200}


startService: class com.seatbeltguard.httputils2service


sending message to waiting queue of uninitialized activity (submitjob)


** Activity (main) Resume **


** Activity (main) Pause, UserClosed = false **


java.lang.NullPointerException


at anywheresoftware.b4a.objects.Serial$BluetoothAdmin$1$1.run(Serial.java:323)
at android.os.Handler.handleCallback(Handler.java:587)
at android.os.Handler.dispatchMessage(Handler.java:92)
at android.os.Looper.loop(Looper.java:130)
at android.app.ActivityThread.main(ActivityThread.java:3687)
at java.lang.reflect.Method.invokeNative(Native Method)
at java.lang.reflect.Method.invoke(Method.java:507)
at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:842)
at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:600)
at dalvik.system.NativeStart.main(Native Method)
SBG_Data.s3db

The bluetooth adapter is succesfully enabled, but the program obviously dies.

Is there a why to trap this error? It is definitely related to calling into HttpUtils service, but why is beyond me.

Any help will be appreciated.

Thanks,
Gary

EDIT
I thought it was related to the HttpUtils call, but after disabling that call I am definitely able to recreate this error. I went digging into my libraries and discovered duplicate libraries in the AddLibraries and Libraries folders. I am curious who wins in that scenario...Administrator, GPS, and a couple others were duplicated. I removed all duplicates from the AddLibraries folder as an initial step...problem remains.

When I went into the raw logs for the initialization routine I see this:
Raw logs too long, even after editing... I'll put in a quickreply...
 
Last edited:

kimble01

Member
Licensed User
Longtime User
Raw logs relevant sections...

isServiceEnabled result_bt: 1
isServiceEnabled result_fm: 0
***startService(): Starting service: bluetooth_test***
***startService(): Done starting service: bluetooth_test***

... Bunch More Stuff...
______My Application actually starting____________
ApplicationKeyDatabase: getApplicationKey()
ExecuteMap: Select * from DeviceInfo
New DeviceInfo: (MyMap) {edtthisphonenumber=4793532095, edtthisuser=Gary, edtreceivingnumber=14793532095, serviceenddate=1.37957e+12, bluetoothmac=}
SBG_Data.s3db
/mnt/sdcard/Android/data/com.seatbeltguard/files
DB File Existed...
** Activity (main) Create, isFirst = true **
Checking to see whether we need to run setup...result was: 1
ExecuteMap: Select * from DeviceInfo
About to enable bluetooth...
Bluetooth enabled
strBTMac is CC:05:1B:77:0E:FC
GC_EXTERNAL_ALLOC freed 314K, 48% free 3020K/5703K, external 2005K/2108K, paused 53ms
PackageMonitor: doOnReceive(): failed
java.lang.IllegalStateException: Unable to access the application key
at com.getjar.sdk.data.metadata.PackageMonitor.doOnReceive(PackageMonitor.java:121)
at com.getjar.sdk.data.metadata.PackageMonitor.access$000(PackageMonitor.java:61)
at com.getjar.sdk.data.metadata.PackageMonitor$1.run(PackageMonitor.java:86)
at java.lang.Thread.run(Thread.java:1019)
PackageMonitor: doOnReceive(): FINISHED
Checking for valid user with 4793532095 and 14793532095
About to call CheckForService with the message:{"TimeStamp":"String content","RemoteNumber":"14793532095","BlueToothMac":"CC:05:1B:77:0E:FC","ServiceEndDate":"String content","RemoteName":"String content","DeviceNumber":"4793532095","EventCode":200}
About to check valid user with: {"TimeStamp":"String content","RemoteNumber":"14793532095","BlueToothMac":"CC:05:1B:77:0E:FC","ServiceEndDate":"String content","RemoteName":"String content","DeviceNumber":"4793532095","EventCode":200}
** Activity (main) Resume **
Not enough disk space. Will not index.
Strict mode not enforced: debug disabled

...Bunch More stuff...

Bluetooth state 12 -> 13
onBluetoothDisable...
completeBluetoothDisable: forceDisable = false
audioOff(): mPendingSco: false, mConnectedSco: null, mOutgoingSco: null, mA2dpState: 0, mA2dpSuspended: false, mIncomingSco:android.bluetooth.ScoSocket@405e25a0


android.bluetooth.ScoSocket@405e25a0 SCO OBJECT close() mState = 2
mWakeLock.acquire() android.bluetooth.ScoSocket@405e25a0
send_ctrl_msg: [BTL_IFC CTRL] send BTLIF_DISCONNECT_REQ (SCO) 0 pbytes (hdl 99)
Could not find conn_info with active SCO
mWakeLock.release() in 2 secandroid.bluetooth.ScoSocket@405e25a0
onReceive()-S:13
setParameters(): io 0, keyvalue bluetooth_enabled=false, tid 74, calling tid 110
A2DP profile has been disconnected.
Received profile 2 disconnected intent.
Zygote: pid 11771 has CALL PRIVILEGED permission, then set capability for CAP_SYS_ADMIN (21)
event_filter: Received signal org.bluez.Adapter:propertyChanged from /org/bluez/11504/hci0
Client calling DTUN_METHOD_DM_SET_MODE (id 12)
>>> START GKI_timer_update(), wake_lock_count:1
>>> RESTARTED GKI_timer_update(): run_cond: 1
event_filter: Received signal org.bluez.Adapter:propertyChanged from /org/bluez/11504/hci0
dtun_receive_msg: [DTUN] Received message [BTLIF_DTUN_METHOD_CALL] 4354
handle_method_call: handle_method_call :: received DTUN_METHOD_DM_SET_MODE (id 12), len 1
btapp_dm_SetMode()
event_filter: Received signal org.bluez.Adapter:propertyChanged from /org/bluez/11504/hci0
WifiProgressStore Created
Proxy object connected
onReceive
destroyNotification
***Received Bluetooth TURNING OFF Event****
onBluetoothDisabled()
***stopService(): Stopping service: bluetooth_opp_service***
***stopService(): Stop service: bluetooth_opp_service ***
Stopping standalone OPP Event Loop...
obex_remove_match, me:0x4051a328
Obex Event Loop:socketpair[32 33] closed on loop exit
ye DeleteGlobalRef:eek:bject:0x4051a328, me:0x4051a328, gref:0
stopEventLoopNative:socketpair[-1 -1]
***stopService(): Stopping service: bluetooth_pbap***
***stopService(): Stop service: bluetooth_pbap ***
stop: mPbapMode = 2
Stop Java PBAP server...
onPbapDisabled
***stopService(): Stopping service: bluetooth_test***
***stopService(): Stop service: bluetooth_test ***
id main loop exiting
dtun_client_stop : iface [DTUN_INTERFACE] stopping...
bluetoothd has stopped
But dtun_property_hcid_active is still 1. So clearing it...
intent : action: android.bluetooth.adapter.action.STATE_CHANGED
BluetoothAdapter.EXTRA_STATE: 13
Received action: broadcom.bt.intent.action.BT_SVC_STATE_CHANGE
SVC NAME=bluetooth_opp_service
Stopping OPPClientService....
Finishing service bluetooth_opp_service
OPPClientService stopped....
PbapReceiver onReceive: broadcom.android.bluetooth.intent.action.BT_PBAP_CHANGED
Pbap Service onStartCommand
action: broadcom.android.bluetooth.intent.action.BT_PBAP_CHANGED
Pbap Service closeService
asocket_abort [36,37,38]
blz20_wrp_shutdown: s 36, how 2
blz20_wrp_shutdown: fd (36:36), bta 1, rc 19, wflags 0x20, cflags 0x2, port 9049
blz20_wrp_shutdown: shutdown socket
blz20_wrp_shutdown: wake up any waiting server threads
btlif_signal_event: fd (36:36), bta 1, rc 19, wflags 0x20, cflags 0x2, port 9049
btlif_signal_event: event BTLIF_BTS_EVT_ABORT matched
btlif_wait_response: unblocked fd (36:36), bta 1, rc 19, wflags 0x20, cflags 0x2, port 9049
blz20_wrp_poll: set errno 125 (Operation Canceled) l.2021
blz20_wrp_write: wrote 1 bytes out of 1 on fd 38
Accept exception: java.io.IOException: Operation Canceled
asocket_destroy
asocket_abort [36,37,38]
blz20_wrp_shutdown: s 36, how 2
blz20_wrp_shutdown: fd (36:36), bta 1, rc 19, wflags 0x20, cflags 0x2, port 9049
blz20_wrp_shutdown: shutdown socket
blz20_wrp_shutdown: wake up any waiting server threads
btlif_signal_event: fd (36:36), bta 1, rc 19, wflags 0x20, cflags 0x2, port 9049
btlif_signal_event: event BTLIF_BTS_EVT_ABORT matched
blz20_wrp_write: wrote 1 bytes out of 1 on fd 38
blz20_wrp_close: s 38
blz20_wrp_close: std close (38)
blz20_wrp_close: s 37
blz20_wrp_close: std close (37)
blz20_wrp_close: s 36
blz20_wrp_close: fd (36:36), bta 1, rc 19, wflags 0x20, cflags 0x2, port 9049
__close_prot_rfcomm: fd 36
send_ctrl_msg: [BTL_IFC CTRL] send BTLIF_BTS_RFC_LISTEN_CANCEL (BTS) 8 pbytes (hdl 17)
btl_ifc_ctrl_rx: [BTL_IFC CTRL] recv BTLIF_BTS_RFC_CLOSE_CFM (BTS) 7 pbytes (hdl 17)
btlif_ctrl_callback: btlif_ctrl_callback : msg id BTLIF_BTS_RFC_CLOSE_CFM
wrp_close_s_only: wrp_close_s_only [36] (36:36) [brcm.bt.btlif]
wrp_close_s_only: data socket closed
wsactive_del: delete wsock 36 from active list [ad42003c]
wrp_close_s_only: listen socket closed
wsactive_del: delete wsock 36 from active list [ad42003c]
wrp_close_s_only: wsock fully closed, return to pool
btsk_free: success
Accept exception: java.io.IOException: socket closed
Accept exception: java.io.IOException: socket closed
Accept exception: java.io.IOException: socket closed
....A LOT of these...
Stopping OPPServerService....
Received action: broadcom.bt.intent.action.BT_SVC_STATE_CHANGE
SVC NAME=bluetooth_pbap
successfully stopped pbap service
OPPServerService stopped....
[main/1] onDestroy()
[main/1] stop()
[main/1] onDestroy()
Thread interrupted
*****RequestWorker interrupted...Aborting...
Finishing service bluetooth_pbap
[Thread-17/17] Rows Updated = 0
Received action: broadcom.bt.intent.action.BT_SVC_STATE_CHANGE
SVC NAME=bluetooth_test
Finishing service bluetooth_test
Unregister file observer: com.broadcom.bt.app.opp.util.ExchangeFolderManager$ExchangeFolderObserver@40523018
Unregister file observer: com.broadcom.bt.app.opp.util.ExchangeFolderManager$ExchangeFolderObserver@40522b38
LTH] onEvent :32768 null
LTH] onEvent :32768 null
...More Stuff...
property: sys.settings_secure_version=24
notifying: content://settings/secure/bluetooth_on
Bluetooth state 13 -> 10
Input event: value=0
isBackKeyPressed(), return value = true
updateBackKeyPressed(), pressed = false
Received android.bluetooth.adapter.action.STATE_CHANGED
Pbap Service onDestroy
Pbap Service closeService
onReceive()-S:10
notification manager receiver action = android.intent.action.BATTERY_CHANGED
onReceive
***Received Bluetooth Event that can be ignored****


onReceive Intent:android.bluetooth.adapter.action.STATE_CHANGED
intent : action: android.bluetooth.adapter.action.STATE_CHANGED
BluetoothAdapter.EXTRA_STATE: 10
Notification canceled by : mLedNotification = null mScreenOn = true mInCall = false mNotificationPulseEnabled = true
....More Stuff.....
updatePackage=com.seatbeltguard
__________MY Application Starting______________
[com.seatbeltguard] REPLACING:true
Usage: UsageMonitor: starting usage monitoring thread with a monitoring interval of 3000
Usage: UsageMonitor: usage monitoring thread [instantiated]
SBG_Data.s3db
Usage: UsageMonitor: usage monitoring thread [start() called]
GooglePlayBillingService handleCommand - action null
/mnt/sdcard/Android/data/com.seatbeltguard/files
DB File Existed...
Usage: UsageMonitor: usage monitoring thread started [thread id: 380]
PackageMonitor: doOnReceive(): FINISHED
Usage: UsageMonitor: UsageMonitoringThread: started
PackageMonitor: onReceive(): START
PackageMonitor: doOnReceive(): START
database:purchase.db
database:GetJarDBApplicationKey
database:dragdrop.db
database:mycenter.db
database:webviewCache.db
database:webview.db
database:ImSyncHelper.db-journal
database:gosms_purchase.db
database:ImSyncHelper.db
database:ChargeRecordManager.db
database:sms_filter_rule.db
database:gomms.db
database:messaging.db
database:gommssms.db
database:sms.db
database:GoSmsAutoReply.db
database:schedule.db
database:sms_interception.db
database:MessageBox.db
database:im.db
database:SyncHelper.db
database:notifMsg.db
database:google_analytics.db
Forced Update was: true
Forced update!
ApplicationKeyDatabase: getApplicationKey()
ExecuteMap: Select * from DeviceInfo
Saving this DeviceInfo: (MyMap) {edtthisphonenumber=4793532095, edtthisuser=Gary, edtreceivingnumber=14793532095, serviceenddate=1.37957e+12, bluetoothmac=}


PackageMonitor: doOnReceive(): failed
java.lang.IllegalStateException: Unable to access the application key
at com.getjar.sdk.data.metadata.PackageMonitor.doOnReceive(PackageMonitor.java:121)
at com.getjar.sdk.data.metadata.PackageMonitor.access$000(PackageMonitor.java:61)
at com.getjar.sdk.data.metadata.PackageMonitor$1.run(PackageMonitor.java:86)
at java.lang.Thread.run(Thread.java:1019)
PackageMonitor: doOnReceive(): FINISHED
restore() mFlagTruncated = 0
Not enough disk space. Will not index.
restore() mFlagTruncated = 0
restore() mFlagTruncated = 0
ExecuteMap: Select * from DeviceInfo
New DeviceInfo: (MyMap) {edtthisphonenumber=4793532095, edtthisuser=Gary, edtreceivingnumber=14793532095, serviceenddate=1.37957e+12, bluetoothmac=}
SBG_Data.s3db
/mnt/sdcard/Android/data/com.seatbeltguard/files
DB File Existed...
** Activity (main) Create, isFirst = true **
onReceive()-S:0
setStreamVolumeIndex() stream 7, index 7
setStreamVolumeIndex() stream 5, index 0
setStreamVolume() for output 1 stream 5, volume 0.000000, delay 0
setStreamVolumeIndex() stream 2, index 0
setStreamVolume() for output 1 stream 2, volume 0.000000, delay 0
setStreamVolumeIndex() stream 1, index 0
setStreamVolume() for output 1 stream 1, volume 0.000000, delay 0
setStreamVolumeIndex() stream 7, index 7
android.provider.Settings$SettingNotFoundException: bluetooth_enabled
at android.provider.Settings$Secure.getInt(Settings.java:2619)
at android.server.BluetoothService.enable(BluetoothService.java:603)
at android.server.BluetoothService.enable(BluetoothService.java:590)
at android.bluetooth.IBluetooth$Stub.onTransact(IBluetooth.java:69)
at android.os.Binder.execTransact(Binder.java:320)
at dalvik.system.NativeStart.run(Native Method)
enable...
keep current bluetooth address
Bluetooth state 10 -> 11
onReceive()-S:11
bt_enable...
BTOFF_FMOFF_handling
BTOFF_FMOFF_handling : receiving BT_ON
bt_start_daemons( ctl_act: 0 ), soft_on_off: 0:Starting daemons...
Starting btld...
Received android.bluetooth.adapter.action.STATE_CHANGED
restore() mFlagTruncated = 0
Strict mode not enforced: debug disabled
Checking to see whether we need to run setup...result was: 1
ExecuteMap: Select * from DeviceInfo
About to enable bluetooth...
android.provider.Settings$SettingNotFoundException: bluetooth_enabled
at android.provider.Settings$Secure.getInt(Settings.java:2619)
at android.server.BluetoothService.enable(BluetoothService.java:603)
at android.server.BluetoothService.enable(BluetoothService.java:590)
at android.bluetooth.IBluetooth$Stub.onTransact(IBluetooth.java:69)
at android.os.Binder.execTransact(Binder.java:320)
at dalvik.system.NativeStart.run(Native Method)
enable...
keep current bluetooth address
Oops: enable BT in state 11
Bluetooth is not enabled!
Updating BluetoothMac in DeviceInfo
UpdateRecord: UPDATE [DeviceInfo] SET [BlueToothMac] = ? WHERE [edtThisPhoneNumber] = ?
ExecuteMap: Select * from DeviceInfo
New DeviceInfo Map: {"edtreceivingnumber":"14793532095","edtthisphonenumber":"4793532095","serviceenddate":"1.37957e+12","bluetoothmac":"","edtthisuser":"Gary"}
**************************** Profile detection *************************
* Model:SPH-D700
* Manufacturer:Samsung
* API:10
* VERSION.INCREMENTAL:FC09
* BOARD:SPH-D700
* DEVICE:SPH-D700
* VERSION.INCREMENTAL:sprint/SPH-D700/SPH-D700:2.3.6/GINGERBREAD/FC09:user/release-keys
* HOST:SEP-50
* ID:GINGERBREAD
* BRAND:sprint
* CPU:armeabi-v7a
* ro.board.platform:s5pc110
* package:com.qikffc.android
************************************************************************
********************* Profiles Selected ***********************
** DEFAULT
** GINGERBREAD
** SAMSUNG
** SAMSUNG_EPIC_GB
***************************************************************
AuthFlow: waitOnAuth() [stack::VMStack.java.-2:Thread.java.737:Logger.java.157:AuthManager.java.624:UsageMonitor.java.183]
AuthFlow: waitOnAuth() [stack::VMStack.java.-2:Thread.java.737:Logger.java.157:AuthManager.java.624:EarnStateDatabase.java.53:UsageMonitor.java.186]


Usage: UsageMonitor: UsageMonitoringThread: exiting
GC_CONCURRENT freed 1302K, 55% free 3075K/6727K, external 1596K/2108K, paused 3ms+2ms
GC_EXTERNAL_ALLOC freed 631K, 50% free 3028K/6023K, external 2005K/2108K, paused 216ms
/system/bin/btld:pid:11919, main(): calling set_aid_and_cap
in set_aid_and_cap() pid:11919
btl_cfg_get_btld_status: service.brcm.bt.btld=0
BTLD starting...
btl_cfg_set_btld_status: service.brcm.bt.btld=1, ret=0
btld_cfg_btld_pid : service.brcm.bt.btld_pid = 11919
btl_cfg_set_btld_pid success : service.brcm.bt.btld_pid = 11919, ret = 0
BTE version BCM1200_MI_10.3.17.2
btld version BTL-ANDROID_MI_2.6.2.0+_VICTORY_B1975_R953:2062M starting...
Build time: Dec 29 2011 00:53:12
btl_cfg_get_bdaddr: BDA=CC051B770EFC
##### ERROR : main: main :: BTLD starting
#####
WARNING : [service.brcm.bt.patchfile] property_get failed (0)
btl_cfg_get_3way_conf_support: service.brcm.bt.3way_support=true
WARNING : [service.brcm.bt.bt_wake_polar] property_get failed (0)
btl_cfg_get_lpm_params: service.brcm.bt.bt_wake_polar=1
WARNING : [service.brcm.bt.host_wake_polar] property_get failed (0)
btl_cfg_get_lpm_params: service.brcm.bt.host_wake_polar=1
WARNING : [service.brcm.bt.pcm_clk] property_get failed (0)
WARNING : [service.brcm.bt.pcm_ms_role] property_get failed (0)
btl_cfg_get_contacts_db: service.brcm.bt.contacts_db=/data/data/com.android.providers.contacts/databases/contacts2.db
GC_CONCURRENT freed 541K, 53% free 3169K/6727K, external 1596K/2108K, paused 2ms+9ms
#### : lpm_through_rfkill = 1
************************** Properties ****************************
* android.vm.dexfile: true
* file.encoding: UTF-8
* file.separator: /
* http.agent: Dalvik/1.4.0 (Linux; U; Android 2.3.6; SPH-D700 Build/GINGERBREAD)
* java.boot.class.path: /system/framework/core.jar:/system/framework/bouncycastle.jar:/system/framework/ext.jar:/system/framework/framework.jar:/system/framework/android.policy.jar:/system/framework/services.jar:/system/framework/core-junit.jar
* java.class.path: .
* java.class.version: 46.0
* java.compiler:
* java.ext.dirs:
* java.home: /system
* java.io.tmpdir: /sdcard
* java.library.path: /vendor/lib:/system/lib
* java.net.preferIPv6Addresses: true
* java.runtime.name: Android Runtime
* java.runtime.version: 0.9
* java.specification.name: Dalvik Core Library
* java.specification.vendor: The Android Project
* java.specification.version: 0.9
* java.vendor: The Android Project
* java.vendor.url: Android
* java.version: 0
* java.vm.name: Dalvik
* java.vm.specification.name: Dalvik Virtual Machine Specification
* java.vm.specification.vendor: The Android Project
* java.vm.specification.version: 0.9
* java.vm.vendor: The Android Project
* java.vm.vendor.url: Android
* java.vm.version: 1.4.0
* javax.net.ssl.trustStore: /system/etc/security/cacerts.bks
* line.separator:
* mngspkphone: true
* os.arch: armv7l
* os.name: Linux
* os.version: 2.6.35.7
* path.separator: :
* recording.auto-focus: false
* recording.codec_profile: h264
* recording.ffc.preview-framerate: 15
* recording.ffc.video: 640x480 15 low
* recording.ffc.vtmode: 2
* recording.live.audio-aux: FYg=
* recording.live.video: 352x288 15 low
* recording.live.video-aux: AQLBLP/hAAlnQgAN6QLBLIABAAVozgEPIA==
* recording.live.video-aux-rotation-90: AQLBLP/hAAlnQgAN6QLBLIABAAVozgEPIA==
* recording.liveffc.audio-aux: FYg=
* recording.liveffc.video: 640x480 15 low
* recording.liveffc.video-aux: AQFAe//hAAlnQgAW6QFAeyABAAVozgEPIA==
* recording.liveffc.video-aux-rotation-90: AQFAe//hAAlnQgAW6QFAeyABAAVozgEPIA==
* recording.normal.preview-framerate: 15
* recording.normal.video: 640x480 15 medium
* user.dir: /
* user.home:
* user.language: en
* user.name:
* user.region: US
******************************************************************
USERIAL_Init
bte_create_signal_handler() sigset: x4800
signal_handler_thread( sigset x4800 )


GKI creating JOINABLE task 2
gki_task_entry task_id=2
Leaving GKI_create_task 13000 2 20d3d8 APPL 1e1940 0
GKI creating JOINABLE task 1
gki_task_entry task_id=1
Leaving GKI_create_task df194 1 20d418 BTU 1e1940 0
GKI creating JOINABLE task 0
gki_task_entry task_id=0
USERIAL_Open( port: 0, baud: 7
USERIAL_Open opening /dev/s3c2410_serial0

***userial_io_init_bt_wake(): Ooops, asserted BT_WAKE signal, but still got BT_WAKE state == to 0
sock = 7
Leaving USERIAL_Open
Leaving GKI_create_task 10a9c 0 20d458 HCI 1e1940 0
GKI creating JOINABLE task 3
WARNING : [service.brcm.bt.TRC_BTAPP] property_get failed (0)
[bttrc] using compile default trace settings
btl_cfg_get_soft_onoff_enabled : service.brcm.bt.soft_onoff = 0
btapp_dm_cmd_init: result = 0, btl_if_handle = 19
GKI_sched_lock
GKI_sched_unlock
gki_task_entry task_id=3
USERIAL_Ioctl: Received USERIAL_OP_BAUD_WR on port: 0, ioctl baud14
userial_change_rate: Closing UART Port
socket signaled
socket signal reseted
my_read return size <=0:-1, exiting userial_read_thread
USERIAL_Close
Leaving GKI_create_task 3a3c0 3 20d8e0 SBC 1e1940 0
GKI creating JOINABLE task 4
gki_task_entry task_id=4
gki_alloc_free_queue in, id:3
gki_alloc_free_queue calling gki_init_free_queue, id:3 size:1712, totol:100
gki_alloc_free_queue ret OK, id:3 size:1712, totol:100
dtun_server_register_interface: Register DTUN interface [0]
dtun_server_start: dtun_server_start
BTL_IF_RegisterSubSystem: Registered subsystem [DTUN]
BTL_IF_RegisterSubSystem: Registered subsystem [DM]
gki_alloc_free_queue in, id:0
gki_alloc_free_queue calling gki_init_free_queue, id:0 size:64, totol:48
gki_alloc_free_queue ret OK, id:0 size:64, totol:48
gki_alloc_free_queue in, id:2
gki_alloc_free_queue calling gki_init_free_queue, id:2 size:660, totol:45
gki_alloc_free_queue ret OK, id:2 size:660, totol:45
wrp_sock_create: wrp_sock_create : created socket (fd 13)
wrp_sock_bind: BTLIF_MAKE_LOCAL_SERVER_NAME return name: brcm.bt.btlif.9000
wrp_sock_bind: result:0 server_name:brcm.bt.btlif.9000
wrp_sock_listen: wrp_sock_listen : (CTRL) listening on brcm.bt.btlif:9000 (13)
userial_change_rate: Closing UART Port
Leaving GKI_create_task 3cb74 4 20d498 AVR 1e1940 0
GKI_run
userial_change_rate: Attempting to reopen the UART Port at 0x002dc6c0
userial_change_rate: Attempting to reopen the UART Port at 3000000
USERIAL_Open( port: 0, baud: 14
USERIAL_Open opening /dev/s3c2410_serial0

***userial_io_init_bt_wake(): Ooops, asserted BT_WAKE signal, but still got BT_WAKE state == to 0
sock = 7
Leaving USERIAL_Open
BTM: btm_read_local_name_complete: local name is [BCM4329B1]
bte_main_rln_cplt: input local name for patch download [BCM4329B1], len 9
bte try to find prefix [BCM4329B1] config file in this phone
Found patchfile: /system/bin/BCM4329B1_002.002.023.0746.0832.hcd
bte_main_rln_cplt: Downloading patchfile /system/bin/BCM4329B1_002.002.023.0746.0832.hcd
GKI_sched_lock
GKI_sched_unlock
BCM2045_PRM_Init
Minidrv download completed
gki_alloc_free_queue in, id:1
gki_alloc_free_queue calling gki_init_free_queue, id:1 size:288, totol:26
gki_alloc_free_queue ret OK, id:1 size:288, totol:26
[hcis] Unknown HCI message type drop this byte 0x34
[hcis] Unknown HCI message type drop this byte 0x31
restore() mFlagTruncated = 0
Checking for valid user with 4793532095 and 14793532095
About to call CheckForService with the message:{"TimeStamp":"String content","RemoteNumber":"14793532095","BlueToothMac":"","ServiceEndDate":"String content","RemoteName":"String content","DeviceNumber":"4793532095","EventCode":200}
About to check valid user with: {"TimeStamp":"String content","RemoteNumber":"14793532095","BlueToothMac":"","ServiceEndDate":"String content","RemoteName":"String content","DeviceNumber":"4793532095","EventCode":200}
** Activity (main) Resume **
Launch RAM successful
restore() mFlagTruncated = 0
USERIAL_Ioctl: Received USERIAL_OP_BAUD_WR on port: 0, ioctl baud7
userial_change_rate: Closing UART Port
socket signaled
socket signal reseted
my_read return size <=0:-1, exiting userial_read_thread


USERIAL_Close
userial_change_rate: Closing UART Port
userial_change_rate: Attempting to reopen the UART Port at 0x0001c200
userial_change_rate: Attempting to reopen the UART Port at 115200
USERIAL_Open( port: 0, baud: 7
USERIAL_Open opening /dev/s3c2410_serial0

***userial_io_init_bt_wake(): Ooops, asserted BT_WAKE signal, but still got BT_WAKE state == to 0
sock = 7
Leaving USERIAL_Open
USERIAL_Ioctl: Received USERIAL_OP_BAUD_WR on port: 0, ioctl baud14
userial_change_rate: Closing UART Port
socket signaled
socket signal reseted
my_read return size <=0:-1, exiting userial_read_thread
USERIAL_Close
userial_change_rate: Closing UART Port
userial_change_rate: Attempting to reopen the UART Port at 0x002dc6c0
userial_change_rate: Attempting to reopen the UART Port at 3000000
USERIAL_Open( port: 0, baud: 14
USERIAL_Open opening /dev/s3c2410_serial0

***userial_io_init_bt_wake(): Ooops, asserted BT_WAKE signal, but still got BT_WAKE state == to 0
sock = 7
Leaving USERIAL_Open
Setting local bd addr to CC:05:1B:77:0E:FC
Cannot open file /etc/bluetooth/did.conf
Cannot open file /etc/bluetooth/did.conf
Cannot open file /etc/bluetooth/did.conf
btl_cfg_get_soft_onoff_enabled : service.brcm.bt.soft_onoff = 0
BTAPP_enable_bta(): unsupported id: 31
BTAPP_enable_bta(): unsupported id: 32
btl_cfg_get_btport_redirection_enable: service.brcm.bt.btport_redir_on=
GKI_sched_lock
GKI_sched_unlock
GKI_sched_lock
GKI_sched_unlock
bta_ag_co_init: ag_voice_settings = 96
bta_ag_co_init: ag_vsc_sco_pcm(0x17dfa7) = {0, 0, 0, 0, 0}
bta_ag_co_init: ag_vsc_pcm_config(0x17dfa2) = {0, 0, 3, 3, 0}
**** Starting DTUN [OPC] Interface ****
OPS Supported Formats = 0x77
GKI_sched_lock
GKI_sched_unlock
OPS event:0
Initialized IFS (res = 6)
Registered IFS (res = 0)
btapp_sc_init: result = 0, btl_if_handle = 15
btapp_rc_init
GKI_sched_lock
GKI_sched_unlock
btm_sec_set_security_level: set BTM_SEC_OUT_MITM
btm_sec_set_security_level: set BTM_SEC_IN_MITM
btm_sec_set_security_level: set BTM_SEC_OUT_MITM
btm_sec_set_security_level: set BTM_SEC_IN_MITM
btm_sec_set_security_level: set BTM_SEC_OUT_MITM
btm_sec_set_security_level: set BTM_SEC_IN_MITM
AVRCP enabled
Advanced Audio enabled
GKI_sched_lock
GKI_sched_unlock
Flick Init
BTL_IF_RegisterSubSystem: Registered subsystem [TEST]
BTL_IF_RegisterSubSystem: Registered subsystem [DUN]
BTL_IF_RegisterSubSystem: Registered subsystem [SPP]
BTL_IF_RegisterSubSystem: Registered subsystem [AG]
BTL_IF_RegisterSubSystem: Registered subsystem [SCO]
BTL_IF_RegisterSubSystem: Registered subsystem [FTPS]
BTL_IF_RegisterSubSystem: Registered subsystem [PR]
BTL_IF_RegisterSubSystem: Registered subsystem [SAPS]
BTAPP_HH: btapp_hh_init
BTL_IF_RegisterSubSystem: Registered subsystem [HH]
BTAPP_HH: btapp_hh_init: BTL_IF_RegisterSubSystem result = 0, btl_if_handle = 12
btapp_bts_init: initiating BT socket subsystem
BTL_IF_RegisterSubSystemMultiClnt: Registered subsystem [BTS]
jvapi_if_dm_cback: #### jw_if_dm_cback ev BTA_JV_ENABLE_EVT ####
BTL_IF_RegisterSubSystem: Registered subsystem [FLICK]
Shutting down VM
threadid=1: thread exiting with uncaught exception (group=0x40015578)
java.lang.NullPointerException
at anywheresoftware.b4a.objects.Serial$BluetoothAdmin$1$1.run(Serial.java:323)
at android.os.Handler.handleCallback(Handler.java:587)
at android.os.Handler.dispatchMessage(Handler.java:92)
at android.os.Looper.loop(Looper.java:130)
at android.app.ActivityThread.main(ActivityThread.java:3687)
at java.lang.reflect.Method.invokeNative(Native Method)
at java.lang.reflect.Method.invoke(Method.java:507)
at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:842)
at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:600)
at dalvik.system.NativeStart.main(Native Method)
FATAL EXCEPTION: main
java.lang.NullPointerException
at anywheresoftware.b4a.objects.Serial$BluetoothAdmin$1$1.run(Serial.java:323)
at android.os.Handler.handleCallback(Handler.java:587)


at android.os.Handler.dispatchMessage(Handler.java:92)
at android.os.Looper.loop(Looper.java:130)
at android.app.ActivityThread.main(ActivityThread.java:3687)
at java.lang.reflect.Method.invokeNative(Native Method)
at java.lang.reflect.Method.invoke(Method.java:507)
at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:842)
at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:600)
at dalvik.system.NativeStart.main(Native Method)
Dumpstate > /data/log/dumpstate_app_error
begin
BTLD start retry count 6/20
Starting bluetoothd deamon
restore() mFlagTruncated = 0
property: sys.settings_system_version=81
setScreenOffTimeouts mKeylightDelay=6000 mDimDelay=17000 mScreenOffDelay=7000 mDimScreen=true
notifying: content://settings/system/mode_ringer
this is not dir. type: 4, name: .
this is not dir. type: 4, name: ..
this is not reg. type: 4, name: CC:05:1B:77:0E:FC
this is not reg. type: 4, name: CC:05:1B:77:0E:FC
this is not reg. type: 4, name: 00:00:00:00:00:00
this is not reg. type: 4, name: 00:00:00:00:00:00
HCID DTUN client starting
dtun_start_interface : iface [DTUN_INTERFACE] starting...
BTLIF_MAKE_LOCAL_SERVER_NAME return name: brcm.bt.btlif.9000
connect_srv ret:0 server name:brcm.bt.btlif.9000
restore() mFlagTruncated = 0
...


It appears that the Bluetooth Adapter is interacted with long before my application attempts to enable it...
 
Upvote 0
Top