Hi all

attaching the logs

I am getting wierd log, which i think is causing the problem:
"probe failed with driver input-headset for device
/org/bluez/483/hci0/dev_12_47_C5_EA_B1_8A"

Can anyone tell me why this occurs??

Regards,
Ajith


LOG:

03-18 18:29:03.554: INFO/BluetoothService(112): Ajith: In Create Bond...
03-18 18:29:03.554: INFO/BluetoothService(112): Ajith:  calling
createPairedDeviceNative...
03-18 18:29:03.577: INFO//system/bin/bluetoothd(482): Ajith: In
adapter_set_state
03-18 18:29:03.577: INFO//system/bin/bluetoothd(482): Ajith: calling
emit_array_property_changed for DeviceAjith: In  emit_array_property_changed
03-18 18:29:03.593: ERROR/BluetoothEventLoop.cpp(112): event_filter:
Received signal org.bluez.Adapter:DeviceCreated from /org/bluez/483/hci0
03-18 18:29:03.593: INFO/Ajith(112): Ajith: onDeviceCreated, address =
12:47:C5:EA:B1:8A
03-18 18:29:03.593: ERROR/BluetoothEventLoop.cpp(112): event_filter:
Received signal org.bluez.Adapter:PropertyChanged from /org/bluez/483/hci0
03-18 18:29:03.593: INFO/Ajith(112):  In onPropertyChanged propValues
==[Ljava.lang.String;@406f5748
03-18 18:29:03.597: INFO/BluetoothService(112): Ajith: setting
PendingOutGoingBonding to address :12:47:C5:EA:B1:8A
03-18 18:29:03.597: INFO/BluetoothService(112): Ajith: setting Bond state to
BOND_BONDING
03-18 18:29:03.601: INFO/BluetoothService(112): Ajith: In setBondState() ,
Old state =10 address = 12:47:C5:EA:B1:8A
03-18 18:29:03.601: DEBUG/BluetoothService(112): 12:47:C5:EA:B1:8A bond
state 10 -> 11 (0)
03-18 18:29:03.605: INFO/BluetoothService(112): Ajith: adding address from
mstate
03-18 18:29:03.628: INFO/BluetoothService(112): Ajith: returning true from
CREATEBOND()
03-18 18:29:03.628: VERBOSE/BluetoothEventRedirector(289): Received
android.bluetooth.device.action.BOND_STATE_CHANGED
03-18 18:29:04.937: ERROR/BluetoothEventLoop.cpp(112): event_filter:
Received signal org.bluez.Device:PropertyChanged from
/org/bluez/483/hci0/dev_12_47_C5_EA_B1_8A
03-18 18:29:04.941: INFO/Ajith(112): In onDevicePropertyChanged, propValues
==[Ljava.lang.String;@407f4ec8
03-18 18:29:04.949: INFO/Ajith(112): Ajith: In setRemoteDeviceProperty
address :12:47:C5:EA:B1:8A name : Connected value :true
03-18 18:29:04.956: INFO/Ajith(112): Ajith: putting properties for adderss:
12:47:C5:EA:B1:8A into mDeviceProperties , name =Connected value =true
03-18 18:29:05.011: INFO//system/bin/bluetoothd(482): bluetoothd[483]:
link_key_request (sba=12:47:65:D5:C0:C1, dba=12:47:C5:EA:B1:8A)
03-18 18:29:05.023: INFO//system/bin/bluetoothd(482): Ajith: In
adapter_set_statebluetoothd[483]: io_capa_request (sba=12:47:65:D5:C0:C1,
dba=12:47:C5:EA:B1:8A)
03-18 18:29:05.581: INFO//system/bin/bluetoothd(482): bluetoothd[483]:
io_capa_response (sba=12:47:65:D5:C0:C1, dba=12:47:C5:EA:B1:8A)
03-18 18:29:06.499: INFO/BluetoothEventLoop.cpp(112): agent_event_filter:
Received method org.bluez.Agent:RequestConfirmation
03-18 18:29:06.503: INFO/BluetoothService(112): Ajith: In setBondState() ,
Old state =11 address = 12:47:C5:EA:B1:8A
03-18 18:29:06.503: INFO/BluetoothService(112): Ajith: oldstate=state
03-18 18:29:06.542: INFO/ActivityManager(112): Starting: Intent {
act=android.bluetooth.device.action.PAIRING_REQUEST flg=0x10000000
cmp=com.android.settings/.bluetooth.BluetoothPairingDialog (has extras) }
from pid 289
03-18 18:29:06.652: DEBUG/dalvikvm(289): GC_CONCURRENT freed 719K, 52% free
3117K/6471K, external 3008K/3599K, paused 2ms+3ms
03-18 18:29:06.886: INFO/ActivityManager(112): Displayed
com.android.settings/.bluetooth.BluetoothPairingDialog: +335ms
03-18 18:29:10.144: INFO/Ajith(112): Ajith: Calling getUuidFromCache()
03-18 18:29:10.144: INFO/Ajith(112): Ajith:In getUuidFromCache, calling
getRemoteDeviceProperty
03-18 18:29:10.144: INFO/Ajith(112): Ajith: In getRemoteDeviceProperty
03-18 18:29:10.144: INFO/Ajith(112): Ajith: properties for address
=12:47:C5:EA:B1:8A is not null , hence getting it from hashmap
03-18 18:29:10.144: INFO/Ajith(112): Ajith: Here the property type to be
queried for in Hashmap is .... "UUIDs"
03-18 18:29:10.144: INFO/Ajith(112): Ajith: Values obtained from
getRemoteDeviceProperty are == null
03-18 18:29:10.144: INFO/Ajith(112): Ajith: value=null, hence returning null
back to getRemoteUuids
03-18 18:29:10.148: INFO/Ajith(112): Ajith: In getRemoteDeviceProperty
03-18 18:29:10.148: INFO/Ajith(112): Ajith: properties for address
=12:47:C5:EA:B1:8A is not null , hence getting it from hashmap
03-18 18:29:10.148: INFO/Ajith(112): Ajith: Here the property type to be
queried for in Hashmap is .... "UUIDs"
03-18 18:29:10.148: INFO/Ajith(112): Ajith: Calling getUuidFromCache()
03-18 18:29:10.148: INFO/Ajith(112): Ajith:In getUuidFromCache, calling
getRemoteDeviceProperty
03-18 18:29:10.148: INFO/Ajith(112): Ajith: In getRemoteDeviceProperty
03-18 18:29:10.148: INFO/Ajith(112): Ajith: properties for address
=00:17:83:C8:A7:27 is not null , hence getting it from hashmap
03-18 18:29:10.148: INFO/Ajith(112): Ajith: Here the property type to be
queried for in Hashmap is .... "UUIDs"
03-18 18:29:10.148: INFO/Ajith(112): Ajith: Values obtained from
getRemoteDeviceProperty are == null
03-18 18:29:10.148: INFO/Ajith(112): Ajith: value=null, hence returning null
back to getRemoteUuids
03-18 18:29:10.148: INFO/Ajith(112): Ajith: In getRemoteDeviceProperty
03-18 18:29:10.148: INFO/Ajith(112): Ajith: properties for address
=00:17:83:C8:A7:27 is not null , hence getting it from hashmap
03-18 18:29:10.148: INFO/Ajith(112): Ajith: Here the property type to be
queried for in Hashmap is .... "UUIDs"
03-18 18:29:10.152: INFO/Ajith(112): Ajith: Calling getUuidFromCache()
03-18 18:29:10.152: INFO/Ajith(112): Ajith:In getUuidFromCache, calling
getRemoteDeviceProperty
03-18 18:29:10.152: INFO/Ajith(112): Ajith: In getRemoteDeviceProperty
03-18 18:29:10.152: INFO/Ajith(112): Ajith: properties for address
=D4:E8:B2:42:D7:32 is not null , hence getting it from hashmap
03-18 18:29:10.152: INFO/Ajith(112): Ajith: Here the property type to be
queried for in Hashmap is .... "UUIDs"
03-18 18:29:10.152: INFO/Ajith(112): Ajith: Values obtained from
getRemoteDeviceProperty are == null
03-18 18:29:10.152: INFO/Ajith(112): Ajith: value=null, hence returning null
back to getRemoteUuids
03-18 18:29:10.156: INFO/Ajith(112): Ajith: In getRemoteDeviceProperty
03-18 18:29:10.159: INFO/Ajith(112): Ajith: properties for address
=D4:E8:B2:42:D7:32 is not null , hence getting it from hashmap
03-18 18:29:10.159: INFO/Ajith(112): Ajith: Here the property type to be
queried for in Hashmap is .... "UUIDs"
03-18 18:29:10.206: INFO//system/bin/bluetoothd(482): bluetoothd[483]:
link_key_notify (sba=12:47:65:D5:C0:C1, dba=12:47:C5:EA:B1:8A, type=5)
03-18 18:29:10.234: INFO//system/bin/bluetoothd(482): bluetoothd[483]:
PRAVEEN ----------------- device_browse()-------------------------
03-18 18:29:10.234: INFO/BluetoothEventLoop.cpp(112): agent_event_filter:
Received method org.bluez.Agent:Release
03-18 18:29:10.234: ERROR/BluetoothEventLoop.cpp(112): event_filter:
Received signal org.bluez.Device:PropertyChanged from
/org/bluez/483/hci0/dev_12_47_C5_EA_B1_8A
03-18 18:29:10.234: INFO/Ajith(112): In onDevicePropertyChanged, propValues
==[Ljava.lang.String;@407ecb38
03-18 18:29:10.234: INFO/BluetoothEventLoop(112): Ajith:
onDevicePropertyChanged , name=Paired
03-18 18:29:10.234: INFO/BluetoothEventLoop(112): Ajith: null ==
mBluetoothService.getPendingOutgoingBonding()
03-18 18:29:10.234: INFO/BluetoothService(112): Ajith: In setBondState() ,
Old state =11 address = 12:47:C5:EA:B1:8A
03-18 18:29:10.234: INFO/BluetoothService(112): Ajith: oldState ==
BluetoothDevice.BOND_BONDING
03-18 18:29:10.234: INFO/BluetoothService(112): Ajith: address  ==
mPendingOutgoingBonding, hence setting it to null
03-18 18:29:10.234: INFO/BluetoothService(112): Ajith: state =
BluetoothDevice.BOND_BONDED
03-18 18:29:10.249: INFO/BluetoothDeviceProfileState(112): Entering ACL
Connected state with: -1
03-18 18:29:10.249: DEBUG/BluetoothService(112): 12:47:C5:EA:B1:8A bond
state 11 -> 12 (0)
03-18 18:29:10.249: INFO/BluetoothService(112): Ajith: adding address from
mstate
03-18 18:29:10.324: VERBOSE/BluetoothEventRedirector(289): Received
android.bluetooth.device.action.BOND_STATE_CHANGED
03-18 18:29:10.331: INFO/Ajith(112): Ajith: Calling getUuidFromCache()
03-18 18:29:10.331: INFO/Ajith(112): Ajith:In getUuidFromCache, calling
getRemoteDeviceProperty
03-18 18:29:10.331: INFO/Ajith(112): Ajith: In getRemoteDeviceProperty
03-18 18:29:10.331: INFO/Ajith(112): Ajith: properties for address
=12:47:C5:EA:B1:8A is not null , hence getting it from hashmap
03-18 18:29:10.331: INFO/Ajith(112): Ajith: Here the property type to be
queried for in Hashmap is .... "UUIDs"
03-18 18:29:10.331: INFO/Ajith(112): Ajith: Values obtained from
getRemoteDeviceProperty are == null
03-18 18:29:10.331: INFO/Ajith(112): Ajith: value=null, hence returning null
back to getRemoteUuids
03-18 18:29:10.433: DEBUG/BT HSHFP(186): Saved priority 12:47:C5:EA:B1:8A =
0
03-18 18:29:10.488: DEBUG/dalvikvm(186): GC_CONCURRENT freed 546K, 52% free
2966K/6151K, external 1625K/2137K, paused 2ms+3ms
03-18 18:29:26.031: DEBUG/GPSD(274): dns_query_loop: gethostbyname failed.
h_errno 2
03-18 18:29:26.031: DEBUG/GPSD(274): dns_query_loop: retrying in 30 sec
03-18 18:29:41.956: INFO/TelephonyRegistry(112): notifyDataConnection:
state=0 isDataConnectivityPossible=false reason=null interfaceName=null
networkType=7
03-18 18:29:41.984: INFO/WimaxStateTracker(112): isAvailable()
!!!!!!!!!!!!!!!!!!!!!!!! return false
03-18 18:29:41.988: DEBUG/Tethering(112): MasterInitialState.processMessage
what=3
03-18 18:29:41.991: INFO/TelephonyRegistry(112): notifyDataConnection:
state=1 isDataConnectivityPossible=true reason=null interfaceName=null
networkType=7
03-18 18:29:47.038: DEBUG/dalvikvm(316): GC_EXPLICIT freed 4K, 58% free
2688K/6343K, external 1645K/2157K, paused 18ms
03-18 18:29:56.038: DEBUG/GPSD(274): dns_query_loop: gethostbyname failed.
h_errno 2
03-18 18:29:56.038: DEBUG/GPSD(274): dns_query_loop: retrying in 30 sec
03-18 18:30:26.046: DEBUG/GPSD(274): dns_query_loop: gethostbyname failed.
h_errno 2
03-18 18:30:26.046: DEBUG/GPSD(274): dns_query_loop: retrying in 30 sec
03-18 18:30:56.054: DEBUG/GPSD(274): dns_query_loop: gethostbyname failed.
h_errno 2
03-18 18:30:56.054: DEBUG/GPSD(274): dns_query_loop: retrying in 30 sec
03-18 18:31:16.054: INFO//system/bin/bluetoothd(482): bluetoothd[483]: probe
failed with driver input-headset for device
/org/bluez/483/hci0/dev_12_47_C5_EA_B1_8A
03-18 18:31:16.089: INFO//system/bin/bluetoothd(482): Ajith: In
emit_array_property_changed
03-18 18:31:16.089: ERROR/BluetoothEventLoop.cpp(112): event_filter:
Received signal org.bluez.Device:PropertyChanged from
/org/bluez/483/hci0/dev_12_47_C5_EA_B1_8A
03-18 18:31:16.089: INFO/Ajith(112): In onDevicePropertyChanged, propValues
==[Ljava.lang.String;@4077d498
03-18 18:31:16.089: INFO/Ajith(112): Ajith: In setRemoteDeviceProperty
address :12:47:C5:EA:B1:8A name : UUIDs value
:00001103-0000-1000-8000-00805f9b34fb,00001105-0000-1000-8000-00805f9b34fb,00001106-0000-1000-8000-00805f9b34fb,0000110a-0000-1000-8000-00805f9b34fb,0000110c-0000-1000-8000-00805f9b34fb,00001112-0000-1000-8000-00805f9b34fb,0000111f-0000-1000-8000-00805f9b34fb,0000112d-0000-1000-8000-00805f9b34fb,0000112f-0000-1000-8000-00805f9b34fb,
03-18 18:31:16.089: INFO/Ajith(112): Ajith: putting properties for adderss:
12:47:C5:EA:B1:8A into mDeviceProperties , name =UUIDs value
=00001103-0000-1000-8000-00805f9b34fb,00001105-0000-1000-8000-00805f9b34fb,00001106-0000-1000-8000-00805f9b34fb,0000110a-0000-1000-8000-00805f9b34fb,0000110c-0000-1000-8000-00805f9b34fb,00001112-0000-1000-8000-00805f9b34fb,0000111f-0000-1000-8000-00805f9b34fb,0000112d-0000-1000-8000-00805f9b34fb,0000112f-0000-1000-8000-00805f9b34fb,
03-18 18:31:16.089: INFO/Ajith(112): Ajith: In
updateDeviceServiceChannelCache()
03-18 18:31:16.089: INFO/Ajith(112): Ajith: Calling getUuidFromCache()
03-18 18:31:16.089: INFO/Ajith(112): Ajith:In getUuidFromCache, calling
getRemoteDeviceProperty


On Fri, Mar 18, 2011 at 3:52 PM, Ajith Kamath <[email protected]> wrote:

> Hi
>
> I am working on Bluez stack and currently facing a problem.
> I am able to pair with another phone.
>
> As soon as pairing occurs , it will take about nearly 1 min to get UUID
> property changed signal from bluez.
> This is happening only with phones
>
> If I try doing it with headsets, I will get UUID signal  immediately after
> I receive  property changed signal for "Paired"
>
> Can someone Please tell me what might be causing this error.
> any help is highly aprreciated.
>
> Regards,
> Ajith
>
>
>
>
>

-- 
You received this message because you are subscribed to the Google
Groups "Android Developers" group.
To post to this group, send email to [email protected]
To unsubscribe from this group, send email to
[email protected]
For more options, visit this group at
http://groups.google.com/group/android-developers?hl=en

Reply via email to