Hi Fan,
Please find logs below and I testing with Jabra BT620s headset.
D/BluetoothService( 902): Registering pbap record
D/dalvikvm( 963): GC freed 3694 objects / 222536 bytes in 33ms
D/BT HSHFP( 958): pulled 00:13:17:72:02:B5 off auto-connect queue
D/BT HSHFP( 958): Headset state 0 -> 1, result = 1
D/BT HSHFP( 958): SDP UUID: TYPE_HANDSFREE
I/BT HSHFP( 958): Created RFCOMM socket fd 45.
I/BT HSHFP( 958): async connect is in progress (Operation now in progress)
W/Process ( 902): Unable to open /proc/1048/status
V/BluetoothEventRedirector( 976): Received
android.bluetooth.headset.action.STA
TE_CHANGED
D/CachedBluetoothDevice( 976): onProfileStateChanged:[]
D/dalvikvm( 958): GC freed 3933 objects / 242224 bytes in 42ms
E/BluetoothEventLoop.cpp( 902): event_filter: Received signal
org.bluez.Device:
PropertyChanged from /org/bluez/1004/hci0/dev_00_13_17_72_02_B5
I/BT HSHFP( 958): Successful RFCOMM socket connect.
D/BT HSHFP( 958): RFCOMM connection attempt took 3871 ms
D/BT HSHFP( 958): Rfcomm connected
D/BT HSHFP( 958): Headset state 1 -> 2, result = 1
V/BluetoothEventRedirector( 976): Received
android.bluetooth.headset.action.STA
TE_CHANGED
D/CachedBluetoothDevice( 976): onProfileStateChanged:[]
D/AudioPolicyManagerALSA( 887): setDeviceConnectionState() BT SCO device,
addr
ess 00:13:17:72:02:B5
D/A2dpAudioInterface( 887): setParameters() bt_headset_name=Jabra
BT620s;bt_hea
dset_nrec=on
I/Bluetooth AT recv( 958): AT+BRSF=27
I/Bluetooth AT sent( 958): +BRSF: 99
I/Bluetooth AT sent( 958): OK
I/Bluetooth AT recv( 958): AT+CIND=?
I/Bluetooth AT sent( 958): +CIND:
("service",(0-1)),("call",(0-1)),("callsetup"
,(0-3)),("callheld",(0-2)),("signal",(0-5)),("roam",(0-1)),("battchg",(0-5))
I/Bluetooth AT sent( 958): OK
D/PowerManagerService( 902): setPowerState: mPowerState=7 newState=3
noChangeLi
ghts=false
D/PowerManagerService( 902): oldKeyboardBright=false
newKeyboardBright=false
D/PowerManagerService( 902): oldScreenBright=true newScreenBright=true
D/PowerManagerService( 902): oldButtonBright=true newButtonBright=false
D/PowerManagerService( 902): oldScreenOn=true newScreenOn=true
D/PowerManagerService( 902): oldBatteryLow=false newBatteryLow=false
I/Bluetooth AT recv( 958): AT+CIND?
I/Bluetooth AT sent( 958): +CIND: 0,0,0,0,5,0,4
I/Bluetooth AT sent( 958): OK
I/Bluetooth AT recv( 958): AT+CMER=3, 0, 0, 1
I/Bluetooth AT sent( 958): OK
I/Bluetooth AT recv( 958): AT+CHLD=?
I/Bluetooth AT sent( 958): +CHLD: (0,1,2,3)
I/Bluetooth AT sent( 958): OK
I/Bluetooth AT recv( 958): AT+CCWA=1
I/Bluetooth AT sent( 958): OK
I/Bluetooth AT recv( 958): AT+NREC=0
D/A2dpAudioInterface( 887): setParameters() bt_headset_nrec=off
I/Bluetooth AT sent( 958): OK
I/Bluetooth AT recv( 958): AT+VGS=07
I/Bluetooth AT sent( 958): OK
I/ActivityManager( 902): Start proc com.android.calendar for service
com.androi
d.calendar/.AlertService: pid=1135 uid=10014 gids={3003}
I/ActivityThread( 1135): Publishing provider calendar:
com.android.providers.cal
endar.CalendarProvider
D/AccountManagerService( 902): bind attempt failed for Session:
expectLaunch fa
lse, connected false, stats (0/0/0), lifetime 0.0,
getAccountsByTypeAndFeatures,
legacy_hosted_or_google
E/ActivityThread( 1135): Failed to find provider info for subscribedfeeds
D/AndroidRuntime( 1135): Shutting down VM
W/dalvikvm( 1135): threadid=3: thread exiting with uncaught exception
(group=0x4
001b170)
E/AndroidRuntime( 1135): Uncaught handler: thread main exiting due to
uncaught e
xception
E/AndroidRuntime( 1135): java.lang.NullPointerException
E/AndroidRuntime( 1135): at
com.android.providers.calendar.CalendarSyncAd
apter.onAccountsChanged(CalendarSyncAdapter.java:1400)
E/AndroidRuntime( 1135): at
android.content.AbstractSyncableContentProvid
er$1.onAccountsUpdated(AbstractSyncableContentProvider.java:187)
E/AndroidRuntime( 1135): at
android.accounts.AccountManager$10.run(Accoun
tManager.java:826)
E/AndroidRuntime( 1135): at
android.os.Handler.handleCallback(Handler.jav
a:587)
E/AndroidRuntime( 1135): at
android.os.Handler.dispatchMessage(Handler.ja
va:92)
E/AndroidRuntime( 1135): at android.os.Looper.loop(Looper.java:123)
E/AndroidRuntime( 1135): at
android.app.ActivityThread.main(ActivityThrea
d.java:4325)
E/AndroidRuntime( 1135): at
java.lang.reflect.Method.invokeNative(Native
Method)
E/AndroidRuntime( 1135): at
java.lang.reflect.Method.invoke(Method.java:5
21)
E/AndroidRuntime( 1135): at
com.android.internal.os.ZygoteInit$MethodAndA
rgsCaller.run(ZygoteInit.java:860)
E/AndroidRuntime( 1135): at
com.android.internal.os.ZygoteInit.main(Zygot
eInit.java:618)
E/AndroidRuntime( 1135): at dalvik.system.NativeStart.main(Native
Method)
I/Process ( 902): Sending signal. PID: 1135 SIG: 3
E/ActivityThread( 902): Failed to find provider info for
android.server.checkin
E/Checkin ( 902): Error reporting crash:
java.lang.IllegalArgumentException: Un
known URL content://android.server.checkin/crashes
I/dalvikvm( 1135): threadid=7: reacting to signal 3
I/dalvikvm( 1135): Wrote stack trace to '/data/anr/traces.txt'
D/BluetoothA2dpService( 902): Auto-connecting A2DP to sink
00:13:17:72:02:B5
D/BluetoothA2dpService( 902): connectSink(00:13:17:72:02:B5)
E/BluetoothEventLoop.cpp( 902): event_filter: Received signal
org.bluez.AudioSi
nk:PropertyChanged from /org/bluez/1004/hci0/dev_00_13_17_72_02_B5
D/A2dpAudioInterface( 887): setParameters() A2dpSuspended=false
D/BluetoothA2dpService( 902): A2DP state : device: 00:13:17:72:02:B5
State:0->1
V/BluetoothEventRedirector( 976): Received
android.bluetooth.a2dp.action.SINK_S
TATE_CHANGED
D/CachedBluetoothDevice( 976): onProfileStateChanged:[]
E/BluetoothEventLoop.cpp( 902): event_filter: Received signal
org.bluez.AudioSi
nk:Connected from /org/bluez/1004/hci0/dev_00_13_17_72_02_B5
E/BluetoothEventLoop.cpp( 902): event_filter: Received signal
org.bluez.AudioSi
nk:PropertyChanged from /org/bluez/1004/hci0/dev_00_13_17_72_02_B5
E/BluetoothEventLoop.cpp( 902): event_filter: Received signal
org.bluez.AudioSi
nk:PropertyChanged from /org/bluez/1004/hci0/dev_00_13_17_72_02_B5
D/BluetoothA2dpService( 902): A2DP state : device: 00:13:17:72:02:B5
State:1->2
D/AudioPolicyManagerALSA( 887): opening A2DP output for device
00:13:17:72:02:B
5
D/A2DP ( 887): a2dp_init rate: 44100 channels: 2
D/A2DP ( 887): a2dp_thread started
D/A2DP ( 887): bluetooth_init
D/A2dpAudioInterface( 887): A2dpAudioStreamOut::set 80, 0, 0, 0
D/A2dpAudioInterface( 887): setBluetoothEnabled 1
I/AudioFlinger( 887): AudioFlinger's thread 0x48d90 ready to run
I/AudioFlinger( 887): AudioFlinger's thread 0x4c1b8 ready to run
V/BluetoothEventRedirector( 976): Received
android.bluetooth.a2dp.action.SINK_S
TATE_CHANGED
D/CachedBluetoothDevice( 976): onProfileStateChanged:[]
I/Process ( 1135): Sending signal. PID: 1135 SIG: 9
I/ActivityManager( 902): Process com.android.calendar (pid 1135) has died.
W/ActivityManager( 902): Service crashed 2 times, stopping:
ServiceRecord{43a18
680 com.android.calendar/.AlertService}
W/InputManagerService( 902): Window already focused, ignoring focus gain
of: co
m.android.internal.view.iinputmethodclient$stub$pr...@43a8c960
W/WindowManager( 902): No window to dispatch pointer action 0
W/WindowManager( 902): No window to dispatch pointer action 1
I/ActivityManager( 902): Starting activity: Intent {
act=android.intent.action.
MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000
cmp=com.android.music
/.MusicBrowserActivity }
I/ActivityManager( 902): Start proc com.android.music for activity
com.android.
music/.MusicBrowserActivity: pid=1165 uid=10015 gids={3003, 1015}
I/ARMAssembler( 902): generated
scanline__00000177:03515104_00000001_00000000 [
73 ipp] (95 ins) at [0x3e0668:0x3e07e4] in 122071 ns
W/MediaPlayer( 1165): info/warning (1, 44)
W/MediaPlayer( 1165): info/warning (1, 44)
D/dalvikvm( 902): GC freed 12411 objects / 623456 bytes in 196ms
I/MediaPlayer( 1165): Info (1,44)
I/MediaPlayer( 1165): Info (1,44)
I/ActivityManager( 902): Displayed activity
com.android.music/.MusicBrowserActi
vity: 856 ms (total 856 ms)
E/gralloc ( 902): [unregister] handle 0x3dab38 still locked
(state=40000001)
I/ActivityManager( 902): Starting activity: Intent {
act=com.android.music.PLAY
BACK_VIEWER cmp=com.android.music/.MediaPlaybackActivity }
E/gralloc ( 902): [unregister] handle 0x34fe00 still locked
(state=40000001)
D/dalvikvm( 1165): GC freed 2012 objects / 134592 bytes in 50ms
W/MediaPlayer( 1165): info/warning (1, 44)
D/A2DP ( 887): bluetooth_configure
D/A2DP ( 887): Received BT_RESPONSE - BT_GET_CAPABILITIES
D/A2DP ( 887): Received BT_RESPONSE - BT_OPEN
D/A2DP ( 887): bluetooth_a2dp_hw_params sending configuration:
D/A2DP ( 887): channel_mode: JOINT STEREO
D/A2DP ( 887): frequency: 44100
D/A2DP ( 887): allocation_method: LOUDNESS
D/A2DP ( 887): subbands: 8
D/A2DP ( 887): block_length: 16
D/A2DP ( 887): min_bitpool: 2
D/A2DP ( 887): max_bitpool: 50
D/A2DP ( 887): Received BT_RESPONSE - BT_SET_CONFIGURATION
D/A2DP ( 887): MTU: 895
D/A2DP ( 887): frame_duration: 2902 us
D/A2DP ( 887): allocation=0
D/A2DP ( 887): subbands=1
D/A2DP ( 887): blocks=3
D/A2DP ( 887): bitpool=50
D/A2DP ( 887): bluetooth_start
I/MediaPlayer( 1165): Info (1,44)
D/A2DP ( 887): Received BT_RESPONSE - BT_START_STREAM
D/A2DP ( 887): Received BT_RESPONSE - BT_NEW_STREAM
E/BluetoothEventLoop.cpp( 902): event_filter: Received signal
org.bluez.AudioSi
nk:Playing from /org/bluez/1004/hci0/dev_00_13_17_72_02_B5
E/BluetoothEventLoop.cpp( 902): event_filter: Received signal
org.bluez.AudioSi
nk:PropertyChanged from /org/bluez/1004/hci0/dev_00_13_17_72_02_B5
E/BluetoothEventLoop.cpp( 902): event_filter: Received signal
org.bluez.AudioSi
nk:PropertyChanged from /org/bluez/1004/hci0/dev_00_13_17_72_02_B5
D/BluetoothA2dpService( 902): A2DP state : device: 00:13:17:72:02:B5
State:2->4
V/BluetoothEventRedirector( 976): Received
android.bluetooth.a2dp.action.SINK_S
TATE_CHANGED
D/CachedBluetoothDevice( 976): onProfileStateChanged:[]
W/AudioFlinger( 887): write blocked for 599 msecs, 1 delayed writes, thread
0x
Thanks
Bheemsen
On Thu, Dec 3, 2009 at 10:20 AM, BHEEMSEN KULKARNI <bheems...@gmail.com>wrote:
> Hi All,
>
> I facing issue with A2DP, I am doing audio streaming to BT headset
> (connected to phone and media audio), if I restart the board (power cycle).
>
> After power cycle, I see the BT headset connected to "phone and media
> audio", when I play the mp3 file from music player, I don’t hearing anything
> on BT headset or local speaker.
>
> If I disconnect and connect the BT headset, the audio streaming to BT
> headset works.
> Any suggestion what could be problem?
> Thanks in Advance.
>
> Please find attached logs.
> I/ARMAssembler( 907): generated
> scanline__00000077:03515104_00000000_00000000 [
>
> 33 ipp] (47 ins) at [0x3280a8:0x328164] in 122070 ns I/Process ( 1045):
> Sending signal. PID: 1045 SIG: 9 I/ActivityManager( 907): Process
> com.android.calendar (pid 1045) has died.
>
> I/ARMAssembler( 907): generated
> scanline__00000177:03515104_00001001_00000000 [
>
> 91 ipp] (114 ins) at [0x328168:0x328330] in 213623 ns D/BT HSHFP( 965):
> pulled 00:13:17:72:02:B5 off auto-connect queue D/BT HSHFP( 965): Headset
> state 0 -> 1, result = 1 D/BT HSHFP( 965): SDP UUID: TYPE_HANDSFREE I/BT
> HSHFP( 965): Created RFCOMM socket fd 44.
>
> I/BT HSHFP( 965): async connect is in progress (Operation now in progress)
> V/BluetoothEventRedirector( 1021): Received
> android.bluetooth.headset.action.STA
>
> TE_CHANGED
>
> D/CachedBluetoothDevice( 1021): onProfileStateChanged:[] D/dalvikvm( 965):
> GC freed 3954 objects / 243816 bytes in 44ms E/BluetoothEventLoop.cpp( 907):
> event_filter: Received signal org.bluez.Device:
>
> PropertyChanged from /org/bluez/958/hci0/dev_00_13_17_72_02_B5
>
> I/BT HSHFP( 965): Successful RFCOMM socket connect.
>
> D/BT HSHFP( 965): RFCOMM connection attempt took 955 ms D/BT HSHFP( 965):
> Rfcomm connected D/BT HSHFP( 965): Headset state 1 -> 2, result = 1
> V/BluetoothEventRedirector( 1021): Received
> android.bluetooth.headset.action.STA
>
> TE_CHANGED
>
> D/CachedBluetoothDevice( 1021): onProfileStateChanged:[] I/Bluetooth AT
> recv( 965): AT+BRSF=27 I/Bluetooth AT sent( 965): +BRSF: 99 I/Bluetooth AT
> sent( 965): OK I/Bluetooth AT recv( 965): AT+CIND=?
>
> I/Bluetooth AT sent( 965): +CIND:
> ("service",(0-1)),("call",(0-1)),("callsetup"
>
>
> ,(0-3)),("callheld",(0-2)),("signal",(0-5)),("roam",(0-1)),("battchg",(0-5))
>
> I/Bluetooth AT sent( 965): OK
>
> I/Bluetooth AT recv( 965): AT+CIND?
>
> I/Bluetooth AT sent( 965): +CIND: 0,0,0,0,5,0,4 I/Bluetooth AT sent( 965):
> OK
>
> # I/Bluetooth AT recv( 965): AT+CMER=3, 0, 0, 1 I/Bluetooth AT sent( 965):
> OK I/Bluetooth AT recv( 965): AT+CHLD=?
>
> I/Bluetooth AT sent( 965): +CHLD: (0,1,2,3) I/Bluetooth AT sent( 965): OK
>
> #
>
> # I/Bluetooth AT recv( 965): AT+CCWA=1
>
> I/Bluetooth AT sent( 965): OK
>
> I/Bluetooth AT recv( 965): AT+NREC=0
>
> I/Bluetooth AT sent( 965): OK
>
> I/Bluetooth AT recv( 965): AT+VGS=07
>
> I/Bluetooth AT sent( 965): OK
>
> W/Process ( 907): Unable to open /proc/1045/status D/PowerManagerService(
> 907): setPowerState: mPowerState=7 newState=3 noChangeLi ghts=false
>
> D/PowerManagerService( 907): oldKeyboardBright=false
> newKeyboardBright=false
>
> D/PowerManagerService( 907): oldScreenBright=true newScreenBright=true
>
> D/PowerManagerService( 907): oldButtonBright=true newButtonBright=false
>
> D/PowerManagerService( 907): oldScreenOn=true newScreenOn=true
>
> D/PowerManagerService( 907): oldBatteryLow=false newBatteryLow=false
>
> D/BluetoothA2dpService( 907): Auto-connecting A2DP to sink
> 00:13:17:72:02:B5 D/BluetoothA2dpService( 907):
> connectSink(00:13:17:72:02:B5) E/BluetoothEventLoop.cpp( 907): event_filter:
> Received signal org.bluez.AudioSi nk:PropertyChanged from
> /org/bluez/958/hci0/dev_00_13_17_72_02_B5
>
> D/BluetoothA2dpService( 907): A2DP state : device: 00:13:17:72:02:B5
> State:0->1
>
> V/BluetoothEventRedirector( 1021): Received
> android.bluetooth.a2dp.action.SINK_S
>
> TATE_CHANGED
>
> D/CachedBluetoothDevice( 1021): onProfileStateChanged:[]
> E/BluetoothEventLoop.cpp( 907): event_filter: Received signal
> org.bluez.AudioSi nk:Connected from
> /org/bluez/958/hci0/dev_00_13_17_72_02_B5
>
> E/BluetoothEventLoop.cpp( 907): event_filter: Received signal
> org.bluez.AudioSi nk:PropertyChanged from
> /org/bluez/958/hci0/dev_00_13_17_72_02_B5
>
> E/BluetoothEventLoop.cpp( 907): event_filter: Received signal
> org.bluez.AudioSi nk:PropertyChanged from
> /org/bluez/958/hci0/dev_00_13_17_72_02_B5
>
> D/BluetoothA2dpService( 907): A2DP state : device: 00:13:17:72:02:B5
> State:1->2
>
> V/BluetoothEventRedirector( 1021): Received
> android.bluetooth.a2dp.action.SINK_S
>
> TATE_CHANGED
>
> D/CachedBluetoothDevice( 1021): onProfileStateChanged:[]
>
> D/A2DP ( 890): a2dp_init rate: 44100 channels: 2
>
> D/A2DP ( 890): a2dp_thread started
>
> D/A2DP ( 890): bluetooth_init
>
> D/A2dpAudioInterface( 890): A2dpAudioStreamOut::set 80, 0, 0, 0
> D/A2dpAudioInterface( 890): setBluetoothEnabled 1 I/AudioFlinger( 890):
> AudioFlinger's thread 0x42d48 ready to run I/AudioFlinger( 890):
> AudioFlinger's thread 0x3c620 ready to run
>
--
unsubscribe: android-porting+unsubscr...@googlegroups.com
website: http://groups.google.com/group/android-porting
