twilio / voice-quickstart-android

Quickstart app for the Voice Android SDK
https://www.twilio.com/docs/api/voice-sdk/android/getting-started
MIT License
187 stars 141 forks source link

Voice quickstart getting crashed when dialing a number #60

Closed aashu09 closed 7 years ago

aashu09 commented 7 years ago

Hi,

I am using Twilio Voice Quickstart for Android to implement programmable voice and its implemented successfully but I am getting an issue of crash of Twilio SDK.

Scenario -

I am making call by dialing a number, call is connecting properly. Then internet disconnected in between running call, call disconnected from my end. Then internet reconnected and I am going to dial a number again, after dial a number application is getting crashed.

Here is logs of crasing -

06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] JNI DETECTED ERROR IN APPLICATION: use of deleted global reference 0x9b6 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] from void com.twilio.voice.impl.useragent.Call.makeCall(com.twilio.voice.impl.session.Account, java.lang.String, com.twilio.voice.impl.useragent.MessageData) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] "Thread-16" prio=5 tid=44 Runnable 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] | group="main" sCount=0 dsCount=0 obj=0x12fa3e20 self=0x74b3a43400 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] | sysTid=14778 nice=0 cgrp=default sched=0/0 handle=0x74ad508450 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] | state=R schedstat=( 34631871 23463905 120 ) utm=2 stm=0 core=1 HZ=100 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] | stack=0x74ad406000-0x74ad408000 stackSize=1037KB 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] | held mutexes= "mutator lock"(shared held) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] native: #00 pc 000000000047ef3c /system/lib64/libart.so (_ZN3art15DumpNativeStackERNSt3113basic_ostreamIcNS0_11char_traitsIcEEEEiP12BacktraceMapPKcPNS_9ArtMethodEPv+220) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] native: #01 pc 000000000047ef38 /system/lib64/libart.so (_ZN3art15DumpNativeStackERNSt3__113basic_ostreamIcNS0_11char_traitsIcEEEEiP12BacktraceMapPKcPNS_9ArtMethodEPv+216) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] native: #02 pc 0000000000452fc4 /system/lib64/libart.so (_ZNK3art6Thread9DumpStackERNSt3113basic_ostreamIcNS1_11char_traitsIcEEEEbP12BacktraceMap+480) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] native: #03 pc 00000000002f04a4 /system/lib64/libart.so (ZN3art9JavaVMExt8JniAbortEPKcS2+1136) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] native: #04 pc 00000000002f0ca8 /system/lib64/libart.so (_ZN3art9JavaVMExt9JniAbortFEPKcS2_z+188) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] native: #05 pc 0000000000457c24 /system/lib64/libart.so (_ZNK3art6Thread13DecodeJObjectEP8_jobject+352) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] native: #06 pc 0000000000101ad8 /system/lib64/libart.so (_ZN3art11ScopedCheck13CheckInstanceERNS_18ScopedObjectAccessENS0_12InstanceKindEP8_jobjectb+196) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] native: #07 pc 00000000000ffce8 /system/lib64/libart.so (_ZN3art11ScopedCheck5CheckERNS_18ScopedObjectAccessEbPKcPNS_12JniValueTypeE+1124) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] native: #08 pc 0000000000103ec4 /system/lib64/libart.so (_ZN3art8CheckJNI9DeleteRefEPKcP7_JNIEnvP8_jobjectNS_15IndirectRefKindE+636) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] native: #09 pc 000000000018694c /data/app/com.app.dealer_app-1/lib/arm64/libvoice.so (Java_com_twilio_voice_impl_useragent_Call_makeCall+476) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] native: #10 pc 00000000000a34b4 /data/app/com.app.dealer_app-1/oat/arm64/base.odex (Java_com_twilio_voice_impl_useragent_Call_makeCallLcom_twilio_voice_impl_session_Account_2Ljava_lang_String_2Lcom_twilio_voice_impl_useragent_MessageData_2+208) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] at com.twilio.voice.impl.useragent.Call.makeCall(Native method) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] at com.twilio.voice.impl.useragent.Call.(Call.java:20) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] at com.twilio.voice.CallControlManager.newCall(CallControlManager.java:546) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] at com.twilio.voice.OutgoingCallCommand.run(OutgoingCallCommand.java:55) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] at android.os.Handler.handleCallback(Handler.java:751) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] at android.os.Handler.dispatchMessage(Handler.java:95) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] at android.os.Looper.loop(Looper.java:154) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] at com.twilio.voice.CallCommandHandlerImpl.run(CallCommandHandlerImpl.java:48) 06-07 15:51:50.025 13915-14778/? A/art: art/runtime/java_vm_ext.cc:470] 06-07 15:51:50.025 13915-13922/? W/art: Suspending all threads took: 75.133ms 06-07 15:51:50.032 13915-13939/? V/FA: Activity resumed, time: 10897011 06-07 15:51:50.107 13915-13915/? I/BeaconService: set scan intervals received 06-07 15:51:50.107 13915-13915/? D/CycledLeScanner: Set scan periods called with 1100, 0 Background mode must have changed. 06-07 15:51:50.107 13915-13915/? D/CycledLeScanner: We are not in the background. Cancelling wakeup alarm 06-07 15:51:50.107 13915-13915/? D/CycledLeScanner: cancel wakeup alarm: PendingIntent{4cc87fe: android.os.BinderProxy@8f97b5f} 06-07 15:51:50.109 13915-13915/? D/CycledLeScanner: Set a wakeup alarm to go off in 9223372036843878634 ms: PendingIntent{4cc87fe: android.os.BinderProxy@8f97b5f} 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] Runtime aborting... 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] Aborting thread: 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] Dumping all threads without appropriate locks held: thread list lock mutator lock 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] All threads: 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] DALVIK THREADS (51): 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] "Thread-16" prio=5 tid=44 Runnable 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] | group="" sCount=0 dsCount=0 obj=0x12fa3e20 self=0x74b3a43400 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] | sysTid=14778 nice=0 cgrp=default sched=0/0 handle=0x74ad508450 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] | state=R schedstat=( 84367032 30260678 191 ) utm=3 stm=4 core=5 HZ=100 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] | stack=0x74ad406000-0x74ad408000 stackSize=1037KB 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] | held mutexes= "abort lock" "mutator lock"(shared held) 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #00 pc 000000000047ef3c /system/lib64/libart.so (_ZN3art15DumpNativeStackERNSt3113basic_ostreamIcNS0_11char_traitsIcEEEEiP12BacktraceMapPKcPNS_9ArtMethodEPv+220) 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #01 pc 000000000047ef38 /system/lib64/libart.so (_ZN3art15DumpNativeStackERNSt3113basic_ostreamIcNS0_11char_traitsIcEEEEiP12BacktraceMapPKcPNS_9ArtMethodEPv+216) 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #02 pc 0000000000452fc4 /system/lib64/libart.so (_ZNK3art6Thread9DumpStackERNSt3113basic_ostreamIcNS1_11char_traitsIcEEEEbP12BacktraceMap+480) 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #03 pc 000000000046ab4c /system/lib64/libart.so (_ZN3art14DumpCheckpoint3RunEPNS_6ThreadE+832) 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #04 pc 0000000000462d34 /system/lib64/libart.so (_ZN3art10ThreadList13RunCheckpointEPNS_7ClosureE+476) 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #05 pc 0000000000462924 /system/lib64/libart.so (_ZN3art10ThreadList4DumpERNSt3113basic_ostreamIcNS1_11char_traitsIcEEEEb+848) 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #06 pc 0000000000440d6c /system/lib64/libart.so (_ZNK3art10AbortState4DumpERNSt3113basic_ostreamIcNS1_11char_traitsIcEEEE+592) 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #07 pc 000000000043473c /system/lib64/libart.so (_ZN3art7Runtime5AbortEPKc+148) 06-07 15:51:50.173 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #08 pc 00000000000e5afc /system/lib64/libart.so (_ZN3art10LogMessageD2Ev+1592) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #09 pc 00000000002f08cc /system/lib64/libart.so (ZN3art9JavaVMExt8JniAbortEPKcS2+2200) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #10 pc 00000000002f0ca8 /system/lib64/libart.so (_ZN3art9JavaVMExt9JniAbortFEPKcS2_z+188) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #11 pc 0000000000457c24 /system/lib64/libart.so (_ZNK3art6Thread13DecodeJObjectEP8_jobject+352) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #12 pc 0000000000101ad8 /system/lib64/libart.so (_ZN3art11ScopedCheck13CheckInstanceERNS_18ScopedObjectAccessENS0_12InstanceKindEP8_jobjectb+196) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #13 pc 00000000000ffce8 /system/lib64/libart.so (_ZN3art11ScopedCheck5CheckERNS_18ScopedObjectAccessEbPKcPNS_12JniValueTypeE+1124) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #14 pc 0000000000103ec4 /system/lib64/libart.so (_ZN3art8CheckJNI9DeleteRefEPKcP7_JNIEnvP8_jobjectNS_15IndirectRefKindE+636) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #15 pc 000000000018694c /data/app/com.app.dealer_app-1/lib/arm64/libvoice.so (Java_com_twilio_voice_impl_useragent_Call_makeCall+476) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #16 pc 00000000000a34b4 /data/app/com.app.dealer_app-1/oat/arm64/base.odex (Java_com_twilio_voice_impl_useragent_Call_makeCallLcom_twilio_voice_impl_session_Account_2Ljava_lang_String_2Lcom_twilio_voice_impl_useragent_MessageData_2+208) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at com.twilio.voice.impl.useragent.Call.makeCall(Native method) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at com.twilio.voice.impl.useragent.Call.(Call.java:20) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at com.twilio.voice.CallControlManager.newCall(CallControlManager.java:546) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at com.twilio.voice.OutgoingCallCommand.run(OutgoingCallCommand.java:55) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at android.os.Handler.handleCallback(Handler.java:751) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at android.os.Handler.dispatchMessage(Handler.java:95) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at android.os.Looper.loop(Looper.java:154) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at com.twilio.voice.CallCommandHandlerImpl.run(CallCommandHandlerImpl.java:48) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] "main" prio=5 tid=1 Native 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | group="" sCount=1 dsCount=0 obj=0x75aa1a88 self=0x74ce095a00 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | sysTid=13915 nice=-10 cgrp=default sched=0/0 handle=0x74d2006a98 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | state=S schedstat=( 2732187600 470220672 3721 ) utm=228 stm=44 core=0 HZ=100 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | stack=0x7fe9438000-0x7fe943a000 stackSize=8MB 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | held mutexes= 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: switch_to+0x8c/0x98 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait_queue_me+0xd4/0x130 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait+0xfc/0x210 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: do_futex+0xe0/0x920 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: SyS_futex+0x11c/0x1b0 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: cpu_switch_to+0x48/0x4c 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #00 pc 000000000001bcec /system/lib64/libc.so (syscall+28) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #01 pc 00000000000e7e4c /system/lib64/libart.so (_ZN3art17ConditionVariable16WaitHoldingLocksEPNS_6ThreadE+156) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #02 pc 000000000054d754 /system/lib64/libart.so (_ZN3artL12GoToRunnableEPNS_6ThreadE+328) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #03 pc 000000000054d5c8 /system/lib64/libart.so (_ZN3art12JniMethodEndEjPNS_6ThreadE+28) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #04 pc 0000000000859030 /system/framework/arm64/boot-framework.oat (Java_android_os_BinderProxy_transactNativeILandroid_os_Parcel_2Landroid_os_Parcel_2I+220) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at android.os.BinderProxy.transactNative(Native method) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at android.os.BinderProxy.transact(Binder.java:615) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at android.view.IWindowSession$Stub$Proxy.relayout(IWindowSession.java:952) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at android.view.ViewRootImpl.relayoutWindow(ViewRootImpl.java:5719) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at android.view.ViewRootImpl.performTraversals(ViewRootImpl.java:1758) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at android.view.ViewRootImpl.doTraversal(ViewRootImpl.java:1254) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at android.view.ViewRootImpl$TraversalRunnable.run(ViewRootImpl.java:6338) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at android.view.Choreographer$CallbackRecord.run(Choreographer.java:874) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at android.view.Choreographer.doCallbacks(Choreographer.java:686) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at android.view.Choreographer.doFrame(Choreographer.java:621) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at android.view.Choreographer$FrameDisplayEventReceiver.run(Choreographer.java:860) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at android.os.Handler.handleCallback(Handler.java:751) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at android.os.Handler.dispatchMessage(Handler.java:95) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at android.os.Looper.loop(Looper.java:154) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at android.app.ActivityThread.main(ActivityThread.java:6121) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.reflect.Method.invoke!(Native method) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:889) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:779) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] "Jit thread pool worker thread 0" prio=5 tid=2 Native 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | group="" sCount=1 dsCount=0 obj=0x12cc8310 self=0x74c6626000 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | sysTid=13920 nice=9 cgrp=default sched=0/0 handle=0x74cd701450 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | state=S schedstat=( 590560526 98475471 604 ) utm=49 stm=9 core=5 HZ=100 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | stack=0x74cd603000-0x74cd605000 stackSize=1021KB 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | held mutexes= 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: switch_to+0x8c/0x98 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait_queue_me+0xd4/0x130 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait+0xfc/0x210 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: do_futex+0xe0/0x920 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: SyS_futex+0x11c/0x1b0 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: cpu_switch_to+0x48/0x4c 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #00 pc 000000000001bcec /system/lib64/libc.so (syscall+28) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #01 pc 00000000000e7e4c /system/lib64/libart.so (_ZN3art17ConditionVariable16WaitHoldingLocksEPNS_6ThreadE+156) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #02 pc 000000000046c910 /system/lib64/libart.so (_ZN3art10ThreadPool7GetTaskEPNS_6ThreadE+248) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #03 pc 000000000046bdac /system/lib64/libart.so (_ZN3art16ThreadPoolWorker3RunEv+124) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #04 pc 000000000046b6d0 /system/lib64/libart.so (_ZN3art16ThreadPoolWorker8CallbackEPv+132) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #05 pc 0000000000068734 /system/lib64/libc.so (_ZL15pthread_startPv+208) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #06 pc 000000000001da7c /system/lib64/libc.so (__start_thread+16) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] (no managed stack frames) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] "Signal Catcher" prio=5 tid=3 WaitingInMainSignalCatcherLoop 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | group="" sCount=1 dsCount=0 obj=0x12cc83a0 self=0x74ce096400 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | sysTid=13921 nice=0 cgrp=default sched=0/0 handle=0x74cd600450 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | state=S schedstat=( 326615 24948 1 ) utm=0 stm=0 core=4 HZ=100 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | stack=0x74cd506000-0x74cd508000 stackSize=1005KB 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | held mutexes= 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: switch_to+0x8c/0x98 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: do_sigtimedwait+0xec/0x1dc 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: SyS_rt_sigtimedwait+0xd8/0x134 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: cpu_switch_to+0x48/0x4c 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #00 pc 000000000006ad4c /system/lib64/libc.so (rt_sigtimedwait+8) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #01 pc 0000000000024d48 /system/lib64/libc.so (sigwait+64) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #02 pc 00000000004456a0 /system/lib64/libart.so (_ZN3art9SignalSet4WaitEv+48) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #03 pc 000000000044515c /system/lib64/libart.so (_ZN3art13SignalCatcher13WaitForSignalEPNS_6ThreadERNS_9SignalSetE+232) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #04 pc 00000000004436f0 /system/lib64/libart.so (_ZN3art13SignalCatcher3RunEPv+400) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #05 pc 0000000000068734 /system/lib64/libc.so (_ZL15__pthread_startPv+208) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #06 pc 000000000001da7c /system/lib64/libc.so (start_thread+16) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] (no managed stack frames) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] "JDWP" prio=5 tid=4 WaitingInMainDebuggerLoop 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | group="" sCount=1 dsCount=0 obj=0x12cc8430 self=0x74c6636400 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | sysTid=13922 nice=0 cgrp=default sched=0/0 handle=0x74cd503450 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | state=S schedstat=( 1229031471 236091418 793 ) utm=117 stm=5 core=3 HZ=100 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | stack=0x74cd409000-0x74cd40b000 stackSize=1005KB 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | held mutexes= 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: switch_to+0x8c/0x98 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: poll_schedule_timeout+0x54/0xbc 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: do_select+0x454/0x4c0 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: core_sys_select+0x200/0x318 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: SyS_pselect6+0x180/0x230 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: cpu_switch_to+0x48/0x4c 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #00 pc 000000000006aca4 /system/lib64/libc.so (pselect6+8) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #01 pc 0000000000023244 /system/lib64/libc.so (select+156) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #02 pc 00000000005581d0 /system/lib64/libart.so (_ZN3art4JDWP12JdwpAdbState15ProcessIncomingEv+348) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #03 pc 00000000003052b4 /system/lib64/libart.so (_ZN3art4JDWP9JdwpState3RunEv+916) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #04 pc 0000000000304764 /system/lib64/libart.so (_ZN3art4JDWPL15StartJdwpThreadEPv+48) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #05 pc 0000000000068734 /system/lib64/libc.so (_ZL15pthread_startPv+208) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #06 pc 000000000001da7c /system/lib64/libc.so (start_thread+16) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] (no managed stack frames) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] "ReferenceQueueDaemon" prio=5 tid=5 Waiting 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | group="" sCount=1 dsCount=0 obj=0x12cc84c0 self=0x74ce098200 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | sysTid=13923 nice=0 cgrp=default sched=0/0 handle=0x74cd406450 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | state=S schedstat=( 12132034 1583695 41 ) utm=0 stm=0 core=3 HZ=100 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | stack=0x74cd304000-0x74cd306000 stackSize=1037KB 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] | held mutexes= 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: switch_to+0x8c/0x98 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait_queue_me+0xd4/0x130 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait+0xfc/0x210 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: do_futex+0xe0/0x920 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: SyS_futex+0x11c/0x1b0 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: cpu_switch_to+0x48/0x4c 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #00 pc 000000000001bcec /system/lib64/libc.so (syscall+28) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #01 pc 00000000000e7e4c /system/lib64/libart.so (_ZN3art17ConditionVariable16WaitHoldingLocksEPNS_6ThreadE+156) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #02 pc 000000000037c0ac /system/lib64/libart.so (_ZN3art7Monitor4WaitEPNS_6ThreadElibNS_11ThreadStateE+660) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #03 pc 0000000000000810 /system/framework/arm64/boot.oat (Java_java_lang_Object_wait+124) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Object.wait!(Native method) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] - waiting on <0x024835ac> (a java.lang.Class) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Daemons$ReferenceQueueDaemon.run(Daemons.java:150) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] - locked <0x024835ac> (a java.lang.Class) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Thread.run(Thread.java:761) 06-07 15:51:50.174 13915-14778/? A/art: art/runtime/runtime.cc:422] 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] "FinalizerDaemon" prio=5 tid=6 Waiting 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | group="" sCount=1 dsCount=0 obj=0x12cc8550 self=0x74c3ccc400 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | sysTid=13924 nice=0 cgrp=default sched=0/0 handle=0x74cd301450 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | state=S schedstat=( 17158702 1747237 44 ) utm=1 stm=0 core=4 HZ=100 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | stack=0x74cd1ff000-0x74cd201000 stackSize=1037KB 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | held mutexes= 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: switch_to+0x8c/0x98 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait_queue_me+0xd4/0x130 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait+0xfc/0x210 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: do_futex+0xe0/0x920 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: SyS_futex+0x11c/0x1b0 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: cpu_switch_to+0x48/0x4c 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #00 pc 000000000001bcec /system/lib64/libc.so (syscall+28) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #01 pc 00000000000e7e4c /system/lib64/libart.so (_ZN3art17ConditionVariable16WaitHoldingLocksEPNS_6ThreadE+156) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #02 pc 000000000037c0ac /system/lib64/libart.so (_ZN3art7Monitor4WaitEPNS_6ThreadElibNS_11ThreadStateE+660) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #03 pc 0000000000000980 /system/framework/arm64/boot.oat (Java_java_lang_Object_waitJI+140) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Object.wait!(Native method) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] - waiting on <0x08217275> (a java.lang.Object) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Object.wait(Object.java:407) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:188) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] - locked <0x08217275> (a java.lang.Object) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:209) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Daemons$FinalizerDaemon.run(Daemons.java:204) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Thread.run(Thread.java:761) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] "FinalizerWatchdogDaemon" prio=5 tid=7 Sleeping 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | group="" sCount=1 dsCount=0 obj=0x12cc85e0 self=0x74c3ccce00 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | sysTid=13925 nice=0 cgrp=default sched=0/0 handle=0x74cd1fc450 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | state=S schedstat=( 1412768 9114 24 ) utm=0 stm=0 core=3 HZ=100 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | stack=0x74cd0fa000-0x74cd0fc000 stackSize=1037KB 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | held mutexes= 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: switch_to+0x8c/0x98 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait_queue_me+0xd4/0x130 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait+0xfc/0x210 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: do_futex+0xe0/0x920 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: SyS_futex+0x11c/0x1b0 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: cpu_switch_to+0x48/0x4c 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #00 pc 000000000001bcf0 /system/lib64/libc.so (syscall+32) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #01 pc 00000000000e83ec /system/lib64/libart.so (_ZN3art17ConditionVariable9TimedWaitEPNS_6ThreadEli+176) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #02 pc 000000000037c0bc /system/lib64/libart.so (_ZN3art7Monitor4WaitEPNS_6ThreadElibNS_11ThreadStateE+676) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #03 pc 00000000000b8288 /system/framework/arm64/boot.oat (Java_java_lang_Thread_sleepLjava_lang_Object_2JI+164) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Thread.sleep!(Native method) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] - sleeping on <0x029b920a> (a java.lang.Object) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Thread.sleep(Thread.java:371) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] - locked <0x029b920a> (a java.lang.Object) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Thread.sleep(Thread.java:313) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Daemons$FinalizerWatchdogDaemon.sleepFor(Daemons.java:314) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Daemons$FinalizerWatchdogDaemon.waitForFinalization(Daemons.java:336) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Daemons$FinalizerWatchdogDaemon.run(Daemons.java:253) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Thread.run(Thread.java:761) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] "HeapTaskDaemon" prio=5 tid=8 Blocked 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | group="" sCount=1 dsCount=0 obj=0x12cc8670 self=0x74c3ccd800 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | sysTid=13926 nice=0 cgrp=default sched=0/0 handle=0x74cd0f7450 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | state=S schedstat=( 209279271 6195468 96 ) utm=18 stm=1 core=5 HZ=100 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | stack=0x74ccff5000-0x74ccff7000 stackSize=1037KB 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | held mutexes= 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: switch_to+0x8c/0x98 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait_queue_me+0xd4/0x130 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait+0xfc/0x210 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: do_futex+0xe0/0x920 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: SyS_futex+0x11c/0x1b0 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: cpu_switch_to+0x48/0x4c 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #00 pc 000000000001bcf0 /system/lib64/libc.so (syscall+32) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #01 pc 00000000000e83ec /system/lib64/libart.so (_ZN3art17ConditionVariable9TimedWaitEPNS_6ThreadEli+176) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #02 pc 00000000002428fc /system/lib64/libart.so (_ZN3art2gc13TaskProcessor7GetTaskEPNS_6ThreadE+344) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #03 pc 00000000002431f0 /system/lib64/libart.so (_ZN3art2gc13TaskProcessor11RunAllTasksEPNS_6ThreadE+92) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #04 pc 00000000001e6ac0 /system/framework/arm64/boot-core-libart.oat (Java_dalvik_system_VMRuntime_runHeapTasks__+124) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] at dalvik.system.VMRuntime.runHeapTasks(Native method) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] - waiting to lock an unknown object 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Daemons$HeapTaskDaemon.run(Daemons.java:433) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Thread.run(Thread.java:761) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] "Binder:13915_1" prio=5 tid=9 Native 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | group="" sCount=1 dsCount=0 obj=0x12cc8820 self=0x74c3d7d800 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | sysTid=13928 nice=0 cgrp=default sched=0/0 handle=0x74ccef4450 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | state=S schedstat=( 88806044 30530576 233 ) utm=6 stm=1 core=1 HZ=100 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | stack=0x74ccdfa000-0x74ccdfc000 stackSize=1005KB 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | held mutexes= 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: switch_to+0x8c/0x98 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: binder_thread_read+0xedc/0x1024 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: binder_ioctl+0x428/0x894 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: do_vfs_ioctl+0x4bc/0x5a8 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: SyS_ioctl+0x74/0xbc 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: cpu_switch_to+0x48/0x4c 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #00 pc 000000000006ac58 /system/lib64/libc.so (ioctl+4) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #01 pc 000000000001fab4 /system/lib64/libc.so (ioctl+140) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #02 pc 0000000000055568 /system/lib64/libbinder.so (_ZN7android14IPCThreadState14talkWithDriverEb+264) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #03 pc 00000000000556cc /system/lib64/libbinder.so (_ZN7android14IPCThreadState20getAndExecuteCommandEv+24) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #04 pc 0000000000055e04 /system/lib64/libbinder.so (_ZN7android14IPCThreadState14joinThreadPoolEb+72) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #05 pc 0000000000072d48 /system/lib64/libbinder.so (???) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #06 pc 000000000001245c /system/lib64/libutils.so (_ZN7android6Thread11_threadLoopEPv+272) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #07 pc 000000000009f1b0 /system/lib64/libandroid_runtime.so (_ZN7android14AndroidRuntime15javaThreadShellEPv+116) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #08 pc 0000000000068734 /system/lib64/libc.so (_ZL15pthread_startPv+208) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #09 pc 000000000001da7c /system/lib64/libc.so (start_thread+16) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] (no managed stack frames) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] "Binder:13915_2" prio=5 tid=10 Native 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | group="" sCount=1 dsCount=0 obj=0x12cc88b0 self=0x74c6651800 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | sysTid=13929 nice=0 cgrp=default sched=0/0 handle=0x74ccdf7450 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | state=S schedstat=( 84145157 26859065 222 ) utm=6 stm=1 core=1 HZ=100 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | stack=0x74cccfd000-0x74cccff000 stackSize=1005KB 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | held mutexes= 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: switch_to+0x8c/0x98 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait_queue_me+0xd4/0x130 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait+0xfc/0x210 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: do_futex+0xe0/0x920 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: SyS_futex+0x11c/0x1b0 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: cpu_switch_to+0x48/0x4c 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #00 pc 000000000001bcec /system/lib64/libc.so (syscall+28) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #01 pc 00000000000e7e4c /system/lib64/libart.so (_ZN3art17ConditionVariable16WaitHoldingLocksEPNS_6ThreadE+156) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #02 pc 00000000001047a0 /system/lib64/libart.so (_ZN3art8CheckJNI11CallMethodVEPKcP7_JNIEnvP8_jobjectP7_jclassP10_jmethodIDSt9va_listNS_9Primitive4TypeENS_10InvokeTypeE+320) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #03 pc 00000000000f6a50 /system/lib64/libart.so (_ZN3art8CheckJNI18CallBooleanMethodVEP7_JNIEnvP8_jobjectP10_jmethodIDSt9va_list+96) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #04 pc 00000000000a67e0 /system/lib64/libandroid_runtime.so (???) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #05 pc 00000000000fd2c8 /system/lib64/libandroid_runtime.so (???) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #06 pc 0000000000049e2c /system/lib64/libbinder.so (_ZN7android7BBinder8transactEjRKNS_6ParcelEPS1_j+132) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #07 pc 0000000000055bec /system/lib64/libbinder.so (_ZN7android14IPCThreadState14executeCommandEi+992) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #08 pc 0000000000055750 /system/lib64/libbinder.so (_ZN7android14IPCThreadState20getAndExecuteCommandEv+156) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #09 pc 0000000000055e04 /system/lib64/libbinder.so (_ZN7android14IPCThreadState14joinThreadPoolEb+72) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #10 pc 0000000000072d48 /system/lib64/libbinder.so (???) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #11 pc 000000000001245c /system/lib64/libutils.so (_ZN7android6Thread11_threadLoopEPv+272) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #12 pc 000000000009f1b0 /system/lib64/libandroid_runtime.so (_ZN7android14AndroidRuntime15javaThreadShellEPv+116) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #13 pc 0000000000068734 /system/lib64/libc.so (_ZL15pthread_startPv+208) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #14 pc 000000000001da7c /system/lib64/libc.so (start_thread+16) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] (no managed stack frames) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] "Profile Saver" prio=5 tid=11 Native 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | group="" sCount=1 dsCount=0 obj=0x12cc8b80 self=0x74c3d97c00 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | sysTid=13931 nice=-10 cgrp=default sched=0/0 handle=0x74cccf2450 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | state=S schedstat=( 36809216 3784010 36 ) utm=3 stm=0 core=0 HZ=100 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | stack=0x74ccbf8000-0x74ccbfa000 stackSize=1005KB 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | held mutexes= 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: switch_to+0x8c/0x98 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait_queue_me+0xd4/0x130 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait+0xfc/0x210 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: do_futex+0xe0/0x920 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: SyS_futex+0x11c/0x1b0 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: cpu_switch_to+0x48/0x4c 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #00 pc 000000000001bcec /system/lib64/libc.so (syscall+28) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #01 pc 00000000000e7e4c /system/lib64/libart.so (_ZN3art17ConditionVariable16WaitHoldingLocksEPNS_6ThreadE+156) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #02 pc 000000000031e614 /system/lib64/libart.so (_ZN3art12ProfileSaver3RunEv+284) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #03 pc 000000000031fd4c /system/lib64/libart.so (_ZN3art12ProfileSaver21RunProfileSaverThreadEPv+100) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #04 pc 0000000000068734 /system/lib64/libc.so (_ZL15pthread_startPv+208) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #05 pc 000000000001da7c /system/lib64/libc.so (__start_thread+16) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] (no managed stack frames) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] "pool-1-thread-1" prio=5 tid=12 Waiting 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | group="" sCount=1 dsCount=0 obj=0x12d0c940 self=0x74c3d7b000 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | sysTid=13938 nice=0 cgrp=default sched=0/0 handle=0x74ccb55450 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | state=S schedstat=( 87514279 36534583 258 ) utm=3 stm=4 core=5 HZ=100 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | stack=0x74cca53000-0x74cca55000 stackSize=1037KB 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] | held mutexes= 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: switch_to+0x8c/0x98 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait_queue_me+0xd4/0x130 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait+0xfc/0x210 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: do_futex+0xe0/0x920 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: SyS_futex+0x11c/0x1b0 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: cpu_switch_to+0x48/0x4c 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #00 pc 000000000001bcec /system/lib64/libc.so (syscall+28) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #01 pc 00000000000e7e4c /system/lib64/libart.so (_ZN3art17ConditionVariable16WaitHoldingLocksEPNS_6ThreadE+156) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #02 pc 000000000037c0ac /system/lib64/libart.so (_ZN3art7Monitor4WaitEPNS_6ThreadElibNS_11ThreadStateE+660) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #03 pc 0000000000000980 /system/framework/arm64/boot.oat (Java_java_lang_Object_waitJI+140) 06-07 15:51:50.175 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Object.wait!(Native method) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] - waiting on <0x00786a7b> (a java.lang.Object) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Thread.parkFor$(Thread.java:2127) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] - locked <0x00786a7b> (a java.lang.Object) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at sun.misc.Unsafe.park(Unsafe.java:325) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.locks.LockSupport.park(LockSupport.java:161) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2035) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1058) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1118) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Thread.run(Thread.java:761) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] "Measurement Worker" prio=5 tid=13 TimedWaiting 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | group="" sCount=1 dsCount=0 obj=0x12c1b4c0 self=0x74c3d7ba00 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | sysTid=13939 nice=0 cgrp=default sched=0/0 handle=0x74cca49450 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | state=S schedstat=( 187164019 59878848 380 ) utm=15 stm=3 core=4 HZ=100 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | stack=0x74cc947000-0x74cc949000 stackSize=1037KB 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | held mutexes= 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: switch_to+0x8c/0x98 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait_queue_me+0xd4/0x130 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait+0xfc/0x210 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: do_futex+0xe0/0x920 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: SyS_futex+0x11c/0x1b0 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: cpu_switch_to+0x48/0x4c 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #00 pc 000000000001bcf0 /system/lib64/libc.so (syscall+32) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #01 pc 00000000000e83ec /system/lib64/libart.so (_ZN3art17ConditionVariable9TimedWaitEPNS_6ThreadEli+176) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #02 pc 000000000037c0bc /system/lib64/libart.so (_ZN3art7Monitor4WaitEPNS_6ThreadElibNS_11ThreadStateE+676) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #03 pc 0000000000000980 /system/framework/arm64/boot.oat (Java_java_lang_Object_waitJI+140) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Object.wait!(Native method) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] - waiting on <0x09d90098> (a java.lang.Object) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Object.wait(Object.java:407) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at com.google.android.gms.internal.zzaud$zzd.run(unavailable:-1) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] "Queue" prio=5 tid=14 Waiting 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | group="" sCount=1 dsCount=0 obj=0x12c28280 self=0x74c3d7c400 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | sysTid=13941 nice=-8 cgrp=default sched=0/0 handle=0x74c6ca0450 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | state=S schedstat=( 21361982 886354 18 ) utm=1 stm=0 core=5 HZ=100 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | stack=0x74c6b9e000-0x74c6ba0000 stackSize=1037KB 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | held mutexes= 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: switch_to+0x8c/0x98 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait_queue_me+0xd4/0x130 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait+0xfc/0x210 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: do_futex+0xe0/0x920 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: SyS_futex+0x11c/0x1b0 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: cpu_switch_to+0x48/0x4c 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #00 pc 000000000001bcec /system/lib64/libc.so (syscall+28) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #01 pc 00000000000e7e4c /system/lib64/libart.so (_ZN3art17ConditionVariable16WaitHoldingLocksEPNS_6ThreadE+156) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #02 pc 000000000037c0ac /system/lib64/libart.so (_ZN3art7Monitor4WaitEPNS_6ThreadElibNS_11ThreadStateE+660) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #03 pc 0000000000000980 /system/framework/arm64/boot.oat (Java_java_lang_Object_waitJI+140) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Object.wait!(Native method) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] - waiting on <0x0d23c0f1> (a java.lang.Object) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Thread.parkFor$(Thread.java:2127) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] - locked <0x0d23c0f1> (a java.lang.Object) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at sun.misc.Unsafe.park(Unsafe.java:325) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.locks.LockSupport.park(LockSupport.java:161) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2035) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.PriorityBlockingQueue.take(PriorityBlockingQueue.java:519) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at io.fabric.sdk.android.services.concurrency.DependencyPriorityBlockingQueue.performOperation(DependencyPriorityBlockingQueue.java:197) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at io.fabric.sdk.android.services.concurrency.DependencyPriorityBlockingQueue.get(DependencyPriorityBlockingQueue.java:236) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at io.fabric.sdk.android.services.concurrency.DependencyPriorityBlockingQueue.take(DependencyPriorityBlockingQueue.java:65) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at io.fabric.sdk.android.services.concurrency.DependencyPriorityBlockingQueue.take(DependencyPriorityBlockingQueue.java:46) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1058) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1118) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Thread.run(Thread.java:761) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] "Queue" prio=5 tid=15 Waiting 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | group="" sCount=1 dsCount=0 obj=0x12c283a0 self=0x74c3cce200 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | sysTid=13943 nice=10 cgrp=default sched=0/0 handle=0x74c6a96450 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | state=S schedstat=( 250661507 67160842 325 ) utm=21 stm=2 core=2 HZ=100 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | stack=0x74c6994000-0x74c6996000 stackSize=1037KB 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | held mutexes= 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: switch_to+0x8c/0x98 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait_queue_me+0xd4/0x130 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait+0xfc/0x210 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: do_futex+0xe0/0x920 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: SyS_futex+0x11c/0x1b0 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: cpu_switch_to+0x48/0x4c 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #00 pc 000000000001bcec /system/lib64/libc.so (syscall+28) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #01 pc 00000000000e7e4c /system/lib64/libart.so (_ZN3art17ConditionVariable16WaitHoldingLocksEPNS_6ThreadE+156) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #02 pc 000000000037c0ac /system/lib64/libart.so (_ZN3art7Monitor4WaitEPNS_6ThreadElibNS_11ThreadStateE+660) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #03 pc 0000000000000980 /system/framework/arm64/boot.oat (Java_java_lang_Object_waitJI+140) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Object.wait!(Native method) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] - waiting on <0x0868c4d6> (a java.lang.Object) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Thread.parkFor$(Thread.java:2127) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] - locked <0x0868c4d6> (a java.lang.Object) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at sun.misc.Unsafe.park(Unsafe.java:325) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.locks.LockSupport.park(LockSupport.java:161) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2035) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.PriorityBlockingQueue.take(PriorityBlockingQueue.java:519) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at io.fabric.sdk.android.services.concurrency.DependencyPriorityBlockingQueue.performOperation(DependencyPriorityBlockingQueue.java:197) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at io.fabric.sdk.android.services.concurrency.DependencyPriorityBlockingQueue.get(DependencyPriorityBlockingQueue.java:236) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at io.fabric.sdk.android.services.concurrency.DependencyPriorityBlockingQueue.take(DependencyPriorityBlockingQueue.java:65) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at io.fabric.sdk.android.services.concurrency.DependencyPriorityBlockingQueue.take(DependencyPriorityBlockingQueue.java:46) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1058) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1118) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Thread.run(Thread.java:761) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] "Queue" prio=5 tid=16 Waiting 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | group="" sCount=1 dsCount=0 obj=0x12c28430 self=0x74c3ccec00 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | sysTid=13944 nice=10 cgrp=default sched=0/0 handle=0x74b418a450 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | state=S schedstat=( 2676411 1176510 22 ) utm=0 stm=0 core=0 HZ=100 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | stack=0x74b4088000-0x74b408a000 stackSize=1037KB 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] | held mutexes= 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: switch_to+0x8c/0x98 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait_queue_me+0xd4/0x130 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: futex_wait+0xfc/0x210 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: do_futex+0xe0/0x920 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: SyS_futex+0x11c/0x1b0 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] kernel: cpu_switch_to+0x48/0x4c 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #00 pc 000000000001bcec /system/lib64/libc.so (syscall+28) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #01 pc 00000000000e7e4c /system/lib64/libart.so (_ZN3art17ConditionVariable16WaitHoldingLocksEPNS_6ThreadE+156) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #02 pc 000000000037c0ac /system/lib64/libart.so (_ZN3art7Monitor4WaitEPNS_6ThreadElibNS_11ThreadStateE+660) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] native: #03 pc 0000000000000980 /system/framework/arm64/boot.oat (Java_java_lang_Object_waitJI+140) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Object.wait!(Native method) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] - waiting on <0x04193357> (a java.lang.Object) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.lang.Thread.parkFor$(Thread.java:2127) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] - locked <0x04193357> (a java.lang.Object) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at sun.misc.Unsafe.park(Unsafe.java:325) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.locks.LockSupport.park(LockSupport.java:161) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2035) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.PriorityBlockingQueue.take(PriorityBlockingQueue.java:519) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at io.fabric.sdk.android.services.concurrency.DependencyPriorityBlockingQueue.performOperation(DependencyPriorityBlockingQueue.java:197) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at io.fabric.sdk.android.services.concurrency.DependencyPriorityBlockingQueue.get(DependencyPriorityBlockingQueue.java:236) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at io.fabric.sdk.android.services.concurrency.DependencyPriorityBlockingQueue.take(DependencyPriorityBlockingQueue.java:65) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at io.fabric.sdk.android.services.concurrency.DependencyPriorityBlockingQueue.take(DependencyPriorityBlockingQueue.java:46) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runtime.cc:422] at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1058) 06-07 15:51:50.176 13915-14778/? A/art: art/runtime/runt

