OK, so 2nd attached log indicates that your audio device got busy and
no way to writing data timely. Then Audio Flinger just blocks there.
There might be some initialization stuff not done well in the driver,
so after you reconnect A2DP to the Android device it could recover
streaming.

Sincerely,
Jackson

On Dec 5, 12:39 am, BHEEMSEN KULKARNI <[email protected]> wrote:
> 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
> ...
>
> read more »

-- 
unsubscribe: [email protected]
website: http://groups.google.com/group/android-porting

Reply via email to