SIP call fails to go through (hangs on "Connecting")
Tested on: Jami 20200107-01 (F-Droid). Device: HTC U Play (Android 6)
Reproduction: Unknown, but repeatable once broken.
In the logcat below, I attempt to call my SIP provider's echo service (4443), but the call doesn't succeed and gets stuck upon "Connecting...".
logcat:
01-12 13:37:07.930 2594 3351 I ActivityManager: START u0 {act=cx.ring.action.call cmp=cx.ring/.client.CallActivity (has extras)} from uid 10209 pid 16630 on display 0
01-12 13:37:07.932 430 1264 I BufferQueueProducer: [cx.ring/cx.ring.client.ConversationActivity](this:0x7f738dc000,id:3991,api:1,p:16630,c:430) queueBuffer: fps=1.22 dur=10691.02 max=10231.32 min=10.98
01-12 13:37:07.968 16630 16630 D SurfaceView: checkSurfaceViewlLogProperty get invalid command
01-12 13:37:07.997 16630 16646 I CallService: placeCall() thread running... 4443 audioOnly: true
01-12 13:37:07.997 16630 16646 D libdring: try outgoing call to '4443' with account '816c775d368cba'
01-12 13:37:07.997 16630 16646 D libdring: [Account 816c775d368cba] Calling SIP peer 4443
01-12 13:37:07.997 16630 16646 D libdring: Ringbuffer created with id '4066181916576228'
01-12 13:37:07.997 16630 16646 D libdring: UserAgent: New registered account call to 4443
01-12 13:37:07.997 16630 16646 D libdring: [call:4066181916576228] create media ICE transport
01-12 13:37:07.997 16630 16646 D libdring: [ice] added host stun server
01-12 13:37:07.997 16630 16646 D libdring: [ice] added host stun server
01-12 13:37:08.001 2594 3509 D PMS : acquireWakeLockInternal: lock=16697229, flags=0x3000000a, tag="ring:callLock", ws=null, uid=10209, pid=16630
01-12 13:37:08.001 2594 3509 D PMS : acquireWL(1561d42): SCREEN_BRIGHT_WAKE_LOCK ACQUIRE_CAUSES_WAKEUP ON_AFTER_RELEASE ring:callLock 0x3000000a 16630 10209 null
01-12 13:37:08.002 2594 3509 D PMN : onWakeLockAcquired: flags=805306378, tag="ring:callLock", packageName=cx.ring, ownerUid=10209, ownerPid=16630, workSource=null
01-12 13:37:08.004 16630 16630 I SensorManager: registerListenerImpl: listener = android.view.OrientationEventListener$SensorEventListenerImpl@759414c, sensor = {Sensor name="Accelerometer Sensor", vendor="hTC Corp.", version=1, type=1, maxRange=39.2266, resolution=0.01, power=0.17, minDelay=10000}, delay = 200000, handler = null, name = cx.ring
01-12 13:37:08.006 16630 16646 D libdring: [ice:0x7f584b8000] initialization success
01-12 13:37:08.006 16630 16646 D libdring: ICE as master
01-12 13:37:08.007 16630 16646 D libdring: [ice:0x7f584b8000] (local) ufrag=4c673719, pwd=3404bf1e0283fece5d4c7a14
01-12 13:37:08.008 16630 16646 D libdring: SDP: Local SDP Session:
01-12 13:37:08.008 16630 16646 D libdring: v=0
01-12 13:37:08.008 16630 16646 D libdring: o=localhost 3787843028 0 IN IP4 192.168.10.203
01-12 13:37:08.008 16630 16646 D libdring: s=Jami Daemon
01-12 13:37:08.008 16630 16646 D libdring: c=IN IP4 192.168.10.203
01-12 13:37:08.008 16630 16646 D libdring: t=0 0
01-12 13:37:08.008 16630 16646 D libdring: m=audio 32748 RTP/AVP 104 9 112 111 110 8 0 101
01-12 13:37:08.008 16630 16646 D libdring: a=rtpmap:104 opus/48000/2
01-12 13:37:08.008 16630 16646 D libdring: a=rtpmap:9 G722/8000
01-12 13:37:08.008 16630 16646 D libdring: a=rtpmap:112 speex/32000
01-12 13:37:08.008 16630 16646 D libdring: a=rtpmap:111 speex/16000
01-12 13:37:08.008 16630 16646 D libdring: a=rtpmap:110 speex/8000
01-12 13:37:08.008 16630 16646 D libdring: a=rtpmap:8 PCMA/8000
01-12 13:37:08.008 16630 16646 D libdring: a=rtpmap:0 PCMU/8000
01-12 13:37:08.008 16630 16646 D libdring: a=rtpmap:101 telephone-event/8000
01-12 13:37:08.008 16630 16646 D libdring: a=fmtp:101 0-15
01-12 13:37:08.008 16630 16646 D libdring: a=rtcp:32749 IN IP4 192.168.10.203
01-12 13:37:08.008 16630 16646 D libdring: a=sendrecv
01-12 13:37:08.008 16630 16646 D libdring: ----- Switch current call id to '4066181916576228' -----
01-12 13:37:08.008 16630 16646 W libdring: [call:4066181916576228] video muting true
01-12 13:37:08.008 16630 16630 V PhoneWindow: DecorView setVisiblity: visibility = 4 ,Parent =null, this =com.android.internal.policy.PhoneWindow$DecorView{745f2ac I.E...... R.....ID 0,0-0,0}
01-12 13:37:08.008 16630 16646 W CallService: getConfUpdates 4066181916576228
01-12 13:37:08.008 16630 16646 W CallService: getConfUpdates filter 4066181916576228 1 (tracked 4066181916576228 1)
01-12 13:37:08.008 16630 16650 W libdring: [call:4066181916576228] fill SDP with ICE transport 0x7f64eb9678
01-12 13:37:08.008 16630 16646 W CallService: getConfCallUpdates 4066181916576228
01-12 13:37:08.009 16630 16650 E libdring: addIceCandidates failed: cannot access media#1 (may be deactivated)
01-12 13:37:08.009 16630 16650 E libdring: addIceCandidates failed: cannot access media#1 (may be deactivated)
01-12 13:37:08.009 16630 16650 D libdring: contact header: <sip:179835_sfl@72.10.148.66:34526> / <sip:179835_sfl@montreal6.voip.ms> -> <sip:4443@montreal6.voip.ms>
01-12 13:37:08.009 16630 21540 D libdring: Registered thread 0x7f488a5008 (0x40F6)
01-12 13:37:08.009 16630 16630 D WindowClient: Add to mViews: com.android.internal.policy.PhoneWindow$DecorView{745f2ac I.E...... R.....ID 0,0-0,0}, this = android.view.WindowManagerGlobal@44d2983
01-12 13:37:08.010 16630 18983 D ConversationFacade: onConfStateChange Thread id: 6995
01-12 13:37:08.010 16630 16650 D libc-netbsd: [getaddrinfo]: caller process=cx.ring
01-12 13:37:08.010 16630 16650 D libc-netbsd: getaddrinfo( app_uid:10209
01-12 13:37:08.010 16630 16650 D libc-netbsd: getaddrinfo() uid prop:
01-12 13:37:08.010 16630 16650 D libc-netbsd: getaddrinfo() getuid():10209
01-12 13:37:08.010 16630 16650 D libc-netbsd: [getaddrinfo]: mtk ai_addrlen=0; ai_canonname=(null); ai_flags=0; ai_family=0
01-12 13:37:08.010 16630 16650 D libc-netbsd: [NET] android_getaddrinfo_proxy get netid:0
01-12 13:37:08.014 16630 16630 D ViewRootImpl: hardware acceleration is enabled, this = android.view.ViewRootImpl@de85102
01-12 13:37:08.017 16630 16630 V PhoneWindow: DecorView setVisiblity: visibility = 0 ,Parent =android.view.ViewRootImpl@de85102, this =com.android.internal.policy.PhoneWindow$DecorView{745f2ac V.E...... R.....ID 0,0-0,0}
01-12 13:37:08.019 16630 16630 I SurfaceView: updateWindow -- onWindowVisibilityChanged, visibility = 0, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.025 430 430 I BufferQueueConsumer: [cx.ring/cx.ring.client.CallActivity](this:0x7f72bc7000,id:3992,api:0,p:-1,c:430) setConsumerName: cx.ring/cx.ring.client.CallActivity
01-12 13:37:08.042 16630 16630 E RecyclerView: No adapter attached; skipping layout
01-12 13:37:08.042 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.045 430 1264 I BufferQueueProducer: [cx.ring/cx.ring.client.CallActivity](this:0x7f72bc7000,id:3992,api:1,p:16630,c:430) connect(P): api=1 producer=(16630:cx.ring) producerControlledByApp=true
01-12 13:37:08.045 16630 18925 W libEGL : [ANDROID_RECORDABLE] format: 1
01-12 13:37:08.046 16630 18925 D mali_winsys: new_window_surface returns 0x3000
01-12 13:37:08.048 16630 16630 W CallPresenter: mPendingSubject onNext 0 1
01-12 13:37:08.049 16630 16630 W CallPresenter: flatMapObservable 1
01-12 13:37:08.049 16630 16630 W CallPresenter: mPendingSubject onNext 0 1
01-12 13:37:08.049 16630 16630 W CallPresenter: flatMapObservable 1
01-12 13:37:08.049 16630 16630 W CallPresenter: confUpdate 4066181916576228
01-12 13:37:08.049 16630 16630 W CallFragment: initOutGoingCallDisplay
01-12 13:37:08.052 16630 16650 D libc-netbsd: [NET] android_getaddrinfo_proxy-, success
01-12 13:37:08.052 16630 16650 D libc-netbsd: getaddrinfo: montreal6.voip.ms get result from proxy gai_error = 0
01-12 13:37:08.053 16630 16650 D libdring: [call:4066181916576228] INVITE@0x7f49e7e828 state changed to 1 (CALLING): cause=0, tsx@0x7f4b1cd0a8 status 0 (Default status message)
01-12 13:37:08.053 16630 16650 D libdring: [call:4066181916576228] state change 0/1, cnx 0/2, code 0
01-12 13:37:08.053 16630 16650 D libdring: [call:4066181916576228] emit client call state change CONNECTING, code 0
01-12 13:37:08.054 16630 16650 D CallService: call state changed: 4066181916576228, CONNECTING, 0
01-12 13:37:08.055 16630 16650 D ConversationFacade: onCallStateChange Thread id: 7458
01-12 13:37:08.055 16630 16650 D HardwareServiceImpl: updateAudioState: Call state updated to CONNECTING Call is incoming: false Call is video: false
01-12 13:37:08.055 16630 16650 D BluetoothWrapper: registerScoUpdate: Register BT media receiver
01-12 13:37:08.058 16630 16650 D BluetoothWrapper: registerBtConnection: Register BT connection
01-12 13:37:08.060 16630 16650 D BluetoothHeadset: Register mBluetoothStateChangeCallback = android.bluetooth.BluetoothHeadset$1@35afe5a
01-12 13:37:08.063 16630 16650 D Ringer : stopRing: called...
01-12 13:37:08.063 2594 3528 V AudioService: setMode(mode=0, callingPackage=cx.ring)
01-12 13:37:08.064 2594 3528 V AudioService: setModeInt(mode=0, pid=16630, caller=cx.ring)
01-12 13:37:08.067 16630 16630 E RecyclerView: No adapter attached; skipping layout
01-12 13:37:08.067 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.068 16630 16630 I SurfaceView: updateWindow -- OnScrollChangedListener, this = android.view.SurfaceView$2@a248d68
01-12 13:37:08.073 2594 3528 D AudioService: setStreamVolInt streamType = 2 index = 20 device = 2 force = true caller = cx.ring
01-12 13:37:08.073 2594 3528 D AudioService: update vol cache, stream:2, device:speaker, index:20, caller:cx.ring
01-12 13:37:08.078 2594 3528 D AudioService: update vol cache, stream:8, device:earpiece, index:150, caller:cx.ring
01-12 13:37:08.079 2594 3528 D AudioService: update vol cache, stream:8, device:speaker, index:43, caller:cx.ring
01-12 13:37:08.082 2594 3528 D AudioService: update vol cache, stream:8, device:headset, index:21, caller:cx.ring
01-12 13:37:08.083 16630 18925 I [MALI][Gralloc]: [+]r_hnd(0x7f4a114de0), client(56), share_fd(193)
01-12 13:37:08.084 2594 3528 D AudioService: update vol cache, stream:8, device:bt_a2dp, index:107, caller:cx.ring
01-12 13:37:08.085 2594 3528 D AudioService: update vol cache, stream:8, device:1073741824, index:107, caller:cx.ring
01-12 13:37:08.088 16630 16650 D BluetoothWrapper: unregister: Unregister BT media receiver
01-12 13:37:08.090 16630 18925 I [MALI][Gralloc]: [+]r_hnd(0x7f57997c80), client(56), share_fd(191)
01-12 13:37:08.091 2594 2622 D AudioService: stopBTSco, from pid=16630
01-12 13:37:08.095 16630 16650 W ConversationFacade: CALL_STATE_CHANGED : updating call state to CONNECTING
01-12 13:37:08.104 2594 2673 I ActivityManager: [AppLaunch] Displayed Displayed cx.ring/.client.CallActivity: +163ms
01-12 13:37:08.104 2594 2673 D ActivityManager: AP_PROF:AppLaunch_LaunchTime:cx.ring/.client.CallActivity:163:596787282
01-12 13:37:08.106 16630 16630 V InputMethodManager: onWindowFocus: androidx.recyclerview.widget.RecyclerView{c5991b7 VFED..... .F....I. 1080,240-1080,240 #7f0a00b8 app:id/conf_control_group} softInputMode=290 first=true flags=#8da90180
01-12 13:37:08.106 16630 16630 V InputMethodManager: START INPUT: androidx.recyclerview.widget.RecyclerView{c5991b7 VFED..... .F....I. 1080,240-1080,240 #7f0a00b8 app:id/conf_control_group} ic=null tba=EditorInfo { packageName=cx.ring, inputType=0x0, imeOptions=0x0, privateImeOptions=null } controlFlags=#105
01-12 13:37:08.110 16630 16630 W CallFragment: updateContactBubble 1
01-12 13:37:08.113 16630 16630 W CallFragment: updateContactBubble 1
01-12 13:37:08.124 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.126 16630 16630 I SurfaceView: updateWindow -- OnScrollChangedListener, this = android.view.SurfaceView$2@a248d68
01-12 13:37:08.141 16630 18925 I [MALI][Gralloc]: [+]r_hnd(0x7f26db7c40), client(56), share_fd(188)
01-12 13:37:08.148 16630 16630 W CallPresenter: mPendingSubject onNext 0 1
01-12 13:37:08.148 16630 16630 W CallPresenter: flatMapObservable 1
01-12 13:37:08.148 16630 16630 W CallPresenter: confUpdate 4066181916576228
01-12 13:37:08.149 2594 3571 D PMN : onWakeLockReleased: flags=805306378, tag="ring:callLock", packageName=cx.ring, ownerUid=10209, ownerPid=16630, workSource=null
01-12 13:37:08.150 2594 3350 D PMS : acquireWakeLockInternal: lock=254358590, flags=0x30000020, tag="ring:callLock", ws=null, uid=10209, pid=16630
01-12 13:37:08.151 2594 3350 D PMS : acquireWL(86a529f): PROXIMITY_SCREEN_OFF_WAKE_LOCK ACQUIRE_CAUSES_WAKEUP ON_AFTER_RELEASE ring:callLock 0x30000020 16630 10209 null
01-12 13:37:08.151 2594 3350 D PMN : onWakeLockAcquired: flags=805306400, tag="ring:callLock", packageName=cx.ring, ownerUid=10209, ownerPid=16630, workSource=null
01-12 13:37:08.151 16630 16630 W CallFragment: initOutGoingCallDisplay
01-12 13:37:08.155 16630 16630 V InputMethodManager: START INPUT: b.b.q.i{712fd24 VFED..CL. .F...... 0,240-3,243 #7f0a0118 app:id/dialpad_edit_text} ic=com.android.internal.widget.EditableInputConnection@34a2098 tba=EditorInfo { packageName=cx.ring, inputType=0x3, imeOptions=0x4006, privateImeOptions=null } controlFlags=#100
01-12 13:37:08.155 16630 16630 I InputMethodManager: [startInputInner] EditorInfo { packageName=cx.ring, inputType=0x3, imeOptions=0x4006, privateImeOptions=null }, windowGainingFocus=null, mServedView=b.b.q.i{712fd24 VFED..CL. .F...... 0,240-3,243 #7f0a0118 app:id/dialpad_edit_text}, mServedInputConnectionWrapper=android.view.inputmethod.InputMethodManager$ControlledInputConnectionWrapper@a0e60f1
01-12 13:37:08.158 16630 16630 W CallFragment: displayHangupButton false
01-12 13:37:08.163 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.189 16630 18925 I [MALI][Gralloc]: [-]r_hnd(0x7f4a114ac0), client(56), share_fd(132)
01-12 13:37:08.190 16630 18925 I [MALI][Gralloc]: [-]r_hnd(0x7f4a114f20), client(56), share_fd(135)
01-12 13:37:08.191 16630 18925 I [MALI][Gralloc]: [-]r_hnd(0x7f26db5d00), client(56), share_fd(80)
01-12 13:37:08.191 16630 18925 E Surface : getSlotFromBufferLocked: unknown buffer: 0x7f4a1157e0
01-12 13:37:08.191 16630 18925 D mali_winsys: [MALI] win=0x27742e10, native_buffer=0x6aaa1f10, fd=-1
01-12 13:37:08.191 16630 18925 I [MALI][Gralloc]: [-]r_hnd(0x7f4a1157e0), client(56), share_fd(129)
01-12 13:37:08.196 16630 16630 W CallFragment: displayHangupButton false
01-12 13:37:08.197 16630 16630 W CallFragment: updateContactBubble 1
01-12 13:37:08.200 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.396 5323 5387 I [B+]AppInfoSyncManager: [onAppInfoEvent] app:cx.ring
01-12 13:37:08.435 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:07.930 2594 3351 I ActivityManager: START u0 {act=cx.ring.action.call cmp=cx.ring/.client.CallActivity (has extras)} from uid 10209 pid 16630 on display 0
01-12 13:37:07.932 430 1264 I BufferQueueProducer: [cx.ring/cx.ring.client.ConversationActivity](this:0x7f738dc000,id:3991,api:1,p:16630,c:430) queueBuffer: fps=1.22 dur=10691.02 max=10231.32 min=10.98
01-12 13:37:07.968 16630 16630 D SurfaceView: checkSurfaceViewlLogProperty get invalid command
01-12 13:37:07.997 16630 16646 I CallService: placeCall() thread running... 4443 audioOnly: true
01-12 13:37:07.997 16630 16646 D libdring: try outgoing call to '4443' with account '816c775d368cba'
01-12 13:37:07.997 16630 16646 D libdring: [Account 816c775d368cba] Calling SIP peer 4443
01-12 13:37:07.997 16630 16646 D libdring: Ringbuffer created with id '4066181916576228'
01-12 13:37:07.997 16630 16646 D libdring: UserAgent: New registered account call to 4443
01-12 13:37:07.997 16630 16646 D libdring: [call:4066181916576228] create media ICE transport
01-12 13:37:07.997 16630 16646 D libdring: [ice] added host stun server
01-12 13:37:07.997 16630 16646 D libdring: [ice] added host stun server
01-12 13:37:08.001 2594 3509 D PMS : acquireWakeLockInternal: lock=16697229, flags=0x3000000a, tag="ring:callLock", ws=null, uid=10209, pid=16630
01-12 13:37:08.001 2594 3509 D PMS : acquireWL(1561d42): SCREEN_BRIGHT_WAKE_LOCK ACQUIRE_CAUSES_WAKEUP ON_AFTER_RELEASE ring:callLock 0x3000000a 16630 10209 null
01-12 13:37:08.002 2594 3509 D PMN : onWakeLockAcquired: flags=805306378, tag="ring:callLock", packageName=cx.ring, ownerUid=10209, ownerPid=16630, workSource=null
01-12 13:37:08.004 16630 16630 I SensorManager: registerListenerImpl: listener = android.view.OrientationEventListener$SensorEventListenerImpl@759414c, sensor = {Sensor name="Accelerometer Sensor", vendor="hTC Corp.", version=1, type=1, maxRange=39.2266, resolution=0.01, power=0.17, minDelay=10000}, delay = 200000, handler = null, name = cx.ring
01-12 13:37:08.006 16630 16646 D libdring: [ice:0x7f584b8000] initialization success
01-12 13:37:08.006 16630 16646 D libdring: ICE as master
01-12 13:37:08.007 16630 16646 D libdring: [ice:0x7f584b8000] (local) ufrag=4c673719, pwd=3404bf1e0283fece5d4c7a14
01-12 13:37:08.008 16630 16646 D libdring: SDP: Local SDP Session:
01-12 13:37:08.008 16630 16646 D libdring: v=0
01-12 13:37:08.008 16630 16646 D libdring: o=localhost 3787843028 0 IN IP4 192.168.10.203
01-12 13:37:08.008 16630 16646 D libdring: s=Jami Daemon
01-12 13:37:08.008 16630 16646 D libdring: c=IN IP4 192.168.10.203
01-12 13:37:08.008 16630 16646 D libdring: t=0 0
01-12 13:37:08.008 16630 16646 D libdring: m=audio 32748 RTP/AVP 104 9 112 111 110 8 0 101
01-12 13:37:08.008 16630 16646 D libdring: a=rtpmap:104 opus/48000/2
01-12 13:37:08.008 16630 16646 D libdring: a=rtpmap:9 G722/8000
01-12 13:37:08.008 16630 16646 D libdring: a=rtpmap:112 speex/32000
01-12 13:37:08.008 16630 16646 D libdring: a=rtpmap:111 speex/16000
01-12 13:37:08.008 16630 16646 D libdring: a=rtpmap:110 speex/8000
01-12 13:37:08.008 16630 16646 D libdring: a=rtpmap:8 PCMA/8000
01-12 13:37:08.008 16630 16646 D libdring: a=rtpmap:0 PCMU/8000
01-12 13:37:08.008 16630 16646 D libdring: a=rtpmap:101 telephone-event/8000
01-12 13:37:08.008 16630 16646 D libdring: a=fmtp:101 0-15
01-12 13:37:08.008 16630 16646 D libdring: a=rtcp:32749 IN IP4 192.168.10.203
01-12 13:37:08.008 16630 16646 D libdring: a=sendrecv
01-12 13:37:08.008 16630 16646 D libdring: ----- Switch current call id to '4066181916576228' -----
01-12 13:37:08.008 16630 16646 W libdring: [call:4066181916576228] video muting true
01-12 13:37:08.008 16630 16630 V PhoneWindow: DecorView setVisiblity: visibility = 4 ,Parent =null, this =com.android.internal.policy.PhoneWindow$DecorView{745f2ac I.E...... R.....ID 0,0-0,0}
01-12 13:37:08.008 16630 16646 W CallService: getConfUpdates 4066181916576228
01-12 13:37:08.008 16630 16646 W CallService: getConfUpdates filter 4066181916576228 1 (tracked 4066181916576228 1)
01-12 13:37:08.008 16630 16650 W libdring: [call:4066181916576228] fill SDP with ICE transport 0x7f64eb9678
01-12 13:37:08.008 16630 16646 W CallService: getConfCallUpdates 4066181916576228
01-12 13:37:08.009 16630 16650 E libdring: addIceCandidates failed: cannot access media#1 (may be deactivated)
01-12 13:37:08.009 16630 16650 E libdring: addIceCandidates failed: cannot access media#1 (may be deactivated)
01-12 13:37:08.009 16630 16650 D libdring: contact header: <sip:179835_sfl@72.10.148.66:34526> / <sip:179835_sfl@montreal6.voip.ms> -> <sip:4443@montreal6.voip.ms>
01-12 13:37:08.009 16630 21540 D libdring: Registered thread 0x7f488a5008 (0x40F6)
01-12 13:37:08.009 16630 16630 D WindowClient: Add to mViews: com.android.internal.policy.PhoneWindow$DecorView{745f2ac I.E...... R.....ID 0,0-0,0}, this = android.view.WindowManagerGlobal@44d2983
01-12 13:37:08.010 16630 18983 D ConversationFacade: onConfStateChange Thread id: 6995
01-12 13:37:08.010 16630 16650 D libc-netbsd: [getaddrinfo]: caller process=cx.ring
01-12 13:37:08.010 16630 16650 D libc-netbsd: getaddrinfo( app_uid:10209
01-12 13:37:08.010 16630 16650 D libc-netbsd: getaddrinfo() uid prop:
01-12 13:37:08.010 16630 16650 D libc-netbsd: getaddrinfo() getuid():10209
01-12 13:37:08.010 16630 16650 D libc-netbsd: [getaddrinfo]: mtk ai_addrlen=0; ai_canonname=(null); ai_flags=0; ai_family=0
01-12 13:37:08.010 16630 16650 D libc-netbsd: [NET] android_getaddrinfo_proxy get netid:0
01-12 13:37:08.014 16630 16630 D ViewRootImpl: hardware acceleration is enabled, this = android.view.ViewRootImpl@de85102
01-12 13:37:08.017 16630 16630 V PhoneWindow: DecorView setVisiblity: visibility = 0 ,Parent =android.view.ViewRootImpl@de85102, this =com.android.internal.policy.PhoneWindow$DecorView{745f2ac V.E...... R.....ID 0,0-0,0}
01-12 13:37:08.019 16630 16630 I SurfaceView: updateWindow -- onWindowVisibilityChanged, visibility = 0, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.025 430 430 I BufferQueueConsumer: [cx.ring/cx.ring.client.CallActivity](this:0x7f72bc7000,id:3992,api:0,p:-1,c:430) setConsumerName: cx.ring/cx.ring.client.CallActivity
01-12 13:37:08.042 16630 16630 E RecyclerView: No adapter attached; skipping layout
01-12 13:37:08.042 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.045 430 1264 I BufferQueueProducer: [cx.ring/cx.ring.client.CallActivity](this:0x7f72bc7000,id:3992,api:1,p:16630,c:430) connect(P): api=1 producer=(16630:cx.ring) producerControlledByApp=true
01-12 13:37:08.045 16630 18925 W libEGL : [ANDROID_RECORDABLE] format: 1
01-12 13:37:08.046 16630 18925 D mali_winsys: new_window_surface returns 0x3000
01-12 13:37:08.048 16630 16630 W CallPresenter: mPendingSubject onNext 0 1
01-12 13:37:08.049 16630 16630 W CallPresenter: flatMapObservable 1
01-12 13:37:08.049 16630 16630 W CallPresenter: mPendingSubject onNext 0 1
01-12 13:37:08.049 16630 16630 W CallPresenter: flatMapObservable 1
01-12 13:37:08.049 16630 16630 W CallPresenter: confUpdate 4066181916576228
01-12 13:37:08.049 16630 16630 W CallFragment: initOutGoingCallDisplay
01-12 13:37:08.052 16630 16650 D libc-netbsd: [NET] android_getaddrinfo_proxy-, success
01-12 13:37:08.052 16630 16650 D libc-netbsd: getaddrinfo: montreal6.voip.ms get result from proxy gai_error = 0
01-12 13:37:08.053 16630 16650 D libdring: [call:4066181916576228] INVITE@0x7f49e7e828 state changed to 1 (CALLING): cause=0, tsx@0x7f4b1cd0a8 status 0 (Default status message)
01-12 13:37:08.053 16630 16650 D libdring: [call:4066181916576228] state change 0/1, cnx 0/2, code 0
01-12 13:37:08.053 16630 16650 D libdring: [call:4066181916576228] emit client call state change CONNECTING, code 0
01-12 13:37:08.054 16630 16650 D CallService: call state changed: 4066181916576228, CONNECTING, 0
01-12 13:37:08.055 16630 16650 D ConversationFacade: onCallStateChange Thread id: 7458
01-12 13:37:08.055 16630 16650 D HardwareServiceImpl: updateAudioState: Call state updated to CONNECTING Call is incoming: false Call is video: false
01-12 13:37:08.055 16630 16650 D BluetoothWrapper: registerScoUpdate: Register BT media receiver
01-12 13:37:08.058 16630 16650 D BluetoothWrapper: registerBtConnection: Register BT connection
01-12 13:37:08.060 16630 16650 D BluetoothHeadset: Register mBluetoothStateChangeCallback = android.bluetooth.BluetoothHeadset$1@35afe5a
01-12 13:37:08.063 16630 16650 D Ringer : stopRing: called...
01-12 13:37:08.063 2594 3528 V AudioService: setMode(mode=0, callingPackage=cx.ring)
01-12 13:37:08.064 2594 3528 V AudioService: setModeInt(mode=0, pid=16630, caller=cx.ring)
01-12 13:37:08.067 16630 16630 E RecyclerView: No adapter attached; skipping layout
01-12 13:37:08.067 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.068 16630 16630 I SurfaceView: updateWindow -- OnScrollChangedListener, this = android.view.SurfaceView$2@a248d68
01-12 13:37:08.073 2594 3528 D AudioService: setStreamVolInt streamType = 2 index = 20 device = 2 force = true caller = cx.ring
01-12 13:37:08.073 2594 3528 D AudioService: update vol cache, stream:2, device:speaker, index:20, caller:cx.ring
01-12 13:37:08.078 2594 3528 D AudioService: update vol cache, stream:8, device:earpiece, index:150, caller:cx.ring
01-12 13:37:08.079 2594 3528 D AudioService: update vol cache, stream:8, device:speaker, index:43, caller:cx.ring
01-12 13:37:08.082 2594 3528 D AudioService: update vol cache, stream:8, device:headset, index:21, caller:cx.ring
01-12 13:37:08.083 16630 18925 I [MALI][Gralloc]: [+]r_hnd(0x7f4a114de0), client(56), share_fd(193)
01-12 13:37:08.084 2594 3528 D AudioService: update vol cache, stream:8, device:bt_a2dp, index:107, caller:cx.ring
01-12 13:37:08.085 2594 3528 D AudioService: update vol cache, stream:8, device:1073741824, index:107, caller:cx.ring
01-12 13:37:08.088 16630 16650 D BluetoothWrapper: unregister: Unregister BT media receiver
01-12 13:37:08.090 16630 18925 I [MALI][Gralloc]: [+]r_hnd(0x7f57997c80), client(56), share_fd(191)
01-12 13:37:08.091 2594 2622 D AudioService: stopBTSco, from pid=16630
01-12 13:37:08.095 16630 16650 W ConversationFacade: CALL_STATE_CHANGED : updating call state to CONNECTING
01-12 13:37:08.104 2594 2673 I ActivityManager: [AppLaunch] Displayed Displayed cx.ring/.client.CallActivity: +163ms
01-12 13:37:08.104 2594 2673 D ActivityManager: AP_PROF:AppLaunch_LaunchTime:cx.ring/.client.CallActivity:163:596787282
01-12 13:37:08.106 16630 16630 V InputMethodManager: onWindowFocus: androidx.recyclerview.widget.RecyclerView{c5991b7 VFED..... .F....I. 1080,240-1080,240 #7f0a00b8 app:id/conf_control_group} softInputMode=290 first=true flags=#8da90180
01-12 13:37:08.106 16630 16630 V InputMethodManager: START INPUT: androidx.recyclerview.widget.RecyclerView{c5991b7 VFED..... .F....I. 1080,240-1080,240 #7f0a00b8 app:id/conf_control_group} ic=null tba=EditorInfo { packageName=cx.ring, inputType=0x0, imeOptions=0x0, privateImeOptions=null } controlFlags=#105
01-12 13:37:08.110 16630 16630 W CallFragment: updateContactBubble 1
01-12 13:37:08.113 16630 16630 W CallFragment: updateContactBubble 1
01-12 13:37:08.124 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.126 16630 16630 I SurfaceView: updateWindow -- OnScrollChangedListener, this = android.view.SurfaceView$2@a248d68
01-12 13:37:08.141 16630 18925 I [MALI][Gralloc]: [+]r_hnd(0x7f26db7c40), client(56), share_fd(188)
01-12 13:37:08.148 16630 16630 W CallPresenter: mPendingSubject onNext 0 1
01-12 13:37:08.148 16630 16630 W CallPresenter: flatMapObservable 1
01-12 13:37:08.148 16630 16630 W CallPresenter: confUpdate 4066181916576228
01-12 13:37:08.149 2594 3571 D PMN : onWakeLockReleased: flags=805306378, tag="ring:callLock", packageName=cx.ring, ownerUid=10209, ownerPid=16630, workSource=null
01-12 13:37:08.150 2594 3350 D PMS : acquireWakeLockInternal: lock=254358590, flags=0x30000020, tag="ring:callLock", ws=null, uid=10209, pid=16630
01-12 13:37:08.151 2594 3350 D PMS : acquireWL(86a529f): PROXIMITY_SCREEN_OFF_WAKE_LOCK ACQUIRE_CAUSES_WAKEUP ON_AFTER_RELEASE ring:callLock 0x30000020 16630 10209 null
01-12 13:37:08.151 2594 3350 D PMN : onWakeLockAcquired: flags=805306400, tag="ring:callLock", packageName=cx.ring, ownerUid=10209, ownerPid=16630, workSource=null
01-12 13:37:08.151 16630 16630 W CallFragment: initOutGoingCallDisplay
01-12 13:37:08.155 16630 16630 V InputMethodManager: START INPUT: b.b.q.i{712fd24 VFED..CL. .F...... 0,240-3,243 #7f0a0118 app:id/dialpad_edit_text} ic=com.android.internal.widget.EditableInputConnection@34a2098 tba=EditorInfo { packageName=cx.ring, inputType=0x3, imeOptions=0x4006, privateImeOptions=null } controlFlags=#100
01-12 13:37:08.155 16630 16630 I InputMethodManager: [startInputInner] EditorInfo { packageName=cx.ring, inputType=0x3, imeOptions=0x4006, privateImeOptions=null }, windowGainingFocus=null, mServedView=b.b.q.i{712fd24 VFED..CL. .F...... 0,240-3,243 #7f0a0118 app:id/dialpad_edit_text}, mServedInputConnectionWrapper=android.view.inputmethod.InputMethodManager$ControlledInputConnectionWrapper@a0e60f1
01-12 13:37:08.158 16630 16630 W CallFragment: displayHangupButton false
01-12 13:37:08.163 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.189 16630 18925 I [MALI][Gralloc]: [-]r_hnd(0x7f4a114ac0), client(56), share_fd(132)
01-12 13:37:08.190 16630 18925 I [MALI][Gralloc]: [-]r_hnd(0x7f4a114f20), client(56), share_fd(135)
01-12 13:37:08.191 16630 18925 I [MALI][Gralloc]: [-]r_hnd(0x7f26db5d00), client(56), share_fd(80)
01-12 13:37:08.191 16630 18925 E Surface : getSlotFromBufferLocked: unknown buffer: 0x7f4a1157e0
01-12 13:37:08.191 16630 18925 D mali_winsys: [MALI] win=0x27742e10, native_buffer=0x6aaa1f10, fd=-1
01-12 13:37:08.191 16630 18925 I [MALI][Gralloc]: [-]r_hnd(0x7f4a1157e0), client(56), share_fd(129)
01-12 13:37:08.196 16630 16630 W CallFragment: displayHangupButton false
01-12 13:37:08.197 16630 16630 W CallFragment: updateContactBubble 1
01-12 13:37:08.200 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.396 5323 5387 I [B+]AppInfoSyncManager: [onAppInfoEvent] app:cx.ring
01-12 13:37:08.435 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.469 2594 2673 D ActivityManager: ACT-IDLE_NOW_MSG from windowsVisible() for idle: ActivityRecord{b34e478 u0 cx.ring/.client.CallActivity t5628}
01-12 13:37:08.486 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.508 16630 16630 V PhoneWindow: DecorView setVisiblity: visibility = 4 ,Parent =android.view.ViewRootImpl@e47c210, this =com.android.internal.policy.PhoneWindow$DecorView{22765b5 I.E...... R......D 0,0-1080,1920}
01-12 13:37:08.509 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.516 16630 18925 I [MALI][Gralloc]: [+]r_hnd(0x7f4a1157e0), client(56), share_fd(121)
01-12 13:37:08.518 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.535 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.551 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.469 2594 2673 D ActivityManager: ACT-IDLE_NOW_MSG from windowsVisible() for idle: ActivityRecord{b34e478u0 cx.ring/.client.CallActivity t5628}
01-12 13:37:08.486 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.508 16630 16630 V PhoneWindow: DecorView setVisiblity: visibility = 4 ,Parent =android.view.ViewRootImpl@e47c210, this =com.android.internal.policy.PhoneWindow$DecorView{22765b5 I.E...... R......D 0,0-1080,1920}
01-12 13:37:08.509 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.516 16630 18925 I [MALI][Gralloc]: [+]r_hnd(0x7f4a1157e0), client(56), share_fd(121)
01-12 13:37:08.518 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.535 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
01-12 13:37:08.551 16630 16630 I SurfaceView: updateWindow -- OnPreDrawListener, mHaveFrame = false, this = android.view.SurfaceView{42fe26a G.E...... ......I. 0,0-0,0 #7f0a032e app:id/video_surface}
Edited by Maxim Cournoyer