Please provide me the solution,I need it urgently.

aashu09 commented 7 years ago

@kbagchiGWC ANy update on above issue?

Its very urgent, please I am upgraded user of Twilio and use lots of twilio calling.

kbagchiGWC commented 7 years ago

@aashu09 I will reproduce the issue and provide an update soon. So far I have not been successful recreating the crash.

What version of the SDK are you using? Is the issue device specific? How reproducible is the issue?

aashu09 commented 7 years ago

@kbagchiGWC thanks for your response.

I am using twilio version - com.twilio:voice-android:2.0.0-beta10

Please provide me the solution as soon as possible.

I am your upgraded user with the id "robert@callproof.com robert@callproof.com" and use lots of payable functionality of Twilio, so please provide me solution as soon as possible.

I have to release my app to client, so please help me out.

*Note: This issue is also coming in your voice quickstart SDK (https://github.com/twilio/voice-quickstart-android https://github.com/twilio/voice-quickstart-android*) with same scenario.

Thanks

On Thu, Jun 8, 2017 at 11:19 AM, kbagchiGWC notifications@github.com wrote:

@aashu09 https://github.com/aashu09 I will reproduce the issue and provide an update soon. What version of the SDK are you using?

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/twilio/voice-quickstart-android/issues/60#issuecomment-307005415, or mute the thread https://github.com/notifications/unsubscribe-auth/APc7-H7T5gRQQ8TVPUXn8oDZN4s9CQ8Bks5sB4uGgaJpZM4NyhWi .

aashu09 commented 7 years ago

I am giving you the scenario for issue -

  1. open the app and dial call
  2. disconnect from wifi in between running call
  3. call will automatically be disconnected
  4. reconnect wifi
  5. again dial call
  6. call will immediately disconnect and application will be crashed

Follow above scenario, you will find the issue.

Thanks

aashu09 commented 7 years ago

Please response, its urgent.

aashu09 commented 7 years ago

Have you find any solutions ?

kbagchiGWC commented 7 years ago

@aashu09 We are able to reproduce the crash. The crash happens when a call is initiated with no network availability. You can reproduce it easily by putting the phone in airplane mode and initiating a call. We are debugging the issue, will let you know as soon as we have a fix.

Thanks for reporting the issue.

aashu09 commented 7 years ago

Ohh thank god, you found that.

Please resolve that issue in earliest day because i have to give my application to customers in the starting of next week, so please help me out from this as soon as possible.

I know this SDK is on beta version and issues might come but really thankful that you are always ready to resolve them.

Thanks again and looking for solution as soon as possible. On 09-Jun-2017 10:33 PM, "kbagchiGWC" notifications@github.com wrote:

@aashu09 https://github.com/aashu09 We are able to reproduce the crash. The crash happen when a call is initiated with network availability. You can reproduce it easily by putting the phone in airplane mode and initiating a call. We are debugging the issue, will let you know as soon as we have a fix.

Thanks for reporting the issue.

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/twilio/voice-quickstart-android/issues/60#issuecomment-307444556, or mute the thread https://github.com/notifications/unsubscribe-auth/APc7-FB7YKrT_0246ybMkuNGqG9m7Oajks5sCXrKgaJpZM4NyhWi .

aashu09 commented 7 years ago

Any update on my issue? Please response.

aashu09 commented 7 years ago

The same above issue is coming on Incoming calls too when play with networks, so please test on both Incoming as well as outgoing and resolve it ASAP.

Thanks

aashu09 commented 7 years ago

Please response as I am only looking for your solutions. Thanks

kbagchiGWC commented 7 years ago

@aashu09 We are working on it. I will let you know when the fix is available.

aashu09 commented 7 years ago

Thats will be helpful to me. I am waiting for update.

Thanks

kbagchiGWC commented 7 years ago

@aashu09 this is fixed in beta12. Let me know if you have any questions.

aashu09 commented 7 years ago

Really appreciate for JNI error. Its looking solved in beta12 but I am getting an another error in the same scenario and beta12 i.e.

When in the same above scenario, network reconnect after connection loss and I dial a call then call is not able to connect. I have tried it again and again but not able to dial a call.

Scenario -

  1. open the app and dial call
  2. disconnect from wifi in between running call
  3. call will automatically be disconnected
  4. reconnect wifi
  5. again dial call
  6. call will immediately disconnect and not able to call anymore.

Here are logs -

06-14 11:10:27.490 19849-19849/com.app.dealer_app I/PJSIP: 11:10:27.490 os_core_unix.c !pjlib 2.5.1 for POSIX initialized 06-14 11:10:27.494 19849-19849/com.app.dealer_app D/PJSIP: 11:10:27.494 sip_endpoint.c .Creating endpoint instance... 06-14 11:10:27.495 19849-19849/com.app.dealer_app I/PJSIP: 11:10:27.495 pjlib .select() I/O Queue created (0x73e63ca5d0) 06-14 11:10:27.495 19849-19849/com.app.dealer_app I/PJSIP: 11:10:27.495 sip_endpoint.c .Module "mod-msg-print" registered 06-14 11:10:27.495 19849-19849/com.app.dealer_app D/PJSIP: 11:10:27.495 sip_transport. .Transport manager created. 06-14 11:10:27.495 19849-19849/com.app.dealer_app I/PJSIP: 11:10:27.495 pjsua_core.c .PJSUA state changed: NULL --> CREATED 06-14 11:10:27.521 19849-19849/com.app.dealer_app D/CallControlManager: got connectivity broadcast: haveConn=true, reason=null, isFailover=false 06-14 11:10:27.524 19849-20600/com.app.dealer_app I/libOpenSLES: Emulating old channel mask behavior (ignoring positional mask 0x4, using default mask 0x1 based on channel count of 1) 06-14 11:10:27.527 19849-20600/com.app.dealer_app W/AudioTrack: AUDIO_OUTPUT_FLAG_FAST denied by client; transfer 1, track 16000 Hz, output 48000 Hz 06-14 11:10:27.528 19849-20600/com.app.dealer_app D/AudioTrack: Client defaulted notificationFrames to 216 for frameCount 648 06-14 11:10:27.528 19849-19849/com.app.dealer_app D/CallControlManager: got wifi SSID: "IdeaMessenger306" 06-14 11:10:27.529 19849-19849/com.app.dealer_app D/CallControlManager: NET CHANGE EVENT: connected=true, wasConnected=true, netTypeChanged=true 06-14 11:10:27.529 19849-20600/com.app.dealer_app W/libOpenSLES: Conversion from OpenSL ES positional channel mask 0x4 to Android mask 0 loses channels 06-14 11:10:27.529 19849-20600/com.app.dealer_app I/libOpenSLES: Emulating old channel mask behavior (ignoring positional mask 0x4, using default mask 0x10 based on channel count of 1) 06-14 11:10:27.529 19849-19849/com.app.dealer_app D/CallControlManager: switched networks to type 1 06-14 11:10:27.538 19849-20600/com.app.dealer_app W/AudioRecord: AUDIO_INPUT_FLAG_FAST denied by server; frameCount 0 06-14 11:10:27.637 19849-20600/com.app.dealer_app D/CallControlManager: onCallSDPCreated notification received 06-14 11:10:27.641 19849-20600/com.app.dealer_app D/CallControlManager: received CALLING 06-14 11:10:28.785 19849-19849/com.app.dealer_app I/DealerApplication: !!!!! onRegistred .. cSAZqgL6i7A:APA91bGUjBek5fRtSSy13kx9osi_CeXxhag7jYXA2Ggku2Na2VHnCyoQdsK11n-fqLy2ZRyHln6dyBZI7Ky0Na6DxRClI4KGzpUfNKWUM0H2vRL_Q0IuaHkgnpkoriY8uBIIHiEDUWEm 06-14 11:10:29.319 19849-19849/com.app.dealer_app I/DealerApplication: !!!!! onRegistred .. cSAZqgL6i7A:APA91bGUjBek5fRtSSy13kx9osi_CeXxhag7jYXA2Ggku2Na2VHnCyoQdsK11n-fqLy2ZRyHln6dyBZI7Ky0Na6DxRClI4KGzpUfNKWUM0H2vRL_Q0IuaHkgnpkoriY8uBIIHiEDUWEm 06-14 11:10:30.986 19849-20598/com.app.dealer_app D/CallControlManager: received CONNECTING 06-14 11:10:30.999 19849-20598/com.app.dealer_app D/CallControlManager: received CONFIRMED 06-14 11:10:31.005 19849-20598/com.app.dealer_app D/CallControlManager: onTransactionState received CAa78225485086d489f4acda5b74658c75 06-14 11:10:31.008 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:31.006Z","timestamp_ms":1497418831006,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":1,"total_packets_received":0,"total_packets_lost":0,"total_bytes_received":0,"total_bytes_sent":160,"jitter":0,"rtt":0,"audio_level_in":0,"audio_level_out":0,"mos":4.437552191410176} 06-14 11:10:32.051 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:32.048Z","timestamp_ms":1497418832048,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":48,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":53,"total_packets_received":48,"total_packets_lost":0,"total_bytes_received":7680,"total_bytes_sent":8480,"jitter":4,"rtt":0,"audio_level_in":0,"audio_level_out":5376,"mos":4.437552191410176} 06-14 11:10:33.085 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:33.083Z","timestamp_ms":1497418833083,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":51,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":104,"total_packets_received":99,"total_packets_lost":0,"total_bytes_received":15840,"total_bytes_sent":16640,"jitter":4,"rtt":0,"audio_level_in":0,"audio_level_out":9344,"mos":4.437552191410176} 06-14 11:10:34.103 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:34.098Z","timestamp_ms":1497418834098,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":51,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":155,"total_packets_received":150,"total_packets_lost":0,"total_bytes_received":24000,"total_bytes_sent":24800,"jitter":2,"rtt":0,"audio_level_in":768,"audio_level_out":0,"mos":4.437552191410176} 06-14 11:10:35.147 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:35.145Z","timestamp_ms":1497418835145,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":208,"total_packets_received":202,"total_packets_lost":0,"total_bytes_received":32320,"total_bytes_sent":33280,"jitter":8,"rtt":0,"audio_level_in":0,"audio_level_out":2560,"mos":4.437552191410176} 06-14 11:10:36.193 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:36.190Z","timestamp_ms":1497418836190,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":1,"packets_lost_fraction":1,"total_packets_sent":260,"total_packets_received":254,"total_packets_lost":1,"total_bytes_received":40640,"total_bytes_sent":41600,"jitter":7,"rtt":113,"audio_level_in":6400,"audio_level_out":9216,"mos":4.3206561610821765} 06-14 11:10:37.241 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:37.238Z","timestamp_ms":1497418837238,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":312,"total_packets_received":306,"total_packets_lost":1,"total_bytes_received":48960,"total_bytes_sent":49920,"jitter":3,"rtt":113,"audio_level_in":0,"audio_level_out":0,"mos":4.3798704303221765} 06-14 11:10:38.288 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:38.285Z","timestamp_ms":1497418838285,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":49,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":365,"total_packets_received":355,"total_packets_lost":1,"total_bytes_received":56800,"total_bytes_sent":58400,"jitter":11,"rtt":113,"audio_level_in":0,"audio_level_out":11136,"mos":4.3798704303221765} 06-14 11:10:39.332 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:39.330Z","timestamp_ms":1497418839330,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":37,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":417,"total_packets_received":392,"total_packets_lost":1,"total_bytes_received":62720,"total_bytes_sent":66720,"jitter":8,"rtt":113,"audio_level_in":768,"audio_level_out":0,"mos":4.3798704303221765} 06-14 11:10:40.375 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:40.372Z","timestamp_ms":1497418840372,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":2,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":469,"total_packets_received":394,"total_packets_lost":1,"total_bytes_received":63040,"total_bytes_sent":75040,"jitter":8,"rtt":113,"audio_level_in":768,"audio_level_out":7296,"mos":4.3798704303221765} 06-14 11:10:40.444 19849-20669/com.app.dealer_app D/EventPublisher: Start publishing events to : https://eventgw.twilio.com/v3/EndpointMetrics {"name":"call-metric-event","group":"call-quality-stats","timestamp":"2017-06-14T05:40:40.379Z","level":"INFO","private":false,"payload_type":"application\/json","publisher":"twilio-voice-android","publisher_metadata":{"client_address":"FE80::B0E5:3EFF:FEBA:409B","os_name":"android","os_version":"7.1.2","device_model":"LGE Nexus 5X","device_vendor":"LGE","device_type":"Nexus 5X","cpu_architecture":"arm64-v8a"},"payload":[{"timestamp":"2017-06-14T05:40:31.006Z","timestamp_ms":1497418831006,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":1,"total_packets_received":0,"total_packets_lost":0,"total_bytes_received":0,"total_bytes_sent":160,"jitter":0,"rtt":0,"audio_level_in":0,"audio_level_out":0,"mos":4.437552191410176},{"timestamp":"2017-06-14T05:40:32.048Z","timestamp_ms":1497418832048,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":48,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":53,"total_packets_received":48,"total_packets_lost":0,"total_bytes_received":7680,"total_bytes_sent":8480,"jitter":4,"rtt":0,"audio_level_in":0,"audio_level_out":5376,"mos":4.437552191410176},{"timestamp":"2017-06-14T05:40:33.083Z","timestamp_ms":1497418833083,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":51,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":104,"total_packets_received":99,"total_packets_lost":0,"total_bytes_received":15840,"total_bytes_sent":16640,"jitter":4,"rtt":0,"audio_level_in":0,"audio_level_out":9344,"mos":4.437552191410176},{"timestamp":"2017-06-14T05:40:34.098Z","timestamp_ms":1497418834098,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":51,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":155,"total_packets_received":150,"total_packets_lost":0,"total_bytes_received":24000,"total_bytes_sent":24800,"jitter":2,"rtt":0,"audio_level_in":768,"audio_level_out":0,"mos":4.437552191410176},{"timestamp":"2017-06-14T05:40:35.145Z","timestamp_ms":1497418835145,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":208,"total_packets_received":202,"total_packets_lost":0,"total_bytes_received":32320,"total_bytes_sent":33280,"jitter":8,"rtt":0,"audio_level_in":0,"audio_level_out":2560,"mos":4.437552191410176},{"timestamp":"2017-06-14T05:40:36.190Z","timestamp_ms":1497418836190,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":1,"packets_lost_fraction":1,"total_packets_sent":260,"total_packets_received":254,"total_packets_lost":1,"total_bytes_received":40640,"total_bytes_sent":41600,"jitter":7,"rtt":113,"audio_level_in":6400,"audio_level_out":9216,"mos":4.3206561610821765},{"timestamp":"2017-06-14T05:40:37.238Z","timestamp_ms":1497418837238,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":312,"total_packets_received":306,"total_packets_lost":1,"total_bytes_received":48960,"total_bytes_sent":49920,"jitter":3,"rtt":113,"audio_level_in":0,"audio_level_out":0,"mos":4.3798704303221765},{"timestamp":"2017-06-14T05:40:38.285Z","timestamp_ms":1497418838285,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":49,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":365,"total_packets_received":355,"total_packets_lost":1,"total_bytes_received":56800,"total_bytes_sent":58400,"jitter":11,"rtt":113,"audio_level_in":0,"audio_level_out":11136,"mos":4.3798704303221765},{"timestamp":"2017-06-14T05:40:39.330Z","timestamp_ms":1497418839330,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0. 06-14 11:10:41.486 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:41.485Z","timestamp_ms":1497418841485,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":55,"packets_lost":1,"packets_lost_fraction":1,"total_packets_sent":525,"total_packets_received":449,"total_packets_lost":2,"total_bytes_received":71840,"total_bytes_sent":84000,"jitter":3,"rtt":81,"audio_level_in":5376,"audio_level_out":0,"mos":4.345510560778177} 06-14 11:10:41.495 19849-20809/com.app.dealer_app D/EventPublisher: Response: 200 - OK 06-14 11:10:42.496 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:42.492Z","timestamp_ms":1497418842492,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":50,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":575,"total_packets_received":499,"total_packets_lost":2,"total_bytes_received":79840,"total_bytes_sent":92000,"jitter":2,"rtt":81,"audio_level_in":2688,"audio_level_out":0,"mos":4.400767940018177} 06-14 11:10:43.539 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:43.535Z","timestamp_ms":1497418843535,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":51,"packets_lost":1,"packets_lost_fraction":1,"total_packets_sent":627,"total_packets_received":550,"total_packets_lost":3,"total_bytes_received":88000,"total_bytes_sent":100320,"jitter":3,"rtt":81,"audio_level_in":5888,"audio_level_out":0,"mos":4.345510560778177} 06-14 11:10:44.552 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:44.551Z","timestamp_ms":1497418844551,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":51,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":678,"total_packets_received":601,"total_packets_lost":3,"total_bytes_received":96160,"total_bytes_sent":108480,"jitter":1,"rtt":81,"audio_level_in":4736,"audio_level_out":0,"mos":4.400767940018177} 06-14 11:10:45.562 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:45.560Z","timestamp_ms":1497418845560,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":50,"packets_lost":2,"packets_lost_fraction":3,"total_packets_sent":728,"total_packets_received":651,"total_packets_lost":5,"total_bytes_received":104160,"total_bytes_sent":116480,"jitter":4,"rtt":81,"audio_level_in":4224,"audio_level_out":11136,"mos":4.206566002298176} 06-14 11:10:46.604 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:46.602Z","timestamp_ms":1497418846602,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":780,"total_packets_received":703,"total_packets_lost":5,"total_bytes_received":112480,"total_bytes_sent":124800,"jitter":4,"rtt":80,"audio_level_in":3712,"audio_level_out":0,"mos":4.400767940018177} 06-14 11:10:47.650 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:47.647Z","timestamp_ms":1497418847647,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":833,"total_packets_received":755,"total_packets_lost":5,"total_bytes_received":120800,"total_bytes_sent":133280,"jitter":2,"rtt":80,"audio_level_in":3072,"audio_level_out":0,"mos":4.400767940018177} 06-14 11:10:48.697 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:48.693Z","timestamp_ms":1497418848693,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":885,"total_packets_received":807,"total_packets_lost":5,"total_bytes_received":129120,"total_bytes_sent":141600,"jitter":2,"rtt":80,"audio_level_in":7424,"audio_level_out":128,"mos":4.400767940018177} 06-14 11:10:49.740 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:49.738Z","timestamp_ms":1497418849738,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":53,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":938,"total_packets_received":860,"total_packets_lost":5,"total_bytes_received":137600,"total_bytes_sent":150080,"jitter":2,"rtt":80,"audio_level_in":4480,"audio_level_out":0,"mos":4.400767940018177} 06-14 11:10:50.752 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:50.748Z","timestamp_ms":1497418850748,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":48,"packets_lost":2,"packets_lost_fraction":4,"total_packets_sent":988,"total_packets_received":908,"total_packets_lost":7,"total_bytes_received":145280,"total_bytes_sent":158080,"jitter":2,"rtt":82,"audio_level_in":4992,"audio_level_out":128,"mos":4.124191323058176} 06-14 11:10:50.771 19849-20669/com.app.dealer_app D/EventPublisher: Start publishing events to : https://eventgw.twilio.com/v3/EndpointMetrics {"name":"call-metric-event","group":"call-quality-stats","timestamp":"2017-06-14T05:40:50.754Z","level":"INFO","private":false,"payload_type":"application\/json","publisher":"twilio-voice-android","publisher_metadata":{"client_address":"FE80::B0E5:3EFF:FEBA:409B","os_name":"android","os_version":"7.1.2","device_model":"LGE Nexus 5X","device_vendor":"LGE","device_type":"Nexus 5X","cpu_architecture":"arm64-v8a"},"payload":[{"timestamp":"2017-06-14T05:40:41.485Z","timestamp_ms":1497418841485,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":55,"packets_lost":1,"packets_lost_fraction":1,"total_packets_sent":525,"total_packets_received":449,"total_packets_lost":2,"total_bytes_received":71840,"total_bytes_sent":84000,"jitter":3,"rtt":81,"audio_level_in":5376,"audio_level_out":0,"mos":4.345510560778177},{"timestamp":"2017-06-14T05:40:42.492Z","timestamp_ms":1497418842492,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":50,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":575,"total_packets_received":499,"total_packets_lost":2,"total_bytes_received":79840,"total_bytes_sent":92000,"jitter":2,"rtt":81,"audio_level_in":2688,"audio_level_out":0,"mos":4.400767940018177},{"timestamp":"2017-06-14T05:40:43.535Z","timestamp_ms":1497418843535,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":51,"packets_lost":1,"packets_lost_fraction":1,"total_packets_sent":627,"total_packets_received":550,"total_packets_lost":3,"total_bytes_received":88000,"total_bytes_sent":100320,"jitter":3,"rtt":81,"audio_level_in":5888,"audio_level_out":0,"mos":4.345510560778177},{"timestamp":"2017-06-14T05:40:44.551Z","timestamp_ms":1497418844551,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":51,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":678,"total_packets_received":601,"total_packets_lost":3,"total_bytes_received":96160,"total_bytes_sent":108480,"jitter":1,"rtt":81,"audio_level_in":4736,"audio_level_out":0,"mos":4.400767940018177},{"timestamp":"2017-06-14T05:40:45.560Z","timestamp_ms":1497418845560,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":50,"packets_lost":2,"packets_lost_fraction":3,"total_packets_sent":728,"total_packets_received":651,"total_packets_lost":5,"total_bytes_received":104160,"total_bytes_sent":116480,"jitter":4,"rtt":81,"audio_level_in":4224,"audio_level_out":11136,"mos":4.206566002298176},{"timestamp":"2017-06-14T05:40:46.602Z","timestamp_ms":1497418846602,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":780,"total_packets_received":703,"total_packets_lost":5,"total_bytes_received":112480,"total_bytes_sent":124800,"jitter":4,"rtt":80,"audio_level_in":3712,"audio_level_out":0,"mos":4.400767940018177},{"timestamp":"2017-06-14T05:40:47.647Z","timestamp_ms":1497418847647,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":833,"total_packets_received":755,"total_packets_lost":5,"total_bytes_received":120800,"total_bytes_sent":133280,"jitter":2,"rtt":80,"audio_level_in":3072,"audio_level_out":0,"mos":4.400767940018177},{"timestamp":"2017-06-14T05:40:48.693Z","timestamp_ms":1497418848693,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":885,"total_packets_received":807,"total_packets_lost":5,"total_bytes_received":129120,"total_bytes_sent":141600,"jitter":2,"rtt":80,"audio_level_in":7424,"audio_level_out":128,"mos":4.400767940018177},{"timestamp":"2017-06-14T05:40:49.738Z","timestamp_ms":1497418849738,"call_sid":"CAa78225485086d489f4ac 06-14 11:10:51.791 19849-20955/com.app.dealer_app D/EventPublisher: Response: 200 - OK 06-14 11:10:51.822 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:51.819Z","timestamp_ms":1497418851819,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":1,"packets_lost_fraction":1,"total_packets_sent":1041,"total_packets_received":960,"total_packets_lost":8,"total_bytes_received":153600,"total_bytes_sent":166560,"jitter":5,"rtt":82,"audio_level_in":5376,"audio_level_out":10496,"mos":4.345510560778177} 06-14 11:10:52.865 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:52.864Z","timestamp_ms":1497418852864,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":1,"packets_lost_fraction":1,"total_packets_sent":1094,"total_packets_received":1012,"total_packets_lost":9,"total_bytes_received":161920,"total_bytes_sent":175040,"jitter":1,"rtt":82,"audio_level_in":2816,"audio_level_out":0,"mos":4.345510560778177} 06-14 11:10:53.909 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:53.907Z","timestamp_ms":1497418853907,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":50,"packets_lost":2,"packets_lost_fraction":3,"total_packets_sent":1146,"total_packets_received":1062,"total_packets_lost":11,"total_bytes_received":169920,"total_bytes_sent":183360,"jitter":5,"rtt":82,"audio_level_in":2944,"audio_level_out":128,"mos":4.206566002298176} 06-14 11:10:54.919 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:54.916Z","timestamp_ms":1497418854916,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":59,"packets_lost":1,"packets_lost_fraction":1,"total_packets_sent":1196,"total_packets_received":1121,"total_packets_lost":12,"total_bytes_received":179360,"total_bytes_sent":191360,"jitter":232735,"rtt":82,"audio_level_in":7296,"audio_level_out":3200,"mos":687268.8860423329} 06-14 11:10:55.962 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:55.959Z","timestamp_ms":1497418855959,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":1248,"total_packets_received":1173,"total_packets_lost":12,"total_bytes_received":187680,"total_bytes_sent":199680,"jitter":101733,"rtt":84,"audio_level_in":4480,"audio_level_out":1024,"mos":55358.84831556004} 06-14 11:10:57.009 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:57.005Z","timestamp_ms":1497418857005,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":49,"packets_lost":3,"packets_lost_fraction":5,"total_packets_sent":1301,"total_packets_received":1222,"total_packets_lost":15,"total_bytes_received":195520,"total_bytes_sent":208160,"jitter":4597,"rtt":84,"audio_level_in":5632,"audio_level_out":384,"mos":0.9919303571331761} 06-14 11:10:57.026 19849-20669/com.app.dealer_app D/EventPublisher: Start publishing events to : https://eventgw.twilio.com/v3/EndpointEvents {"name":"high-jitter","group":"network-quality-warning-raised","timestamp":"2017-06-14T05:40:57.020Z","level":"WARNING","private":false,"payload_type":"application\/json","publisher":"twilio-voice-android","publisher_metadata":{"client_address":"FE80::B0E5:3EFF:FEBA:409B","os_name":"android","os_version":"7.1.2","device_model":"LGE Nexus 5X","device_vendor":"LGE","device_type":"Nexus 5X","cpu_architecture":"arm64-v8a"},"payload":{"timestamp_ms":1497418857012,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","platform":"android","direction":"OUTGOING","data":{"threshold":30,"values":[1,5,232735,101733,4597]}}} 06-14 11:10:57.971 19849-21047/com.app.dealer_app D/EventPublisher: Response: 200 - OK 06-14 11:10:58.072 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:58.068Z","timestamp_ms":1497418858068,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":53,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":1353,"total_packets_received":1275,"total_packets_lost":15,"total_bytes_received":204000,"total_bytes_sent":216480,"jitter":154,"rtt":84,"audio_level_in":3456,"audio_level_out":256,"mos":3.4419994879221765} 06-14 11:10:58.088 19849-20669/com.app.dealer_app D/EventPublisher: Start publishing events to : https://eventgw.twilio.com/v3/EndpointEvents {"name":"high-jitter","group":"network-quality-warning-raised","timestamp":"2017-06-14T05:40:58.079Z","level":"WARNING","private":false,"payload_type":"application\/json","publisher":"twilio-voice-android","publisher_metadata":{"client_address":"FE80::B0E5:3EFF:FEBA:409B","os_name":"android","os_version":"7.1.2","device_model":"LGE Nexus 5X","device_vendor":"LGE","device_type":"Nexus 5X","cpu_architecture":"arm64-v8a"},"payload":{"timestamp_ms":1497418858073,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","platform":"android","direction":"OUTGOING","data":{"threshold":30,"values":[5,232735,101733,4597,154]}}} 06-14 11:10:59.038 19849-20809/com.app.dealer_app D/EventPublisher: Response: 200 - OK 06-14 11:10:59.120 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:40:59.118Z","timestamp_ms":1497418859118,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":1,"packets_lost_fraction":1,"total_packets_sent":1406,"total_packets_received":1327,"total_packets_lost":16,"total_bytes_received":212320,"total_bytes_sent":224960,"jitter":8,"rtt":84,"audio_level_in":4736,"audio_level_out":256,"mos":4.345510560778177} 06-14 11:10:59.134 19849-20669/com.app.dealer_app D/EventPublisher: Start publishing events to : https://eventgw.twilio.com/v3/EndpointEvents {"name":"high-jitter","group":"network-quality-warning-raised","timestamp":"2017-06-14T05:40:59.128Z","level":"WARNING","private":false,"payload_type":"application\/json","publisher":"twilio-voice-android","publisher_metadata":{"client_address":"FE80::B0E5:3EFF:FEBA:409B","os_name":"android","os_version":"7.1.2","device_model":"LGE Nexus 5X","device_vendor":"LGE","device_type":"Nexus 5X","cpu_architecture":"arm64-v8a"},"payload":{"timestamp_ms":1497418859124,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","platform":"android","direction":"OUTGOING","data":{"threshold":30,"values":[232735,101733,4597,154,8]}}} 06-14 11:11:00.111 19849-20955/com.app.dealer_app D/EventPublisher: Response: 200 - OK 06-14 11:11:00.172 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:41:00.171Z","timestamp_ms":1497418860171,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":1,"packets_lost_fraction":1,"total_packets_sent":1459,"total_packets_received":1379,"total_packets_lost":17,"total_bytes_received":220640,"total_bytes_sent":233440,"jitter":2,"rtt":84,"audio_level_in":1408,"audio_level_out":256,"mos":4.345510560778177} 06-14 11:11:00.176 19849-20669/com.app.dealer_app D/EventPublisher: Start publishing events to : https://eventgw.twilio.com/v3/EndpointEvents {"name":"high-jitter","group":"network-quality-warning-raised","timestamp":"2017-06-14T05:41:00.173Z","level":"WARNING","private":false,"payload_type":"application\/json","publisher":"twilio-voice-android","publisher_metadata":{"client_address":"FE80::B0E5:3EFF:FEBA:409B","os_name":"android","os_version":"7.1.2","device_model":"LGE Nexus 5X","device_vendor":"LGE","device_type":"Nexus 5X","cpu_architecture":"arm64-v8a"},"payload":{"timestamp_ms":1497418860172,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","platform":"android","direction":"OUTGOING","data":{"threshold":30,"values":[101733,4597,154,8,2]}}} 06-14 11:11:01.145 19849-21047/com.app.dealer_app D/EventPublisher: Response: 200 - OK 06-14 11:11:01.217 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:41:01.215Z","timestamp_ms":1497418861215,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":51,"packets_lost":1,"packets_lost_fraction":1,"total_packets_sent":1508,"total_packets_received":1430,"total_packets_lost":18,"total_bytes_received":228800,"total_bytes_sent":241280,"jitter":2,"rtt":81,"audio_level_in":384,"audio_level_out":384,"mos":4.345510560778177} 06-14 11:11:01.230 19849-20669/com.app.dealer_app D/EventPublisher: Start publishing events to : https://eventgw.twilio.com/v3/EndpointMetrics {"name":"call-metric-event","group":"call-quality-stats","timestamp":"2017-06-14T05:41:01.218Z","level":"INFO","private":false,"payload_type":"application\/json","publisher":"twilio-voice-android","publisher_metadata":{"client_address":"FE80::B0E5:3EFF:FEBA:409B","os_name":"android","os_version":"7.1.2","device_model":"LGE Nexus 5X","device_vendor":"LGE","device_type":"Nexus 5X","cpu_architecture":"arm64-v8a"},"payload":[{"timestamp":"2017-06-14T05:40:51.819Z","timestamp_ms":1497418851819,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":1,"packets_lost_fraction":1,"total_packets_sent":1041,"total_packets_received":960,"total_packets_lost":8,"total_bytes_received":153600,"total_bytes_sent":166560,"jitter":5,"rtt":82,"audio_level_in":5376,"audio_level_out":10496,"mos":4.345510560778177},{"timestamp":"2017-06-14T05:40:52.864Z","timestamp_ms":1497418852864,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":1,"packets_lost_fraction":1,"total_packets_sent":1094,"total_packets_received":1012,"total_packets_lost":9,"total_bytes_received":161920,"total_bytes_sent":175040,"jitter":1,"rtt":82,"audio_level_in":2816,"audio_level_out":0,"mos":4.345510560778177},{"timestamp":"2017-06-14T05:40:53.907Z","timestamp_ms":1497418853907,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":50,"packets_lost":2,"packets_lost_fraction":3,"total_packets_sent":1146,"total_packets_received":1062,"total_packets_lost":11,"total_bytes_received":169920,"total_bytes_sent":183360,"jitter":5,"rtt":82,"audio_level_in":2944,"audio_level_out":128,"mos":4.206566002298176},{"timestamp":"2017-06-14T05:40:54.916Z","timestamp_ms":1497418854916,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":59,"packets_lost":1,"packets_lost_fraction":1,"total_packets_sent":1196,"total_packets_received":1121,"total_packets_lost":12,"total_bytes_received":179360,"total_bytes_sent":191360,"jitter":232735,"rtt":82,"audio_level_in":7296,"audio_level_out":3200,"mos":687268.8860423329},{"timestamp":"2017-06-14T05:40:55.959Z","timestamp_ms":1497418855959,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":1248,"total_packets_received":1173,"total_packets_lost":12,"total_bytes_received":187680,"total_bytes_sent":199680,"jitter":101733,"rtt":84,"audio_level_in":4480,"audio_level_out":1024,"mos":55358.84831556004},{"timestamp":"2017-06-14T05:40:57.005Z","timestamp_ms":1497418857005,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":49,"packets_lost":3,"packets_lost_fraction":5,"total_packets_sent":1301,"total_packets_received":1222,"total_packets_lost":15,"total_bytes_received":195520,"total_bytes_sent":208160,"jitter":4597,"rtt":84,"audio_level_in":5632,"audio_level_out":384,"mos":0.9919303571331761},{"timestamp":"2017-06-14T05:40:58.068Z","timestamp_ms":1497418858068,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":53,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":1353,"total_packets_received":1275,"total_packets_lost":15,"total_bytes_received":204000,"total_bytes_sent":216480,"jitter":154,"rtt":84,"audio_level_in":3456,"audio_level_out":256,"mos":3.4419994879221765},{"timestamp":"2017-06-14T05:40:59.118Z","timestamp_ms":1497418859118,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":52,"packets_lost":1,"packets_lost_fraction":1,"total_packets_sent":1406,"total_packets_received":1327,"total_packets_lost":16,"total_bytes_received":212320,"total_bytes_sent":224960,"jitter":8,"rtt":84,"audio_level_in":4736,"audio_level_out":256,"mos":4.345510560778177},{"timestamp":"2017-06-14T05:41:00.171Z","timest 06-14 11:11:02.246 19849-20809/com.app.dealer_app E/EventPublisher: java.net.SocketException: Network is unreachable 06-14 11:11:02.246 19849-20809/com.app.dealer_app E/EventPublisher: java.net.SocketException: Network is unreachable 06-14 11:11:02.273 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:41:02.272Z","timestamp_ms":1497418862272,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":50,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":1559,"total_packets_received":1480,"total_packets_lost":18,"total_bytes_received":236800,"total_bytes_sent":249440,"jitter":3,"rtt":81,"audio_level_in":0,"audio_level_out":384,"mos":4.400767940018177} 06-14 11:11:02.324 19849-19849/com.app.dealer_app D/CallControlManager: got connectivity broadcast: haveConn=false, reason=null, isFailover=false 06-14 11:11:02.325 19849-19849/com.app.dealer_app D/CallControlManager: NET CHANGE EVENT: connected=false, wasConnected=true, netTypeChanged=false 06-14 11:11:02.383 19849-20600/com.app.dealer_app E/ReinviteCallCommand: Failed to update call on IP address change; call will not be notified if the other party hangs up 06-14 11:11:03.316 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:41:03.314Z","timestamp_ms":1497418863314,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":1559,"total_packets_received":1480,"total_packets_lost":18,"total_bytes_received":236800,"total_bytes_sent":249440,"jitter":3,"rtt":81,"audio_level_in":0,"audio_level_out":0,"mos":4.400767940018177} 06-14 11:11:03.320 19849-20669/com.app.dealer_app D/EventPublisher: Start publishing events to : https://eventgw.twilio.com/v3/EndpointEvents {"name":"high-jitter","group":"network-quality-warning-cleared","timestamp":"2017-06-14T05:41:03.318Z","level":"INFO","private":false,"payload_type":"application\/json","publisher":"twilio-voice-android","publisher_metadata":{"client_address":"FE80::B0E5:3EFF:FEBA:409B","os_name":"android","os_version":"7.1.2","device_model":"LGE Nexus 5X","device_vendor":"LGE","device_type":"Nexus 5X","cpu_architecture":"arm64-v8a"},"payload":{"timestamp_ms":1497418863316,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","platform":"android","direction":"OUTGOING"}} 06-14 11:11:03.328 19849-20955/com.app.dealer_app E/EventPublisher: java.net.UnknownHostException: Unable to resolve host "eventgw.twilio.com": No address associated with hostname 06-14 11:11:03.328 19849-20955/com.app.dealer_app E/EventPublisher: java.net.UnknownHostException: Unable to resolve host "eventgw.twilio.com": No address associated with hostname 06-14 11:11:04.357 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:41:04.355Z","timestamp_ms":1497418864355,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":1559,"total_packets_received":1480,"total_packets_lost":18,"total_bytes_received":236800,"total_bytes_sent":249440,"jitter":3,"rtt":81,"audio_level_in":0,"audio_level_out":0,"mos":4.400767940018177} 06-14 11:11:05.373 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:41:05.370Z","timestamp_ms":1497418865370,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":1559,"total_packets_received":1480,"total_packets_lost":18,"total_bytes_received":236800,"total_bytes_sent":249440,"jitter":3,"rtt":81,"audio_level_in":0,"audio_level_out":0,"mos":4.400767940018177} 06-14 11:11:06.378 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:41:06.376Z","timestamp_ms":1497418866376,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":1559,"total_packets_received":1480,"total_packets_lost":18,"total_bytes_received":236800,"total_bytes_sent":249440,"jitter":3,"rtt":81,"audio_level_in":0,"audio_level_out":0,"mos":4.400767940018177} 06-14 11:11:07.422 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:41:07.420Z","timestamp_ms":1497418867420,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":1559,"total_packets_received":1480,"total_packets_lost":18,"total_bytes_received":236800,"total_bytes_sent":249440,"jitter":3,"rtt":81,"audio_level_in":896,"audio_level_out":0,"mos":4.400767940018177} 06-14 11:11:08.466 19849-20669/com.app.dealer_app D/RTCStatsSample: {"timestamp":"2017-06-14T05:41:08.463Z","timestamp_ms":1497418868463,"call_sid":"CAa78225485086d489f4acda5b74658c75","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":1559,"total_packets_received":1480,"total_packets_lost":18,"total_bytes_received":236800,"total_bytes_sent":249440,"jitter":3,"rtt":81,"audio_level_in":128,"audio_level_out":0,"mos":4.400767940018177} 06-14 11:11:09.380 19849-19849/com.app.dealer_app D/CallControlManager: Exceeded the connectivity threshold of 7000 seconds. Disconnecting calls. 06-14 11:11:09.381 19849-19849/com.app.dealer_app D/Call: Calling disconnect CONNECTED 06-14 11:11:09.383 19849-20600/com.app.dealer_app E/CallControlManager: received transport error: status: -432021336 06-14 11:11:09.383 19849-20600/com.app.dealer_app D/CallControlManager: received DISCONNECTED 06-14 11:11:09.383 19849-20600/com.app.dealer_app D/RTCMonitor: Stopped publishing 06-14 11:11:09.384 19849-20669/com.app.dealer_app D/RTCMonitor: java.lang.InterruptedException 06-14 11:11:09.384 19849-20600/com.app.dealer_app A/PJSIP: 11:11:09.384 pjsua_call.c .Failed to send end session message: Network is unreachable [status=120101] 06-14 11:11:09.385 19849-20600/com.app.dealer_app E/HangupCallCommand: Failed to hang up call com.twilio.voice.impl.session.SessionException: pjsua_call_hangup(): Network is unreachable at com.twilio.voice.impl.useragent.Call.hangup(Native Method) at com.twilio.voice.impl.useragent.Call.hangup(Call.java:98) at com.twilio.voice.HangupCallCommand.run(HangupCallCommand.java:24) at android.os.Handler.handleCallback(Handler.java:751) at android.os.Handler.dispatchMessage(Handler.java:95) at android.os.Looper.loop(Looper.java:154) at com.twilio.voice.CallCommandHandlerImpl.run(CallCommandHandlerImpl.java:48) 06-14 11:11:09.464 19849-21388/com.app.dealer_app D/FA: Logging event (FE): _e, Bundle[{_o=auto, _et=42601, _sc=TwilioOutgoingCallActivity, _si=1591285798433809828}] 06-14 11:11:21.545 19849-19849/com.app.dealer_app D/CallControlManager: got connectivity broadcast: haveConn=true, reason=null, isFailover=false 06-14 11:11:21.547 19849-19849/com.app.dealer_app D/CallControlManager: got wifi SSID: "IdeaMessenger306" 06-14 11:11:21.547 19849-19849/com.app.dealer_app D/CallControlManager: NET CHANGE EVENT: connected=true, wasConnected=false, netTypeChanged=true 06-14 11:11:21.547 19849-19849/com.app.dealer_app D/CallControlManager: switched networks to type 1 06-14 11:11:28.535 19849-21388/com.app.dealer_app D/FA: Logging event (FE): _e, Bundle[{_o=auto, _et=19055, _sc=HomeActivity, _si=1591285798433809827}] 0mote service 06-14 11:11:28.706 19849-19856/com.app.dealer_app I/art: Do full code cache collection, code=104KB, data=124KB 06-14 11:11:28.706 19849-19856/com.app.dealer_app I/art: Starting a blocking GC JitCodeCache 06-14 11:11:28.706 19849-19856/com.app.dealer_app I/art: After code cache collection, code=86KB, data=82KB 06-14 11:11:28.710 19849-20600/com.app.dealer_app I/libOpenSLES: Emulating old channel mask behavior (ignoring positional mask 0x4, using default mask 0x1 based on channel count of 1) 06-14 11:11:28.910 19849-20600/com.app.dealer_app W/AudioTrack: AUDIO_OUTPUT_FLAG_FAST denied by client; transfer 1, track 16000 Hz, output 48000 Hz 06-14 11:11:28.912 19849-20600/com.app.dealer_app D/AudioTrack: Client defaulted notificationFrames to 216 for frameCount 648 06-14 11:11:28.913 19849-20600/com.app.dealer_app W/libOpenSLES: Conversion from OpenSL ES positional channel mask 0x4 to Android mask 0 loses channels 06-14 11:11:28.913 19849-20600/com.app.dealer_app I/libOpenSLES: Emulating old channel mask behavior (ignoring positional mask 0x4, using default mask 0x10 based on channel count of 1) 06-14 11:11:28.921 19849-20600/com.app.dealer_app W/AudioRecord: AUDIO_INPUT_FLAG_FAST denied by server; frameCount 0 06-14 11:11:28.941 19849-20600/com.app.dealer_app D/CallControlManager: onCallSDPCreated notification received 06-14 11:11:28.942 19849-20600/com.app.dealer_app E/CallControlManager: received transport error: status: -507457368 06-14 11:11:28.942 19849-20600/com.app.dealer_app D/CallControlManager: received DISCONNECTED 06-14 11:21:29.629 19849-31017/com.app.dealer_app D/FA: Logging event (FE): _e, Bundle[{_o=auto, _et=600613, _sc=HomeActivity, _si=1591285798433809827}]

Please check it and help me out one more time. Thanks

aashu09 commented 7 years ago

Please response.

kbagchiGWC commented 7 years ago

@aashu09 was the network available when the call was made? The log above seems like the scenario when the call was established, then the network was disconnected and after that the user tried to disconnect the call. It failed at that point with com.twilio.voice.impl.session.SessionException: pjsua_call_hangup(): Network is unreachable error message.

It is important to keep in mind that the Voice SDK cannot force the network to be connected. If you initiate a call without network connectivity - it will fail.

kbagchiGWC commented 7 years ago

Scenario -

  1. open the app and dial call
  2. disconnect from wifi in between running call
  3. call will automatically be disconnected
  4. reconnect wifi
  5. again dial call
  6. call will immediately disconnect and not able to call anymore.

At step #6 - the call is failing immediately indicates the the network is still not available. You should be seeing logs similar to below when this happens. What is concerning is this "not able to call anymore? Can you please provide logs?

06-14 10:39:58.928 32309-8773/com.twilio.voice.app.debug I/PJSIP: 10:39:58.928 resolver.c ....Transmitting 52 bytes to NS 0 (192.168.1.1:53): DNS SRV query for _sips._tcp.chunderm.gll.twilio.com: Network is unreachable 06-14 10:39:58.928 32309-8773/com.twilio.voice.app.debug I/PJSIP: 10:39:58.928 sip_resolve.c ....Failed to resolve 'chunderm.gll.twilio.com'. Err=120101 (Network is unreachable) 06-14 10:39:58.928 32309-8773/com.twilio.voice.app.debug W/PJSIP: 10:39:58.928 tsx0x7f8f86b8a ....Failed to send Request msg INVITE/cseq=8696 (tdta0x7f8947e000)! err=120101 (Network is unreachable) 06-14 10:39:58.928 32309-8773/com.twilio.voice.app.debug E/CallControlManager: received transport error: status: -1991778136 06-14 10:39:58.928 32309-8773/com.twilio.voice.app.debug D/CallControlManager: received DISCONNECTED

aashu09 commented 7 years ago

Scenario -

  1. open the app and dial call
  2. disconnect from wifi in between running call
  3. call will automatically be disconnected
  4. reconnect wifi
  5. again dial call
  6. call will immediately disconnect and not able to call anymore.

You told network is not available but network is available after step 6 however call is not connecting.

I have tested it on https://github.com/twilio/voice-quickstart-android with beta12 version and the same issue occurs means when you will follow above scenario then after within 15 seconds of reconnection of network you will not be able to dial a call.

I am attaching logs by testing on https://github.com/twilio/voice-quickstart-android with beta12 version -

06-15 10:36:29.759 12141-12141/? I/art: Late-enabling -Xcheck:jni 06-15 10:36:29.818 12141-12141/com.twilio.voice.quickstart D/FirebaseApp: com.google.firebase.auth.FirebaseAuth is not linked. Skipping initialization. 06-15 10:36:29.824 12141-12141/com.twilio.voice.quickstart W/InstanceID/Rpc: Found 10015 06-15 10:36:29.829 12141-12141/com.twilio.voice.quickstart D/FirebaseApp: com.google.firebase.crash.FirebaseCrash is not linked. Skipping initialization. 06-15 10:36:29.839 12141-12141/com.twilio.voice.quickstart I/FA: App measurement is starting up, version: 10084 06-15 10:36:29.839 12141-12141/com.twilio.voice.quickstart I/FA: To enable debug logging run: adb shell setprop log.tag.FA VERBOSE 06-15 10:36:29.839 12141-12141/com.twilio.voice.quickstart D/FA: Debug-level message logging enabled 06-15 10:36:29.839 12141-12141/com.twilio.voice.quickstart D/FA: AppMeasurement singleton hash: 155922709 06-15 10:36:29.848 12141-12141/com.twilio.voice.quickstart I/FA: To enable faster debug mode event logging run: adb shell setprop debug.firebase.analytics.app com.twilio.voice.quickstart 06-15 10:36:29.857 12141-12141/com.twilio.voice.quickstart I/FirebaseInitProvider: FirebaseApp initialization successful 06-15 10:36:29.874 12141-12141/com.twilio.voice.quickstart W/art: Before Android 4.1, method android.graphics.PorterDuffColorFilter android.support.graphics.drawable.VectorDrawableCompat.updateTintFilter(android.graphics.PorterDuffColorFilter, android.content.res.ColorStateList, android.graphics.PorterDuff$Mode) would have incorrectly overridden the package-private method in android.graphics.drawable.Drawable 06-15 10:36:29.983 12141-12174/com.twilio.voice.quickstart I/OMXClient: Using client-side OMX mux. 06-15 10:36:29.984 12141-12175/com.twilio.voice.quickstart D/OpenGLRenderer: Use EGL_SWAP_BEHAVIOR_PRESERVED: true 06-15 10:36:29.986 12141-12174/com.twilio.voice.quickstart E/OMXMaster: A component of name 'OMX.qcom.audio.decoder.aac' already exists, ignoring this one. 06-15 10:36:30.032 12141-12181/com.twilio.voice.quickstart I/OMXClient: Using client-side OMX mux. 06-15 10:36:30.036 12141-12181/com.twilio.voice.quickstart E/OMXMaster: A component of name 'OMX.qcom.audio.decoder.aac' already exists, ignoring this one. 06-15 10:36:30.064 12141-12175/com.twilio.voice.quickstart I/Adreno: QUALCOMM build : 576fcee, I44fb0b5862 Build Date : 08/26/15 OpenGL ES Shader Compiler Version: XE031.05.13.02 Local Branch : M13 Remote Branch : Remote Branch : Reconstruct Branch : 06-15 10:36:30.069 12141-12175/com.twilio.voice.quickstart I/OpenGLRenderer: Initialized EGL, version 1.4 06-15 10:36:30.299 12141-12163/com.twilio.voice.quickstart D/FA: Connected to remote service 06-15 10:36:35.302 12141-12141/com.twilio.voice.quickstart I/VoiceActivity: Registering with FCM 06-15 10:36:39.214 12141-12141/com.twilio.voice.quickstart I/PJSIP: 10:36:39.214 os_core_unix.c !pjlib 2.5.1 for POSIX initialized 06-15 10:36:39.215 12141-12141/com.twilio.voice.quickstart D/PJSIP: 10:36:39.215 sip_endpoint.c .Creating endpoint instance... 06-15 10:36:39.216 12141-12141/com.twilio.voice.quickstart I/PJSIP: 10:36:39.216 pjlib .select() I/O Queue created (0xb3ad1044) 06-15 10:36:39.216 12141-12141/com.twilio.voice.quickstart I/PJSIP: 10:36:39.216 sip_endpoint.c .Module "mod-msg-print" registered 06-15 10:36:39.216 12141-12141/com.twilio.voice.quickstart D/PJSIP: 10:36:39.216 sip_transport. .Transport manager created. 06-15 10:36:39.216 12141-12141/com.twilio.voice.quickstart I/PJSIP: 10:36:39.216 pjsua_core.c .PJSUA state changed: NULL --> CREATED 06-15 10:36:39.232 12141-12342/com.twilio.voice.quickstart W/AudioTrack: AUDIO_OUTPUT_FLAG_FAST denied by client; transfer 1, track 16000 Hz, output 48000 Hz 06-15 10:36:39.233 12141-12141/com.twilio.voice.quickstart D/CallControlManager: got connectivity broadcast: haveConn=true, reason=null, isFailover=false 06-15 10:36:39.234 12141-12342/com.twilio.voice.quickstart W/AudioRecord: AUDIO_INPUT_FLAG_FAST denied by client; transfer 1, track 16000 Hz, primary 48000 Hz 06-15 10:36:39.238 12141-12141/com.twilio.voice.quickstart D/CallControlManager: got wifi SSID: "IdeaMessenger306" 06-15 10:36:39.238 12141-12141/com.twilio.voice.quickstart D/CallControlManager: NET CHANGE EVENT: connected=true, wasConnected=true, netTypeChanged=true 06-15 10:36:39.238 12141-12141/com.twilio.voice.quickstart D/CallControlManager: switched networks to type 1 06-15 10:36:39.464 12141-12342/com.twilio.voice.quickstart D/CallControlManager: onCallSDPCreated notification received 06-15 10:36:39.466 12141-12342/com.twilio.voice.quickstart D/CallControlManager: received CALLING 06-15 10:36:41.547 12141-12340/com.twilio.voice.quickstart D/CallControlManager: received CONNECTING 06-15 10:36:41.552 12141-12340/com.twilio.voice.quickstart D/CallControlManager: received CONFIRMED 06-15 10:36:41.556 12141-12340/com.twilio.voice.quickstart D/CallControlManager: onTransactionState received CA60fe23b0df055416d1736c3ef115f644 06-15 10:36:41.558 12141-12381/com.twilio.voice.quickstart D/RTCStatsSample: {"timestamp":"2017-06-15T05:06:41.556Z","timestamp_ms":1497503201556,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":0,"total_packets_received":0,"total_packets_lost":0,"total_bytes_received":0,"total_bytes_sent":0,"jitter":0,"rtt":0,"audio_level_in":0,"audio_level_out":0,"mos":4.437552191410176} 06-15 10:36:42.599 12141-12381/com.twilio.voice.quickstart D/RTCStatsSample: {"timestamp":"2017-06-15T05:06:42.596Z","timestamp_ms":1497503202596,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","packets_received":47,"packets_lost":1,"packets_lost_fraction":2,"total_packets_sent":51,"total_packets_received":47,"total_packets_lost":1,"total_bytes_received":7520,"total_bytes_sent":8160,"jitter":13,"rtt":0,"audio_level_in":128,"audio_level_out":10624,"mos":4.333274642930176} 06-15 10:36:43.639 12141-12381/com.twilio.voice.quickstart D/RTCStatsSample: {"timestamp":"2017-06-15T05:06:43.638Z","timestamp_ms":1497503203638,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","packets_received":47,"packets_lost":5,"packets_lost_fraction":9,"total_packets_sent":105,"total_packets_received":94,"total_packets_lost":6,"total_bytes_received":15040,"total_bytes_sent":16800,"jitter":13,"rtt":0,"audio_level_in":0,"audio_level_out":10368,"mos":3.701487198250176} 06-15 10:36:44.440 12141-12141/com.twilio.voice.quickstart D/CallControlManager: got connectivity broadcast: haveConn=false, reason=null, isFailover=false 06-15 10:36:44.441 12141-12141/com.twilio.voice.quickstart D/CallControlManager: NET CHANGE EVENT: connected=false, wasConnected=true, netTypeChanged=false 06-15 10:36:44.491 12141-12342/com.twilio.voice.quickstart E/ReinviteCallCommand: Failed to update call on IP address change; call will not be notified if the other party hangs up 06-15 10:36:44.681 12141-12381/com.twilio.voice.quickstart D/RTCStatsSample: {"timestamp":"2017-06-15T05:06:44.680Z","timestamp_ms":1497503204680,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","packets_received":29,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":139,"total_packets_received":123,"total_packets_lost":6,"total_bytes_received":19680,"total_bytes_sent":22240,"jitter":20,"rtt":0,"audio_level_in":0,"audio_level_out":0,"mos":4.420009193714176} 06-15 10:36:45.314 12141-12163/com.twilio.voice.quickstart I/FA: Tag Manager is not found and thus will not be used 06-15 10:36:45.316 12141-12163/com.twilio.voice.quickstart D/FA: Logging event (FE): _s, Bundle[{_o=auto, _sc=VoiceActivity, _si=306614701998664949}] 06-15 10:36:45.365 12141-12163/com.twilio.voice.quickstart D/FA: Connected to remote service 06-15 10:36:45.714 12141-12381/com.twilio.voice.quickstart D/RTCStatsSample: {"timestamp":"2017-06-15T05:06:45.713Z","timestamp_ms":1497503205713,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":139,"total_packets_received":123,"total_packets_lost":6,"total_bytes_received":19680,"total_bytes_sent":22240,"jitter":20,"rtt":0,"audio_level_in":256,"audio_level_out":0,"mos":4.420009193714176} 06-15 10:36:46.730 12141-12381/com.twilio.voice.quickstart D/RTCStatsSample: {"timestamp":"2017-06-15T05:06:46.729Z","timestamp_ms":1497503206729,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":139,"total_packets_received":123,"total_packets_lost":6,"total_bytes_received":19680,"total_bytes_sent":22240,"jitter":20,"rtt":0,"audio_level_in":256,"audio_level_out":0,"mos":4.420009193714176} 06-15 10:36:47.772 12141-12381/com.twilio.voice.quickstart D/RTCStatsSample: {"timestamp":"2017-06-15T05:06:47.771Z","timestamp_ms":1497503207771,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":139,"total_packets_received":123,"total_packets_lost":6,"total_bytes_received":19680,"total_bytes_sent":22240,"jitter":20,"rtt":0,"audio_level_in":128,"audio_level_out":0,"mos":4.420009193714176} 06-15 10:36:48.808 12141-12381/com.twilio.voice.quickstart D/RTCStatsSample: {"timestamp":"2017-06-15T05:06:48.807Z","timestamp_ms":1497503208807,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":139,"total_packets_received":123,"total_packets_lost":6,"total_bytes_received":19680,"total_bytes_sent":22240,"jitter":20,"rtt":0,"audio_level_in":128,"audio_level_out":0,"mos":4.420009193714176} 06-15 10:36:49.855 12141-12381/com.twilio.voice.quickstart D/RTCStatsSample: {"timestamp":"2017-06-15T05:06:49.852Z","timestamp_ms":1497503209852,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":139,"total_packets_received":123,"total_packets_lost":6,"total_bytes_received":19680,"total_bytes_sent":22240,"jitter":20,"rtt":0,"audio_level_in":512,"audio_level_out":0,"mos":4.420009193714176} 06-15 10:36:50.900 12141-12381/com.twilio.voice.quickstart D/RTCStatsSample: {"timestamp":"2017-06-15T05:06:50.898Z","timestamp_ms":1497503210898,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":139,"total_packets_received":123,"total_packets_lost":6,"total_bytes_received":19680,"total_bytes_sent":22240,"jitter":20,"rtt":0,"audio_level_in":640,"audio_level_out":0,"mos":4.420009193714176} 06-15 10:36:50.955 12141-12381/com.twilio.voice.quickstart D/EventPublisher: Start publishing events to : https://eventgw.twilio.com/v3/EndpointMetrics {"name":"call-metric-event","group":"call-quality-stats","timestamp":"2017-06-15T05:06:50.904Z","level":"INFO","private":false,"payload_type":"application\/json","publisher":"twilio-voice-android","publisher_metadata":{"client_address":"FE80::84A9:1DFF:FE72:4E21","os_name":"android","os_version":"6.0","device_model":"Motorola Nexus 6","device_vendor":"motorola","device_type":"Nexus 6","cpu_architecture":"armeabi-v7a"},"payload":[{"timestamp":"2017-06-15T05:06:41.556Z","timestamp_ms":1497503201556,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":0,"total_packets_received":0,"total_packets_lost":0,"total_bytes_received":0,"total_bytes_sent":0,"jitter":0,"rtt":0,"audio_level_in":0,"audio_level_out":0,"mos":4.437552191410176},{"timestamp":"2017-06-15T05:06:42.596Z","timestamp_ms":1497503202596,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","packets_received":47,"packets_lost":1,"packets_lost_fraction":2,"total_packets_sent":51,"total_packets_received":47,"total_packets_lost":1,"total_bytes_received":7520,"total_bytes_sent":8160,"jitter":13,"rtt":0,"audio_level_in":128,"audio_level_out":10624,"mos":4.333274642930176},{"timestamp":"2017-06-15T05:06:43.638Z","timestamp_ms":1497503203638,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","packets_received":47,"packets_lost":5,"packets_lost_fraction":9,"total_packets_sent":105,"total_packets_received":94,"total_packets_lost":6,"total_bytes_received":15040,"total_bytes_sent":16800,"jitter":13,"rtt":0,"audio_level_in":0,"audio_level_out":10368,"mos":3.701487198250176},{"timestamp":"2017-06-15T05:06:44.680Z","timestamp_ms":1497503204680,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","packets_received":29,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":139,"total_packets_received":123,"total_packets_lost":6,"total_bytes_received":19680,"total_bytes_sent":22240,"jitter":20,"rtt":0,"audio_level_in":0,"audio_level_out":0,"mos":4.420009193714176},{"timestamp":"2017-06-15T05:06:45.713Z","timestamp_ms":1497503205713,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":139,"total_packets_received":123,"total_packets_lost":6,"total_bytes_received":19680,"total_bytes_sent":22240,"jitter":20,"rtt":0,"audio_level_in":256,"audio_level_out":0,"mos":4.420009193714176},{"timestamp":"2017-06-15T05:06:46.729Z","timestamp_ms":1497503206729,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":139,"total_packets_received":123,"total_packets_lost":6,"total_bytes_received":19680,"total_bytes_sent":22240,"jitter":20,"rtt":0,"audio_level_in":256,"audio_level_out":0,"mos":4.420009193714176},{"timestamp":"2017-06-15T05:06:47.771Z","timestamp_ms":1497503207771,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":139,"total_packets_received":123,"total_packets_lost":6,"total_bytes_received":19680,"total_bytes_sent":22240,"jitter":20,"rtt":0,"audio_level_in":128,"audio_level_out":0,"mos":4.420009193714176},{"timestamp":"2017-06-15T05:06:48.807Z","timestamp_ms":1497503208807,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","packets_received":0,"packets_lost":0,"packets_lost_fraction":0,"total_packets_sent":139,"total_packets_received":123,"total_packets_lost":6,"total_bytes_received":19680,"total_bytes_sent":22240,"jitter":20,"rtt":0,"audio_level_in":128,"audio_level_out":0,"mos":4.420009193714176},{"timestamp":"2017-06-15T05:06:49.852Z","timestamp_ms":1497503209852,"call_sid":"CA60fe23b0df055416d1736c3ef115f644","sdk_version":"2.0.0-beta12","p 06-15 10:36:50.965 12141-12636/com.twilio.voice.quickstart E/EventPublisher: java.net.UnknownHostException: Unable to resolve host "eventgw.twilio.com": No address associated with hostname 06-15 10:36:50.965 12141-12636/com.twilio.voice.quickstart E/EventPublisher: java.net.UnknownHostException: Unable to resolve host "eventgw.twilio.com": No address associated with hostname 06-15 10:36:51.490 12141-12141/com.twilio.voice.quickstart D/CallControlManager: Exceeded the connectivity threshold of 7000 seconds. Disconnecting calls. 06-15 10:36:51.490 12141-12141/com.twilio.voice.quickstart D/Call: Calling disconnect CONNECTED 06-15 10:36:51.491 12141-12342/com.twilio.voice.quickstart E/CallControlManager: received transport error: status: -1647345564 06-15 10:36:51.491 12141-12342/com.twilio.voice.quickstart D/CallControlManager: received DISCONNECTED 06-15 10:36:51.491 12141-12342/com.twilio.voice.quickstart D/RTCMonitor: Stopped publishing 06-15 10:36:51.492 12141-12342/com.twilio.voice.quickstart A/PJSIP: 10:36:51.492 pjsua_call.c .Failed to send end session message: Network is unreachable [status=120101] 06-15 10:36:51.492 12141-12342/com.twilio.voice.quickstart E/HangupCallCommand: Failed to hang up call com.twilio.voice.impl.session.SessionException: pjsua_call_hangup(): Network is unreachable at com.twilio.voice.impl.useragent.Call.hangup(Native Method) at com.twilio.voice.impl.useragent.Call.hangup(Call.java:98) at com.twilio.voice.HangupCallCommand.run(HangupCallCommand.java:24) at android.os.Handler.handleCallback(Handler.java:739) at android.os.Handler.dispatchMessage(Handler.java:95) at android.os.Looper.loop(Looper.java:148) at com.twilio.voice.CallCommandHandlerImpl.run(CallCommandHandlerImpl.java:48) 06-15 10:36:51.496 12141-12381/com.twilio.voice.quickstart D/RTCMonitor: java.lang.InterruptedException 06-15 10:36:51.552 12141-12141/com.twilio.voice.quickstart D/VoiceActivity: Disconnected 06-15 10:36:51.552 12141-12141/com.twilio.voice.quickstart E/VoiceActivity: Call Error: 31302, Transport error 06-15 10:36:51.560 12141-12141/com.twilio.voice.quickstart D/Call: Calling disconnect DISCONNECTED 06-15 10:36:51.568 12141-12141/com.twilio.voice.quickstart D/VoiceActivity: Disconnected 06-15 10:36:51.568 12141-12141/com.twilio.voice.quickstart E/VoiceActivity: Call Error: 31000, Generic error 06-15 10:36:51.571 12141-12141/com.twilio.voice.quickstart D/Call: Calling disconnect DISCONNECTED 06-15 10:36:58.194 12141-12141/com.twilio.voice.quickstart D/CallControlManager: got connectivity broadcast: haveConn=true, reason=null, isFailover=false 06-15 10:36:58.212 12141-12141/com.twilio.voice.quickstart D/CallControlManager: got wifi SSID: "IdeaMessenger306" 06-15 10:36:58.212 12141-12141/com.twilio.voice.quickstart D/CallControlManager: NET CHANGE EVENT: connected=true, wasConnected=false, netTypeChanged=true 06-15 10:36:58.212 12141-12141/com.twilio.voice.quickstart D/CallControlManager: switched networks to type 1 06-15 10:37:01.308 12141-12342/com.twilio.voice.quickstart W/AudioTrack: AUDIO_OUTPUT_FLAG_FAST denied by client; transfer 1, track 16000 Hz, output 48000 Hz 06-15 10:37:01.310 12141-12342/com.twilio.voice.quickstart W/AudioRecord: AUDIO_INPUT_FLAG_FAST denied by client; transfer 1, track 16000 Hz, primary 48000 Hz 06-15 10:37:01.597 12141-12342/com.twilio.voice.quickstart D/CallControlManager: onCallSDPCreated notification received 06-15 10:37:01.597 12141-12342/com.twilio.voice.quickstart E/CallControlManager: received transport error: status: -1647337372 06-15 10:37:01.597 12141-12342/com.twilio.voice.quickstart D/CallControlManager: received DISCONNECTED 06-15 10:37:01.603 12141-12141/com.twilio.voice.quickstart D/VoiceActivity: Disconnected 06-15 10:37:01.603 12141-12141/com.twilio.voice.quickstart E/VoiceActivity: Call Error: 31302, Transport error 06-15 10:37:01.606 12141-12141/com.twilio.voice.quickstart D/Call: Calling disconnect DISCONNECTED 06-15 10:37:01.612 12141-12141/com.twilio.voice.quickstart D/VoiceActivity: Disconnected 06-15 10:37:01.613 12141-12141/com.twilio.voice.quickstart E/VoiceActivity: Call Error: 31000, Generic error 06-15 10:37:01.615 12141-12141/com.twilio.voice.quickstart D/Call: Calling disconnect DISCONNECTED 06-15 10:37:06.931 12141-12342/com.twilio.voice.quickstart W/AudioTrack: AUDIO_OUTPUT_FLAG_FAST denied by client; transfer 1, track 16000 Hz, output 48000 Hz 06-15 10:37:06.932 12141-12342/com.twilio.voice.quickstart W/AudioRecord: AUDIO_INPUT_FLAG_FAST denied by client; transfer 1, track 16000 Hz, primary 48000 Hz 06-15 10:37:07.169 12141-12342/com.twilio.voice.quickstart D/CallControlManager: onCallSDPCreated notification received 06-15 10:37:07.170 12141-12342/com.twilio.voice.quickstart E/CallControlManager: received transport error: status: -1647316892 06-15 10:37:07.171 12141-12342/com.twilio.voice.quickstart D/CallControlManager: received DISCONNECTED 06-15 10:37:07.181 12141-12141/com.twilio.voice.quickstart D/VoiceActivity: Disconnected 06-15 10:37:07.181 12141-12141/com.twilio.voice.quickstart E/VoiceActivity: Call Error: 31302, Transport error 06-15 10:37:07.184 12141-12141/com.twilio.voice.quickstart D/Call: Calling disconnect DISCONNECTED 06-15 10:37:07.193 12141-12141/com.twilio.voice.quickstart D/VoiceActivity: Disconnected 06-15 10:37:07.193 12141-12141/com.twilio.voice.quickstart E/VoiceActivity: Call Error: 31000, Generic error 06-15 10:37:07.196 12141-12141/com.twilio.voice.quickstart D/Call: Calling disconnect DISCONNECTED 06-15 10:37:10.183 12141-12908/com.twilio.voice.quickstart E/libOpenSLES: frameworks/wilhelm/src/itf/IBufferQueue.c:57: pthread_mutex_lock_timeout_np returned 110 06-15 10:37:10.203 12141-12908/com.twilio.voice.quickstart E/libOpenSLES: frameworks/wilhelm/src/itf/IBufferQueue.c:57: pthread_mutex_lock_timeout_np returned 110 06-15 10:37:10.233 12141-12908/com.twilio.voice.quickstart E/libOpenSLES: frameworks/wilhelm/src/itf/IBufferQueue.c:57: pthread_mutex_lock_timeout_np returned 110 06-15 10:37:19.001 12141-12342/com.twilio.voice.quickstart W/AudioTrack: AUDIO_OUTPUT_FLAG_FAST denied by client; transfer 1, track 16000 Hz, output 48000 Hz 06-15 10:37:19.003 12141-12342/com.twilio.voice.quickstart W/AudioRecord: AUDIO_INPUT_FLAG_FAST denied by client; transfer 1, track 16000 Hz, primary 48000 Hz 06-15 10:37:19.304 12141-12342/com.twilio.voice.quickstart D/CallControlManager: onCallSDPCreated notification received 06-15 10:37:19.304 12141-12342/com.twilio.voice.quickstart E/CallControlManager: received transport error: status: -1728847772 06-15 10:37:19.304 12141-12342/com.twilio.voice.quickstart D/CallControlManager: received DISCONNECTED 06-15 10:37:19.310 12141-12141/com.twilio.voice.quickstart D/VoiceActivity: Disconnected 06-15 10:37:19.311 12141-12141/com.twilio.voice.quickstart E/VoiceActivity: Call Error: 31302, Transport error 06-15 10:37:19.313 12141-12141/com.twilio.voice.quickstart D/Call: Calling disconnect DISCONNECTED 06-15 10:37:19.322 12141-12141/com.twilio.voice.quickstart D/VoiceActivity: Disconnected 06-15 10:37:19.323 12141-12141/com.twilio.voice.quickstart E/VoiceActivity: Call Error: 31000, Generic error 06-15 10:37:19.325 12141-12141/com.twilio.voice.quickstart D/Call: Calling disconnect DISCONNECTED 06-15 10:37:22.318 12141-13086/com.twilio.voice.quickstart E/libOpenSLES: frameworks/wilhelm/src/itf/IBufferQueue.c:57: pthread_mutex_lock_timeout_np returned 110 06-15 10:37:22.339 12141-13086/com.twilio.voice.quickstart E/libOpenSLES: frameworks/wilhelm/src/itf/IBufferQueue.c:57: pthread_mutex_lock_timeout_np returned 110 06-15 10:37:22.371 12141-13086/com.twilio.voice.quickstart E/libOpenSLES: frameworks/wilhelm/src/itf/IBufferQueue.c:57: pthread_mutex_lock_timeout_np returned 110 06-15 10:37:22.413 12141-13086/com.twilio.voice.quickstart E/libOpenSLES: frameworks/wilhelm/src/itf/IBufferQueue.c:57: pthread_mutex_lock_timeout_np returned 110

Please look at logs, Just after network re-connection I have tried 3-4 times but call didn't connect.

Please check and response.

aashu09 commented 7 years ago

In the above scenario, click on call button just after reconnection of network and hit continously untill call will not be connected successfully,call will be connect after 20-30 hit on call button.

Please check the scenario. I am also attaching a video of issue with this comment, please see and resolve it as soon as possible.

Thanks Voice issue video.zip

aashu09 commented 7 years ago

Please response.

aashu09 commented 7 years ago

Have you reproduce the issue?

Please response

aashu09 commented 7 years ago

Please understand the situation. Its an urgent issue which I have to fix ASAP. I have been waiting for your response, so please at-least response.

aashu09 commented 7 years ago

Have you looked the issue and video?

Can you please response?

idelgado commented 7 years ago

We're currently testing a fix for this issue.

We appreciate your feedback and logs but in general commenting repeatedly won't lead to a faster resolution.

aashu09 commented 7 years ago

I am happy to hear about testing on fix.

I am repeatedly commenting you just because of no response from your end.

Thanks for response and heartly waiting for the fix. On 16-Jun-2017 8:03 PM, "Iñaqui" notifications@github.com wrote:

We're currently testing a fix for this issue.

We appreciate your feedback and logs but in general commenting repeatedly won't lead to a faster resolution.

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/twilio/voice-quickstart-android/issues/60#issuecomment-309042178, or mute the thread https://github.com/notifications/unsubscribe-auth/APc7-PilpyoDcN7Cg1U-94oMPkB8EL3wks5sEpJOgaJpZM4NyhWi .

idelgado commented 7 years ago

Cool. We will follow up shortly. Thanks!

aashu09 commented 7 years ago

Well, its very nice to listen that you are working on issue. Appreciate your work and support.

Thanks...just waiting for a fix On 16-Jun-2017 8:57 PM, "Iñaqui" notifications@github.com wrote:

Cool. We will follow up shortly. Thanks!

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/twilio/voice-quickstart-android/issues/60#issuecomment-309056686, or mute the thread https://github.com/notifications/unsubscribe-auth/APc7-IIBH_327337i1R6VAw-7ulXvq0Gks5sEp7mgaJpZM4NyhWi .

idelgado commented 7 years ago

@aashu09 We are adding test coverage for network handover scenarios in our test environment. We are still working to fix the issue and will provide another update soon.

aashu09 commented 7 years ago

Ok, Now when I see on Changelog form of Twilio then its showing beta12 to latest version. I think you didn't provide updated sdk to implement in application as you are still working on fix.

I am waiting for the update because I have released my build to client and written above issue as "Known issue", so they are also waiting to resolve it.

appreciate your work and thanks for response.

kbagchiGWC commented 7 years ago

@aashu09 - The Voice SDK does not check network connectivity before initiating a call. The application has to guarantee the network availability before making a call. In your scenario - the connectivity gets lost when the wifi is switched off, then it gets turned back on. It takes some time for a network to be connected and available after it gets switched on. From the attached video, it looks like your application is initiating a call without waiting for the network to be available and receives transport error.

Referring to your comment - In the above scenario, click on call button just after reconnection of network and hit continuously until call will not be connected successfully,call will be connect after 20-30 hit on call button. - Instead of clicking the button without the network, you can disable the button until the network is available. You can achieve this by implementing a BroadcastReceiver to receive android.net.conn.CONNECTIVITY_CHANGE"notifications to check the network availability before initiating a call.

aashu09 commented 7 years ago

Yes you are right that I should check network connectivity in my own app but I have already implemented a code to check network connectivity and If any user hit a call button without network then app shows a Toast message for "No internet connection". In above video, network connected successfully before hit the call button.

I have already implemented that scenario only I have fired this issue to you because after re-connection of network call is not connecting.

Please check again and If you need some more information then I am happy to give you because I just need to resolve this issue.

Please look again.

Thanks

aashu09 commented 7 years ago

@idelgado said that you are working on a fix but now you saying another thing.

Please understand my situation too and try to fix ASAP.

Thanks

idelgado commented 7 years ago

@aashu09 Unfortunately @kbagchiGWC was not able to reproduce the issue described above. There was another network handover issue that was found that we thought was related but we don't believe that is the case after further investigation.

We're working to reproduce the issue on other devices. Based on the video I agree, you should be able to successfully make a call once the wifi reconnects.

aashu09 commented 7 years ago

@kbagchiGWC and @idelgado you can see new attached video with this comment. In this, you can see that network is properly connected with device however call is not able to connect.

This video is made with Voice quickstart SDK.

See this and response.

Voice video with network.zip

aashu09 commented 7 years ago

@idelgado please again look my new video and try to reproduce the issue.

I know you are trying your best but please see in video, the issue is coming.

Thanks

aashu09 commented 7 years ago

Have you looked the new video ?

Please response.

idelgado commented 7 years ago

@aashu09 we are able to reproduce the issue now and are investigating a fix.

aashu09 commented 7 years ago

Oh thank you so much. Now please fix it soon. On 21-Jun-2017 10:23 PM, "Iñaqui" notifications@github.com wrote:

@aashu09 https://github.com/aashu09 we are able to reproduce the issue now and are investigating a fix.

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/twilio/voice-quickstart-android/issues/60#issuecomment-310139952, or mute the thread https://github.com/notifications/unsubscribe-auth/APc7-I0bc2_7gqTRTmBitfroUaPFJWRUks5sGUptgaJpZM4NyhWi .

kbagchiGWC commented 7 years ago

@aashu09 we were able to identify the root cause and fix the issue you have reported. Once the fix is approved by our QA team, we will make it available. I will keep you posted.

aashu09 commented 7 years ago

Really thankful to you. Please update me when you will fix it. On 22-Jun-2017 10:14 AM, "kbagchiGWC" notifications@github.com wrote:

@aashu09 https://github.com/aashu09 we were able identify the root cause and fix the issue you have reported. Once the fix is approved by our QA team, we will make it available. I will keep you posted.

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/twilio/voice-quickstart-android/issues/60#issuecomment-310273972, or mute the thread https://github.com/notifications/unsubscribe-auth/APc7-FyFa_i7RIa4vjzHdYeZLjD0xJ7tks5sGfEegaJpZM4NyhWi .

kbagchiGWC commented 7 years ago

@aashu09 We have identified another network handoff corner case that we are working through. Once this is fixed and passed by our QA team - we will have an official beta release available. In the mean time, can you please test the release candidate attached here and let us know if it fixed your issue ? Rename the file to twilio-voice-android-2.0.0-rc71.aar after you download. twilio-voice-android-2.0.0-rc71.zip

aashu09 commented 7 years ago

Yes, definitely. You can mail me on aashu.sharma.09.90@gmail.com and kapil@callproof.com

Please email on above both emails. On 23-Jun-2017 1:37 AM, "kbagchiGWC" notifications@github.com wrote:

@aashu09 https://github.com/aashu09 can you share your e-mail address? We can share the release candidate with you to make sure your issue is fixed. We have identified another network handoff corner case that we are working through. Once this is fixed and passed by our QA team - we will have an official beta release available.

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/twilio/voice-quickstart-android/issues/60#issuecomment-310488221, or mute the thread https://github.com/notifications/unsubscribe-auth/APc7-J4sLp_iIrm8mg2gxAP_IuB3Dcgtks5sGsmAgaJpZM4NyhWi .

kbagchiGWC commented 7 years ago

@aashu09 I have attached the release candidate above ^. Please try this out and let me know if this fixed the issue.

aashu09 commented 7 years ago

Okay, i will try it and inform you. Thank you for helping me.

kapilojha commented 7 years ago

@kbagchiGWC I have tried twilio-voice-android-2.0.0-rc71.aar by steps you said but application is crashing when I initiate the call means when I click on Call button to initiate call its crashing and also when I need to reopen the application after crash it never allow me to open the app and immediately crashing.

Please look at the DEBUG logs I am providing below to test on https://github.com/twilio/voice-quickstart-android and also see attached video.

crash_on_call.zip

06-26 10:54:01.012 24708-24708/? E/Zygote: MountEmulatedStorage() 06-26 10:54:01.012 24708-24708/? E/Zygote: v2 06-26 10:54:01.012 24708-24708/? I/libpersona: KNOX_SDCARD checking this for 10744 06-26 10:54:01.012 24708-24708/? I/libpersona: KNOX_SDCARD not a persona 06-26 10:54:01.017 24708-24708/? I/SELinux: Function: selinux_compare_spd_ram, SPD-policy is existed. and_ver=SEPF_SM-T815Y_5.0.2-1 ver=51 06-26 10:54:01.017 24708-24708/? I/SELinux: Function: selinux_compare_spd_ram , priority [1] , priority version is VE=SEPF_SM-T815Y_5.0.2-1_0051 06-26 10:54:01.017 24708-24708/? E/SELinux: [DEBUG] get_category: variable seinfo: default sensitivity: NULL, cateogry: NULL 06-26 10:54:01.017 24708-24708/? I/art: Late-enabling -Xcheck:jni 06-26 10:54:01.032 24708-24708/? D/TimaKeyStoreProvider: TimaSignature is unavailable 06-26 10:54:01.032 24708-24708/? D/ActivityThread: Added TimaKeyStore provider 06-26 10:54:01.077 24708-24708/com.twilio.voice.quickstart D/FirebaseApp: com.google.firebase.auth.FirebaseAuth is not linked. Skipping initialization. 06-26 10:54:01.087 24708-24708/com.twilio.voice.quickstart W/InstanceID/Rpc: Found 10009 06-26 10:54:01.122 24708-24708/com.twilio.voice.quickstart D/FirebaseApp: com.google.firebase.crash.FirebaseCrash is not linked. Skipping initialization. 06-26 10:54:01.142 24708-24708/com.twilio.voice.quickstart I/FA: App measurement is starting up, version: 10084 06-26 10:54:01.142 24708-24708/com.twilio.voice.quickstart I/FA: To enable debug logging run: adb shell setprop log.tag.FA VERBOSE 06-26 10:54:01.142 24708-24708/com.twilio.voice.quickstart D/FA: Debug-level message logging enabled 06-26 10:54:01.142 24708-24708/com.twilio.voice.quickstart D/FA: AppMeasurement singleton hash: 230558010 06-26 10:54:01.147 24708-24749/com.twilio.voice.quickstart W/System.err: remove failed: ENOENT (No such file or directory) : /data/data/com.twilio.voice.quickstart/shared_prefs/com.google.android.gms.measurement.prefs.xml.bak 06-26 10:54:01.157 24708-24708/com.twilio.voice.quickstart I/FA: To enable faster debug mode event logging run: adb shell setprop debug.firebase.analytics.app com.twilio.voice.quickstart 06-26 10:54:01.197 24708-24708/com.twilio.voice.quickstart I/FirebaseInitProvider: FirebaseApp initialization successful 06-26 10:54:01.257 24708-24708/com.twilio.voice.quickstart W/art: Before Android 4.1, method android.graphics.PorterDuffColorFilter android.support.graphics.drawable.VectorDrawableCompat.updateTintFilter(android.graphics.PorterDuffColorFilter, android.content.res.ColorStateList, android.graphics.PorterDuff$Mode) would have incorrectly overridden the package-private method in android.graphics.drawable.Drawable 06-26 10:54:01.282 24708-24708/com.twilio.voice.quickstart D/PhoneWindow: FMB installDecor mIsFloating : false 06-26 10:54:01.282 24708-24708/com.twilio.voice.quickstart D/PhoneWindow: FMB installDecor flags : -2139029248 06-26 10:54:01.412 24708-24708/com.twilio.voice.quickstart E/SRIB_DCS: Returning true for title=com.twilio.voice.quickstart/com.twilio.voice.quickstart.VoiceActivity 06-26 10:54:01.417 24708-24839/com.twilio.voice.quickstart D/OpenGLRenderer: Render dirty regions requested: true 06-26 10:54:01.442 24708-24708/com.twilio.voice.quickstart D/PhoneWindow: FMB isFloatingMenuEnabled mFloatingMenuBtn : null 06-26 10:54:01.442 24708-24708/com.twilio.voice.quickstart D/PhoneWindow: FMB isFloatingMenuEnabled return false 06-26 10:54:01.472 24708-24708/com.twilio.voice.quickstart D/SRIB_DCS: log_dcs ThreadedRenderer::initialize entered! 06-26 10:54:01.477 24708-24839/com.twilio.voice.quickstart I/OpenGLRenderer: Initialized EGL, version 1.4 06-26 10:54:01.477 24708-24839/com.twilio.voice.quickstart D/OpenGLRenderer: SRIB_DCS Render thread getDcsFlag flag=6619250 06-26 10:54:01.477 24708-24839/com.twilio.voice.quickstart D/libEGL: SRIB_DCS popluate_dcs_list 06-26 10:54:01.477 24708-24839/com.twilio.voice.quickstart D/libEGL: SRIB_DCS is_app_eligible 06-26 10:54:01.477 24708-24839/com.twilio.voice.quickstart D/libEGL: SRIB_DCS NOT Found in dcs list!!! proc=com.twilio.voice.quickstart 06-26 10:54:01.477 24708-24839/com.twilio.voice.quickstart D/libEGL: SRIB_DCS is_app_eligible 06-26 10:54:01.477 24708-24839/com.twilio.voice.quickstart D/libEGL: SRIB_DCS NOT Found in dcs list!!! proc=com.twilio.voice.quickstart 06-26 10:54:01.482 24708-24839/com.twilio.voice.quickstart I/OpenGLRenderer: HWUI protection enabled for context , &this =0xa94ec920 ,&mEglDisplay = 1 , &mEglConfig = -1453821680 06-26 10:54:01.482 24708-24839/com.twilio.voice.quickstart D/libEGL: SRIB_DCS is_app_eligible 06-26 10:54:01.482 24708-24839/com.twilio.voice.quickstart D/libEGL: SRIB_DCS NOT Found in dcs list!!! proc=com.twilio.voice.quickstart 06-26 10:54:01.487 24708-24839/com.twilio.voice.quickstart D/OpenGLRenderer: Get maximum texture size. GL_MAX_TEXTURE_SIZE is 8192 06-26 10:54:01.487 24708-24839/com.twilio.voice.quickstart D/OpenGLRenderer: Enabling debug mode 0 06-26 10:54:01.487 24708-24839/com.twilio.voice.quickstart D/libEGL: SRIB_DCS is_app_eligible 06-26 10:54:01.487 24708-24839/com.twilio.voice.quickstart D/libEGL: SRIB_DCS NOT Found in dcs list!!! proc=com.twilio.voice.quickstart 06-26 10:54:01.487 24708-24839/com.twilio.voice.quickstart D/mali_winsys: new_window_surface returns 0x3000, [1536x2048]-format:1 06-26 10:54:01.487 24708-24839/com.twilio.voice.quickstart D/OpenGLRenderer: SRIB_DCS CanvasContext getDcsFlag flag=0 06-26 10:54:01.487 24708-24839/com.twilio.voice.quickstart D/OpenGLRenderer: SRIB_DCS OpenGLRenderer setDcsFlag flag=0 06-26 10:54:01.512 24708-24839/com.twilio.voice.quickstart D/OpenGLRenderer: SRIB_DCS OpenGLRenderer getDcsFlag flag=0 06-26 10:54:01.737 24708-24782/com.twilio.voice.quickstart D/FA: Connected to remote service 06-26 10:54:01.787 24708-24708/com.twilio.voice.quickstart I/Timeline: Timeline: Activity_idle id: android.os.BinderProxy@33a4926d time:1656398 06-26 10:54:01.787 24708-24708/com.twilio.voice.quickstart I/Timeline: Timeline: Activity_idle id: android.os.BinderProxy@33a4926d time:1656398 06-26 10:54:03.072 24708-24847/com.twilio.voice.quickstart W/System.err: remove failed: ENOENT (No such file or directory) : /data/data/com.twilio.voice.quickstart/shared_prefs/com.google.android.gms.appid.xml.bak 06-26 10:54:13.057 24708-24782/com.twilio.voice.quickstart I/FA: Tag Manager is not found and thus will not be used 06-26 10:54:13.062 24708-24782/com.twilio.voice.quickstart D/FA: Logging event (FE): _s, Bundle[{_o=auto, _sc=VoiceActivity, _si=1974508590921571580}] 06-26 10:54:13.152 24708-24782/com.twilio.voice.quickstart D/FA: Connected to remote service 06-26 10:55:03.227 24708-24708/com.twilio.voice.quickstart D/ViewRootImpl: ViewPostImeInputStage ACTION_DOWN 06-26 10:55:03.282 24708-24708/com.twilio.voice.quickstart I/VoiceActivity: *** called 06-26 10:55:03.282 24708-24708/com.twilio.voice.quickstart D/AndroidRuntime: Shutting down VM 06-26 10:55:03.287 24708-24708/com.twilio.voice.quickstart E/AndroidRuntime: FATAL EXCEPTION: main Process: com.twilio.voice.quickstart, PID: 24708 java.lang.NoClassDefFoundError: Failed resolution of: Lcom/getkeepsafe/relinker/ReLinker; at com.twilio.voice.Voice.loadLibrary(Voice.java:282) at com.twilio.voice.Voice.call(Voice.java:133) at com.twilio.voice.quickstart.VoiceActivity$7.onClick(VoiceActivity.java:427) at android.view.View.performClick(View.java:5210) at android.view.View$PerformClick.run(View.java:20976) at android.os.Handler.handleCallback(Handler.java:739) at android.os.Handler.dispatchMessage(Handler.java:95) at android.os.Looper.loop(Looper.java:145) at android.app.ActivityThread.main(ActivityThread.java:6145) at java.lang.reflect.Method.invoke(Native Method) at java.lang.reflect.Method.invoke(Method.java:372) at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:1399) at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:1194) Caused by: java.lang.ClassNotFoundException: Didn't find class "com.getkeepsafe.relinker.ReLinker" on path: DexPathList[[zip file "/data/app/com.twilio.voice.quickstart-1/base.apk"],nativeLibraryDirectories=[/data/app/com.twilio.voice.quickstart-1/lib/arm, /vendor/lib, /system/lib]] at dalvik.system.BaseDexClassLoader.findClass(BaseDexClassLoader.java:56) at java.lang.ClassLoader.loadClass(ClassLoader.java:511) at java.lang.ClassLoader.loadClass(ClassLoader.java:469) at com.twilio.voice.Voice.loadLibrary(Voice.java:282)  at com.twilio.voice.Voice.call(Voice.java:133)  at com.twilio.voice.quickstart.VoiceActivity$7.onClick(VoiceActivity.java:427)  at android.view.View.performClick(View.java:5210)  at android.view.View$PerformClick.run(View.java:20976)  at android.os.Handler.handleCallback(Handler.java:739)  at android.os.Handler.dispatchMessage(Handler.java:95)  at android.os.Looper.loop(Looper.java:145)  at android.app.ActivityThread.main(ActivityThread.java:6145)  at java.lang.reflect.Method.invoke(Native Method)  at java.lang.reflect.Method.invoke(Method.java:372)  at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:1399)  at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:1194)  Suppressed: java.lang.ClassNotFoundException: com.getkeepsafe.relinker.ReLinker at java.lang.Class.classForName(Native Method) at java.lang.BootClassLoader.findClass(ClassLoader.java:781) at java.lang.BootClassLoader.loadClass(ClassLoader.java:841) at java.lang.ClassLoader.loadClass(ClassLoader.java:504) ... 14 more Caused by: java.lang.NoClassDefFoundError: Class not found using the boot class loader; no stack available

Please try to resolve all issues so that I could push my app to play store, I am only waiting for your fixes.

Thanks

sashjakk commented 7 years ago

@kapilojha Hey, according to your stack trace you are missing one vital dependency - ReLinker. You can see that Voice SDK uses it from bintray in this POM file.

To resolve the issue just add compile 'com.getkeepsafe.relinker:relinker:1.2.2'.

Pleas note that dependencies are not resolved automatically for you when consuming libraries from project directories.

aashu09 commented 7 years ago

@sashjakk when I add compile 'com.getkeepsafe.relinker:relinker:1.2.2', application is working without any crash but as I see before I never used this dependency with Voice SDK. I don't know why this time its needed it ?

@kbagchiGWC I have tested "twilio-voice-android-2.0.0-rc71.aar" as said by you and Its working ok ok means in the same network scenario, initially 1-2 times it does not connect call but after that it started to connect call. Overall Its very much better than previous version but need some more testing and fixes.

Please officially release it as soon as possible so that I could use it in my application whom I am going to push on play store.

Thanks

aashu09 commented 7 years ago

Please response.

kbagchiGWC commented 7 years ago

@aashu09 we are working on an official release. Will let you know when it is available.