SIP : Unable to make outgoing call.

BelledonneCommunications / linphone-android

Linphone.org mirror for linphone-android (https://gitlab.linphone.org/BC/public/linphone-android)

GNU General Public License v3.0

1.11k stars 680 forks source link

2020-03-29 01:21:03.636 3631-3631/org.linphone.debug I/Linphone: Changing [client] [INVITE] transaction [0x73b01ef600], from state [PROCEEDING] to [COMPLETED] 2020-03-29 01:21:03.637 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: message sent to [UDP://pbx.test.com.au:5060], size: [394] bytes ACK sip:113@pbx.test.com.au SIP/2.0 Via: SIP/2.0/UDP 192.168.1.3:57685;branch=z9hG4bK.TQNn2tqfp;rport Call-ID: QuqYUKTGFa From: <sip:856114@pbx.test.com.au>;tag=oSXPHIL2m To: <sip:113@pbx.test.com.au>;tag=as2ef257ed Contact: <sip:856114@171.76.40.227:57685;transport=udp>;+sip.instance="<urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1>" Max-Forwards: 70 CSeq: 20 ACK 2020-03-29 01:21:03.637 3631-3631/org.linphone.debug I/Linphone: AuthRequested pushed 2020-03-29 01:21:03.637 3631-3631/org.linphone.debug I/Linphone: No auth info found for call id [QuqYUKTGFa] 2020-03-29 01:21:03.637 3631-3631/org.linphone.debug I/Linphone: No auth info found for [sip:856114@pbx.test.com.au] 2020-03-29 01:21:03.637 3631-3631/org.linphone.debug I/Linphone: Op 0x73db10e180 added as pending authentication 2020-03-29 01:21:03.637 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: ending recv background task with id=[44fa]. 2020-03-29 01:21:03.638 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x44fa] 2020-03-29 01:21:03.652 3631-3631/org.linphone.debug I/Linphone: Linphone core [0x74c42cbc00] notified [authentication_requested] 2020-03-29 01:21:07.305 3631-3631/org.linphone.debug I/Linphone: Changing [client] [INVITE] transaction [0x73b01ee480], from state [COMPLETED] to [TERMINATED]

2020-03-29 01:25:05.121953+0530 linphone[68064:1643374] [Message] Changing [client] [INVITE] transaction [0x600002384750], from state [PROCEEDING] to [COMPLETED] 2020-03-29 01:25:05.122305+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: message sent to [UDP://pbx.test.com.au:5060], size: [409] bytes 2020-03-29 01:25:05.122418+0530 linphone[68064:1643374] [Message] ACK sip:114@pbx.test.com.au SIP/2.0 2020-03-29 01:25:05.122522+0530 linphone[68064:1643374] [Message] Via: SIP/2.0/UDP 10.0.0.2:62641;branch=z9hG4bK.nYP0BhEiS;rport 2020-03-29 01:25:05.122611+0530 linphone[68064:1643374] [Message] Call-ID: mUkzT0L8-L 2020-03-29 01:25:05.122711+0530 linphone[68064:1643374] [Message] From: "SivaK-iPhone" <sip:856113@pbx.test.com.au>;tag=y1gXYgnzW 2020-03-29 01:25:05.122829+0530 linphone[68064:1643374] [Message] To: "114" <sip:114@pbx.test.com.au>;tag=as6dd495b6 2020-03-29 01:25:05.122933+0530 linphone[68064:1643374] [Message] Contact: <sip:856113@202.52.129.60:5060;gr=urn:uuid:cc4a95ac-217f-00fa-bc4b-3780b3891601>;+org.linphone.specs="lime" 2020-03-29 01:25:05.123016+0530 linphone[68064:1643374] [Message] Max-Forwards: 70 2020-03-29 01:25:05.123124+0530 linphone[68064:1643374] [Message] CSeq: 20 ACK 2020-03-29 01:25:05.123317+0530 linphone[68064:1643374] [Message] linphone_core_find_auth_info(): returning auth info username=856113, realm=pbx.maxo.com.au 2020-03-29 01:25:05.123533+0530 linphone[68064:1643374] [Message] AuthStack::authFound() for Username[856113];Userid[];Realm[pbx.maxo.com.au];Domain[pbx.test.com.au];Algorithm[]; 2020-03-29 01:25:05.123821+0530 linphone[68064:1643374] [Message] Auth info found for [856113] realm [pbx.maxo.com.au] 2020-03-29 01:25:05.124613+0530 linphone[68064:1643374] [Message] Background task belle-sip transaction(0x60000238e560) started. Unknown remaining time since application is not fully in background. 2020-03-29 01:25:05.124860+0530 linphone[68064:1643374] [Message] transaction [0x60000238e560]: starting transaction background task with id=[ff58]. 2020-03-29 01:25:05.125178+0530 linphone[68064:1643374] [Message] Changing [client] [INVITE] transaction [0x60000238e560], from state [INIT] to [CALLING]

2020-03-29 01:21:03.103 3631-3631/org.linphone.debug D/ViewRootImpl@2b6efaa[DialerActivity]: ViewPostIme pointer 1 2020-03-29 01:21:03.105 3631-3631/org.linphone.debug W/Linphone: [Contacts Manager] Couldn't find friend... 2020-03-29 01:21:03.109 3631-3631/org.linphone.debug W/Linphone: [File Utils] External storage is mounted 2020-03-29 01:21:03.112 3631-3631/org.linphone.debug I/Linphone: New MediaSession [0x73dacea758] initialized (LinphoneCore version: 4.4.0-alpha-105-gab93dcb49) 2020-03-29 01:21:03.114 3631-3631/org.linphone.debug I/Linphone: Stream indexes selected (-1 = unassigned): mainAudioStreamIndex=0, mainVideoStreamIndex=-1, mainTextStreamIndex=-1 2020-03-29 01:21:03.114 3631-3631/org.linphone.debug I/Linphone: Found media local-ip from signaling. 2020-03-29 01:21:03.114 3631-3631/org.linphone.debug I/Linphone: stream#0 [audio] in state [Stopped]: multicast role is [inactive] 2020-03-29 01:21:03.115 3631-3631/org.linphone.debug I/Linphone: RtpSession bound to [0.0.0.0] ports [7078] [7079] 2020-03-29 01:21:03.115 3631-3631/org.linphone.debug I/Linphone: Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80 2020-03-29 01:21:03.115 3631-3631/org.linphone.debug I/Linphone: Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32 2020-03-29 01:21:03.115 3631-3631/org.linphone.debug I/Linphone: Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80 2020-03-29 01:21:03.115 3631-3631/org.linphone.debug I/Linphone: Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32 2020-03-29 01:21:03.115 3631-3631/org.linphone.debug I/Linphone: Creating ZRTP engine on rtp session [0x73cc9e2400] ssrc 0xa236cfaf 2020-03-29 01:21:03.115 3631-3631/org.linphone.debug I/Linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2020-03-29 01:21:03.116 3631-3631/org.linphone.debug I/Linphone: Setting DSCP to 46 for MSAudio stream. 2020-03-29 01:21:03.116 3631-3631/org.linphone.debug I/Linphone: Created stream#0 [audio] in state [Stopped] 2020-03-29 01:21:03.116 3631-3631/org.linphone.debug I/Linphone: Linphone core [0x74c42cbc00] notified [call_created] 2020-03-29 01:21:03.116 3631-3631/org.linphone.debug I/Linphone: CallSession [0x73dacea758] moving from state LinphoneCallIdle to LinphoneCallOutgoingInit 2020-03-29 01:21:03.117 3631-3631/org.linphone.debug I/Linphone: [Context] Call state is [OutgoingInit] 2020-03-29 01:21:03.118 3631-3631/org.linphone.debug I/Linphone: [Notifications Manager] Call notification notifiable is Id: 46, local identity: null, myself: null, isGrouped: false, pending intent class org.linphone.call.CallOutgoingActivity 2020-03-29 01:21:03.119 3631-3631/org.linphone.debug W/Linphone: [Contacts Manager] Couldn't find friend... 2020-03-29 01:21:03.124 3631-3631/org.linphone.debug I/Linphone: [Notifications Manager] Notifying 46 2020-03-29 01:21:03.145 3631-3631/org.linphone.debug I/Linphone: [Manager] Call state is [OutgoingInit] 2020-03-29 01:21:03.294 3631-3631/org.linphone.debug W/AudioManager: Use of stream types is deprecated for operations other than volume control 2020-03-29 01:21:03.294 3631-3631/org.linphone.debug W/AudioManager: See the documentation of requestAudioFocus() for what to use instead with android.media.AudioAttributes to qualify your playback use case 2020-03-29 01:21:03.297 3631-3631/org.linphone.debug I/Linphone: Linphone core [0x74c42cbc00] notified [call_state_changed] 2020-03-29 01:21:03.299 3631-3631/org.linphone.debug I/Linphone: [ToneManager] linphoneCoreStopDtmfStream 2020-03-29 01:21:03.299 3631-3631/org.linphone.debug I/Linphone: [ToneManager] doStopTone 2020-03-29 01:21:03.299 3631-3631/org.linphone.debug I/Linphone: ICE state is IceStateNotActivated for stream#0 [audio] in state [Stopped] 2020-03-29 01:21:03.299 3631-3631/org.linphone.debug I/Linphone: Contact has been fixed using proxy 2020-03-29 01:21:03.300 3631-3631/org.linphone.debug I/Linphone: [sip:856114@pbx.test.com.au] calling [sip:113@pbx.test.com.au] on op [0x73db10e180] 2020-03-29 01:21:03.300 3631-3631/org.linphone.debug I/Linphone: Skipping top route of initial route-set because same as request-uri 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x73b01ef600)] acquired [ref=0x45aa] 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 17834 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: transaction [0x73b01ef600]: starting transaction background task with id=[45aa]. 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: Changing [client] [INVITE] transaction [0x73b01ef600], from state [INIT] to [CALLING] 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: message sent to [UDP://pbx.test.com.au:5060], size: [1095] bytes 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: INVITE sip:113@pbx.test.com.au SIP/2.0 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: Via: SIP/2.0/UDP 192.168.1.3:57685;branch=z9hG4bK.TQNn2tqfp;rport 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: From: <sip:856114@pbx.test.com.au>;tag=oSXPHIL2m 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: To: sip:113@pbx.test.com.au 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: CSeq: 20 INVITE 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: Call-ID: QuqYUKTGFa 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: Max-Forwards: 70 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: Supported: replaces, outbound, gruu 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: Content-Type: application/sdp 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: Content-Length: 493 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: Contact: <sip:856114@171.76.40.227:57685;transport=udp>;+sip.instance="<urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1>" 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: User-Agent: Unknown (belle-sip/4.3.0) 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: v=0 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: o=856114 1973 1035 IN IP4 192.168.1.3 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: s=Talk 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: c=IN IP4 192.168.1.3 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: t=0 0 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: m=audio 7078 RTP/AVP 96 97 98 0 8 101 99 100 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: a=rtpmap:96 opus/48000/2 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: a=fmtp:96 useinbandfec=1 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: a=rtpmap:97 speex/16000 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: a=fmtp:97 vbr=on 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: a=rtpmap:98 speex/8000 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: a=fmtp:98 vbr=on 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: a=rtpmap:101 telephone-event/48000 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: a=rtpmap:99 telephone-event/16000 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: a=rtpmap:100 telephone-event/8000 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: a=rtcp-fb:* trr-int 1000 2020-03-29 01:21:03.302 3631-3631/org.linphone.debug I/Linphone: a=rtcp-fb:* ccm tmmbr 2020-03-29 01:21:03.303 3631-3631/org.linphone.debug I/Linphone: CallSession [0x73dacea758] moving from state LinphoneCallOutgoingInit to LinphoneCallOutgoingProgress 2020-03-29 01:21:03.303 3631-3631/org.linphone.debug I/Linphone: [Context] Call state is [OutgoingProgress] 2020-03-29 01:21:03.304 3631-3631/org.linphone.debug I/Linphone: [Manager] Call state is [OutgoingProgress] 2020-03-29 01:21:03.304 3631-3631/org.linphone.debug I/Linphone: Linphone core [0x74c42cbc00] notified [call_state_changed] 2020-03-29 01:21:03.305 3631-3631/org.linphone.debug I/Linphone: Callbacks [0x73dad113d0] unregistered on core [0x74c42cbc00] 2020-03-29 01:21:03.305 3631-3631/org.linphone.debug I/Linphone: Callbacks [0x73dad111f0] unregistered on core [0x74c42cbc00] 2020-03-29 01:21:03.305 3631-3631/org.linphone.debug I/Linphone: Callbacks [0x73dad111a0] unregistered on core [0x74c42cbc00] 2020-03-29 01:21:03.306 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] Activity paused:org.linphone.dialer.DialerActivity@8aaa436 2020-03-29 01:21:03.306 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] runningActivities=0 2020-03-29 01:21:03.324 3631-3631/org.linphone.debug W/ActivityThread: handleWindowVisibility: no activity for token android.os.BinderProxy@bfa624b 2020-03-29 01:21:03.339 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] Activity created:org.linphone.call.CallOutgoingActivity@dd9bae6 2020-03-29 01:21:03.342 3631-3631/org.linphone.debug I/MultiWindowDecorSupport: updateCaptionType >> DecorView@28b40be[], isFloating: false, isApplication: true, hasWindowDecorCaption: false, hasWindowControllerCallback: true 2020-03-29 01:21:03.342 3631-3631/org.linphone.debug D/MultiWindowDecorSupport: setCaptionType = 0, DecorView = DecorView@28b40be[] 2020-03-29 01:21:03.359 3631-3631/org.linphone.debug I/Linphone: Activity started:org.linphone.call.CallOutgoingActivity@dd9bae6 2020-03-29 01:21:03.359 3631-3631/org.linphone.debug I/Linphone: [Permission] Record audio permission is granted 2020-03-29 01:21:03.360 3631-3631/org.linphone.debug I/Linphone: [Permission] Camera permission is granted 2020-03-29 01:21:03.360 3631-3631/org.linphone.debug I/Linphone: [Permission] Read phone state permission is granted 2020-03-29 01:21:03.361 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] Activity resumed:org.linphone.call.CallOutgoingActivity@dd9bae6 2020-03-29 01:21:03.361 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] runningActivities=1 2020-03-29 01:21:03.362 3631-3631/org.linphone.debug I/Linphone: [Generic Activity] Device orientation is 0 (raw value is 0) 2020-03-29 01:21:03.362 3631-3631/org.linphone.debug I/Linphone: Core callbacks [0x73dad10ed0] registered on core [0x74c42cbc00] 2020-03-29 01:21:03.363 3631-3631/org.linphone.debug W/Linphone: [Contacts Manager] Couldn't find friend... 2020-03-29 01:21:03.363 3631-3631/org.linphone.debug I/Linphone: [Permission] android.permission.RECORD_AUDIO permission is granted 2020-03-29 01:21:03.364 3631-3631/org.linphone.debug I/Linphone: Core callbacks [0x73dad0e590] registered on core [0x74c42cbc00] 2020-03-29 01:21:03.364 3631-3631/org.linphone.debug W/Linphone: [Status Fragment] Can't display ZRTP popup, no token ! 2020-03-29 01:21:03.372 3631-3631/org.linphone.debug D/ViewRootImpl@e1b140f[CallOutgoingActivity]: setView = com.android.internal.policy.DecorView@28b40be TM=true MM=false 2020-03-29 01:21:03.373 3631-3631/org.linphone.debug D/ViewRootImpl@2b6efaa[DialerActivity]: MSG_WINDOW_FOCUS_CHANGED 0 1 2020-03-29 01:21:03.373 3631-3631/org.linphone.debug D/InputMethodManager: prepareNavigationBarInfo() DecorView@cc6040e[DialerActivity] 2020-03-29 01:21:03.373 3631-3631/org.linphone.debug D/InputMethodManager: getNavigationBarColor() -855310 2020-03-29 01:21:03.398 3631-3631/org.linphone.debug D/ViewRootImpl@e1b140f[CallOutgoingActivity]: Relayout returned: old=(0,0,1080,2340) new=(0,0,1080,2340) req=(1080,2340)0 dur=8 res=0x7 s={true 497598861312} ch=true 2020-03-29 01:21:03.398 3631-3668/org.linphone.debug D/OpenGLRenderer: createReliableSurface : 0x73b8a4e100, 0x73db341000 2020-03-29 01:21:03.399 3631-3668/org.linphone.debug I/mali_winsys: new_window_surface() [1080x2340] return: 0x3000 2020-03-29 01:21:03.411 3631-3631/org.linphone.debug D/ViewRootImpl@e1b140f[CallOutgoingActivity]: MSG_WINDOW_FOCUS_CHANGED 1 1 2020-03-29 01:21:03.411 3631-3631/org.linphone.debug D/InputMethodManager: prepareNavigationBarInfo() DecorView@28b40be[CallOutgoingActivity] 2020-03-29 01:21:03.411 3631-3631/org.linphone.debug D/InputMethodManager: getNavigationBarColor() -855310 2020-03-29 01:21:03.412 3631-3631/org.linphone.debug D/InputMethodManager: prepareNavigationBarInfo() DecorView@28b40be[CallOutgoingActivity] 2020-03-29 01:21:03.412 3631-3631/org.linphone.debug D/InputMethodManager: getNavigationBarColor() -855310 2020-03-29 01:21:03.412 3631-3631/org.linphone.debug V/InputMethodManager: Starting input: tba=org.linphone.debug ic=null mNaviBarColor -855310 mIsGetNaviBarColorSuccess true , NavVisible : true , NavTrans : false 2020-03-29 01:21:03.412 3631-3631/org.linphone.debug D/InputMethodManager: startInputInner - Id : 0 2020-03-29 01:21:03.412 3631-3631/org.linphone.debug I/InputMethodManager: startInputInner - mService.startInputOrWindowGainedFocus 2020-03-29 01:21:03.423 3631-3631/org.linphone.debug D/InputTransport: Input channel destroyed: 'ClientS', fd=76 2020-03-29 01:21:03.425 3631-3631/org.linphone.debug D/ViewRootImpl@e1b140f[CallOutgoingActivity]: MSG_RESIZED: frame=(0,0,1080,2340) ci=(0,83,0,126) vi=(0,83,0,126) or=1 2020-03-29 01:21:03.437 3631-3668/org.linphone.debug I/mali_egl: eglDestroySurface() in 2020-03-29 01:21:03.439 3631-3668/org.linphone.debug I/mali_winsys: delete_surface() [1080x2340] return 2020-03-29 01:21:03.439 3631-3668/org.linphone.debug I/mali_egl: eglDestroySurface() out 2020-03-29 01:21:03.439 3631-3668/org.linphone.debug W/libEGL: EGLNativeWindowType 0x73b91f53d0 disconnect failed 2020-03-29 01:21:03.446 3631-3631/org.linphone.debug D/ViewRootImpl@2b6efaa[DialerActivity]: Relayout returned: old=(0,0,1080,2340) new=(0,0,1080,2340) req=(1080,2340)8 dur=6 res=0x5 s={false 0} ch=true 2020-03-29 01:21:03.450 3631-3631/org.linphone.debug D/ViewRootImpl@2b6efaa[DialerActivity]: stopped(true) old=false 2020-03-29 01:21:03.450 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] Activity stopped:org.linphone.dialer.DialerActivity@8aaa436 2020-03-29 01:21:03.612 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x4506] 2020-03-29 01:21:03.612 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 17670 2020-03-29 01:21:03.612 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: starting recv background task with id=[4506]. 2020-03-29 01:21:03.613 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: received [297] new bytes from [UDP://pbx.test.com.au:5060]: SIP/2.0 100 Giving a try Via: SIP/2.0/UDP 192.168.1.3:57685;received=171.76.40.227;branch=z9hG4bK.TQNn2tqfp;rport=57685 From: <sip:856114@pbx.test.com.au>;tag=oSXPHIL2m To: sip:113@pbx.test.com.au CSeq: 20 INVITE Call-ID: QuqYUKTGFa Server: Virtual PBX 1.0 Content-Length: 0 2020-03-29 01:21:03.615 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40] [297] bytes parsed 2020-03-29 01:21:03.615 3631-3631/org.linphone.debug I/Linphone: Found transaction matching response. 2020-03-29 01:21:03.615 3631-3631/org.linphone.debug I/Linphone: Changing [client] [INVITE] transaction [0x73b01ef600], from state [CALLING] to [PROCEEDING] 2020-03-29 01:21:03.615 3631-3631/org.linphone.debug I/Linphone: op [0x73db10e180] : set_or_update_dialog() current=[0x0] new=[0x0] 2020-03-29 01:21:03.615 3631-3631/org.linphone.debug I/Linphone: Op [0x73db10e180] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL] 2020-03-29 01:21:03.615 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: ending recv background task with id=[4506]. 2020-03-29 01:21:03.616 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x4506] 2020-03-29 01:21:03.633 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x44fa] 2020-03-29 01:21:03.633 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 17658 2020-03-29 01:21:03.633 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: starting recv background task with id=[44fa]. 2020-03-29 01:21:03.633 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: received [507] new bytes from [UDP://pbx.test.com.au:5060]: 2020-03-29 01:21:03.633 3631-3631/org.linphone.debug I/Linphone: SIP/2.0 401 Unauthorized 2020-03-29 01:21:03.633 3631-3631/org.linphone.debug I/Linphone: Via: SIP/2.0/UDP 192.168.1.3:57685;received=171.76.40.227;branch=z9hG4bK.TQNn2tqfp;rport=57685 2020-03-29 01:21:03.633 3631-3631/org.linphone.debug I/Linphone: From: <sip:856114@pbx.test.com.au>;tag=oSXPHIL2m 2020-03-29 01:21:03.633 3631-3631/org.linphone.debug I/Linphone: To: sip:113@pbx.test.com.au;tag=as2ef257ed 2020-03-29 01:21:03.633 3631-3631/org.linphone.debug I/Linphone: Call-ID: QuqYUKTGFa 2020-03-29 01:21:03.633 3631-3631/org.linphone.debug I/Linphone: CSeq: 20 INVITE 2020-03-29 01:21:03.633 3631-3631/org.linphone.debug I/Linphone: Server: MaxoPBX 2020-03-29 01:21:03.633 3631-3631/org.linphone.debug I/Linphone: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE 2020-03-29 01:21:03.633 3631-3631/org.linphone.debug I/Linphone: Supported: replaces, timer 2020-03-29 01:21:03.633 3631-3631/org.linphone.debug I/Linphone: WWW-Authenticate: Digest algorithm=MD5, realm="pbx.maxo.com.au", nonce="1654bcd5" 2020-03-29 01:21:03.633 3631-3631/org.linphone.debug I/Linphone: Content-Length: 0 2020-03-29 01:21:03.636 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40] [507] bytes parsed 2020-03-29 01:21:03.636 3631-3631/org.linphone.debug I/Linphone: Found transaction matching response. 2020-03-29 01:21:03.636 3631-3631/org.linphone.debug I/Linphone: Changing [client] [INVITE] transaction [0x73b01ef600], from state [PROCEEDING] to [COMPLETED] 2020-03-29 01:21:03.637 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: message sent to [UDP://pbx.test.com.au:5060], size: [394] bytes ACK sip:113@pbx.test.com.au SIP/2.0 Via: SIP/2.0/UDP 192.168.1.3:57685;branch=z9hG4bK.TQNn2tqfp;rport Call-ID: QuqYUKTGFa From: <sip:856114@pbx.test.com.au>;tag=oSXPHIL2m To: <sip:113@pbx.test.com.au>;tag=as2ef257ed Contact: <sip:856114@171.76.40.227:57685;transport=udp>;+sip.instance="<urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1>" Max-Forwards: 70 CSeq: 20 ACK 2020-03-29 01:21:03.637 3631-3631/org.linphone.debug I/Linphone: AuthRequested pushed 2020-03-29 01:21:03.637 3631-3631/org.linphone.debug I/Linphone: No auth info found for call id [QuqYUKTGFa] 2020-03-29 01:21:03.637 3631-3631/org.linphone.debug I/Linphone: No auth info found for [sip:856114@pbx.test.com.au] 2020-03-29 01:21:03.637 3631-3631/org.linphone.debug I/Linphone: Op 0x73db10e180 added as pending authentication 2020-03-29 01:21:03.637 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: ending recv background task with id=[44fa]. 2020-03-29 01:21:03.638 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x44fa] 2020-03-29 01:21:03.652 3631-3631/org.linphone.debug I/Linphone: Linphone core [0x74c42cbc00] notified [authentication_requested] 2020-03-29 01:21:07.305 3631-3631/org.linphone.debug I/Linphone: Changing [client] [INVITE] transaction [0x73b01ee480], from state [COMPLETED] to [TERMINATED] 2020-03-29 01:21:07.306 3631-3631/org.linphone.debug I/Linphone: Client INVITE transaction [0x73b01ee480] terminated 2020-03-29 01:21:07.307 3631-3631/org.linphone.debug I/Linphone: transaction [0x73b01ee480]: ending transaction background task with id=[468a]. 2020-03-29 01:21:07.312 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x468a] 2020-03-29 01:21:09.556 3631-3631/org.linphone.debug D/ViewRootImpl@e1b140f[CallOutgoingActivity]: ViewPostIme pointer 0 2020-03-29 01:21:09.605 3631-3631/org.linphone.debug D/ViewRootImpl@e1b140f[CallOutgoingActivity]: ViewPostIme pointer 1 2020-03-29 01:21:09.606 3631-3631/org.linphone.debug I/Linphone: Terminate CallSession [0x73dacea758] which is currently in state [LinphoneCallOutgoingProgress] 2020-03-29 01:21:09.607 3631-3631/org.linphone.debug I/Linphone: ICT retransmissions stopped 2020-03-29 01:21:09.607 3631-3631/org.linphone.debug I/Linphone: [ToneManager] [0x73dacea758] state changed : [None, LinphoneCallOutgoingProgress] 2020-03-29 01:21:09.607 3631-3631/org.linphone.debug I/Linphone: [ToneManager] stop 2020-03-29 01:21:09.607 3631-3631/org.linphone.debug I/Linphone: [ToneManager] nothing to stop 2020-03-29 01:21:09.607 3631-3631/org.linphone.debug I/Linphone: CallSession [0x73dacea758] moving from state LinphoneCallOutgoingProgress to LinphoneCallEnd 2020-03-29 01:21:09.607 3631-3631/org.linphone.debug I/Linphone: StreamsGroup::finish() called. 2020-03-29 01:21:09.607 3631-3631/org.linphone.debug I/Linphone: ================================================================================= 2020-03-29 01:21:09.607 3631-3631/org.linphone.debug I/Linphone: FILTER USAGE STATISTICS 2020-03-29 01:21:09.607 3631-3631/org.linphone.debug I/Linphone: Name Count Time/tick (ms) CPU Usage 2020-03-29 01:21:09.607 3631-3631/org.linphone.debug I/Linphone: min mean max sd 2020-03-29 01:21:09.607 3631-3631/org.linphone.debug I/Linphone: --------------------------------------------------------------------------------- 2020-03-29 01:21:09.607 3631-3631/org.linphone.debug I/Linphone: MSOpusDec 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.607 3631-3631/org.linphone.debug I/Linphone: MSMKVPlayer 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSVoidSink 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSItcSink 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSFilePlayer 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSAudioFlowControl 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSGenericPLC 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSEqualizer 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSOpusEnc 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSItcSource 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSMKVRecorder 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSTee 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSFileRec 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSAudioMixer 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSResample 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSVolume 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSUlawDec 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSUlawEnc 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSOpenSLESPlayer 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSOpenSLESRecorder 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.608 3631-3631/org.linphone.debug I/Linphone: MSDtmfGen 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: MSRtpRecv 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: MSWebRTCAECM 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: MSRtpSend 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: ================================================================================= 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: ================================================================================= 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: FILTER USAGE STATISTICS 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: Name Count Time/tick (ms) CPU Usage 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: min mean max sd 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: --------------------------------------------------------------------------------- 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: MSOpusDec 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: MSMKVPlayer 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: MSVoidSink 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: MSItcSink 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: MSFilePlayer 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: MSAudioFlowControl 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: MSGenericPLC 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: MSEqualizer 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: MSOpusEnc 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.609 3631-3631/org.linphone.debug I/Linphone: MSItcSource 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.610 3631-3631/org.linphone.debug I/Linphone: MSMKVRecorder 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.610 3631-3631/org.linphone.debug I/Linphone: MSTee 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.610 3631-3631/org.linphone.debug I/Linphone: MSFileRec 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.610 3631-3631/org.linphone.debug I/Linphone: MSAudioMixer 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.610 3631-3631/org.linphone.debug I/Linphone: MSResample 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.610 3631-3631/org.linphone.debug I/Linphone: MSVolume 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.610 3631-3631/org.linphone.debug I/Linphone: MSUlawDec 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.610 3631-3631/org.linphone.debug I/Linphone: MSUlawEnc 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.610 3631-3631/org.linphone.debug I/Linphone: MSOpenSLESPlayer 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.610 3631-3631/org.linphone.debug I/Linphone: MSOpenSLESRecorder 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.610 3631-3631/org.linphone.debug I/Linphone: MSDtmfGen 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.610 3631-3631/org.linphone.debug I/Linphone: MSRtpRecv 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.610 3631-3631/org.linphone.debug I/Linphone: MSWebRTCAECM 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.610 3631-3631/org.linphone.debug I/Linphone: MSRtpSend 0 0.00 0.00 0.00 0.00 0.0 2020-03-29 01:21:09.610 3631-3631/org.linphone.debug I/Linphone: ================================================================================= 2020-03-29 01:21:09.610 3631-3631/org.linphone.debug I/Linphone: Stopping ZRTP context on session [0x0] 2020-03-29 01:21:09.610 3631-3631/org.linphone.debug I/Linphone: ZRTP context destroyed 2020-03-29 01:21:09.613 3631-3631/org.linphone.debug I/Linphone: Linphone core [0x74c42cbc00] notified [call_log_updated] 2020-03-29 01:21:09.613 3631-3631/org.linphone.debug I/Linphone: Resetting the current call 2020-03-29 01:21:09.613 3631-3631/org.linphone.debug I/Linphone: Notifying soundcard that we don't need it anymore for calls 2020-03-29 01:21:09.613 3631-3631/org.linphone.debug I/Linphone: [Context] Call state is [End] 2020-03-29 01:21:09.615 3631-3631/org.linphone.debug I/Linphone: [Service] Destroying video overlay 2020-03-29 01:21:09.616 3631-3631/org.linphone.debug I/Linphone: [Manager] Call state is [End] 2020-03-29 01:21:09.672 3631-3631/org.linphone.debug W/Linphone: [Audio Manager] Routing audio to earpiece 2020-03-29 01:21:09.672 3631-3631/org.linphone.debug D/AudioManager: setSpeakerphoneOn false 2020-03-29 01:21:09.684 3631-3631/org.linphone.debug I/Linphone: Linphone core [0x74c42cbc00] notified [call_state_changed] 2020-03-29 01:21:09.687 3631-3631/org.linphone.debug I/Linphone: Callbacks [0x73dad0e590] unregistered on core [0x74c42cbc00] 2020-03-29 01:21:09.688 3631-3631/org.linphone.debug I/Linphone: Callbacks [0x73dad10ed0] unregistered on core [0x74c42cbc00] 2020-03-29 01:21:09.688 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] Activity paused:org.linphone.call.CallOutgoingActivity@dd9bae6 2020-03-29 01:21:09.688 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] runningActivities=0 2020-03-29 01:21:09.706 3631-3631/org.linphone.debug D/ViewRootImpl@2b6efaa[DialerActivity]: stopped(false) old=true 2020-03-29 01:21:09.709 3631-3631/org.linphone.debug I/Linphone: Activity started:org.linphone.dialer.DialerActivity@8aaa436 2020-03-29 01:21:09.710 3631-3631/org.linphone.debug I/Linphone: [Permission] android.permission.SYSTEM_ALERT_WINDOW permission is denied 2020-03-29 01:21:09.710 3631-3631/org.linphone.debug I/Linphone: [Permission] android.permission.FOREGROUND_SERVICE permission is granted 2020-03-29 01:21:09.711 3631-3631/org.linphone.debug I/Linphone: [Permission] android.permission.WRITE_CONTACTS permission is granted 2020-03-29 01:21:09.711 3631-3631/org.linphone.debug I/Linphone: [Permission] android.permission.READ_CONTACTS permission is granted 2020-03-29 01:21:09.711 3631-3631/org.linphone.debug I/Linphone: [Permission] Requesting android.permission.SYSTEM_ALERT_WINDOW permission 2020-03-29 01:21:09.734 3631-3631/org.linphone.debug D/ViewRootImpl@2b6efaa[DialerActivity]: stopped(false) old=false 2020-03-29 01:21:09.734 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] Activity resumed:org.linphone.dialer.DialerActivity@8aaa436 2020-03-29 01:21:09.734 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] runningActivities=1 2020-03-29 01:21:09.736 3631-3631/org.linphone.debug I/Linphone: [Generic Activity] Device orientation is 0 (raw value is 0) 2020-03-29 01:21:09.737 3631-3631/org.linphone.debug I/Linphone: Core callbacks [0x73dad0f490] registered on core [0x74c42cbc00] 2020-03-29 01:21:09.737 3631-3631/org.linphone.debug I/Linphone: Core callbacks [0x73dad0e090] registered on core [0x74c42cbc00] 2020-03-29 01:21:09.737 3631-3631/org.linphone.debug I/Linphone: Core callbacks [0x73dad0e130] registered on core [0x74c42cbc00] 2020-03-29 01:21:09.747 3631-3631/org.linphone.debug D/ViewRootImpl@2b6efaa[DialerActivity]: Relayout returned: old=(0,0,1080,2340) new=(0,0,1080,2340) req=(1080,2340)0 dur=5 res=0x7 s={true 496763326464} ch=true 2020-03-29 01:21:09.747 3631-3668/org.linphone.debug D/OpenGLRenderer: createReliableSurface : 0x73b8a4ed80, 0x73a966d000 2020-03-29 01:21:09.748 3631-3668/org.linphone.debug I/mali_winsys: new_window_surface() [1080x2340] return: 0x3000 2020-03-29 01:21:09.762 3631-3631/org.linphone.debug I/Linphone: Callbacks [0x73dad0e130] unregistered on core [0x74c42cbc00] 2020-03-29 01:21:09.762 3631-3631/org.linphone.debug I/Linphone: Callbacks [0x73dad0e090] unregistered on core [0x74c42cbc00] 2020-03-29 01:21:09.763 3631-3631/org.linphone.debug I/Linphone: Callbacks [0x73dad0f490] unregistered on core [0x74c42cbc00] 2020-03-29 01:21:09.763 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] Activity paused:org.linphone.dialer.DialerActivity@8aaa436 2020-03-29 01:21:09.763 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] runningActivities=0 2020-03-29 01:21:09.774 3631-3631/org.linphone.debug D/ViewRootImpl@e1b140f[CallOutgoingActivity]: MSG_WINDOW_FOCUS_CHANGED 0 1 2020-03-29 01:21:09.774 3631-3631/org.linphone.debug D/InputMethodManager: prepareNavigationBarInfo() DecorView@28b40be[CallOutgoingActivity] 2020-03-29 01:21:09.774 3631-3631/org.linphone.debug D/InputMethodManager: getNavigationBarColor() -855310 2020-03-29 01:21:09.838 3631-3631/org.linphone.debug I/Linphone: [Permission] android.permission.SYSTEM_ALERT_WINDOW is denied 2020-03-29 01:21:09.838 3631-3631/org.linphone.debug D/ViewRootImpl@2b6efaa[DialerActivity]: stopped(false) old=false 2020-03-29 01:21:09.838 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] Activity resumed:org.linphone.dialer.DialerActivity@8aaa436 2020-03-29 01:21:09.838 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] runningActivities=1 2020-03-29 01:21:09.839 3631-3631/org.linphone.debug I/Linphone: [Generic Activity] Device orientation is 0 (raw value is 0) 2020-03-29 01:21:09.840 3631-3631/org.linphone.debug I/Linphone: Core callbacks [0x73dad11c90] registered on core [0x74c42cbc00] 2020-03-29 01:21:09.840 3631-3631/org.linphone.debug I/Linphone: Core callbacks [0x73dad11ce0] registered on core [0x74c42cbc00] 2020-03-29 01:21:09.840 3631-3631/org.linphone.debug I/Linphone: Core callbacks [0x73dad11d80] registered on core [0x74c42cbc00] 2020-03-29 01:21:09.860 3631-3631/org.linphone.debug D/ViewRootImpl@2b6efaa[DialerActivity]: Relayout returned: old=(0,0,1080,2340) new=(0,0,1080,2340) req=(1080,2340)0 dur=4 res=0x1 s={true 496763326464} ch=false 2020-03-29 01:21:09.881 3631-3668/org.linphone.debug I/mali_egl: eglDestroySurface() in 2020-03-29 01:21:09.882 3631-3668/org.linphone.debug I/mali_winsys: delete_surface() [1080x2340] return 2020-03-29 01:21:09.882 3631-3668/org.linphone.debug I/mali_egl: eglDestroySurface() out 2020-03-29 01:21:09.884 3631-3668/org.linphone.debug W/libEGL: EGLNativeWindowType 0x73b8a4e110 disconnect failed 2020-03-29 01:21:09.889 3631-3631/org.linphone.debug D/ViewRootImpl@e1b140f[CallOutgoingActivity]: Relayout returned: old=(0,0,1080,2340) new=(0,0,1080,2340) req=(1080,2340)8 dur=4 res=0x5 s={false 0} ch=true 2020-03-29 01:21:09.889 3631-3631/org.linphone.debug D/ViewRootImpl@2b6efaa[DialerActivity]: MSG_WINDOW_FOCUS_CHANGED 1 1 2020-03-29 01:21:09.889 3631-3631/org.linphone.debug D/InputMethodManager: prepareNavigationBarInfo() DecorView@cc6040e[DialerActivity] 2020-03-29 01:21:09.889 3631-3631/org.linphone.debug D/InputMethodManager: getNavigationBarColor() -855310 2020-03-29 01:21:09.891 3631-3631/org.linphone.debug D/InputMethodManager: prepareNavigationBarInfo() DecorView@cc6040e[DialerActivity] 2020-03-29 01:21:09.891 3631-3631/org.linphone.debug D/InputMethodManager: getNavigationBarColor() -855310 2020-03-29 01:21:09.891 3631-3631/org.linphone.debug V/InputMethodManager: Starting input: tba=org.linphone.debug ic=null mNaviBarColor -855310 mIsGetNaviBarColorSuccess true , NavVisible : true , NavTrans : false 2020-03-29 01:21:09.891 3631-3631/org.linphone.debug D/InputMethodManager: startInputInner - Id : 0 2020-03-29 01:21:09.891 3631-3631/org.linphone.debug I/InputMethodManager: startInputInner - mService.startInputOrWindowGainedFocus 2020-03-29 01:21:09.892 3631-3631/org.linphone.debug D/InputTransport: Input channel destroyed: 'ClientS', fd=93 2020-03-29 01:21:09.903 3631-3631/org.linphone.debug D/ViewRootImpl@e1b140f[CallOutgoingActivity]: stopped(true) old=false 2020-03-29 01:21:09.906 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] Activity stopped:org.linphone.call.CallOutgoingActivity@dd9bae6 2020-03-29 01:21:09.908 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] Activity destroyed:org.linphone.call.CallOutgoingActivity@dd9bae6 2020-03-29 01:21:09.909 3631-3631/org.linphone.debug D/ViewRootImpl@e1b140f[CallOutgoingActivity]: dispatchDetachedFromWindow 2020-03-29 01:21:09.912 3631-3631/org.linphone.debug D/InputTransport: Input channel destroyed: 'ea168f ', fd=90 2020-03-29 01:21:18.494 3631-3631/org.linphone.debug I/Linphone: Changing [client] [INVITE] transaction [0x73b01eed40], from state [COMPLETED] to [TERMINATED] 2020-03-29 01:21:18.495 3631-3631/org.linphone.debug I/Linphone: Client INVITE transaction [0x73b01eed40] terminated 2020-03-29 01:21:18.496 3631-3631/org.linphone.debug I/Linphone: transaction [0x73b01eed40]: ending transaction background task with id=[4582]. 2020-03-29 01:21:18.501 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x4582] 2020-03-29 01:21:21.301 3631-3631/org.linphone.debug I/Linphone: channel [0x73b9184e80]: keep alive sent to [UDP://sip.linphone.org:5060] 2020-03-29 01:21:21.302 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: keep alive sent to [UDP://pbx.test.com.au:5060] 2020-03-29 01:21:31.003 3631-3631/org.linphone.debug I/Linphone: linphone_core_find_auth_info(): returning auth info username=856114, realm=pbx.test.com.au 2020-03-29 01:21:31.004 3631-3631/org.linphone.debug I/Linphone: AuthStack::authFound() for Username[856114];Userid[];Realm[pbx.test.com.au];Domain[pbx.test.com.au];Algorithm[]; 2020-03-29 01:21:31.005 3631-3631/org.linphone.debug I/Linphone: Auth info found for [856114] realm [pbx.test.com.au] 2020-03-29 01:21:31.013 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x73dba6a100)] acquired [ref=0x4586] 2020-03-29 01:21:31.014 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 17798 2020-03-29 01:21:31.014 3631-3631/org.linphone.debug I/Linphone: transaction [0x73dba6a100]: starting transaction background task with id=[4586]. 2020-03-29 01:21:31.015 3631-3631/org.linphone.debug I/Linphone: Transaction [0x73db350b80] deleted 2020-03-29 01:21:31.016 3631-3631/org.linphone.debug I/Linphone: Changing [client] [REGISTER] transaction [0x73dba6a100], from state [INIT] to [TRYING] 2020-03-29 01:21:31.018 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: message sent to [UDP://pbx.test.com.au:5060], size: [777] bytes 2020-03-29 01:21:31.018 3631-3631/org.linphone.debug I/Linphone: REGISTER sip:pbx.test.com.au SIP/2.0 2020-03-29 01:21:31.018 3631-3631/org.linphone.debug I/Linphone: Via: SIP/2.0/UDP 192.168.1.3:57685;branch=z9hG4bK.XNeAYQTSV;rport 2020-03-29 01:21:31.018 3631-3631/org.linphone.debug I/Linphone: From: <sip:856114@pbx.test.com.au>;tag=Y0Bus9ffC 2020-03-29 01:21:31.018 3631-3631/org.linphone.debug I/Linphone: To: sip:856114@pbx.test.com.au 2020-03-29 01:21:31.018 3631-3631/org.linphone.debug I/Linphone: CSeq: 24 REGISTER 2020-03-29 01:21:31.018 3631-3631/org.linphone.debug I/Linphone: Call-ID: ~knT0Wt-ur 2020-03-29 01:21:31.018 3631-3631/org.linphone.debug I/Linphone: Max-Forwards: 70 2020-03-29 01:21:31.018 3631-3631/org.linphone.debug I/Linphone: Supported: replaces, outbound, gruu 2020-03-29 01:21:31.019 3631-3631/org.linphone.debug I/Linphone: Accept: application/sdp 2020-03-29 01:21:31.019 3631-3631/org.linphone.debug I/Linphone: Accept: text/plain 2020-03-29 01:21:31.019 3631-3631/org.linphone.debug I/Linphone: Accept: application/vnd.gsma.rcs-ft-http+xml 2020-03-29 01:21:31.019 3631-3631/org.linphone.debug I/Linphone: Contact: <sip:856114@171.76.40.227:57685;transport=udp>;+sip.instance="<urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1>" 2020-03-29 01:21:31.019 3631-3631/org.linphone.debug I/Linphone: Expires: 120 2020-03-29 01:21:31.019 3631-3631/org.linphone.debug I/Linphone: User-Agent: Unknown (belle-sip/4.3.0) 2020-03-29 01:21:31.019 3631-3631/org.linphone.debug I/Linphone: Authorization: Digest realm="pbx.test.com.au", nonce="5e7faa7400004642a8cddd4aeac4d3c95ea964be87179872", username="856114", uri="sip:pbx.test.com.au", response="3fedfbf45d443394220c6700593408d6" 2020-03-29 01:21:31.437 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x44f2] 2020-03-29 01:21:31.437 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 17650 2020-03-29 01:21:31.438 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: starting recv background task with id=[44f2]. 2020-03-29 01:21:31.438 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: received [470] new bytes from [UDP://pbx.test.com.au:5060]: 2020-03-29 01:21:31.438 3631-3631/org.linphone.debug I/Linphone: SIP/2.0 401 Unauthorized 2020-03-29 01:21:31.438 3631-3631/org.linphone.debug I/Linphone: Via: SIP/2.0/UDP 192.168.1.3:57685;received=171.76.40.227;branch=z9hG4bK.XNeAYQTSV;rport=57685 2020-03-29 01:21:31.438 3631-3631/org.linphone.debug I/Linphone: From: <sip:856114@pbx.test.com.au>;tag=Y0Bus9ffC 2020-03-29 01:21:31.438 3631-3631/org.linphone.debug I/Linphone: To: sip:856114@pbx.test.com.au;tag=68e745ec67c1f1cc100ee88160c82018.5bdd 2020-03-29 01:21:31.438 3631-3631/org.linphone.debug I/Linphone: CSeq: 24 REGISTER 2020-03-29 01:21:31.438 3631-3631/org.linphone.debug I/Linphone: Call-ID: ~knT0Wt-ur 2020-03-29 01:21:31.439 3631-3631/org.linphone.debug I/Linphone: WWW-Authenticate: Digest realm="pbx.test.com.au", nonce="5e7faae00000ebb7a7b87e38c499611700ffc42e712f1d67", stale=true 2020-03-29 01:21:31.442 3631-3631/org.linphone.debug I/Linphone: Server: Virtual PBX 1.0 2020-03-29 01:21:31.442 3631-3631/org.linphone.debug I/Linphone: Content-Length: 0 2020-03-29 01:21:31.463 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40] [470] bytes parsed 2020-03-29 01:21:31.464 3631-3631/org.linphone.debug I/Linphone: Found transaction matching response. 2020-03-29 01:21:31.464 3631-3631/org.linphone.debug I/Linphone: Changing [client] [REGISTER] transaction [0x73dba6a100], from state [TRYING] to [COMPLETED] 2020-03-29 01:21:31.465 3631-3631/org.linphone.debug I/Linphone: linphone_core_find_auth_info(): returning auth info username=856114, realm=pbx.test.com.au 2020-03-29 01:21:31.465 3631-3631/org.linphone.debug I/Linphone: AuthStack::authFound() for Username[856114];Userid[];Realm[pbx.test.com.au];Domain[pbx.test.com.au];Algorithm[]; 2020-03-29 01:21:31.465 3631-3631/org.linphone.debug I/Linphone: Auth info found for [856114] realm [pbx.test.com.au] 2020-03-29 01:21:31.472 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x73db835bc0)] acquired [ref=0x4456] 2020-03-29 01:21:31.472 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 17494 2020-03-29 01:21:31.473 3631-3631/org.linphone.debug I/Linphone: transaction [0x73db835bc0]: starting transaction background task with id=[4456]. 2020-03-29 01:21:31.473 3631-3631/org.linphone.debug I/Linphone: Changing [client] [REGISTER] transaction [0x73db835bc0], from state [INIT] to [TRYING] 2020-03-29 01:21:31.475 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: message sent to [UDP://pbx.test.com.au:5060], size: [777] bytes 2020-03-29 01:21:31.475 3631-3631/org.linphone.debug I/Linphone: REGISTER sip:pbx.test.com.au SIP/2.0 2020-03-29 01:21:31.475 3631-3631/org.linphone.debug I/Linphone: Via: SIP/2.0/UDP 192.168.1.3:57685;branch=z9hG4bK.p0V4krORD;rport 2020-03-29 01:21:31.475 3631-3631/org.linphone.debug I/Linphone: From: <sip:856114@pbx.test.com.au>;tag=Y0Bus9ffC 2020-03-29 01:21:31.475 3631-3631/org.linphone.debug I/Linphone: To: sip:856114@pbx.test.com.au 2020-03-29 01:21:31.475 3631-3631/org.linphone.debug I/Linphone: CSeq: 25 REGISTER 2020-03-29 01:21:31.475 3631-3631/org.linphone.debug I/Linphone: Call-ID: ~knT0Wt-ur 2020-03-29 01:21:31.475 3631-3631/org.linphone.debug I/Linphone: Max-Forwards: 70 2020-03-29 01:21:31.475 3631-3631/org.linphone.debug I/Linphone: Supported: replaces, outbound, gruu 2020-03-29 01:21:31.475 3631-3631/org.linphone.debug I/Linphone: Accept: application/sdp 2020-03-29 01:21:31.475 3631-3631/org.linphone.debug I/Linphone: Accept: text/plain 2020-03-29 01:21:31.475 3631-3631/org.linphone.debug I/Linphone: Accept: application/vnd.gsma.rcs-ft-http+xml 2020-03-29 01:21:31.475 3631-3631/org.linphone.debug I/Linphone: Contact: <sip:856114@171.76.40.227:57685;transport=udp>;+sip.instance="<urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1>" 2020-03-29 01:21:31.475 3631-3631/org.linphone.debug I/Linphone: Expires: 120 2020-03-29 01:21:31.475 3631-3631/org.linphone.debug I/Linphone: User-Agent: Unknown (belle-sip/4.3.0) 2020-03-29 01:21:31.476 3631-3631/org.linphone.debug I/Linphone: Authorization: Digest realm="pbx.test.com.au", nonce="5e7faae00000ebb7a7b87e38c499611700ffc42e712f1d67", username="856114", uri="sip:pbx.test.com.au", response="b5754b2083d44417150352af5c0e4624" 2020-03-29 01:21:31.476 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: ending recv background task with id=[44f2]. 2020-03-29 01:21:31.480 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x44f2] 2020-03-29 01:21:31.737 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x4442] 2020-03-29 01:21:31.737 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 17474 2020-03-29 01:21:31.737 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: starting recv background task with id=[4442]. 2020-03-29 01:21:31.737 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: received [733] new bytes from [UDP://pbx.test.com.au:5060]: 2020-03-29 01:21:31.737 3631-3631/org.linphone.debug I/Linphone: SIP/2.0 200 OK 2020-03-29 01:21:31.737 3631-3631/org.linphone.debug I/Linphone: Via: SIP/2.0/UDP 192.168.1.3:57685;received=171.76.40.227;branch=z9hG4bK.p0V4krORD;rport=57685 2020-03-29 01:21:31.738 3631-3631/org.linphone.debug I/Linphone: From: <sip:856114@pbx.test.com.au>;tag=Y0Bus9ffC 2020-03-29 01:21:31.738 3631-3631/org.linphone.debug I/Linphone: To: sip:856114@pbx.test.com.au;tag=68e745ec67c1f1cc100ee88160c82018.f2ca 2020-03-29 01:21:31.738 3631-3631/org.linphone.debug I/Linphone: CSeq: 25 REGISTER 2020-03-29 01:21:31.738 3631-3631/org.linphone.debug I/Linphone: Call-ID: ~knT0Wt-ur 2020-03-29 01:21:31.738 3631-3631/org.linphone.debug I/Linphone: Contact: <sip:856114@192.168.1.3:57685;transport=udp>;expires=120;received="sip:171.76.40.227:57685";pub-gruu="sip:856114@202.52.129.60:5060;gr=urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1";temp-gruu="sip:tgruu.AUULW2cDRWMJQRNWZgdBYgRQRhw9CwUmWRQJCjEBFGMJEgBDNQYRYB1AAw0wHEhmCUUeD2MFSGQEFFUPZlNBc04bXTpjZgR+RQI=@202.52.129.60:5060;gr";+sip.instance="urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1" 2020-03-29 01:21:31.738 3631-3631/org.linphone.debug I/Linphone: Server: Virtual PBX 1.0 2020-03-29 01:21:31.738 3631-3631/org.linphone.debug I/Linphone: Content-Length: 0 2020-03-29 01:21:31.746 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40] [733] bytes parsed 2020-03-29 01:21:31.747 3631-3631/org.linphone.debug I/Linphone: Found transaction matching response. 2020-03-29 01:21:31.747 3631-3631/org.linphone.debug I/Linphone: Changing [client] [REGISTER] transaction [0x73db835bc0], from state [TRYING] to [COMPLETED] 2020-03-29 01:21:31.747 3631-3631/org.linphone.debug I/Linphone: No matching contact neither for [Contact: <sip:856114@171.76.40.227:57685>;+sip.instance="<urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1>"] nor [Contact: <sip:856114@171.76.40.227:57685;transport=udp>;+sip.instance="<urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1>"] 2020-03-29 01:21:31.747 3631-3631/org.linphone.debug I/Linphone: Neither Expires header nor corresponding Contact header found, checking from original request 2020-03-29 01:21:31.747 3631-3631/org.linphone.debug I/Linphone: Refresher [0x73dbda4b00]: has no contact for request [0x742efaea80]. 2020-03-29 01:21:31.747 3631-3631/org.linphone.debug I/Linphone: Refresher[0x73dbda4b00]: scheduling next timer in 108000 ms for purpose [normal refresh] 2020-03-29 01:21:31.748 3631-3631/org.linphone.debug I/Linphone: Register refresher [200] reason [OK] for proxy [<sip:pbx.test.com.au;transport=udp>] 2020-03-29 01:21:31.748 3631-3631/org.linphone.debug I/Linphone: No matching contact neither for [Contact: <sip:856114@171.76.40.227:57685>;+sip.instance="<urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1>"] nor [Contact: <sip:856114@171.76.40.227:57685;transport=udp>;+sip.instance="<urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1>"] 2020-03-29 01:21:31.748 3631-3631/org.linphone.debug I/Linphone: Proxy config [0x73ca57d900] for identity [sip:856114@pbx.test.com.au] moving from state [LinphoneRegistrationOk] to [LinphoneRegistrationOk] on core [0x74c42cbc00] 2020-03-29 01:21:31.748 3631-3631/org.linphone.debug I/Linphone: _linphone_update_dependent_proxy_config(): 0x73ca57d900 is registered, checking for [0x73ca57d900] ->dependency=0x0 2020-03-29 01:21:31.761 3631-3631/org.linphone.debug I/Linphone: linphone_core_find_auth_info(): returning auth info username=856114, realm=pbx.test.com.au 2020-03-29 01:21:31.763 3631-3631/org.linphone.debug I/Linphone: Linphone core [0x74c42cbc00] notified [registration_state_changed] 2020-03-29 01:21:31.764 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: ending recv background task with id=[4442]. 2020-03-29 01:21:31.765 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x4442] 2020-03-29 01:21:35.648 3631-3631/org.linphone.debug I/Linphone: Changing [client] [INVITE] transaction [0x73b01ef600], from state [COMPLETED] to [TERMINATED] 2020-03-29 01:21:35.649 3631-3631/org.linphone.debug I/Linphone: Client INVITE transaction [0x73b01ef600] terminated 2020-03-29 01:21:35.649 3631-3631/org.linphone.debug I/Linphone: transaction [0x73b01ef600]: ending transaction background task with id=[45aa]. 2020-03-29 01:21:35.654 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x45aa] 2020-03-29 01:21:36.488 3631-3631/org.linphone.debug I/Linphone: Changing [client] [REGISTER] transaction [0x73dba6a100], from state [COMPLETED] to [TERMINATED] 2020-03-29 01:21:36.489 3631-3631/org.linphone.debug I/Linphone: Client internal REGISTER transaction [0x73dba6a100] terminated 2020-03-29 01:21:36.489 3631-3631/org.linphone.debug I/Linphone: transaction [0x73dba6a100]: ending transaction background task with id=[4586]. 2020-03-29 01:21:36.494 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x4586] 2020-03-29 01:21:36.495 3631-3631/org.linphone.debug I/Linphone: Transaction [0x73dba6a100] deleted 2020-03-29 01:21:36.774 3631-3631/org.linphone.debug I/Linphone: Changing [client] [REGISTER] transaction [0x73db835bc0], from state [COMPLETED] to [TERMINATED] 2020-03-29 01:21:36.775 3631-3631/org.linphone.debug I/Linphone: Client internal REGISTER transaction [0x73db835bc0] terminated 2020-03-29 01:21:36.775 3631-3631/org.linphone.debug I/Linphone: transaction [0x73db835bc0]: ending transaction background task with id=[4456]. 2020-03-29 01:21:36.779 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x4456] 2020-03-29 01:21:39.864 3631-3631/org.linphone.debug D/InputMethodManager: prepareNavigationBarInfo() DecorView@cc6040e[DialerActivity] 2020-03-29 01:21:39.865 3631-3631/org.linphone.debug D/InputMethodManager: getNavigationBarColor() -855310 2020-03-29 01:21:39.865 3631-3631/org.linphone.debug V/InputMethodManager: Starting input: tba=org.linphone.debug ic=null mNaviBarColor -855310 mIsGetNaviBarColorSuccess true , NavVisible : true , NavTrans : false 2020-03-29 01:21:39.865 3631-3631/org.linphone.debug D/InputMethodManager: startInputInner - Id : 0 2020-03-29 01:21:39.865 3631-3631/org.linphone.debug I/InputMethodManager: startInputInner - mService.startInputOrWindowGainedFocus 2020-03-29 01:21:39.872 3631-3631/org.linphone.debug D/InputTransport: Input channel destroyed: 'ClientS', fd=76 2020-03-29 01:21:39.875 3631-3631/org.linphone.debug W/IInputConnectionWrapper: getExtractedText on inactive InputConnection 2020-03-29 01:21:40.272 3631-3631/org.linphone.debug I/Linphone: Callbacks [0x73dad11d80] unregistered on core [0x74c42cbc00] 2020-03-29 01:21:40.272 3631-3631/org.linphone.debug I/Linphone: Callbacks [0x73dad11ce0] unregistered on core [0x74c42cbc00] 2020-03-29 01:21:40.272 3631-3631/org.linphone.debug I/Linphone: Callbacks [0x73dad11c90] unregistered on core [0x74c42cbc00] 2020-03-29 01:21:40.272 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] Activity paused:org.linphone.dialer.DialerActivity@8aaa436 2020-03-29 01:21:40.272 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] runningActivities=0 2020-03-29 01:21:40.305 3631-3631/org.linphone.debug D/ViewRootImpl@2b6efaa[DialerActivity]: stopped(true) old=false 2020-03-29 01:21:40.307 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] Activity stopped:org.linphone.dialer.DialerActivity@8aaa436 2020-03-29 01:21:40.712 3631-3631/org.linphone.debug D/ViewRootImpl@2b6efaa[DialerActivity]: MSG_WINDOW_FOCUS_CHANGED 0 1 2020-03-29 01:21:40.712 3631-3631/org.linphone.debug D/InputMethodManager: prepareNavigationBarInfo() DecorView@cc6040e[DialerActivity] 2020-03-29 01:21:40.712 3631-3631/org.linphone.debug D/InputMethodManager: getNavigationBarColor() -855310 2020-03-29 01:21:40.875 3631-3631/org.linphone.debug I/Linphone: [Platform Helper] Device is not in interactive mode 2020-03-29 01:21:40.875 3631-3631/org.linphone.debug I/Linphone: [Platform Helper] Device interactive mode: false 2020-03-29 01:21:40.972 3631-3631/org.linphone.debug E/ViewRootImpl@2b6efaa[DialerActivity]: Surface is not valid. 2020-03-29 01:21:42.272 3631-3631/org.linphone.debug I/Linphone: [Activity Monitor] App has entered background mode 2020-03-29 01:21:42.273 3631-3631/org.linphone.debug I/Linphone: LinphoneEvent [0x742ec09440] moving to subscription state LinphoneSubscriptionTerminated 2020-03-29 01:21:42.273 3631-3631/org.linphone.debug I/Linphone: Receiving new state [LinphoneSubscriptionTerminated] for event [0x742ec09440] for friend list [0x74c436b920] 2020-03-29 01:21:42.273 3631-3631/org.linphone.debug I/Linphone: Destroying op [0x74336d8800] of type [SalOpUnknown] 2020-03-29 01:21:42.274 3631-3631/org.linphone.debug I/Linphone: Transaction [0x73daf2da00] deleted 2020-03-29 01:23:19.763 3631-3631/org.linphone.debug I/Linphone: linphone_core_find_auth_info(): returning auth info username=856114, realm=pbx.test.com.au 2020-03-29 01:23:19.765 3631-3631/org.linphone.debug I/Linphone: AuthStack::authFound() for Username[856114];Userid[];Realm[pbx.test.com.au];Domain[pbx.test.com.au];Algorithm[]; 2020-03-29 01:23:19.765 3631-3631/org.linphone.debug I/Linphone: Auth info found for [856114] realm [pbx.test.com.au] 2020-03-29 01:23:19.776 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x73db162080)] acquired [ref=0x469a] 2020-03-29 01:23:19.778 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 18074 2020-03-29 01:23:19.778 3631-3631/org.linphone.debug I/Linphone: transaction [0x73db162080]: starting transaction background task with id=[469a]. 2020-03-29 01:23:19.780 3631-3631/org.linphone.debug I/Linphone: Transaction [0x73db835bc0] deleted 2020-03-29 01:23:19.781 3631-3631/org.linphone.debug I/Linphone: Changing [client] [REGISTER] transaction [0x73db162080], from state [INIT] to [TRYING] 2020-03-29 01:23:19.783 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: message sent to [UDP://pbx.test.com.au:5060], size: [777] bytes 2020-03-29 01:23:19.783 3631-3631/org.linphone.debug I/Linphone: REGISTER sip:pbx.test.com.au SIP/2.0 2020-03-29 01:23:19.783 3631-3631/org.linphone.debug I/Linphone: Via: SIP/2.0/UDP 192.168.1.3:57685;branch=z9hG4bK.cubZCMz7x;rport 2020-03-29 01:23:19.784 3631-3631/org.linphone.debug I/Linphone: From: <sip:856114@pbx.test.com.au>;tag=Y0Bus9ffC 2020-03-29 01:23:19.784 3631-3631/org.linphone.debug I/Linphone: To: sip:856114@pbx.test.com.au 2020-03-29 01:23:19.784 3631-3631/org.linphone.debug I/Linphone: CSeq: 26 REGISTER 2020-03-29 01:23:19.784 3631-3631/org.linphone.debug I/Linphone: Call-ID: ~knT0Wt-ur 2020-03-29 01:23:19.784 3631-3631/org.linphone.debug I/Linphone: Max-Forwards: 70 2020-03-29 01:23:19.784 3631-3631/org.linphone.debug I/Linphone: Supported: replaces, outbound, gruu 2020-03-29 01:23:19.784 3631-3631/org.linphone.debug I/Linphone: Accept: application/sdp 2020-03-29 01:23:19.784 3631-3631/org.linphone.debug I/Linphone: Accept: text/plain 2020-03-29 01:23:19.784 3631-3631/org.linphone.debug I/Linphone: Accept: application/vnd.gsma.rcs-ft-http+xml 2020-03-29 01:23:19.784 3631-3631/org.linphone.debug I/Linphone: Contact: <sip:856114@171.76.40.227:57685;transport=udp>;+sip.instance="<urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1>" 2020-03-29 01:23:19.784 3631-3631/org.linphone.debug I/Linphone: Expires: 120 2020-03-29 01:23:19.784 3631-3631/org.linphone.debug I/Linphone: User-Agent: Unknown (belle-sip/4.3.0) 2020-03-29 01:23:19.784 3631-3631/org.linphone.debug I/Linphone: Authorization: Digest realm="pbx.test.com.au", nonce="5e7faae00000ebb7a7b87e38c499611700ffc42e712f1d67", username="856114", uri="sip:pbx.test.com.au", response="b5754b2083d44417150352af5c0e4624" 2020-03-29 01:23:20.055 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x45e6] 2020-03-29 01:23:20.056 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 17894 2020-03-29 01:23:20.056 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: starting recv background task with id=[45e6]. 2020-03-29 01:23:20.056 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: received [470] new bytes from [UDP://pbx.test.com.au:5060]: 2020-03-29 01:23:20.057 3631-3631/org.linphone.debug I/Linphone: SIP/2.0 401 Unauthorized 2020-03-29 01:23:20.057 3631-3631/org.linphone.debug I/Linphone: Via: SIP/2.0/UDP 192.168.1.3:57685;received=171.76.40.227;branch=z9hG4bK.cubZCMz7x;rport=57685 2020-03-29 01:23:20.057 3631-3631/org.linphone.debug I/Linphone: From: <sip:856114@pbx.test.com.au>;tag=Y0Bus9ffC 2020-03-29 01:23:20.057 3631-3631/org.linphone.debug I/Linphone: To: sip:856114@pbx.test.com.au;tag=68e745ec67c1f1cc100ee88160c82018.8abe 2020-03-29 01:23:20.057 3631-3631/org.linphone.debug I/Linphone: CSeq: 26 REGISTER 2020-03-29 01:23:20.057 3631-3631/org.linphone.debug I/Linphone: Call-ID: ~knT0Wt-ur 2020-03-29 01:23:20.057 3631-3631/org.linphone.debug I/Linphone: WWW-Authenticate: Digest realm="pbx.test.com.au", nonce="5e7fab4d000008ff406f0346708b5eedd416358baf0cc49c", stale=true 2020-03-29 01:23:20.057 3631-3631/org.linphone.debug I/Linphone: Server: Virtual PBX 1.0 2020-03-29 01:23:20.057 3631-3631/org.linphone.debug I/Linphone: Content-Length: 0 2020-03-29 01:23:20.087 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40] [470] bytes parsed 2020-03-29 01:23:20.088 3631-3631/org.linphone.debug I/Linphone: Found transaction matching response. 2020-03-29 01:23:20.088 3631-3631/org.linphone.debug I/Linphone: Changing [client] [REGISTER] transaction [0x73db162080], from state [TRYING] to [COMPLETED] 2020-03-29 01:23:20.089 3631-3631/org.linphone.debug I/Linphone: linphone_core_find_auth_info(): returning auth info username=856114, realm=pbx.test.com.au 2020-03-29 01:23:20.090 3631-3631/org.linphone.debug I/Linphone: AuthStack::authFound() for Username[856114];Userid[];Realm[pbx.test.com.au];Domain[pbx.test.com.au];Algorithm[]; 2020-03-29 01:23:20.090 3631-3631/org.linphone.debug I/Linphone: Auth info found for [856114] realm [pbx.test.com.au] 2020-03-29 01:23:20.097 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x73db7df3c0)] acquired [ref=0x45a2] 2020-03-29 01:23:20.098 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 17826 2020-03-29 01:23:20.098 3631-3631/org.linphone.debug I/Linphone: transaction [0x73db7df3c0]: starting transaction background task with id=[45a2]. 2020-03-29 01:23:20.099 3631-3631/org.linphone.debug I/Linphone: Changing [client] [REGISTER] transaction [0x73db7df3c0], from state [INIT] to [TRYING] 2020-03-29 01:23:20.100 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: message sent to [UDP://pbx.test.com.au:5060], size: [777] bytes 2020-03-29 01:23:20.100 3631-3631/org.linphone.debug I/Linphone: REGISTER sip:pbx.test.com.au SIP/2.0 2020-03-29 01:23:20.100 3631-3631/org.linphone.debug I/Linphone: Via: SIP/2.0/UDP 192.168.1.3:57685;branch=z9hG4bK.b08dGHp07;rport 2020-03-29 01:23:20.100 3631-3631/org.linphone.debug I/Linphone: From: <sip:856114@pbx.test.com.au>;tag=Y0Bus9ffC 2020-03-29 01:23:20.100 3631-3631/org.linphone.debug I/Linphone: To: sip:856114@pbx.test.com.au 2020-03-29 01:23:20.101 3631-3631/org.linphone.debug I/Linphone: CSeq: 27 REGISTER 2020-03-29 01:23:20.101 3631-3631/org.linphone.debug I/Linphone: Call-ID: ~knT0Wt-ur 2020-03-29 01:23:20.101 3631-3631/org.linphone.debug I/Linphone: Max-Forwards: 70 2020-03-29 01:23:20.101 3631-3631/org.linphone.debug I/Linphone: Supported: replaces, outbound, gruu 2020-03-29 01:23:20.101 3631-3631/org.linphone.debug I/Linphone: Accept: application/sdp 2020-03-29 01:23:20.101 3631-3631/org.linphone.debug I/Linphone: Accept: text/plain 2020-03-29 01:23:20.101 3631-3631/org.linphone.debug I/Linphone: Accept: application/vnd.gsma.rcs-ft-http+xml 2020-03-29 01:23:20.101 3631-3631/org.linphone.debug I/Linphone: Contact: <sip:856114@171.76.40.227:57685;transport=udp>;+sip.instance="<urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1>" 2020-03-29 01:23:20.101 3631-3631/org.linphone.debug I/Linphone: Expires: 120 2020-03-29 01:23:20.101 3631-3631/org.linphone.debug I/Linphone: User-Agent: Unknown (belle-sip/4.3.0) 2020-03-29 01:23:20.101 3631-3631/org.linphone.debug I/Linphone: Authorization: Digest realm="pbx.test.com.au", nonce="5e7fab4d000008ff406f0346708b5eedd416358baf0cc49c", username="856114", uri="sip:pbx.test.com.au", response="7e46edadc4cf672dfa31b276e8af678f" 2020-03-29 01:23:20.102 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: ending recv background task with id=[45e6]. 2020-03-29 01:23:20.109 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x45e6] 2020-03-29 01:23:20.381 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x458a] 2020-03-29 01:23:20.381 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 17802 2020-03-29 01:23:20.382 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: starting recv background task with id=[458a]. 2020-03-29 01:23:20.383 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: received [733] new bytes from [UDP://pbx.test.com.au:5060]: 2020-03-29 01:23:20.383 3631-3631/org.linphone.debug I/Linphone: SIP/2.0 200 OK 2020-03-29 01:23:20.383 3631-3631/org.linphone.debug I/Linphone: Via: SIP/2.0/UDP 192.168.1.3:57685;received=171.76.40.227;branch=z9hG4bK.b08dGHp07;rport=57685 2020-03-29 01:23:20.383 3631-3631/org.linphone.debug I/Linphone: From: <sip:856114@pbx.test.com.au>;tag=Y0Bus9ffC 2020-03-29 01:23:20.383 3631-3631/org.linphone.debug I/Linphone: To: sip:856114@pbx.test.com.au;tag=68e745ec67c1f1cc100ee88160c82018.9e6f 2020-03-29 01:23:20.383 3631-3631/org.linphone.debug I/Linphone: CSeq: 27 REGISTER 2020-03-29 01:23:20.383 3631-3631/org.linphone.debug I/Linphone: Call-ID: ~knT0Wt-ur 2020-03-29 01:23:20.383 3631-3631/org.linphone.debug I/Linphone: Contact: <sip:856114@192.168.1.3:57685;transport=udp>;expires=120;received="sip:171.76.40.227:57685";pub-gruu="sip:856114@202.52.129.60:5060;gr=urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1";temp-gruu="sip:tgruu.AUULW2cDRWIJSRNWZgdBYgRQRhw9CwUmWRQJCjEBFGMJEgBDNQYRYB1AAw0wHEhmCUUeD2MFSGQEFFUPZlNBc04bXTpjZgR+RQI=@202.52.129.60:5060;gr";+sip.instance="urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1" 2020-03-29 01:23:20.383 3631-3631/org.linphone.debug I/Linphone: Server: Virtual PBX 1.0 2020-03-29 01:23:20.383 3631-3631/org.linphone.debug I/Linphone: Content-Length: 0 2020-03-29 01:23:20.414 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40] [733] bytes parsed 2020-03-29 01:23:20.415 3631-3631/org.linphone.debug I/Linphone: Found transaction matching response. 2020-03-29 01:23:20.416 3631-3631/org.linphone.debug I/Linphone: Changing [client] [REGISTER] transaction [0x73db7df3c0], from state [TRYING] to [COMPLETED] 2020-03-29 01:23:20.416 3631-3631/org.linphone.debug I/Linphone: No matching contact neither for [Contact: <sip:856114@171.76.40.227:57685>;+sip.instance="<urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1>"] nor [Contact: <sip:856114@171.76.40.227:57685;transport=udp>;+sip.instance="<urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1>"] 2020-03-29 01:23:20.416 3631-3631/org.linphone.debug I/Linphone: Neither Expires header nor corresponding Contact header found, checking from original request 2020-03-29 01:23:20.417 3631-3631/org.linphone.debug I/Linphone: Refresher [0x73dbda4b00]: has no contact for request [0x742efaea80]. 2020-03-29 01:23:20.417 3631-3631/org.linphone.debug I/Linphone: Refresher[0x73dbda4b00]: scheduling next timer in 108000 ms for purpose [normal refresh] 2020-03-29 01:23:20.417 3631-3631/org.linphone.debug I/Linphone: Register refresher [200] reason [OK] for proxy [<sip:pbx.test.com.au;transport=udp>] 2020-03-29 01:23:20.418 3631-3631/org.linphone.debug I/Linphone: No matching contact neither for [Contact: <sip:856114@171.76.40.227:57685>;+sip.instance="<urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1>"] nor [Contact: <sip:856114@171.76.40.227:57685;transport=udp>;+sip.instance="<urn:uuid:db0d09b3-f7a3-00cc-8595-a04874dfa5b1>"] 2020-03-29 01:23:20.418 3631-3631/org.linphone.debug I/Linphone: Proxy config [0x73ca57d900] for identity [sip:856114@pbx.test.com.au] moving from state [LinphoneRegistrationOk] to [LinphoneRegistrationOk] on core [0x74c42cbc00] 2020-03-29 01:23:20.419 3631-3631/org.linphone.debug I/Linphone: _linphone_update_dependent_proxy_config(): 0x73ca57d900 is registered, checking for [0x73ca57d900] ->dependency=0x0 2020-03-29 01:23:20.421 3631-3631/org.linphone.debug I/Linphone: Linphone core [0x74c42cbc00] notified [registration_state_changed] 2020-03-29 01:23:20.422 3631-3631/org.linphone.debug I/Linphone: channel [0x73b96e8b40]: ending recv background task with id=[458a]. 2020-03-29 01:23:20.426 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x458a] 2020-03-29 01:23:25.105 3631-3631/org.linphone.debug I/Linphone: Changing [client] [REGISTER] transaction [0x73db162080], from state [COMPLETED] to [TERMINATED] 2020-03-29 01:23:25.106 3631-3631/org.linphone.debug I/Linphone: Client internal REGISTER transaction [0x73db162080] terminated 2020-03-29 01:23:25.106 3631-3631/org.linphone.debug I/Linphone: transaction [0x73db162080]: ending transaction background task with id=[469a]. 2020-03-29 01:23:25.109 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x469a] 2020-03-29 01:23:25.110 3631-3631/org.linphone.debug I/Linphone: Transaction [0x73db162080] deleted 2020-03-29 01:23:25.434 3631-3631/org.linphone.debug I/Linphone: Changing [client] [REGISTER] transaction [0x73db7df3c0], from state [COMPLETED] to [TERMINATED] 2020-03-29 01:23:25.435 3631-3631/org.linphone.debug I/Linphone: Client internal REGISTER transaction [0x73db7df3c0] terminated 2020-03-29 01:23:25.435 3631-3631/org.linphone.debug I/Linphone: transaction [0x73db7df3c0]: ending transaction background task with id=[45a2]. 2020-03-29 01:23:25.439 3631-3631/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x45a2]

My iPhone debug logs ( Working )

2020-03-29 01:24:57.938819+0530 linphone[68064:1643374] [Message] Client internal REGISTER transaction [0x60005ed25bc0] terminated
2020-03-29 01:24:57.939082+0530 linphone[68064:1643374] [Message] transaction [0x60005ed25bc0]: ending transaction background task with id=[fceb].
2020-03-29 01:24:57.939676+0530 linphone[68064:1643374] [Message] Transaction [0x60005ed25bc0] deleted
2020-03-29 01:24:58.277076+0530 linphone[68064:1643374] [Message] Changing [client] [REGISTER] transaction [0x60005ed22a00], from state [COMPLETED] to [TERMINATED]
2020-03-29 01:24:58.277318+0530 linphone[68064:1643374] [Message] Client internal REGISTER transaction [0x60005ed22a00] terminated
2020-03-29 01:24:58.277550+0530 linphone[68064:1643374] [Message] transaction [0x60005ed22a00]: ending transaction background task with id=[fcf4].
2020-03-29 01:24:59.898674+0530 linphone[68064:1643374] [Message] Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background.
2020-03-29 01:24:59.898908+0530 linphone[68064:1643374] [Message] channel [0x7fd1c6710000]: starting recv background task with id=[fe50].
2020-03-29 01:24:59.899128+0530 linphone[68064:1643374] [Message] channel 0x7fd1c6710000: state DISCONNECTED
2020-03-29 01:24:59.899336+0530 linphone[68064:1643374] [Message] channel [0x7fd1c6710000]: ending recv background task with id=[fe50].
2020-03-29 01:24:59.899679+0530 linphone[68064:1643374] [Message] channel [0x7fd1c6710000] removed from http provider.
2020-03-29 01:24:59.900341+0530 linphone[68064:1643374] [Message] Channel [0x7fd1c6710000] destroyed
2020-03-29 01:25:02.404847+0530 linphone[68064:1643374] [Message] [ToneManager] linphoneCorePlayDtmf
2020-03-29 01:25:02.405198+0530 linphone[68064:1643374] [Message] speex_lib_ctl init with neon ? 0
2020-03-29 01:25:02.405453+0530 linphone[68064:1643374] [Message] configuring resampler output to rate=[8000], nchannels=[1]
2020-03-29 01:25:02.405763+0530 linphone[68064:1643374] [Message] ms_filter_link: MSFilePlayer:0x6000d69c14a0,0-->MSDtmfGen:0x6000d69c1540,0
2020-03-29 01:25:02.406029+0530 linphone[68064:1643374] [Message] ms_filter_link: MSDtmfGen:0x6000d69c1540,0-->MSResample:0x6000d69c1680,0
2020-03-29 01:25:02.405959+0530 linphone[68064:1654979] [Message] Priority used: 47
2020-03-29 01:25:02.406285+0530 linphone[68064:1643374] [Message] ms_filter_link: MSResample:0x6000d69c1680,0-->MSAQWrite:0x6000d69c15e0,0
2020-03-29 01:25:02.406395+0530 linphone[68064:1654979] [Message] Ring MSTicker priority set to SCHED_RR and value (47)
2020-03-29 01:25:02.406513+0530 linphone[68064:1643374] [Message] Initializing speex resampler in mode [voip]
2020-03-29 01:25:02.501638+0530 linphone[68064:1643374] [Message] [ToneManager] linphoneCoreStopDtmf
2020-03-29 01:25:02.688828+0530 linphone[68064:1643374] [Message] [ToneManager] linphoneCorePlayDtmf
2020-03-29 01:25:02.783263+0530 linphone[68064:1643374] [Message] [ToneManager] linphoneCoreStopDtmf
2020-03-29 01:25:03.368511+0530 linphone[68064:1643374] [Message] [ToneManager] linphoneCorePlayDtmf
2020-03-29 01:25:03.454924+0530 linphone[68064:1643374] [Message] [ToneManager] linphoneCoreStopDtmf
2020-03-29 01:25:04.527708+0530 linphone[68064:1643374] [Message] file path is /Users/skaruppiah/Library/Developer/CoreSimulator/Devices/DEA4556F-2C24-44D8-9976-7F561EAC551C/data/Containers/Data/Application/7BA6E52C-BCD3-4CFA-AE3A-4797FFF4C0FE/Library/Caches/recording_unknow_Sun-29-Mar-2020-01-25-04.mkv
2020-03-29 01:25:04.528129+0530 linphone[68064:1643374] [Debug] record file path: /Users/skaruppiah/Library/Developer/CoreSimulator/Devices/DEA4556F-2C24-44D8-9976-7F561EAC551C/data/Containers/Data/Application/7BA6E52C-BCD3-4CFA-AE3A-4797FFF4C0FE/Library/Caches/recording_unknow_Sun-29-Mar-2020-01-25-04.mkv
2020-03-29 01:25:04.528469+0530 linphone[68064:1643374] [Message] New MediaSession [0x6000a727e6b8] initialized (LinphoneCore version: 4.4.0-alpha-105-gab93dcb49)
2020-03-29 01:25:04.529097+0530 linphone[68064:1643374] [Message] Stream indexes selected (-1 = unassigned): mainAudioStreamIndex=0, mainVideoStreamIndex=-1, mainTextStreamIndex=-1
2020-03-29 01:25:04.529380+0530 linphone[68064:1643374] [Message] Found media local-ip from signaling.
2020-03-29 01:25:04.530728+0530 linphone[68064:1643374] [Message] [LIME]  /Users/jenkins/builds/cRkGgq4t/1/BC/public/linphone-sdk/lime/src/lime_localStorage.cpp:255 Cannot find Lime User sip:856113@202.52.129.60;gr=urn:uuid:cc4a95ac-217f-00fa-bc4b-3780b3891601 in DB while setting up identity key for ZRTP auxiliary secret
2020-03-29 01:25:04.531021+0530 linphone[68064:1643374] [Message] Port 7212 randomly taken from range [ 7200 , 7299]
2020-03-29 01:25:04.531275+0530 linphone[68064:1643374] [Message] stream#0 [audio] in state [Stopped]: multicast role is [inactive]
2020-03-29 01:25:04.531732+0530 linphone[68064:1643374] [Message] RtpSession bound to [::0] ports [7212] [7213]
2020-03-29 01:25:04.533201+0530 linphone[68064:1643374] [Message] Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80
2020-03-29 01:25:04.533437+0530 linphone[68064:1643374] [Message] Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32
2020-03-29 01:25:04.533637+0530 linphone[68064:1643374] [Message] Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80
2020-03-29 01:25:04.533815+0530 linphone[68064:1643374] [Message] Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32
2020-03-29 01:25:04.534023+0530 linphone[68064:1643374] [Message] Creating ZRTP engine on rtp session [0x7fd1c19bde00] ssrc 0x21e3a34a
2020-03-29 01:25:04.534528+0530 linphone[68064:1643374] [Message] rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION
2020-03-29 01:25:04.534718+0530 linphone[68064:1643374] [Message] Setting DSCP to 46 for MSAudio stream.
2020-03-29 01:25:04.534930+0530 linphone[68064:1643374] [Message] Created stream#0 [audio] in state [Stopped]
2020-03-29 01:25:04.535154+0530 linphone[68064:1643374] [Message] Created new ICE check list 0x6000327641e0 for stream #0
2020-03-29 01:25:04.535370+0530 linphone[68064:1643374] [Warning] Fail to set IPv4 packet info on RTP socket: Invalid argument.
2020-03-29 01:25:04.535549+0530 linphone[68064:1643374] [Warning] Fail to set IPv4 packet info on RTCP socket: Invalid argument.
2020-03-29 01:25:04.535783+0530 linphone[68064:1643374] [Message] ICE state is IceStateInProgress for stream#0 [audio] in state [Stopped]
2020-03-29 01:25:04.536005+0530 linphone[68064:1643374] [Message] Linphone core [0x7fd1c1008200] notified [call_created]
2020-03-29 01:25:04.536210+0530 linphone[68064:1643374] [Message] CallSession [0x6000a727e6b8] moving from state LinphoneCallIdle to LinphoneCallOutgoingInit
2020-03-29 01:25:04.536661+0530 linphone[68064:1643374] [Message] Background task Liblinphone cpu lock started. Unknown remaining time since application is not fully in background.
2020-03-29 01:25:04.537102+0530 linphone[68064:1643374] [Message] (  PhoneMainView.m:642 ) Change current view to CallOutgoingView
2020-03-29 01:25:04.540713+0530 linphone[68064:1643374] [Message] Linphone core [0x7fd1c1008200] notified [call_state_changed]
2020-03-29 01:25:04.540943+0530 linphone[68064:1643374] [Message] ms_filter_link: MSRtpRecv:0x6000d6e6c320,0-->MSVoidSink:0x6000d6e6c5a0,0
2020-03-29 01:25:04.541175+0530 linphone[68064:1643374] [Warning] Fail to set IPv4 packet info on RTP socket: Invalid argument.
2020-03-29 01:25:04.541204+0530 linphone[68064:1654989] [Message] Priority used: 47
2020-03-29 01:25:04.541408+0530 linphone[68064:1643374] [Warning] Fail to set IPv4 packet info on RTCP socket: Invalid argument.
2020-03-29 01:25:04.541499+0530 linphone[68064:1654989] [Message] MSAudio MSTicker priority set to SCHED_RR and value (47)
2020-03-29 01:25:04.541679+0530 linphone[68064:1643374] [Message] Fetching current local IP addresses using getifaddrs().
2020-03-29 01:25:04.541963+0530 linphone[68064:1643374] [Message] ICE: gathering candidates from [stun.linphone.org] using STUN
2020-03-29 01:25:04.542196+0530 linphone[68064:1643374] [Message] ice: Send STUN binding request: [::]:7212 --> 51.0.222.219:3478 [3af65108e3548f458a127550]
2020-03-29 01:25:04.597114+0530 linphone[68064:1643374] [Message] ice: Send STUN binding request: [::]:7213 --> 51.0.222.219:3478 [9d243f1df5d7eba7afc35748]
2020-03-29 01:25:04.713219+0530 linphone[68064:1654989] [Message] First estimation
2020-03-29 01:25:04.717559+0530 linphone[68064:1643374] [Message] ice: Recv binding response: 10.0.0.2:7212 <-- 51.0.222.219:3478 [3af65108e3548f458a127550]
2020-03-29 01:25:04.717847+0530 linphone[68064:1643374] [Message] ice: Add candidate obtained by STUN/TURN: Found transaction matching response:7212:srflx
2020-03-29 01:25:04.777484+0530 linphone[68064:1643374] [Message] ice: Recv binding response: 10.0.0.2:7213 <-- 51.0.222.219:3478 [9d243f1df5d7eba7afc35748]
2020-03-29 01:25:04.777783+0530 linphone[68064:1643374] [Message] ice: Add candidate obtained by STUN/TURN: 171.76.40.227:7213:srflx
2020-03-29 01:25:04.778023+0530 linphone[68064:1643374] [Message] ice: Finished candidates gathering for check list 0x6000327641e0
2020-03-29 01:25:04.778224+0530 linphone[68064:1643374] [Message] Local candidates:
2020-03-29 01:25:04.778436+0530 linphone[68064:1643374] [Message]   [0x60000eba0990]:   type=host ip=10.0.0.2 port=7212 componentID=1 priority=2130706303 foundation= base=0x60000eba0990
2020-03-29 01:25:04.778630+0530 linphone[68064:1643374] [Message]   [0x60000eba0a20]:   type=host ip=10.0.0.2 port=7213 componentID=2 priority=2130706302 foundation= base=0x60000eba0a20
2020-03-29 01:25:04.778870+0530 linphone[68064:1643374] [Message]   [0x60000eba0c60]:   type=srflx ip=171.76.40.227 port=7212 componentID=1 priority=1694498687 foundation= base=0x60000eba0990
2020-03-29 01:25:04.779095+0530 linphone[68064:1643374] [Message]   [0x60000eba0d80]:   type=srflx ip=171.76.40.227 port=7213 componentID=2 priority=1694498686 foundation= base=0x60000eba0a20
2020-03-29 01:25:04.779304+0530 linphone[68064:1643374] [Message] Remote candidates:
2020-03-29 01:25:04.779559+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSRtpRecv:0x6000d6e6c320,0-->MSVoidSink:0x6000d6e6c5a0,0
2020-03-29 01:25:04.779976+0530 linphone[68064:1643374] [Message] [ToneManager] linphoneCoreStopDtmfStream
2020-03-29 01:25:04.780243+0530 linphone[68064:1643374] [Message] [ToneManager] doStopTone
2020-03-29 01:25:04.792640+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSFilePlayer:0x6000d69c14a0,0-->MSDtmfGen:0x6000d69c1540,0
2020-03-29 01:25:04.792857+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSDtmfGen:0x6000d69c1540,0-->MSResample:0x6000d69c1680,0
2020-03-29 01:25:04.793064+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSResample:0x6000d69c1680,0-->MSAQWrite:0x6000d69c15e0,0
2020-03-29 01:25:04.796720+0530 linphone[68064:1654979] [Message] Ring MSTicker thread exiting
2020-03-29 01:25:04.797106+0530 linphone[68064:1643374] [Message] ms_filter_link: MSRtpRecv:0x6000d6e17160,0-->MSVoidSink:0x6000d6e17480,0
2020-03-29 01:25:04.797356+0530 linphone[68064:1643374] [Warning] Fail to set IPv4 packet info on RTP socket: Invalid argument.
2020-03-29 01:25:04.797614+0530 linphone[68064:1643374] [Warning] Fail to set IPv4 packet info on RTCP socket: Invalid argument.
2020-03-29 01:25:04.797971+0530 linphone[68064:1643374] [Message] Contact has been fixed using proxy
2020-03-29 01:25:04.798485+0530 linphone[68064:1643374] [Message] ["SivaK-iPhone" <sip:856113@pbx.test.com.au>] calling ["114" <sip:114@pbx.test.com.au>] on op [0x7fd1cb5f02c0]
2020-03-29 01:25:04.798887+0530 linphone[68064:1643374] [Message] Skipping top route of initial route-set because same as request-uri
2020-03-29 01:25:04.799641+0530 linphone[68064:1643374] [Message] Background task belle-sip transaction(0x600002384750) started. Unknown remaining time since application is not fully in background.
2020-03-29 01:25:04.799859+0530 linphone[68064:1643374] [Message] transaction [0x600002384750]: starting transaction background task with id=[ff46].
2020-03-29 01:25:04.808807+0530 linphone[68064:1643374] [Message] Changing [client] [INVITE] transaction [0x600002384750], from state [INIT] to [CALLING]
2020-03-29 01:25:04.809261+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: message sent to [UDP://pbx.test.com.au:5060], size: [1521] bytes
2020-03-29 01:25:04.809384+0530 linphone[68064:1643374] [Message]   INVITE sip:114@pbx.test.com.au SIP/2.0
2020-03-29 01:25:04.809487+0530 linphone[68064:1643374] [Message]   Via: SIP/2.0/UDP 10.0.0.2:62641;branch=z9hG4bK.nYP0BhEiS;rport
2020-03-29 01:25:04.809604+0530 linphone[68064:1643374] [Message]   From: "SivaK-iPhone" <sip:856113@pbx.test.com.au>;tag=y1gXYgnzW
2020-03-29 01:25:04.809712+0530 linphone[68064:1643374] [Message]   To: "114" <sip:114@pbx.test.com.au>
2020-03-29 01:25:04.809819+0530 linphone[68064:1643374] [Message]   CSeq: 20 INVITE
2020-03-29 01:25:04.809903+0530 linphone[68064:1643374] [Message]   Call-ID: mUkzT0L8-L
2020-03-29 01:25:04.809993+0530 linphone[68064:1643374] [Message]   Max-Forwards: 70
2020-03-29 01:25:04.810077+0530 linphone[68064:1643374] [Message]   Supported: replaces, outbound, gruu
2020-03-29 01:25:04.810163+0530 linphone[68064:1643374] [Message]   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
2020-03-29 01:25:04.810253+0530 linphone[68064:1643374] [Message]   Content-Type: application/sdp
2020-03-29 01:25:04.810440+0530 linphone[68064:1643374] [Message]   Content-Length: 854
2020-03-29 01:25:04.810729+0530 linphone[68064:1643374] [Message]   Contact: <sip:856113@202.52.129.60:5060;gr=urn:uuid:cc4a95ac-217f-00fa-bc4b-3780b3891601>;+org.linphone.specs="lime"
2020-03-29 01:25:04.810951+0530 linphone[68064:1643374] [Message]   User-Agent: LinphoneiOS/4.3.0 (iPhone 11 Pro Max) LinphoneSDK/4.4.0-alpha.167+f8422b1
2020-03-29 01:25:04.811350+0530 linphone[68064:1643374] [Message]   v=0
2020-03-29 01:25:04.811635+0530 linphone[68064:1643374] [Message]   o=856113 1507 2937 IN IP4 10.0.0.2
2020-03-29 01:25:04.811900+0530 linphone[68064:1643374] [Message]   s=Talk
2020-03-29 01:25:04.812204+0530 linphone[68064:1643374] [Message]   c=IN IP4 10.0.0.2
2020-03-29 01:25:04.812501+0530 linphone[68064:1643374] [Message]   t=0 0
2020-03-29 01:25:04.812821+0530 linphone[68064:1643374] [Message]   a=ice-pwd:114e64c74baaef2b515695aa
2020-03-29 01:25:04.813097+0530 linphone[68064:1643374] [Message]   a=ice-ufrag:3df6a9b1
2020-03-29 01:25:04.813351+0530 linphone[68064:1643374] [Message]   a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
2020-03-29 01:25:04.813579+0530 linphone[68064:1643374] [Message]   m=audio 7212 RTP/AVPF 96 97 98 0 8 101 99 100
2020-03-29 01:25:04.813907+0530 linphone[68064:1643374] [Message]   c=IN IP4 171.76.40.227
2020-03-29 01:25:04.814232+0530 linphone[68064:1643374] [Message]   a=rtpmap:96 opus/48000/2
2020-03-29 01:25:04.814545+0530 linphone[68064:1643374] [Message]   a=fmtp:96 useinbandfec=1
2020-03-29 01:25:04.814823+0530 linphone[68064:1643374] [Message]   a=rtpmap:97 speex/16000
2020-03-29 01:25:04.815145+0530 linphone[68064:1643374] [Message]   a=fmtp:97 vbr=on
2020-03-29 01:25:04.815361+0530 linphone[68064:1643374] [Message]   a=rtpmap:98 speex/8000
2020-03-29 01:25:04.815664+0530 linphone[68064:1643374] [Message]   a=fmtp:98 vbr=on
2020-03-29 01:25:04.815939+0530 linphone[68064:1643374] [Message]   a=rtpmap:101 telephone-event/48000
2020-03-29 01:25:04.816255+0530 linphone[68064:1643374] [Message]   a=rtpmap:99 telephone-event/16000
2020-03-29 01:25:04.816511+0530 linphone[68064:1643374] [Message]   a=rtpmap:100 telephone-event/8000
2020-03-29 01:25:04.816717+0530 linphone[68064:1643374] [Message]   a=candidate:1 1 UDP 2130706303 10.0.0.2 7212 typ host
2020-03-29 01:25:04.816908+0530 linphone[68064:1643374] [Message]   a=candidate:1 2 UDP 2130706302 10.0.0.2 7213 typ host
2020-03-29 01:25:04.817115+0530 linphone[68064:1643374] [Message]   a=candidate:2 1 UDP 1694498687 171.76.40.227 7212 typ srflx raddr 10.0.0.2 rport 7212
2020-03-29 01:25:04.817372+0530 linphone[68064:1643374] [Message]   a=candidate:2 2 UDP 1694498686 171.76.40.227 7213 typ srflx raddr 10.0.0.2 rport 7213
2020-03-29 01:25:04.817573+0530 linphone[68064:1643374] [Message]   a=rtcp-fb:* trr-int 1000
2020-03-29 01:25:04.817784+0530 linphone[68064:1643374] [Message]   a=rtcp-fb:* ccm tmmbr
2020-03-29 01:25:04.818173+0530 linphone[68064:1643374] [Message] CallSession [0x6000a727e6b8] moving from state LinphoneCallOutgoingInit to LinphoneCallOutgoingProgress
2020-03-29 01:25:04.818911+0530 linphone[68064:1643374] [Message] Linphone core [0x7fd1c1008200] notified [call_state_changed]
2020-03-29 01:25:04.819215+0530 linphone[68064:1643374] [Message] ICE state is IceStateInProgress for stream#0 [audio] in state [Preparing]
2020-03-29 01:25:05.097986+0530 linphone[68064:1643374] [Message] Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background.
2020-03-29 01:25:05.098246+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: starting recv background task with id=[ff55].
2020-03-29 01:25:05.098535+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: received [315] new bytes from [UDP://pbx.test.com.au:5060]:
2020-03-29 01:25:05.098646+0530 linphone[68064:1643374] [Message]   SIP/2.0 100 Giving a try
2020-03-29 01:25:05.098732+0530 linphone[68064:1643374] [Message]   Via: SIP/2.0/UDP 10.0.0.2:62641;received=192.168.1.2;branch=z9hG4bK.nYP0BhEiS;rport=62641
2020-03-29 01:25:05.098824+0530 linphone[68064:1643374] [Message]   From: "SivaK-iPhone" <sip:856113@pbx.test.com.au>;tag=y1gXYgnzW
2020-03-29 01:25:05.098950+0530 linphone[68064:1643374] [Message]   To: "114" <sip:114@pbx.test.com.au>
2020-03-29 01:25:05.099067+0530 linphone[68064:1643374] [Message]   CSeq: 20 INVITE
2020-03-29 01:25:05.099166+0530 linphone[68064:1643374] [Message]   Call-ID: mUkzT0L8-L
2020-03-29 01:25:05.099288+0530 linphone[68064:1643374] [Message]   Server: Virtual PBX 1.0
2020-03-29 01:25:05.099378+0530 linphone[68064:1643374] [Message]   Content-Length: 0
2020-03-29 01:25:05.100473+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000] [315] bytes parsed
2020-03-29 01:25:05.103743+0530 linphone[68064:1643374] [Message] Found transaction matching response.
2020-03-29 01:25:05.104006+0530 linphone[68064:1643374] [Message] Changing [client] [INVITE] transaction [0x600002384750], from state [CALLING] to [PROCEEDING]
2020-03-29 01:25:05.104227+0530 linphone[68064:1643374] [Message] op [0x7fd1cb5f02c0] : set_or_update_dialog() current=[0x0] new=[0x0]
2020-03-29 01:25:05.104445+0530 linphone[68064:1643374] [Message] Op [0x7fd1cb5f02c0] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL]
2020-03-29 01:25:05.104654+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: ending recv background task with id=[ff55].
2020-03-29 01:25:05.118044+0530 linphone[68064:1643374] [Message] Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background.
2020-03-29 01:25:05.118303+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: starting recv background task with id=[ff57].
2020-03-29 01:25:05.118600+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: received [525] new bytes from [UDP://pbx.test.com.au:5060]:
2020-03-29 01:25:05.118691+0530 linphone[68064:1643374] [Message]   SIP/2.0 401 Unauthorized
2020-03-29 01:25:05.118794+0530 linphone[68064:1643374] [Message]   Via: SIP/2.0/UDP 10.0.0.2:62641;received=192.168.1.2;branch=z9hG4bK.nYP0BhEiS;rport=62641
2020-03-29 01:25:05.118895+0530 linphone[68064:1643374] [Message]   From: "SivaK-iPhone" <sip:856113@pbx.test.com.au>;tag=y1gXYgnzW
2020-03-29 01:25:05.119003+0530 linphone[68064:1643374] [Message]   To: "114" <sip:114@pbx.test.com.au>;tag=as6dd495b6
2020-03-29 01:25:05.119136+0530 linphone[68064:1643374] [Message]   Call-ID: mUkzT0L8-L
2020-03-29 01:25:05.119217+0530 linphone[68064:1643374] [Message]   CSeq: 20 INVITE
2020-03-29 01:25:05.119331+0530 linphone[68064:1643374] [Message]   Server: MaxoPBX
2020-03-29 01:25:05.119552+0530 linphone[68064:1643374] [Message]   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
2020-03-29 01:25:05.119754+0530 linphone[68064:1643374] [Message]   Supported: replaces, timer
2020-03-29 01:25:05.119968+0530 linphone[68064:1643374] [Message]   WWW-Authenticate: Digest algorithm=MD5, realm="pbx.maxo.com.au", nonce="6fb0f363"
2020-03-29 01:25:05.120200+0530 linphone[68064:1643374] [Message]   Content-Length: 0
2020-03-29 01:25:05.121590+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000] [525] bytes parsed
2020-03-29 01:25:05.121775+0530 linphone[68064:1643374] [Message] Found transaction matching response.
2020-03-29 01:25:05.121953+0530 linphone[68064:1643374] [Message] Changing [client] [INVITE] transaction [0x600002384750], from state [PROCEEDING] to [COMPLETED]
2020-03-29 01:25:05.122305+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: message sent to [UDP://pbx.test.com.au:5060], size: [409] bytes
2020-03-29 01:25:05.122418+0530 linphone[68064:1643374] [Message]   ACK sip:114@pbx.test.com.au SIP/2.0
2020-03-29 01:25:05.122522+0530 linphone[68064:1643374] [Message]   Via: SIP/2.0/UDP 10.0.0.2:62641;branch=z9hG4bK.nYP0BhEiS;rport
2020-03-29 01:25:05.122611+0530 linphone[68064:1643374] [Message]   Call-ID: mUkzT0L8-L
2020-03-29 01:25:05.122711+0530 linphone[68064:1643374] [Message]   From: "SivaK-iPhone" <sip:856113@pbx.test.com.au>;tag=y1gXYgnzW
2020-03-29 01:25:05.122829+0530 linphone[68064:1643374] [Message]   To: "114" <sip:114@pbx.test.com.au>;tag=as6dd495b6
2020-03-29 01:25:05.122933+0530 linphone[68064:1643374] [Message]   Contact: <sip:856113@202.52.129.60:5060;gr=urn:uuid:cc4a95ac-217f-00fa-bc4b-3780b3891601>;+org.linphone.specs="lime"
2020-03-29 01:25:05.123016+0530 linphone[68064:1643374] [Message]   Max-Forwards: 70
2020-03-29 01:25:05.123124+0530 linphone[68064:1643374] [Message]   CSeq: 20 ACK
2020-03-29 01:25:05.123317+0530 linphone[68064:1643374] [Message] linphone_core_find_auth_info(): returning auth info username=856113, realm=pbx.maxo.com.au
2020-03-29 01:25:05.123533+0530 linphone[68064:1643374] [Message] AuthStack::authFound() for Username[856113];Userid[];Realm[pbx.maxo.com.au];Domain[pbx.test.com.au];Algorithm[];
2020-03-29 01:25:05.123821+0530 linphone[68064:1643374] [Message] Auth info found for [856113] realm [pbx.maxo.com.au]
2020-03-29 01:25:05.124613+0530 linphone[68064:1643374] [Message] Background task belle-sip transaction(0x60000238e560) started. Unknown remaining time since application is not fully in background.
2020-03-29 01:25:05.124860+0530 linphone[68064:1643374] [Message] transaction [0x60000238e560]: starting transaction background task with id=[ff58].
2020-03-29 01:25:05.125178+0530 linphone[68064:1643374] [Message] Changing [client] [INVITE] transaction [0x60000238e560], from state [INIT] to [CALLING]
2020-03-29 01:25:05.125522+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: message sent to [UDP://pbx.test.com.au:5060], size: [1704] bytes
2020-03-29 01:25:05.125615+0530 linphone[68064:1643374] [Message]   INVITE sip:114@pbx.test.com.au SIP/2.0
2020-03-29 01:25:05.125771+0530 linphone[68064:1643374] [Message]   Via: SIP/2.0/UDP 10.0.0.2:62641;branch=z9hG4bK.wDE4Oizp6;rport
2020-03-29 01:25:05.125960+0530 linphone[68064:1643374] [Message]   From: "SivaK-iPhone" <sip:856113@pbx.test.com.au>;tag=y1gXYgnzW
2020-03-29 01:25:05.126112+0530 linphone[68064:1643374] [Message]   To: "114" <sip:114@pbx.test.com.au>
2020-03-29 01:25:05.126356+0530 linphone[68064:1643374] [Message]   CSeq: 21 INVITE
2020-03-29 01:25:05.126556+0530 linphone[68064:1643374] [Message]   Call-ID: mUkzT0L8-L
2020-03-29 01:25:05.126791+0530 linphone[68064:1643374] [Message]   Max-Forwards: 70
2020-03-29 01:25:05.127015+0530 linphone[68064:1643374] [Message]   Supported: replaces, outbound, gruu
2020-03-29 01:25:05.127223+0530 linphone[68064:1643374] [Message]   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
2020-03-29 01:25:05.127468+0530 linphone[68064:1643374] [Message]   Content-Type: application/sdp
2020-03-29 01:25:05.127754+0530 linphone[68064:1643374] [Message]   Content-Length: 854
2020-03-29 01:25:05.127965+0530 linphone[68064:1643374] [Message]   Contact: <sip:856113@202.52.129.60:5060;gr=urn:uuid:cc4a95ac-217f-00fa-bc4b-3780b3891601>;+org.linphone.specs="lime"
2020-03-29 01:25:05.128186+0530 linphone[68064:1643374] [Message]   User-Agent: LinphoneiOS/4.3.0 (iPhone 11 Pro Max) LinphoneSDK/4.4.0-alpha.167+f8422b1
2020-03-29 01:25:05.128410+0530 linphone[68064:1643374] [Message]   Authorization:  Digest realm="pbx.maxo.com.au", nonce="6fb0f363", algorithm=MD5, username="856113",  uri="sip:114@pbx.test.com.au", response="0f5d7a6de9730eef23f214187b3d80fe"
2020-03-29 01:25:05.128613+0530 linphone[68064:1643374] [Message]   v=0
2020-03-29 01:25:05.128828+0530 linphone[68064:1643374] [Message]   o=856113 1507 2937 IN IP4 10.0.0.2
2020-03-29 01:25:05.129137+0530 linphone[68064:1643374] [Message]   s=Talk
2020-03-29 01:25:05.129403+0530 linphone[68064:1643374] [Message]   c=IN IP4 10.0.0.2
2020-03-29 01:25:05.129646+0530 linphone[68064:1643374] [Message]   t=0 0
2020-03-29 01:25:05.129920+0530 linphone[68064:1643374] [Message]   a=ice-pwd:114e64c74baaef2b515695aa
2020-03-29 01:25:05.130179+0530 linphone[68064:1643374] [Message]   a=ice-ufrag:3df6a9b1
2020-03-29 01:25:05.130390+0530 linphone[68064:1643374] [Message]   a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
2020-03-29 01:25:05.130607+0530 linphone[68064:1643374] [Message]   m=audio 7212 RTP/AVPF 96 97 98 0 8 101 99 100
2020-03-29 01:25:05.130843+0530 linphone[68064:1643374] [Message]   c=IN IP4 171.76.40.227
2020-03-29 01:25:05.131006+0530 linphone[68064:1643374] [Message]   a=rtpmap:96 opus/48000/2
2020-03-29 01:25:05.131192+0530 linphone[68064:1643374] [Message]   a=fmtp:96 useinbandfec=1
2020-03-29 01:25:05.131381+0530 linphone[68064:1643374] [Message]   a=rtpmap:97 speex/16000
2020-03-29 01:25:05.131549+0530 linphone[68064:1643374] [Message]   a=fmtp:97 vbr=on
2020-03-29 01:25:05.131700+0530 linphone[68064:1643374] [Message]   a=rtpmap:98 speex/8000
2020-03-29 01:25:05.131909+0530 linphone[68064:1643374] [Message]   a=fmtp:98 vbr=on
2020-03-29 01:25:05.132116+0530 linphone[68064:1643374] [Message]   a=rtpmap:101 telephone-event/48000
2020-03-29 01:25:05.132331+0530 linphone[68064:1643374] [Message]   a=rtpmap:99 telephone-event/16000
2020-03-29 01:25:05.132515+0530 linphone[68064:1643374] [Message]   a=rtpmap:100 telephone-event/8000
2020-03-29 01:25:05.132726+0530 linphone[68064:1643374] [Message]   a=candidate:1 1 UDP 2130706303 10.0.0.2 7212 typ host
2020-03-29 01:25:05.132934+0530 linphone[68064:1643374] [Message]   a=candidate:1 2 UDP 2130706302 10.0.0.2 7213 typ host
2020-03-29 01:25:05.133137+0530 linphone[68064:1643374] [Message]   a=candidate:2 1 UDP 1694498687 171.76.40.227 7212 typ srflx raddr 10.0.0.2 rport 7212
2020-03-29 01:25:05.133385+0530 linphone[68064:1643374] [Message]   a=candidate:2 2 UDP 1694498686 171.76.40.227 7213 typ srflx raddr 10.0.0.2 rport 7213
2020-03-29 01:25:05.133610+0530 linphone[68064:1643374] [Message]   a=rtcp-fb:* trr-int 1000
2020-03-29 01:25:05.133821+0530 linphone[68064:1643374] [Message]   a=rtcp-fb:* ccm tmmbr
2020-03-29 01:25:05.134134+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: ending recv background task with id=[ff57].
2020-03-29 01:25:05.498070+0530 linphone[68064:1643374] [Message] Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background.
2020-03-29 01:25:05.498360+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: starting recv background task with id=[ff6c].
2020-03-29 01:25:05.498630+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: received [315] new bytes from [UDP://pbx.test.com.au:5060]:
2020-03-29 01:25:05.498745+0530 linphone[68064:1643374] [Message]   SIP/2.0 100 Giving a try
2020-03-29 01:25:05.498858+0530 linphone[68064:1643374] [Message]   Via: SIP/2.0/UDP 10.0.0.2:62641;received=192.168.1.2;branch=z9hG4bK.wDE4Oizp6;rport=62641
2020-03-29 01:25:05.498987+0530 linphone[68064:1643374] [Message]   From: "SivaK-iPhone" <sip:856113@pbx.test.com.au>;tag=y1gXYgnzW
2020-03-29 01:25:05.499128+0530 linphone[68064:1643374] [Message]   To: "114" <sip:114@pbx.test.com.au>
2020-03-29 01:25:05.499226+0530 linphone[68064:1643374] [Message]   CSeq: 21 INVITE
2020-03-29 01:25:05.499343+0530 linphone[68064:1643374] [Message]   Call-ID: mUkzT0L8-L
2020-03-29 01:25:05.499429+0530 linphone[68064:1643374] [Message]   Server: Virtual PBX 1.0
2020-03-29 01:25:05.499518+0530 linphone[68064:1643374] [Message]   Content-Length: 0
2020-03-29 01:25:05.500797+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000] [315] bytes parsed
2020-03-29 01:25:05.503869+0530 linphone[68064:1643374] [Message] Found transaction matching response.
2020-03-29 01:25:05.504104+0530 linphone[68064:1643374] [Message] Changing [client] [INVITE] transaction [0x60000238e560], from state [CALLING] to [PROCEEDING]
2020-03-29 01:25:05.504319+0530 linphone[68064:1643374] [Message] op [0x7fd1cb5f02c0] : set_or_update_dialog() current=[0x0] new=[0x0]
2020-03-29 01:25:05.504509+0530 linphone[68064:1643374] [Message] Op [0x7fd1cb5f02c0] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL]
2020-03-29 01:25:05.504691+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: ending recv background task with id=[ff6c].
2020-03-29 01:25:05.517737+0530 linphone[68064:1643374] [Message] Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background.
2020-03-29 01:25:05.517984+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: starting recv background task with id=[ff6e].
2020-03-29 01:25:05.518257+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: received [784] new bytes from [UDP://pbx.test.com.au:5060]:
2020-03-29 01:25:05.518377+0530 linphone[68064:1643374] [Message]   SIP/2.0 183 Session Progress
2020-03-29 01:25:05.518475+0530 linphone[68064:1643374] [Message]   Via: SIP/2.0/UDP 10.0.0.2:62641;received=192.168.1.2;branch=z9hG4bK.wDE4Oizp6;rport=62641
2020-03-29 01:25:05.518574+0530 linphone[68064:1643374] [Message]   From: "SivaK-iPhone" <sip:856113@pbx.test.com.au>;tag=y1gXYgnzW
2020-03-29 01:25:05.518690+0530 linphone[68064:1643374] [Message]   To: "114" <sip:114@pbx.test.com.au>;tag=as43abd308
2020-03-29 01:25:05.518808+0530 linphone[68064:1643374] [Message]   Call-ID: mUkzT0L8-L
2020-03-29 01:25:05.518916+0530 linphone[68064:1643374] [Message]   CSeq: 21 INVITE
2020-03-29 01:25:05.519036+0530 linphone[68064:1643374] [Message]   Server: MaxoPBX
2020-03-29 01:25:05.519252+0530 linphone[68064:1643374] [Message]   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
2020-03-29 01:25:05.519487+0530 linphone[68064:1643374] [Message]   Supported: replaces, timer
2020-03-29 01:25:05.519756+0530 linphone[68064:1643374] [Message]   Content-Type: application/sdp
2020-03-29 01:25:05.519981+0530 linphone[68064:1643374] [Message]   Contact: <sip:856113@202.52.129.60:5060>
2020-03-29 01:25:05.520250+0530 linphone[68064:1643374] [Message]   Content-Length: 263
2020-03-29 01:25:05.520520+0530 linphone[68064:1643374] [Message]   v=0
2020-03-29 01:25:05.520729+0530 linphone[68064:1643374] [Message]   o=root 1624335393 1624335393 IN IP4 202.52.129.73
2020-03-29 01:25:05.520996+0530 linphone[68064:1643374] [Message]   s=Asterisk PBX 11.19.0
2020-03-29 01:25:05.521232+0530 linphone[68064:1643374] [Message]   c=IN IP4 202.52.129.73
2020-03-29 01:25:05.521445+0530 linphone[68064:1643374] [Message]   t=0 0
2020-03-29 01:25:05.521812+0530 linphone[68064:1643374] [Message]   m=audio 14578 RTP/AVPF 0 8 100
2020-03-29 01:25:05.522064+0530 linphone[68064:1643374] [Message]   a=rtpmap:0 PCMU/8000
2020-03-29 01:25:05.522350+0530 linphone[68064:1643374] [Message]   a=rtpmap:8 PCMA/8000
2020-03-29 01:25:05.522593+0530 linphone[68064:1643374] [Message]   a=rtpmap:100 telephone-event/8000
2020-03-29 01:25:05.522829+0530 linphone[68064:1643374] [Message]   a=fmtp:100 0-16
2020-03-29 01:25:05.523083+0530 linphone[68064:1643374] [Message]   a=ptime:20
2020-03-29 01:25:05.523266+0530 linphone[68064:1643374] [Message]   a=sendrecv
2020-03-29 01:25:05.525031+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000] [521] bytes parsed
2020-03-29 01:25:05.525282+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000] read [263] bytes of body from [pbx.test.com.au:5060]
2020-03-29 01:25:05.525498+0530 linphone[68064:1643374] [Message] Found transaction matching response.
2020-03-29 01:25:05.525718+0530 linphone[68064:1643374] [Message] New client dialog [0x60003276d4a0] , local tag [y1gXYgnzW], remote tag [as43abd308]
2020-03-29 01:25:05.525906+0530 linphone[68064:1643374] [Message] Dialog [0x60003276d4a0]: now updated by transaction [0x60000238e560].
2020-03-29 01:25:05.526179+0530 linphone[68064:1643374] [Message] op [0x7fd1cb5f02c0] : set_or_update_dialog() current=[0x0] new=[0x60003276d4a0]
2020-03-29 01:25:05.526379+0530 linphone[68064:1643374] [Message] Op [0x7fd1cb5f02c0] receiving call response [183], dialog is [0x60003276d4a0] in state [BELLE_SIP_DIALOG_EARLY]
2020-03-29 01:25:05.526965+0530 linphone[68064:1643374] [Message] Found payload PCMU/8000 fmtp=
2020-03-29 01:25:05.527191+0530 linphone[68064:1643374] [Message] Found payload PCMA/8000 fmtp=
2020-03-29 01:25:05.527394+0530 linphone[68064:1643374] [Message] Found payload telephone-event/8000 fmtp=0-16
2020-03-29 01:25:05.527638+0530 linphone[68064:1643374] [Message] Doing SDP offer/answer process of type outgoing
2020-03-29 01:25:05.527832+0530 linphone[68064:1643374] [Message] Processing for stream 0
2020-03-29 01:25:05.528033+0530 linphone[68064:1643374] [Message] Adding opus/48000 for compatibility, just in case.
2020-03-29 01:25:05.528257+0530 linphone[68064:1643374] [Message] Adding speex/16000 for compatibility, just in case.
2020-03-29 01:25:05.528456+0530 linphone[68064:1643374] [Message] Adding speex/8000 for compatibility, just in case.
2020-03-29 01:25:05.528649+0530 linphone[68064:1643374] [Message] Adding telephone-event/48000 for compatibility, just in case.
2020-03-29 01:25:05.528864+0530 linphone[68064:1643374] [Message] Adding telephone-event/16000 for compatibility, just in case.
2020-03-29 01:25:05.529146+0530 linphone[68064:1643374] [Message] CallSession [0x6000a727e6b8] moving from state LinphoneCallOutgoingProgress to LinphoneCallOutgoingEarlyMedia
2020-03-29 01:25:05.529715+0530 linphone[68064:1643374] [Message] Linphone core [0x7fd1c1008200] notified [call_state_changed]
2020-03-29 01:25:05.529936+0530 linphone[68064:1643374] [Message] [ToneManager] [0x6000a727e6b8] state changed : [None, LinphoneCallOutgoingEarlyMedia]
2020-03-29 01:25:05.530116+0530 linphone[68064:1643374] [Message] [ToneManager] stop
2020-03-29 01:25:05.530290+0530 linphone[68064:1643374] [Message] [ToneManager] nothing to stop
2020-03-29 01:25:05.530466+0530 linphone[68064:1643374] [Message] Doing early media...
2020-03-29 01:25:05.530684+0530 linphone[68064:1643374] [Message] StreamsGroup 0x60000238e700 rendering stream#0 [audio] in state [Preparing]
2020-03-29 01:25:05.530904+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSRtpRecv:0x6000d6e17160,0-->MSVoidSink:0x6000d6e17480,0
2020-03-29 01:25:05.531125+0530 linphone[68064:1643374] [Message] Audio bandwidth for StreamsGroup [0x60000238e700] is 80
2020-03-29 01:25:05.531347+0530 linphone[68064:1643374] [Message] Payload type [opus/48000] has explicit bitrate [36] kbit/s
2020-03-29 01:25:05.531561+0530 linphone[68064:1643374] [Message] Payload type [speex/16000] has explicit bitrate [36] kbit/s
2020-03-29 01:25:05.531775+0530 linphone[68064:1643374] [Message] Payload type [speex/8000] has explicit bitrate [36] kbit/s
2020-03-29 01:25:05.532027+0530 linphone[68064:1643374] [Message] Equalizer location: mic
2020-03-29 01:25:05.532232+0530 linphone[68064:1643374] [Message] cannot set noise gate mode to [0] because no volume send
2020-03-29 01:25:05.532464+0530 linphone[68064:1643374] [Warning] Advanced adaptive rate control requested but avpf-tmmbr is not activated in this stream. Reverting to basic rate control instead
2020-03-29 01:25:05.532642+0530 linphone[68064:1643374] [Message] Early media sending allowed, will send real live sound and video.
2020-03-29 01:25:05.532835+0530 linphone[68064:1643374] [Message] stream#0 [audio] in state [Stopped] is not part of any bundle
2020-03-29 01:25:05.533115+0530 linphone[68064:1643374] [Warning] Fail to set IPv4 packet info on RTP socket: Invalid argument.
2020-03-29 01:25:05.533406+0530 linphone[68064:1643374] [Warning] Fail to set IPv4 packet info on RTCP socket: Invalid argument.
2020-03-29 01:25:05.534796+0530 linphone[68064:1643374] [Message] RtpSession [0x7fd1c19bde00] sending to rtp [::ffff:202.52.129.73]:14578 rtcp [::ffff:202.52.129.73]:14579
2020-03-29 01:25:05.535058+0530 linphone[68064:1643374] [Message] Configuring audio session for playback/record
2020-03-29 01:25:05.535282+0530 linphone[68064:1643374] [Message] MSAURead/MSAUWrite: configureAudioSession() took 0 ms.
2020-03-29 01:25:05.535509+0530 linphone[68064:1643374] [Message] MSAURead/MSAUWrite: AVAudioSession is configured at sample rate 48000.
2020-03-29 01:25:05.535724+0530 linphone[68064:1643374] [Message] speex_lib_ctl init with neon ? 0
2020-03-29 01:25:05.535919+0530 linphone[68064:1643374] [Message] Audio session already correctly configured.
2020-03-29 01:25:05.536126+0530 linphone[68064:1643374] [Message] MSAURead/MSAUWrite: AVAudioSession is configured at sample rate 48000.
2020-03-29 01:25:05.536313+0530 linphone[68064:1643374] [Message] speex_lib_ctl init with neon ? 0
2020-03-29 01:25:05.536500+0530 linphone[68064:1643374] [Message] MKVRecorder: initialisation
2020-03-29 01:25:05.536748+0530 linphone[68064:1643374] [Message] speex_lib_ctl init with neon ? 0
2020-03-29 01:25:05.536906+0530 linphone[68064:1643374] [Message] Configuring av recorder with audio format type=audio;encoding=opus;rate=48000;channels=1;fmtp=''
2020-03-29 01:25:05.537060+0530 linphone[68064:1643374] [Message] MKVRecorder: set pin #1 format. type=audio;encoding=opus;rate=48000;channels=1;fmtp=''
2020-03-29 01:25:05.537255+0530 linphone[68064:1643374] [Message] target bitrate not set for stream [0x7fd1cb6c57e0] using payload's bitrate is 80000
2020-03-29 01:25:05.537428+0530 linphone[68064:1643374] [Message] Setting audio encoder network bitrate to [80000] on stream [0x7fd1cb6c57e0]
2020-03-29 01:25:05.537593+0530 linphone[68064:1643374] [Message] RtpSession: target upload bandwidth set to 80000
2020-03-29 01:25:05.537784+0530 linphone[68064:1643374] [Message] MSUlawEnc configured with ptime=20
2020-03-29 01:25:05.537962+0530 linphone[68064:1643374] [Message] Audio session already correctly configured.
2020-03-29 01:25:05.538150+0530 linphone[68064:1643374] [Message] MSAURead/MSAUWrite: AVAudioSession is configured at sample rate 48000.
2020-03-29 01:25:05.538320+0530 linphone[68064:1643374] [Message] configuring MSAURead:0x6000d6e70500-->MSUlawEnc:0x6000d6e70640 from rate [48000] to rate [8000] and from channel [1] to channel [1]
2020-03-29 01:25:05.538484+0530 linphone[68064:1643374] [Message] Audio session already correctly configured.
2020-03-29 01:25:05.538661+0530 linphone[68064:1643374] [Message] MSAURead/MSAUWrite: AVAudioSession is configured at sample rate 48000.
2020-03-29 01:25:05.538885+0530 linphone[68064:1643374] [Message] configuring MSUlawDec:0x6000d6e706e0-->MSAUWrite:0x6000d6e705a0 from rate [8000] to rate [48000] and from channel [1] to channel [1]
2020-03-29 01:25:05.539150+0530 linphone[68064:1643374] [Message] ms_filter_link: MSAURead:0x6000d6e70500,0-->MSResample:0x6000d6e7f0c0,0
2020-03-29 01:25:05.539336+0530 linphone[68064:1643374] [Message] ms_filter_link: MSResample:0x6000d6e7f0c0,0-->MSEqualizer:0x6000d6e70be0,0
2020-03-29 01:25:05.539555+0530 linphone[68064:1643374] [Message] ms_filter_link: MSEqualizer:0x6000d6e70be0,0-->MSVolume:0x6000d6e70780,0
2020-03-29 01:25:05.539832+0530 linphone[68064:1643374] [Message] ms_filter_link: MSVolume:0x6000d6e70780,0-->MSAudioMixer:0x6000d6e7f200,0
2020-03-29 01:25:05.540067+0530 linphone[68064:1643374] [Message] ms_filter_link: MSAudioMixer:0x6000d6e7f200,0-->MSUlawEnc:0x6000d6e70640,0
2020-03-29 01:25:05.540326+0530 linphone[68064:1643374] [Message] ms_filter_link: MSUlawEnc:0x6000d6e70640,0-->MSRtpSend:0x6000d6e155e0,0
2020-03-29 01:25:05.540577+0530 linphone[68064:1643374] [Message] ms_filter_link: MSRtpRecv:0x6000d6e70000,0-->MSUlawDec:0x6000d6e706e0,0
2020-03-29 01:25:05.540840+0530 linphone[68064:1643374] [Message] ms_filter_link: MSUlawDec:0x6000d6e706e0,0-->MSGenericPLC:0x6000d6e70e60,0
2020-03-29 01:25:05.541031+0530 linphone[68064:1643374] [Message] ms_filter_link: MSGenericPLC:0x6000d6e70e60,0-->MSAudioFlowControl:0x6000d6e71040,0
2020-03-29 01:25:05.541252+0530 linphone[68064:1643374] [Message] ms_filter_link: MSAudioFlowControl:0x6000d6e71040,0-->MSDtmfGen:0x6000d6e70460,0
2020-03-29 01:25:05.541507+0530 linphone[68064:1643374] [Message] ms_filter_link: MSDtmfGen:0x6000d6e70460,0-->MSVolume:0x6000d6e70820,0
2020-03-29 01:25:05.541753+0530 linphone[68064:1643374] [Message] ms_filter_link: MSVolume:0x6000d6e70820,0-->MSTee:0x6000d6e7f660,0
2020-03-29 01:25:05.541995+0530 linphone[68064:1643374] [Message] ms_filter_link: MSTee:0x6000d6e7f660,0-->MSEqualizer:0x6000d6e70d20,0
2020-03-29 01:25:05.542185+0530 linphone[68064:1643374] [Message] ms_filter_link: MSEqualizer:0x6000d6e70d20,0-->MSAudioMixer:0x6000d6e710e0,0
2020-03-29 01:25:05.542444+0530 linphone[68064:1643374] [Message] speex_lib_ctl init with neon ? 0
2020-03-29 01:25:05.542688+0530 linphone[68064:1643374] [Message] ms_filter_link: MSFilePlayer:0x6000d6e71400,0-->MSResample:0x6000d6e715e0,0
2020-03-29 01:25:05.542941+0530 linphone[68064:1643374] [Message] ms_filter_link: MSResample:0x6000d6e715e0,0-->MSAudioMixer:0x6000d6e710e0,1
2020-03-29 01:25:05.543170+0530 linphone[68064:1643374] [Message] ms_filter_link: MSAudioMixer:0x6000d6e710e0,0-->MSResample:0x6000d6e7f160,0
2020-03-29 01:25:05.543385+0530 linphone[68064:1643374] [Message] ms_filter_link: MSResample:0x6000d6e7f160,0-->MSAUWrite:0x6000d6e705a0,0
2020-03-29 01:25:05.543623+0530 linphone[68064:1643374] [Message] ms_filter_link: MSAudioMixer:0x6000d6e7f480,1-->MSResample:0x6000d6e7a9e0,0
2020-03-29 01:25:05.543835+0530 linphone[68064:1643374] [Message] ms_filter_link: MSResample:0x6000d6e7a9e0,0-->MSOpusEnc:0x6000d6e708c0,0
2020-03-29 01:25:05.544051+0530 linphone[68064:1643374] [Message] ms_filter_link: MSOpusEnc:0x6000d6e708c0,0-->MSMKVRecorder:0x6000d6e7f700,1
2020-03-29 01:25:05.544295+0530 linphone[68064:1643374] [Message] ms_filter_link: MSItcSource:0x6000d6e7a940,0-->MSMKVRecorder:0x6000d6e7f700,0
2020-03-29 01:25:05.544543+0530 linphone[68064:1643374] [Message] ms_filter_link: MSAudioMixer:0x6000d6e7f200,1-->MSAudioMixer:0x6000d6e7f480,0
2020-03-29 01:25:05.544860+0530 linphone[68064:1643374] [Message] ms_filter_link: MSTee:0x6000d6e7f660,1-->MSAudioMixer:0x6000d6e7f480,1
2020-03-29 01:25:05.545205+0530 linphone[68064:1643374] [Message] ms_filter_link: MSAudioMixer:0x6000d6e7f480,0-->MSFileRec:0x6000d6e7f3e0,0
2020-03-29 01:25:05.545445+0530 linphone[68064:1643374] [Message] Audio session already correctly configured.
2020-03-29 01:25:05.545658+0530 linphone[68064:1643374] [Message] MSAURead/MSAUWrite: AVAudioSession is configured at sample rate 48000.
2020-03-29 01:25:05.546296+0530 linphone[68064:1643374] [Message] AudioUnit created with type kAudioUnitSubType_VoiceProcessingIO.
2020-03-29 01:25:05.546471+0530 linphone[68064:1643374] [Message] configure_audio_unit() now called.
2020-03-29 01:25:05.546656+0530 linphone[68064:1643374] [Message] configure_audio_unit() took 1 ms.
2020-03-29 01:25:05.546810+0530 linphone[68064:1643374] [Message] start_audio_unit(): about to start audio unit.
2020-03-29 01:25:05.547255+0530 linphone[68064:1643374] [Message] I/O unit latency [0.000000], quality [64]
2020-03-29 01:25:05.548652+0530 linphone[68064:1643374] [Message] AudioUnit started, current hw output latency [0.010000] input [0.010000] iobuf[0.021333] hw sample rate [48000.000000]
2020-03-29 01:25:05.548827+0530 linphone[68064:1643374] [Message] start_audio_unit() took 2 ms.
2020-03-29 01:25:05.549001+0530 linphone[68064:1643374] [Message] check_audio_unit_is_up(): audio unit is started.
2020-03-29 01:25:05.549176+0530 linphone[68064:1643374] [Message] ms_ticker_set_time_func: ticker's time method updated.
2020-03-29 01:25:05.549355+0530 linphone[68064:1643374] [Message] Initializing speex resampler in mode [voip]
2020-03-29 01:25:05.549629+0530 linphone[68064:1643374] [Message] Initializing speex resampler in mode [voip]
2020-03-29 01:25:05.549798+0530 linphone[68064:1643374] [Message] Initializing speex resampler in mode [voip]
2020-03-29 01:25:05.549977+0530 linphone[68064:1643374] [Message] configure_audio_session(): AudioUnit is already started, skipping this process.
2020-03-29 01:25:05.550189+0530 linphone[68064:1643374] [Message] check_audio_unit_is_up(): audio unit is started.
2020-03-29 01:25:05.550386+0530 linphone[68064:1643374] [Message] Initializing speex resampler in mode [voip]
2020-03-29 01:25:05.550633+0530 linphone[68064:1643374] [Message] MSOpusEnc: codec bitrate set to [30000] with ptime [20]
2020-03-29 01:25:05.550860+0530 linphone[68064:1643374] [Message] Setting opus codec bitrate to [30000] from network bitrate [46000] with ptime [20]
2020-03-29 01:25:05.551097+0530 linphone[68064:1643374] [Message] Filter MSRtpRecv is already being scheduled; nothing to do.
2020-03-29 01:25:05.551341+0530 linphone[68064:1643374] [Message] MSVolume set gain to [0.000000 db], [1.000000] linear
2020-03-29 01:25:05.551586+0530 linphone[68064:1643374] [Warning] 'eq_active' linphonerc parameter has no effect anymore. Please use 'mic_eq_active' or 'spk_eq_active' instead
2020-03-29 01:25:05.551792+0530 linphone[68064:1643374] [Warning] 'eq_gains' linphonerc parameter has no effect anymore. Please use 'mic_eq_gains' or 'spk_eq_gains' instead
2020-03-29 01:25:05.552067+0530 linphone[68064:1643374] [Message] MSVolume set gain to [0.000000 db], [1.000000] linear
2020-03-29 01:25:05.552360+0530 linphone[68064:1643374] [Message] [LIME] Missing identity keys for mutual authentication, do not set auxiliary secret from identity keys
2020-03-29 01:25:05.552659+0530 linphone[68064:1643374] [Warning] Fail to set IPv4 packet info on RTP socket: Invalid argument.
2020-03-29 01:25:05.552908+0530 linphone[68064:1643374] [Warning] Fail to set IPv4 packet info on RTCP socket: Invalid argument.
2020-03-29 01:25:05.553172+0530 linphone[68064:1643374] [Message] ICE state is IceStateNotActivated for stream#0 [audio] in state [Running]
2020-03-29 01:25:05.553416+0530 linphone[68064:1643374] [Message] CallSession[0x6000a727e6b8] : payload type 0 PCMU/8000 fmtp= added to frozen list
2020-03-29 01:25:05.553622+0530 linphone[68064:1643374] [Message] CallSession[0x6000a727e6b8] : payload type 8 PCMA/8000 fmtp= added to frozen list
2020-03-29 01:25:05.553903+0530 linphone[68064:1643374] [Message] CallSession[0x6000a727e6b8] : payload type 100 telephone-event/8000 fmtp= added to frozen list
2020-03-29 01:25:05.554124+0530 linphone[68064:1643374] [Message] CallSession[0x6000a727e6b8] : payload type 96 opus/48000 fmtp=useinbandfec=1 added to frozen list
2020-03-29 01:25:05.554338+0530 linphone[68064:1643374] [Message] CallSession[0x6000a727e6b8] : payload type 97 speex/16000 fmtp=vbr=on added to frozen list
2020-03-29 01:25:05.554558+0530 linphone[68064:1643374] [Message] CallSession[0x6000a727e6b8] : payload type 98 speex/8000 fmtp=vbr=on added to frozen list
2020-03-29 01:25:05.554796+0530 linphone[68064:1643374] [Message] CallSession[0x6000a727e6b8] : payload type 101 telephone-event/48000 fmtp= added to frozen list
2020-03-29 01:25:05.554995+0530 linphone[68064:1643374] [Message] CallSession[0x6000a727e6b8] : payload type 99 telephone-event/16000 fmtp= added to frozen list
2020-03-29 01:25:05.555260+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: ending recv background task with id=[ff6e].
2020-03-29 01:25:05.555878+0530 linphone[68064:1643374] [Message] Garbage collecting unowned object of type belle_sdp_session_description_t
2020-03-29 01:25:05.557031+0530 linphone[68064:1643374] [Warning] linphone_call_stats_get_sender_loss_rate(): there is no RTCP packet sent.
2020-03-29 01:25:05.557217+0530 linphone[68064:1643374] [Warning] linphone_call_stats_get_receiver_loss_rate(): there is no RTCP packet received.
2020-03-29 01:25:05.560224+0530 linphone[68064:1654989] [Message] MSAudioMixer [0x6000d6e7f200] is entering bypass mode.
2020-03-29 01:25:06.059827+0530 linphone[68064:1654989] [Message] Stun packet sent on rtcp for session [0x7fd1c19bde00]
2020-03-29 01:25:06.540615+0530 linphone[68064:1643374] [Warning] linphone_call_stats_get_sender_loss_rate(): there is no RTCP packet sent.
2020-03-29 01:25:06.540876+0530 linphone[68064:1643374] [Warning] linphone_call_stats_get_receiver_loss_rate(): there is no RTCP packet received.
2020-03-29 01:25:06.549849+0530 linphone[68064:1654989] [Message] MSAudioMixer [0x6000d6e710e0] is entering bypass mode.
2020-03-29 01:25:06.557453+0530 linphone[68064:1643374] [Message] First estimation
2020-03-29 01:25:06.557687+0530 linphone[68064:1643374] [Message] Bandwidth usage for CallSession [0x6000a727e6b8]:
2020-03-29 01:25:06.557788+0530 linphone[68064:1643374] [Message]       Stream #0 (audio) | cpu: 1.87% | RTP : [d=73.95,u=39.01] RTCP: [d=0.00,u=0.80]  (kbits/sec)
2020-03-29 01:25:06.560042+0530 linphone[68064:1654989] [Message] Stun packet sent on rtcp for session [0x7fd1c19bde00]
2020-03-29 01:25:06.709881+0530 linphone[68064:1654989] [Message] sound/wall clock skew is average=7.963337 ms
2020-03-29 01:25:06.710168+0530 linphone[68064:1654989] [Message] Sending RTCP SR compound message on session [0x7fd1c19bde00].
2020-03-29 01:25:06.717420+0530 linphone[68064:1643374] [Message] MSAudio_stream_iterate[0x7fd1cb6c57e0], local statistics available:
2020-03-29 01:25:06.717571+0530 linphone[68064:1643374] [Message]       Local current jitter buffer size:  57.4ms
2020-03-29 01:25:06.717851+0530 linphone[68064:1643374] [Message] Linphone core [0x7fd1c1008200] notified [call_stats_updated]
2020-03-29 01:25:07.059173+0530 linphone[68064:1654989] [Message] Stun packet sent on rtcp for session [0x7fd1c19bde00]
2020-03-29 01:25:07.540325+0530 linphone[68064:1643374] [Warning] linphone_call_stats_get_receiver_loss_rate(): there is no RTCP packet received.
2020-03-29 01:25:07.557783+0530 linphone[68064:1643374] [Message] Bandwidth usage for CallSession [0x6000a727e6b8]:
2020-03-29 01:25:07.557923+0530 linphone[68064:1643374] [Message]       Stream #0 (audio) | cpu: 0.80% | RTP : [d=75.39,u=80.78] RTCP: [d=0.00,u=2.31]  (kbits/sec)
2020-03-29 01:25:07.588782+0530 linphone[68064:1654989] [Message] Stun packet sent on rtcp for session [0x7fd1c19bde00]
2020-03-29 01:25:07.674863+0530 linphone[68064:1643374] [Message] Terminate CallSession [0x6000a727e6b8] which is currently in state [LinphoneCallOutgoingEarlyMedia]
2020-03-29 01:25:07.675188+0530 linphone[68064:1643374] [Message] Cancelling INVITE request from ["SivaK-iPhone" <sip:856113@pbx.test.com.au>] to ["114" <sip:114@pbx.test.com.au>]
2020-03-29 01:25:07.675648+0530 linphone[68064:1643374] [Message] Background task belle-sip transaction(0x60005edaa580) started. Unknown remaining time since application is not fully in background.
2020-03-29 01:25:07.675872+0530 linphone[68064:1643374] [Message] transaction [0x60005edaa580]: starting transaction background task with id=[ffd8].
2020-03-29 01:25:07.676092+0530 linphone[68064:1643374] [Message] Found transaction [0x60000238e560] matching request.
2020-03-29 01:25:07.676331+0530 linphone[68064:1643374] [Message] Changing [client] [CANCEL] transaction [0x60005edaa580], from state [INIT] to [TRYING]
2020-03-29 01:25:07.676669+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: message sent to [UDP://pbx.test.com.au:5060], size: [369] bytes
2020-03-29 01:25:07.676794+0530 linphone[68064:1643374] [Message]   CANCEL sip:114@pbx.test.com.au SIP/2.0
2020-03-29 01:25:07.676916+0530 linphone[68064:1643374] [Message]   Via: SIP/2.0/UDP 10.0.0.2:62641;branch=z9hG4bK.wDE4Oizp6;rport
2020-03-29 01:25:07.677014+0530 linphone[68064:1643374] [Message]   Call-ID: mUkzT0L8-L
2020-03-29 01:25:07.680756+0530 linphone[68064:1643374] [Message]   From: "SivaK-iPhone" <sip:856113@pbx.test.com.au>;tag=y1gXYgnzW
2020-03-29 01:25:07.680874+0530 linphone[68064:1643374] [Message]   To: "114" <sip:114@pbx.test.com.au>
2020-03-29 01:25:07.681006+0530 linphone[68064:1643374] [Message]   Max-Forwards: 70
2020-03-29 01:25:07.681142+0530 linphone[68064:1643374] [Message]   CSeq: 21 CANCEL
2020-03-29 01:25:07.681250+0530 linphone[68064:1643374] [Message]   User-Agent: LinphoneiOS/4.3.0 (iPhone 11 Pro Max) LinphoneSDK/4.4.0-alpha.167+f8422b1
2020-03-29 01:25:07.681453+0530 linphone[68064:1643374] [Message] [ToneManager] [0x6000a727e6b8] state changed : [None, LinphoneCallOutgoingEarlyMedia]
2020-03-29 01:25:07.681645+0530 linphone[68064:1643374] [Message] [ToneManager] stop
2020-03-29 01:25:07.681832+0530 linphone[68064:1643374] [Message] [ToneManager] nothing to stop
2020-03-29 01:25:07.682096+0530 linphone[68064:1643374] [Message] ms_ticker_set_time_func: ticker's time method updated.
2020-03-29 01:25:07.682280+0530 linphone[68064:1643374] [Message] Filter MSRtpRecv is not scheduled; nothing to do.
2020-03-29 01:25:07.682488+0530 linphone[68064:1643374] [Message] ===========================================================
2020-03-29 01:25:07.682686+0530 linphone[68064:1643374] [Message]              AUDIO SESSION'S RTP STATISTICS
2020-03-29 01:25:07.682913+0530 linphone[68064:1643374] [Message] -----------------------------------------------------------
2020-03-29 01:25:07.683081+0530 linphone[68064:1643374] [Message] sent                                        105 packets
2020-03-29 01:25:07.683319+0530 linphone[68064:1643374] [Message]                                               0 duplicated packets
2020-03-29 01:25:07.683543+0530 linphone[68064:1643374] [Message]                                           18060 bytes
2020-03-29 01:25:07.683746+0530 linphone[68064:1643374] [Message] received                                    103 packets
2020-03-29 01:25:07.683970+0530 linphone[68064:1643374] [Message]                                               0 duplicated packets
2020-03-29 01:25:07.684162+0530 linphone[68064:1643374] [Message]                                           17716 bytes
2020-03-29 01:25:07.684369+0530 linphone[68064:1643374] [Message] incoming delivered to the app             17200 bytes
2020-03-29 01:25:07.684601+0530 linphone[68064:1643374] [Message] incoming cumulative lost                      0 packets
2020-03-29 01:25:07.684780+0530 linphone[68064:1643374] [Message] incoming received too late                    0 packets
2020-03-29 01:25:07.685028+0530 linphone[68064:1643374] [Message] incoming bad formatted                        0 packets
2020-03-29 01:25:07.685297+0530 linphone[68064:1643374] [Message] incoming discarded (queue overflow)           0 packets
2020-03-29 01:25:07.685536+0530 linphone[68064:1643374] [Message] sent rtcp                                     1 packets
2020-03-29 01:25:07.685719+0530 linphone[68064:1643374] [Message] received rtcp                                 0 packets
2020-03-29 01:25:07.686004+0530 linphone[68064:1643374] [Message] ===========================================================
2020-03-29 01:25:07.686271+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSAURead:0x6000d6e70500,0-->MSResample:0x6000d6e7f0c0,0
2020-03-29 01:25:07.686557+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSResample:0x6000d6e7f0c0,0-->MSEqualizer:0x6000d6e70be0,0
2020-03-29 01:25:07.686824+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSEqualizer:0x6000d6e70be0,0-->MSVolume:0x6000d6e70780,0
2020-03-29 01:25:07.687059+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSVolume:0x6000d6e70780,0-->MSAudioMixer:0x6000d6e7f200,0
2020-03-29 01:25:07.687321+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSAudioMixer:0x6000d6e7f200,0-->MSUlawEnc:0x6000d6e70640,0
2020-03-29 01:25:07.687649+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSUlawEnc:0x6000d6e70640,0-->MSRtpSend:0x6000d6e155e0,0
2020-03-29 01:25:07.688056+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSRtpRecv:0x6000d6e70000,0-->MSUlawDec:0x6000d6e706e0,0
2020-03-29 01:25:07.688325+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSUlawDec:0x6000d6e706e0,0-->MSGenericPLC:0x6000d6e70e60,0
2020-03-29 01:25:07.688567+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSGenericPLC:0x6000d6e70e60,0-->MSAudioFlowControl:0x6000d6e71040,0
2020-03-29 01:25:07.688823+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSAudioFlowControl:0x6000d6e71040,0-->MSDtmfGen:0x6000d6e70460,0
2020-03-29 01:25:07.689094+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSDtmfGen:0x6000d6e70460,0-->MSVolume:0x6000d6e70820,0
2020-03-29 01:25:07.689352+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSVolume:0x6000d6e70820,0-->MSTee:0x6000d6e7f660,0
2020-03-29 01:25:07.689572+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSTee:0x6000d6e7f660,0-->MSEqualizer:0x6000d6e70d20,0
2020-03-29 01:25:07.689809+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSEqualizer:0x6000d6e70d20,0-->MSAudioMixer:0x6000d6e710e0,0
2020-03-29 01:25:07.690063+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSFilePlayer:0x6000d6e71400,0-->MSResample:0x6000d6e715e0,0
2020-03-29 01:25:07.690385+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSResample:0x6000d6e715e0,0-->MSAudioMixer:0x6000d6e710e0,1
2020-03-29 01:25:07.690641+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSAudioMixer:0x6000d6e710e0,0-->MSResample:0x6000d6e7f160,0
2020-03-29 01:25:07.690864+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSResample:0x6000d6e7f160,0-->MSAUWrite:0x6000d6e705a0,0
2020-03-29 01:25:07.691118+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSAudioMixer:0x6000d6e7f480,1-->MSResample:0x6000d6e7a9e0,0
2020-03-29 01:25:07.691374+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSResample:0x6000d6e7a9e0,0-->MSOpusEnc:0x6000d6e708c0,0
2020-03-29 01:25:07.691593+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSOpusEnc:0x6000d6e708c0,0-->MSMKVRecorder:0x6000d6e7f700,1
2020-03-29 01:25:07.691813+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSItcSource:0x6000d6e7a940,0-->MSMKVRecorder:0x6000d6e7f700,0
2020-03-29 01:25:07.692058+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSAudioMixer:0x6000d6e7f200,1-->MSAudioMixer:0x6000d6e7f480,0
2020-03-29 01:25:07.692328+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSTee:0x6000d6e7f660,1-->MSAudioMixer:0x6000d6e7f480,1
2020-03-29 01:25:07.692645+0530 linphone[68064:1643374] [Message] ms_filter_unlink: MSAudioMixer:0x6000d6e7f480,0-->MSFileRec:0x6000d6e7f3e0,0
2020-03-29 01:25:07.692899+0530 linphone[68064:1643374] [Message] =================================================================================
2020-03-29 01:25:07.693141+0530 linphone[68064:1643374] [Message]                          FILTER USAGE STATISTICS
2020-03-29 01:25:07.693402+0530 linphone[68064:1643374] [Message] Name                          Count           Time/tick (ms)            CPU Usage
2020-03-29 01:25:07.693592+0530 linphone[68064:1643374] [Message]                                         min     mean    max     sd
2020-03-29 01:25:07.693798+0530 linphone[68064:1643374] [Message] ---------------------------------------------------------------------------------
2020-03-29 01:25:07.694031+0530 linphone[68064:1643374] [Message] MSOpusEnc                     99        0.00    0.08    0.23    0.08         52.9
2020-03-29 01:25:07.694309+0530 linphone[68064:1643374] [Message] MSRtpSend                     213       0.00    0.03    0.30    0.04         17.9
2020-03-29 01:25:07.694512+0530 linphone[68064:1643374] [Message] MSRtpRecv                     310       0.01    0.02    0.50    0.03         12.5
2020-03-29 01:25:07.694823+0530 linphone[68064:1643374] [Message] MSResample                    461       0.00    0.01    0.05    0.01          6.5
2020-03-29 01:25:07.695169+0530 linphone[68064:1643374] [Message] MSUlawDec                     100       0.00    0.00    0.01    0.00          1.7
2020-03-29 01:25:07.695448+0530 linphone[68064:1643374] [Message] MSAURead                      213       0.00    0.00    0.25    0.02          1.4
2020-03-29 01:25:07.695753+0530 linphone[68064:1643374] [Message] MSAudioMixer                  639       0.00    0.00    0.28    0.01          1.2
2020-03-29 01:25:07.696066+0530 linphone[68064:1643374] [Message] MSUlawEnc                     181       0.00    0.00    0.01    0.00          1.2
2020-03-29 01:25:07.696410+0530 linphone[68064:1643374] [Message] MSMKVRecorder                 49        0.00    0.00    0.00    0.00          0.8
2020-03-29 01:25:07.696833+0530 linphone[68064:1643374] [Message] MSGenericPLC                  213       0.00    0.00    0.02    0.00          0.7
2020-03-29 01:25:07.697148+0530 linphone[68064:1643374] [Message] MSVolume                      282       0.00    0.00    0.01    0.00          0.6
2020-03-29 01:25:07.697442+0530 linphone[68064:1643374] [Message] MSDtmfGen                     238       0.00    0.00    0.02    0.00          0.5
2020-03-29 01:25:07.697729+0530 linphone[68064:1643374] [Message] MSAQWrite                     25        0.00    0.00    0.00    0.00          0.3
2020-03-29 01:25:07.698058+0530 linphone[68064:1643374] [Message] MSAUWrite                     156       0.00    0.00    0.01    0.00          0.3
2020-03-29 01:25:07.698289+0530 linphone[68064:1643374] [Message] MSEqualizer                   282       0.00    0.00    0.00    0.00          0.3
2020-03-29 01:25:07.698608+0530 linphone[68064:1643374] [Message] MSFileRec                     99        0.00    0.00    0.00    0.00          0.3
2020-03-29 01:25:07.698850+0530 linphone[68064:1643374] [Message] MSTee                         101       0.00    0.00    0.00    0.00          0.3
2020-03-29 01:25:07.699168+0530 linphone[68064:1643374] [Message] MSFilePlayer                  238       0.00    0.00    0.02    0.00          0.2
2020-03-29 01:25:07.699408+0530 linphone[68064:1643374] [Message] MSAudioFlowControl            101       0.00    0.00    0.00    0.00          0.2
2020-03-29 01:25:07.699669+0530 linphone[68064:1643374] [Message] MSItcSource                   213       0.00    0.00    0.01    0.00          0.1
2020-03-29 01:25:07.699960+0530 linphone[68064:1643374] [Message] MSVoidSink                    0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.700242+0530 linphone[68064:1643374] [Message] MSSpeexEC                     0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.700449+0530 linphone[68064:1643374] [Message] =================================================================================
2020-03-29 01:25:07.700756+0530 linphone[68064:1643374] [Message] MKVRecorder: destroyed
2020-03-29 01:25:07.700986+0530 linphone[68064:1643374] [Message] CallSession [0x6000a727e6b8] moving from state LinphoneCallOutgoingEarlyMedia to LinphoneCallEnd
2020-03-29 01:25:07.701223+0530 linphone[68064:1643374] [Message] StreamsGroup::finish() called.
2020-03-29 01:25:07.701489+0530 linphone[68064:1643374] [Message] =================================================================================
2020-03-29 01:25:07.701780+0530 linphone[68064:1643374] [Message]                          FILTER USAGE STATISTICS
2020-03-29 01:25:07.702022+0530 linphone[68064:1643374] [Message] Name                          Count           Time/tick (ms)            CPU Usage
2020-03-29 01:25:07.702254+0530 linphone[68064:1643374] [Message]                                         min     mean    max     sd
2020-03-29 01:25:07.702439+0530 linphone[68064:1643374] [Message] ---------------------------------------------------------------------------------
2020-03-29 01:25:07.702703+0530 linphone[68064:1643374] [Message] MSAQWrite                     0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.702940+0530 linphone[68064:1643374] [Message] MSFilePlayer                  0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.703170+0530 linphone[68064:1643374] [Message] MSAudioFlowControl            0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.703395+0530 linphone[68064:1643374] [Message] MSGenericPLC                  0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.703620+0530 linphone[68064:1643374] [Message] MSEqualizer                   0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.703886+0530 linphone[68064:1643374] [Message] MSOpusEnc                     0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.704102+0530 linphone[68064:1643374] [Message] MSItcSource                   0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.704346+0530 linphone[68064:1643374] [Message] MSMKVRecorder                 0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.704606+0530 linphone[68064:1643374] [Message] MSTee                         0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.704862+0530 linphone[68064:1643374] [Message] MSFileRec                     0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.705090+0530 linphone[68064:1643374] [Message] MSAudioMixer                  0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.705336+0530 linphone[68064:1643374] [Message] MSResample                    0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.705601+0530 linphone[68064:1643374] [Message] MSVolume                      0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.705837+0530 linphone[68064:1643374] [Message] MSUlawDec                     0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.706092+0530 linphone[68064:1643374] [Message] MSUlawEnc                     0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.706345+0530 linphone[68064:1643374] [Message] MSAUWrite                     0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.706614+0530 linphone[68064:1643374] [Message] MSAURead                      0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.706934+0530 linphone[68064:1643374] [Message] MSDtmfGen                     0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.707123+0530 linphone[68064:1643374] [Message] MSVoidSink                    0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.707420+0530 linphone[68064:1643374] [Message] MSRtpRecv                     0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.707672+0530 linphone[68064:1643374] [Message] MSSpeexEC                     0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.707946+0530 linphone[68064:1643374] [Message] MSRtpSend                     0         0.00    0.00    0.00    0.00          0.0
2020-03-29 01:25:07.708180+0530 linphone[68064:1643374] [Message] =================================================================================
2020-03-29 01:25:07.708440+0530 linphone[68064:1643374] [Message] Stopping ZRTP context on session [0x0]
2020-03-29 01:25:07.708636+0530 linphone[68064:1643374] [Message] ZRTP context destroyed
2020-03-29 01:25:07.712111+0530 linphone[68064:1654989] [Message] MSAudio MSTicker thread exiting
2020-03-29 01:25:07.714998+0530 linphone[68064:1643374] [Message] Linphone core [0x7fd1c1008200] notified [call_log_updated]
2020-03-29 01:25:07.715239+0530 linphone[68064:1643374] [Message] Resetting the current call
2020-03-29 01:25:07.715449+0530 linphone[68064:1643374] [Message] Notifying soundcard that we don't need it anymore for calls
2020-03-29 01:25:07.718871+0530 linphone[68064:1643374] [Message] AudioUnit stopped
2020-03-29 01:25:07.720106+0530 linphone[68064:1643374] [Message] AudioUnit destroyed
2020-03-29 01:25:07.721549+0530 linphone[68064:1643374] [Message] (  PhoneMainView.m:625 ) PhoneMainView: Popping view CallOutgoingView, going to DialerView
2020-03-29 01:25:07.721802+0530 linphone[68064:1643374] [Message] (  PhoneMainView.m:642 ) Change current view to DialerView
2020-03-29 01:25:07.727980+0530 linphone[68064:1643374] [Message] [ToneManager] linphoneCoreStopDtmfStream
2020-03-29 01:25:07.728231+0530 linphone[68064:1643374] [Message] [ToneManager] doStopTone
2020-03-29 01:25:07.731320+0530 linphone[68064:1643374] [Message] Linphone core [0x7fd1c1008200] notified [call_state_changed]
2020-03-29 01:25:07.731568+0530 linphone[68064:1643374] [Error] CallPrivate::getMediaStream() : no stream with type 1
2020-03-29 01:25:07.731859+0530 linphone[68064:1643374] [Message] QualityReporting[0x60003ef325d0]: Trying to submit a VQSessionReport: CallTerm too early (call duration: 0 sec) but local IP could not be retrieved so dropping this report
2020-03-29 01:25:07.732092+0530 linphone[68064:1643374] [Message] QualityReporting[0x60003ef325d0]: Send 'VQSessionReport: CallTerm' with status 2
2020-03-29 01:25:07.744924+0530 linphone[68064:1643374] [Message] Background task belle-sip send channel started. Unknown remaining time since application is not fully in background.
2020-03-29 01:25:07.745169+0530 linphone[68064:1643374] [Message] channel [0x7fd1c64aa000]: starting send background task with id=[ffd9].
2020-03-29 01:25:07.745374+0530 linphone[68064:1643374] [Message] channel [0x7fd1c64aa000]: starting resolution of subscribe.linphone.org
2020-03-29 01:25:07.745642+0530 linphone[68064:1643374] [Message] channel 0x7fd1c64aa000: state RES_IN_PROGRESS
2020-03-29 01:25:07.746389+0530 linphone[68064:1643374] [Message] Resolver is using DNS server(s):
2020-03-29 01:25:07.746678+0530 linphone[68064:1643374] [Message]   10.0.0.1
2020-03-29 01:25:07.747366+0530 linphone[68064:1643374] [Message] resolver_process_data dns_res_check() in progress
2020-03-29 01:25:07.747642+0530 linphone[68064:1643374] [Message] DNS resolution awaiting response, queued to main loop
2020-03-29 01:25:07.748262+0530 linphone[68064:1643374] [Message] Resolver is using DNS server(s):
2020-03-29 01:25:07.748502+0530 linphone[68064:1643374] [Message]   10.0.0.1
2020-03-29 01:25:07.749047+0530 linphone[68064:1643374] [Message] resolver_process_data dns_res_check() in progress
2020-03-29 01:25:07.749249+0530 linphone[68064:1643374] [Message] DNS resolution awaiting response, queued to main loop
2020-03-29 01:25:07.778003+0530 linphone[68064:1643374] [Message] subscribe.linphone.org resolved to 2001:41d0:303:3aee::2
2020-03-29 01:25:07.779003+0530 linphone[68064:1643374] [Message] subscribe.linphone.org resolved to 5.135.31.161
2020-03-29 01:25:07.779288+0530 linphone[68064:1643374] [Message] channel 0x7fd1c64aa000: state RES_DONE
2020-03-29 01:25:07.779596+0530 linphone[68064:1643374] [Message] channel 0x7fd1c64aa000: state CONNECTING
2020-03-29 01:25:07.779856+0530 linphone[68064:1643374] [Message] Trying to connect to [TLS://2001:41d0:303:3aee::2:444]
2020-03-29 01:25:07.783383+0530 linphone[68064:1643374] [Error] stream connect failed No route to host
2020-03-29 01:25:07.783761+0530 linphone[68064:1643374] [Error] Cannot connect to [TLS://subscribe.linphone.org:444]
2020-03-29 01:25:07.784051+0530 linphone[68064:1643374] [Message] channel 0x7fd1c64aa000: state ERROR
2020-03-29 01:25:07.784316+0530 linphone[68064:1643374] [Message] channel 0x7fd1c64aa000: state RETRY
2020-03-29 01:25:07.796880+0530 linphone[68064:1643374] [Message] channel 0x7fd1c64aa000: state CONNECTING
2020-03-29 01:25:07.797173+0530 linphone[68064:1643374] [Message] Trying to connect to [TLS://::ffff:5.135.31.161:444]
2020-03-29 01:25:07.937393+0530 linphone[68064:1643374] [Message] Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background.
2020-03-29 01:25:07.937660+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: starting recv background task with id=[ffe5].
2020-03-29 01:25:07.937957+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: received [354] new bytes from [UDP://pbx.test.com.au:5060]:
2020-03-29 01:25:07.938079+0530 linphone[68064:1643374] [Message]   SIP/2.0 200 canceling
2020-03-29 01:25:07.938188+0530 linphone[68064:1643374] [Message]   Via: SIP/2.0/UDP 10.0.0.2:62641;received=192.168.1.2;branch=z9hG4bK.wDE4Oizp6;rport=62641
2020-03-29 01:25:07.938298+0530 linphone[68064:1643374] [Message]   Call-ID: mUkzT0L8-L
2020-03-29 01:25:07.938419+0530 linphone[68064:1643374] [Message]   From: "SivaK-iPhone" <sip:856113@pbx.test.com.au>;tag=y1gXYgnzW
2020-03-29 01:25:07.938570+0530 linphone[68064:1643374] [Message]   To: "114" <sip:114@pbx.test.com.au>;tag=cfe2f65e791b5356c10afde9b57dec02-aee9
2020-03-29 01:25:07.938707+0530 linphone[68064:1643374] [Message]   CSeq: 21 CANCEL
2020-03-29 01:25:07.938831+0530 linphone[68064:1643374] [Message]   Server: Virtual PBX 1.0
2020-03-29 01:25:07.938999+0530 linphone[68064:1643374] [Message]   Content-Length: 0
2020-03-29 01:25:07.940316+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000] [354] bytes parsed
2020-03-29 01:25:07.940528+0530 linphone[68064:1643374] [Message] Found transaction matching response.
2020-03-29 01:25:07.940733+0530 linphone[68064:1643374] [Message] Changing [client] [CANCEL] transaction [0x60005edaa580], from state [TRYING] to [COMPLETED]
2020-03-29 01:25:07.940953+0530 linphone[68064:1643374] [Message] op [0x7fd1cb5f02c0] : set_or_update_dialog() current=[0x60003276d4a0] new=[0x0]
2020-03-29 01:25:07.941184+0530 linphone[68064:1643374] [Message] Op [0x7fd1cb5f02c0] receiving call response [200], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL]
2020-03-29 01:25:07.941427+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: ending recv background task with id=[ffe5].
2020-03-29 01:25:07.957468+0530 linphone[68064:1643374] [Message] Channel [0x7fd1c64aa000]: Connected at TCP level, now doing TLS handshake with cname=subscribe.linphone.org
2020-03-29 01:25:07.958128+0530 linphone[68064:1643374] [Message] Channel [0x7fd1c64aa000]: SSL handshake in progress...
2020-03-29 01:25:07.959176+0530 linphone[68064:1643374] [Message] Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background.
2020-03-29 01:25:07.959545+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: starting recv background task with id=[ffe7].
2020-03-29 01:25:07.959901+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: received [448] new bytes from [UDP://pbx.test.com.au:5060]:
2020-03-29 01:25:07.960131+0530 linphone[68064:1643374] [Message]   SIP/2.0 487 Request Terminated
2020-03-29 01:25:07.960313+0530 linphone[68064:1643374] [Message]   Via: SIP/2.0/UDP 10.0.0.2:62641;received=192.168.1.2;branch=z9hG4bK.wDE4Oizp6;rport=62641
2020-03-29 01:25:07.960538+0530 linphone[68064:1643374] [Message]   From: "SivaK-iPhone" <sip:856113@pbx.test.com.au>;tag=y1gXYgnzW
2020-03-29 01:25:07.960729+0530 linphone[68064:1643374] [Message]   To: "114" <sip:114@pbx.test.com.au>;tag=as43abd308
2020-03-29 01:25:07.960967+0530 linphone[68064:1643374] [Message]   Call-ID: mUkzT0L8-L
2020-03-29 01:25:07.961168+0530 linphone[68064:1643374] [Message]   CSeq: 21 INVITE
2020-03-29 01:25:07.961331+0530 linphone[68064:1643374] [Message]   Server: MaxoPBX
2020-03-29 01:25:07.961575+0530 linphone[68064:1643374] [Message]   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
2020-03-29 01:25:07.961844+0530 linphone[68064:1643374] [Message]   Supported: replaces, timer
2020-03-29 01:25:07.962057+0530 linphone[68064:1643374] [Message]   Content-Length: 0
2020-03-29 01:25:07.964177+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000] [448] bytes parsed
2020-03-29 01:25:07.964412+0530 linphone[68064:1643374] [Message] Found transaction matching response.
2020-03-29 01:25:07.964653+0530 linphone[68064:1643374] [Message] Changing [client] [INVITE] transaction [0x60000238e560], from state [PROCEEDING] to [COMPLETED]
2020-03-29 01:25:07.965038+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: message sent to [UDP://pbx.test.com.au:5060], size: [409] bytes
2020-03-29 01:25:07.965153+0530 linphone[68064:1643374] [Message]   ACK sip:114@pbx.test.com.au SIP/2.0
2020-03-29 01:25:07.965277+0530 linphone[68064:1643374] [Message]   Via: SIP/2.0/UDP 10.0.0.2:62641;branch=z9hG4bK.wDE4Oizp6;rport
2020-03-29 01:25:07.965391+0530 linphone[68064:1643374] [Message]   Call-ID: mUkzT0L8-L
2020-03-29 01:25:07.965510+0530 linphone[68064:1643374] [Message]   From: "SivaK-iPhone" <sip:856113@pbx.test.com.au>;tag=y1gXYgnzW
2020-03-29 01:25:07.965638+0530 linphone[68064:1643374] [Message]   To: "114" <sip:114@pbx.test.com.au>;tag=as43abd308
2020-03-29 01:25:07.965868+0530 linphone[68064:1643374] [Message]   Contact: <sip:856113@202.52.129.60:5060;gr=urn:uuid:cc4a95ac-217f-00fa-bc4b-3780b3891601>;+org.linphone.specs="lime"
2020-03-29 01:25:07.966195+0530 linphone[68064:1643374] [Message]   Max-Forwards: 70
2020-03-29 01:25:07.966520+0530 linphone[68064:1643374] [Message]   CSeq: 21 ACK
2020-03-29 01:25:07.967025+0530 linphone[68064:1643374] [Message] Dialog [0x60003276d4a0]: now updated by transaction [0x60000238e560].
2020-03-29 01:25:07.967311+0530 linphone[68064:1643374] [Message] Dialog [0x60003276d4a0] deleted (is_expired=0)
2020-03-29 01:25:07.967601+0530 linphone[68064:1643374] [Message] op [0x7fd1cb5f02c0] : set_or_update_dialog() current=[0x0] new=[0x60003276d4a0]
2020-03-29 01:25:07.967880+0530 linphone[68064:1643374] [Message] Op [0x7fd1cb5f02c0] receiving call response [487], dialog is [0x60003276d4a0] in state [BELLE_SIP_DIALOG_TERMINATED]
2020-03-29 01:25:07.968170+0530 linphone[68064:1643374] [Message] [ToneManager] [0x6000a727e6b8] state changed : [None, LinphoneCallEnd]
2020-03-29 01:25:07.968435+0530 linphone[68064:1643374] [Message] [ToneManager] stop
2020-03-29 01:25:07.968700+0530 linphone[68064:1643374] [Message] [ToneManager] nothing to stop
2020-03-29 01:25:07.968972+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: ending recv background task with id=[ffe7].
2020-03-29 01:25:07.977900+0530 linphone[68064:1643374] [Message] Dialog [0x60003276d4a0] terminated for op [0x7fd1cb5f02c0]
2020-03-29 01:25:07.997192+0530 linphone[68064:1643374] [Message] CallSession [0x6000a727e6b8] moving from state LinphoneCallEnd to LinphoneCallReleased
2020-03-29 01:25:07.997489+0530 linphone[68064:1643374] [Message] [ToneManager] [0x6000a727e6b8] state changed : [None, LinphoneCallReleased]
2020-03-29 01:25:07.998404+0530 linphone[68064:1643374] [Message] Linphone core [0x7fd1c1008200] notified [call_state_changed]
2020-03-29 01:25:07.998649+0530 linphone[68064:1643374] [Message] [ToneManager] [0x6000a727e6b8] state changed : [None, LinphoneCallReleased]
2020-03-29 01:25:07.998879+0530 linphone[68064:1643374] [Message] [ToneManager] removeSession mSession size : 0
2020-03-29 01:25:07.999189+0530 linphone[68064:1643374] [Message] op [0x7fd1cb5f02c0] : set_or_update_dialog() current=[0x60003276d4a0] new=[0x0]
2020-03-29 01:25:08.119264+0530 linphone[68064:1643374] [Message] Found certificate depth=[2], flags=[]:
2020-03-29 01:25:08.119430+0530 linphone[68064:1643374] [Message]   cert. version     : 3
2020-03-29 01:25:08.119597+0530 linphone[68064:1643374] [Message]   serial number     : 01:FD:6D:30:FC:A3:CA:51:A8:1B:BC:64:0E:35:03:2D
2020-03-29 01:25:08.119741+0530 linphone[68064:1643374] [Message]   issuer name       : C=US, ST=New Jersey, L=Jersey City, O=The USERTRUST Network, CN=USERTrust RSA Certification Authority
2020-03-29 01:25:08.119877+0530 linphone[68064:1643374] [Message]   subject name      : C=US, ST=New Jersey, L=Jersey City, O=The USERTRUST Network, CN=USERTrust RSA Certification Authority
2020-03-29 01:25:08.119995+0530 linphone[68064:1643374] [Message]   issued  on        : 2010-02-01 00:00:00
2020-03-29 01:25:08.120134+0530 linphone[68064:1643374] [Message]   expires on        : 2038-01-18 23:59:59
2020-03-29 01:25:08.120248+0530 linphone[68064:1643374] [Message]   signed using      : RSA with SHA-384
2020-03-29 01:25:08.120360+0530 linphone[68064:1643374] [Message]   RSA key size      : 4096 bits
2020-03-29 01:25:08.120481+0530 linphone[68064:1643374] [Message]   basic constraints : CA=true
2020-03-29 01:25:08.120568+0530 linphone[68064:1643374] [Message]   key usage         : Key Cert Sign, CRL Sign
2020-03-29 01:25:08.121012+0530 linphone[68064:1643374] [Message] Found certificate depth=[1], flags=[]:
2020-03-29 01:25:08.121177+0530 linphone[68064:1643374] [Message]   cert. version     : 3
2020-03-29 01:25:08.121473+0530 linphone[68064:1643374] [Message]   serial number     : 05:E4:DC:3B:94:38:AB:3B:85:97:CB:A6:A1:98:50:E3
2020-03-29 01:25:08.121743+0530 linphone[68064:1643374] [Message]   issuer name       : C=US, ST=New Jersey, L=Jersey City, O=The USERTRUST Network, CN=USERTrust RSA Certification Authority
2020-03-29 01:25:08.122102+0530 linphone[68064:1643374] [Message]   subject name      : C=FR, ST=Paris, L=Paris, O=Gandi, CN=Gandi Standard SSL CA 2
2020-03-29 01:25:08.122360+0530 linphone[68064:1643374] [Message]   issued  on        : 2014-09-12 00:00:00
2020-03-29 01:25:08.122563+0530 linphone[68064:1643374] [Message]   expires on        : 2024-09-11 23:59:59
2020-03-29 01:25:08.122857+0530 linphone[68064:1643374] [Message]   signed using      : RSA with SHA-384
2020-03-29 01:25:08.123091+0530 linphone[68064:1643374] [Message]   RSA key size      : 2048 bits
2020-03-29 01:25:08.123381+0530 linphone[68064:1643374] [Message]   basic constraints : CA=true, max_pathlen=0
2020-03-29 01:25:08.123610+0530 linphone[68064:1643374] [Message]   key usage         : Digital Signature, Key Cert Sign, CRL Sign
2020-03-29 01:25:08.123845+0530 linphone[68064:1643374] [Message]   ext key usage     : TLS Web Server Authentication, TLS Web Client Authentication
2020-03-29 01:25:08.124239+0530 linphone[68064:1643374] [Message] Found certificate depth=[0], flags=[]:
2020-03-29 01:25:08.124396+0530 linphone[68064:1643374] [Message]   cert. version     : 3
2020-03-29 01:25:08.124711+0530 linphone[68064:1643374] [Message]   serial number     : 3E:09:86:4D:BC:36:E9:0F:EC:69:53:D3:C2:6B:96:40
2020-03-29 01:25:08.125024+0530 linphone[68064:1643374] [Message]   issuer name       : C=FR, ST=Paris, L=Paris, O=Gandi, CN=Gandi Standard SSL CA 2
2020-03-29 01:25:08.125240+0530 linphone[68064:1643374] [Message]   subject name      : CN=*.linphone.org
2020-03-29 01:25:08.125491+0530 linphone[68064:1643374] [Message]   issued  on        : 2020-01-14 00:00:00
2020-03-29 01:25:08.125725+0530 linphone[68064:1643374] [Message]   expires on        : 2022-02-11 23:59:59
2020-03-29 01:25:08.125934+0530 linphone[68064:1643374] [Message]   signed using      : RSA with SHA-256
2020-03-29 01:25:08.126155+0530 linphone[68064:1643374] [Message]   RSA key size      : 2048 bits
2020-03-29 01:25:08.126407+0530 linphone[68064:1643374] [Message]   basic constraints : CA=false
2020-03-29 01:25:08.126632+0530 linphone[68064:1643374] [Message]   subject alt name  : *.linphone.org, linphone.org
2020-03-29 01:25:08.126865+0530 linphone[68064:1643374] [Message]   key usage         : Digital Signature, Key Encipherment
2020-03-29 01:25:08.127074+0530 linphone[68064:1643374] [Message]   ext key usage     : TLS Web Server Authentication, TLS Web Client Authentication
2020-03-29 01:25:08.136825+0530 linphone[68064:1643374] [Message] Channel [0x7fd1c64aa000]: SSL handshake in progress...
2020-03-29 01:25:08.297783+0530 linphone[68064:1643374] [Message] Channel [0x7fd1c64aa000]: SSL handshake finished, SSL version is [TLSv1.2], selected ciphersuite is [TLS-ECDHE-RSA-WITH-AES-256-GCM-SHA384]
2020-03-29 01:25:08.298098+0530 linphone[68064:1643374] [Message] Channel has local address 10.0.0.2:61840
2020-03-29 01:25:08.298337+0530 linphone[68064:1643374] [Message] channel 0x7fd1c64aa000: state READY
2020-03-29 01:25:08.298881+0530 linphone[68064:1643374] [Message] channel [0x7fd1c64aa000]: message sent to [TLS://subscribe.linphone.org:444], size: [444] bytes
2020-03-29 01:25:08.299038+0530 linphone[68064:1643374] [Message]   POST /wizard.php HTTP/1.1
2020-03-29 01:25:08.299168+0530 linphone[68064:1643374] [Message]   Content-Type: text/xml
2020-03-29 01:25:08.299289+0530 linphone[68064:1643374] [Message]   From: sip:856113@pbx.test.com.au
2020-03-29 01:25:08.299442+0530 linphone[68064:1643374] [Message]   Host: subscribe.linphone.org:444
2020-03-29 01:25:08.299565+0530 linphone[68064:1643374] [Message]   Content-Length: 296
2020-03-29 01:25:08.299688+0530 linphone[68064:1643374] [Message]   <?xml version="1.0" encoding="UTF-8"?>
2020-03-29 01:25:08.299786+0530 linphone[68064:1643374] [Message]   <methodCall>
2020-03-29 01:25:08.299875+0530 linphone[68064:1643374] [Message]    <methodName>get_phone_number_for_account</methodName>
2020-03-29 01:25:08.299962+0530 linphone[68064:1643374] [Message]    <params>
2020-03-29 01:25:08.300066+0530 linphone[68064:1643374] [Message]     <param>
2020-03-29 01:25:08.300201+0530 linphone[68064:1643374] [Message]      <value>
2020-03-29 01:25:08.300374+0530 linphone[68064:1643374] [Message]       <string>856113</string>
2020-03-29 01:25:08.300635+0530 linphone[68064:1643374] [Message]      </value>
2020-03-29 01:25:08.300863+0530 linphone[68064:1643374] [Message]     </param>
2020-03-29 01:25:08.301064+0530 linphone[68064:1643374] [Message]     <param>
2020-03-29 01:25:08.301286+0530 linphone[68064:1643374] [Message]      <value>
2020-03-29 01:25:08.301564+0530 linphone[68064:1643374] [Message]       <string>sip.linphone.org</string>
2020-03-29 01:25:08.301790+0530 linphone[68064:1643374] [Message]      </value>
2020-03-29 01:25:08.302043+0530 linphone[68064:1643374] [Message]     </param>
2020-03-29 01:25:08.302260+0530 linphone[68064:1643374] [Message]    </params>
2020-03-29 01:25:08.302489+0530 linphone[68064:1643374] [Message]   </methodCall>
2020-03-29 01:25:08.302827+0530 linphone[68064:1643374] [Message] channel [0x7fd1c64aa000]: ending send background task with id=[ffd9].
2020-03-29 01:25:08.477486+0530 linphone[68064:1643374] [Message] Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background.
2020-03-29 01:25:08.477827+0530 linphone[68064:1643374] [Message] channel [0x7fd1c64aa000]: starting recv background task with id=[10001].
2020-03-29 01:25:08.478125+0530 linphone[68064:1643374] [Message] channel [0x7fd1c64aa000]: received [392] new bytes from [TLS://subscribe.linphone.org:444]:
2020-03-29 01:25:08.478244+0530 linphone[68064:1643374] [Message]   HTTP/1.1 200 OK
2020-03-29 01:25:08.478338+0530 linphone[68064:1643374] [Message]   Date: Sat, 28 Mar 2020 19:57:12 GMT
2020-03-29 01:25:08.478444+0530 linphone[68064:1643374] [Message]   Server: Apache/2.4.34 (Red Hat) OpenSSL/1.0.2k-fips PHP/7.1.30
2020-03-29 01:25:08.478557+0530 linphone[68064:1643374] [Message]   X-Powered-By: PHP/7.1.30
2020-03-29 01:25:08.478638+0530 linphone[68064:1643374] [Message]   Content-Length: 185
2020-03-29 01:25:08.478741+0530 linphone[68064:1643374] [Message]   Content-Type: text/html; charset=UTF-8
2020-03-29 01:25:08.478872+0530 linphone[68064:1643374] [Message]   <?xml version="1.0" encoding="iso-8859-1"?>
2020-03-29 01:25:08.479019+0530 linphone[68064:1643374] [Message]   <methodResponse>
2020-03-29 01:25:08.479241+0530 linphone[68064:1643374] [Message]   <params>
2020-03-29 01:25:08.479394+0530 linphone[68064:1643374] [Message]    <param>
2020-03-29 01:25:08.479608+0530 linphone[68064:1643374] [Message]     <value>
2020-03-29 01:25:08.479813+0530 linphone[68064:1643374] [Message]      <string>ERROR_ACCOUNT_DOESNT_EXIST</string>
2020-03-29 01:25:08.480076+0530 linphone[68064:1643374] [Message]     </value>
2020-03-29 01:25:08.480252+0530 linphone[68064:1643374] [Message]    </param>
2020-03-29 01:25:08.480445+0530 linphone[68064:1643374] [Message]   </params>
2020-03-29 01:25:08.480646+0530 linphone[68064:1643374] [Message]   </methodResponse>
2020-03-29 01:25:08.481258+0530 linphone[68064:1643374] [Message] channel [0x7fd1c64aa000] [207] bytes parsed
2020-03-29 01:25:08.481449+0530 linphone[68064:1643374] [Message] channel [0x7fd1c64aa000] read [185] bytes of body from [subscribe.linphone.org:444]
2020-03-29 01:25:08.481784+0530 linphone[68064:1643374] [Message] channel [0x7fd1c64aa000]: ending recv background task with id=[10001].
2020-03-29 01:25:10.016837+0530 linphone[68064:1643374] [Message] channel [0x7fd1c33c8000]: keep alive sent to [UDP://pbx.test.com.au:5060]
2020-03-29 01:25:12.957733+0530 linphone[68064:1643374] [Message] Changing [client] [CANCEL] transaction [0x60005edaa580], from state [COMPLETED] to [TERMINATED]
2020-03-29 01:25:12.957964+0530 linphone[68064:1643374] [Message] Client CANCEL transaction [0x60005edaa580] terminated
2020-03-29 01:25:12.958170+0530 linphone[68064:1643374] [Message] transaction [0x60005edaa580]: ending transaction background task with id=[ffd8].
2020-03-29 01:25:13.597622+0530 linphone[68064:1643374] [Message] Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background.
2020-03-29 01:25:13.598006+0530 linphone[68064:1643374] [Message] channel [0x7fd1c64aa000]: starting recv background task with id=[10102].
2020-03-29 01:25:13.598340+0530 linphone[68064:1643374] [Message] channel 0x7fd1c64aa000: state DISCONNECTED
2020-03-29 01:25:13.598631+0530 linphone[68064:1643374] [Message] channel [0x7fd1c64aa000]: ending recv background task with id=[10102].
2020-03-29 01:25:13.599199+0530 linphone[68064:1643374] [Message] channel [0x7fd1c64aa000] removed from http provider.
2020-03-29 01:25:13.599943+0530 linphone[68064:1643374] [Message] Channel [0x7fd1c64aa000] destroyed

Pleses Help Me, I make calling but not head sound or speakers. E/libOpenSLES: Too many objects W/libOpenSLES: Leaving Engine::CreateAudioRecorder (SL_RESULT_MEMORY_FAILURE) E/mitek: [OpenSLES] Error 3 while creating the audio recorder [OpenSLES] Problem when initialization of opensles recorder I/mitek: Stun packet sent for session [0xc54e4200] Stun packet sent on rtcp for session [0xc54e4200] I/mitek: Bandwidth usage for CallSession [0xc577b4ac]: RTP audio=[d=2.44,u=0.77], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.77], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec Thread processing load: audio=0.542154 video=0 text=0 I/mitek: Stun packet sent for session [0xc54e4200] Stun packet sent on rtcp for session [0xc54e4200] I/mitek: [OpenSLES] Trying to init opensles recorder on process E/libOpenSLES: Too many objects W/libOpenSLES: Leaving Engine::CreateAudioRecorder (SL_RESULT_MEMORY_FAILURE) E/mitek: [OpenSLES] Error 3 while creating the audio recorder [OpenSLES] Problem when initialization of opensles recorder I/mitek: Stun packet sent for session [0xc54e4200] Stun packet sent on rtcp for session [0xc54e4200] I/mitek: Bandwidth usage for CallSession [0xc577b4ac]: RTP audio=[d=0.65,u=0.77], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.77], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec I/mitek: Thread processing load: audio=0.429386 video=0 text=0 I/mitek: Stun packet sent for session [0xc54e4200] Stun packet sent on rtcp for session [0xc54e4200] I/mitek: [OpenSLES] Trying to init opensles recorder on process E/libOpenSLES: Too many objects W/libOpenSLES: Leaving Engine::CreateAudioRecorder (SL_RESULT_MEMORY_FAILURE) E/mitek: [OpenSLES] Error 3 while creating the audio recorder [OpenSLES] Problem when initialization of opensles recorder I/mitek: Stun packet sent for session [0xc54e4200] Stun packet sent on rtcp for session [0xc54e4200] I/mitek: Bandwidth usage for CallSession [0xc577b4ac]: RTP audio=[d=0.57,u=0.77], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.77], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec Thread processing load: audio=0.517216 video=0 text=0 I/mitek: Stun packet sent for session [0xc54e4200] Stun packet sent on rtcp for session [0xc54e4200] I/mitek: [OpenSLES] Trying to init opensles recorder on process E/libOpenSLES: Too many objects W/libOpenSLES: Leaving Engine::CreateAudioRecorder (SL_RESULT_MEMORY_FAILURE) E/mitek: [OpenSLES] Error 3 while creating the audio recorder [OpenSLES] Problem when initialization of opensles recorder I/mitek: Stun packet sent for session [0xc54e4200] I/mitek: Stun packet sent on rtcp for session [0xc54e4200] I/mitek: Bandwidth usage for CallSession [0xc577b4ac]: RTP audio=[d=0.61,u=0.77], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.77], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec Thread processing load: audio=0.655326 video=0 text=0 I/mitek: Stun packet sent for session [0xc54e4200] Stun packet sent on rtcp for session [0xc54e4200] I/mitek: [OpenSLES] Trying to init opensles recorder on process E/libOpenSLES: Too many objects W/libOpenSLES: Leaving Engine::CreateAudioRecorder (SL_RESULT_MEMORY_FAILURE) E/mitek: [OpenSLES] Error 3 while creating the audio recorder [OpenSLES] Problem when initialization of opensles recorder I/mitek: [OpenSLES] sound/wall clock skew is average=0 ms I/mitek: Stun packet sent for session [0xc54e4200] Stun packet sent on rtcp for session [0xc54e4200] I/mitek: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x2bd6] bellesip_wake_lock_acquire(): cast long of wakelock 11222 channel [0xc556ce00]: starting recv background task with id=[2bd6]. channel [0xc556ce00]: received [563] new bytes from [UDP://221.132.18.218:5060]: BYE sip:8887@1.52.153.2:55477;transport=udp SIP/2.0 Via: SIP/2.0/UDP 221.132.18.218:5060;branch=z9hG4bK3e86a226;rport Max-Forwards: 70 From: sip:8007@221.132.18.218;tag=as14bb8c30 To: sip:8887@221.132.18.218;tag=xo5mtikq9 Call-ID: qnd-444c87 CSeq: 102 BYE User-Agent: FPBX-15.0.16.38(15.7.2) I/mitek: Proxy-Authorization: Digest username="8887", realm="asterisk", algorithm=MD5, uri="sip:221.132.18.218", nonce="443e0c9c", response="66986a74969a22be014bcce8f4988984" X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 Content-Length: 0 I/mitek: channel [0xc556ce00] [563] bytes parsed I/mitek: bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0xc53ce840)] acquired [ref=0x2bf6] bellesip_wake_lock_acquire(): cast long of wakelock 11254 transaction [0xc53ce840]: starting transaction background task with id=[2bf6]. Changing [server] [BYE] transaction [0xc53ce840], from state [INIT] to [TRYING] Changing [server] [BYE] transaction [0xc53ce840], from state [TRYING] to [COMPLETED] channel [0xc556ce00]: message sent to [UDP://221.132.18.218:5060], size: [279] bytes SIP/2.0 200 Ok Via: SIP/2.0/UDP 221.132.18.218:5060;branch=z9hG4bK3e86a226;rport From: sip:8007@221.132.18.218;tag=as14bb8c30 To: sip:8887@221.132.18.218;tag=xo5mtikq9 Call-ID: qnd-444c87 CSeq: 102 BYE User-Agent: Mitek-9991/1.0 Supported: replaces, outbound, gruu Dialog [0xc576f640]: now updated by transaction [0xc53ce840]. Dialog [0xc576f640] deleted (is_expired=0) ms_ticker_set_time_func: ticker's time method updated. D/: PlayerBase::stop() from IPlayer D/AudioTrack: stop(40): called with 427006 frames delivered I/mitek: =========================================================== AUDIO SESSION'S RTP STATISTICS

sent 0 packets 0 duplicated packets 0 bytes received 0 packets 0 duplicated packets 0 bytes incoming delivered to the app 0 bytes incoming cumulative lost 0 packets incoming received too late 0 packets incoming bad formatted 0 packets incoming discarded (queue overflow) 0 packets sent rtcp 0 packets received rtcp 0 packets =========================================================== ms_filter_unlink: MSOpenSLESRecorder:0xc2243780,0-->MSResample:0xc22438c0,0 ms_filter_unlink: MSResample:0xc22438c0,0-->MSEqualizer:0xc2243980,0 ms_filter_unlink: MSEqualizer:0xc2243980,0-->MSVolume:0xc5767ac0,0 ms_filter_unlink: MSVolume:0xc5767ac0,0-->MSAudioMixer:0xc2243940,0 ms_filter_unlink: MSAudioMixer:0xc2243940,0-->MSUlawEnc:0xc2243800,0 ms_filter_unlink: MSUlawEnc:0xc2243800,0-->MSRtpSend:0xc5767a80,0 ms_filter_unlink: MSRtpRecv:0xc2243700,0-->MSUlawDec:0xc2243840,0 ms_filter_unlink: MSUlawDec:0xc2243840,0-->MSGenericPLC:0xc2243a00,0 ms_filter_unlink: MSGenericPLC:0xc2243a00,0-->MSAudioFlowControl:0xc2243a80,0 ms_filter_unlink: MSAudioFlowControl:0xc2243a80,0-->MSDtmfGen:0xc2243740,0 ms_filter_unlink: MSDtmfGen:0xc2243740,0-->MSVolume:0xc2243880,0 ms_filter_unlink: MSVolume:0xc2243880,0-->MSEqualizer:0xc22439c0,0 ms_filter_unlink: MSEqualizer:0xc22439c0,0-->MSAudioMixer:0xc2243ac0,0 ms_filter_unlink: MSFilePlayer:0xc2243b00,0-->MSResample:0xc2243b80,0 ms_filter_unlink: MSResample:0xc2243b80,0-->MSAudioMixer:0xc2243ac0,1 ms_filter_unlink: MSAudioMixer:0xc2243ac0,0-->MSResample:0xc2243900,0 ms_filter_unlink: MSResample:0xc2243900,0-->MSOpenSLESPlayer:0xc22437c0,0 ================================================================================= FILTER USAGE STATISTICS Name Count Time/tick (ms) CPU Usage min mean max sd --------------------------------------------------------------------------------- MSResample 1141 0.06 0.15 0.71 0.07 66.9 MSRtpRecv 985 0.00 0.04 0.99 0.06 16.2 MSOpenSLESRecorder 967 0.00 0.01 8.37 0.27 5.5 MSFilePlayer 2009 0.00 0.01 1.01 0.05 4.6 MSRtpSend 967 0.00 0.01 1.91 0.08 4.5 MSOpenSLESPlayer 1141 0.00 0.00 0.56 0.02 0.8 MSGenericPLC 967 0.00 0.00 0.12 0.00 0.5 MSAudioMixer 1934 0.00 0.00 0.07 0.00 0.5 MSDtmfGen 2009 0.00 0.00 0.02 0.00 0.3 MSVoidSink 0 0.00 0.00 0.00 0.00 0.0 MSAudioFlowControl 0 0.00 0.00 0.00 0.00 0.0 MSEqualizer 0 0.00 0.00 0.00 0.00 0.0 MSVolume 0 0.00 0.00 0.00 0.00 0.0 MSUlawDec 0 0.00 0.00 0.00 0.00 0.0 MSUlawEnc 0 0.00 0.00 0.00 0.00 0.0 MSItcSink 0 0.00 0.00 0.00 0.00 0.0 MSTee 0 0.00 0.00 0.00 0.00 0.0 MSWebRTCAECM 0 0.00 0.00 0.00 0.00 0.0 ================================================================================= ================================================================================= FILTER USAGE STATISTICS Name Count Time/tick (ms) CPU Usage min mean max sd --------------------------------------------------------------------------------- MSResample 1141 0.06 0.15 0.71 0.07 66.9 MSRtpRecv 985 0.00 0.04 0.99 0.06 16.2

I/mitek: MSOpenSLESRecorder 967 0.00 0.01 8.37 0.27 5.5 MSFilePlayer 2009 0.00 0.01 1.01 0.05 4.6 MSRtpSend 967 0.00 0.01 1.91 0.08 4.5 MSOpenSLESPlayer 1141 0.00 0.00 0.56 0.02 0.8 MSGenericPLC 967 0.00 0.00 0.12 0.00 0.5 MSAudioMixer 1934 0.00 0.00 0.07 0.00 0.5 MSDtmfGen 2009 0.00 0.00 0.02 0.00 0.3 MSVoidSink 0 0.00 0.00 0.00 0.00 0.0 MSAudioFlowControl 0 0.00 0.00 0.00 0.00 0.0 MSEqualizer 0 0.00 0.00 0.00 0.00 0.0 MSVolume 0 0.00 0.00 0.00 0.00 0.0 MSUlawDec 0 0.00 0.00 0.00 0.00 0.0 MSUlawEnc 0 0.00 0.00 0.00 0.00 0.0 MSItcSink 0 0.00 0.00 0.00 0.00 0.0 MSTee 0 0.00 0.00 0.00 0.00 0.0 MSWebRTCAECM 0 0.00 0.00 0.00 0.00 0.0

CallSession [0xc577b4ac] moving from state State::StreamsRunning to State::End Notifying soundcard that we don't need it anymore for calls Stopping ZRTP context on session [0x0] ZRTP context destroyed

I/mitek: MSAudio MSTicker thread exiting I/mitek: Stopping ZRTP context on session [0x0] ZRTP context destroyed I/mitek: Resetting the current call E/LinphoneService: callState: CallEnd I/mitek: Linphone core [0xc5644600] notified [call_state_changed] channel [0xc556ce00]: ending recv background task with id=[2bd6]. bellesip_wake_lock_release(): Android wake lock released [ref=0x2bd6] I/mitek: Dialog [0xc576f640] terminated for op [0xd94e9140] I/mitek: CallSession [0xc577b4ac] moving from state State::End to State::Released E/LinphoneService: callState: Released I/mitek: Linphone core [0xc5644600] notified [call_state_changed] op [0xd94e9140] : set_or_update_dialog() current=[0xc576f640] new=[0x0] I/mitek: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x2af6] bellesip_wake_lock_acquire(): cast long of wakelock 10998 channel [0xc556ce00]: starting recv background task with id=[2af6]. channel [0xc556ce00]: received [578] new bytes from [UDP://221.132.18.218:5060]: OPTIONS sip:8887@192.168.0.112:61214;transport=udp SIP/2.0 Via: SIP/2.0/UDP 221.132.18.218;rport;branch=z9hG4bK21d3f838 Max-Forwards: 70 From: "Unknown" sip:Unknown@221.132.18.218;tag=as2bcb5928 To: sip:8887@192.168.0.112:61214 Contact: sip:Unknown@221.132.18.218:5060 Call-ID: 1a7120f73c7b0f936d339b8d7c5b66e5@221.132.18.218:5060 CSeq: 102 OPTIONS User-Agent: FPBX-15.0.16.38(15.7.2) I/mitek: Date: Thu, 07 May 2020 08:01:16 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 I/mitek: channel [0xc556ce00] [578] bytes parsed channel [0xc556ce00]: message sent to [UDP://221.132.18.218:5060], size: [269] bytes SIP/2.0 200 Ok Via: SIP/2.0/UDP 221.132.18.218;rport;branch=z9hG4bK21d3f838 From: "Unknown" sip:Unknown@221.132.18.218;tag=as2bcb5928 To: sip:8887@192.168.0.112:61214;tag=QDdTl Call-ID: 1a7120f73c7b0f936d339b8d7c5b66e5@221.132.18.218:5060 CSeq: 102 OPTIONS channel [0xc556ce00]: ending recv background task with id=[2af6]. I/mitek: bellesip_wake_lock_release(): Android wake lock released [ref=0x2af6] I/mitek: Changing [server] [BYE] transaction [0xc52576a0], from state [COMPLETED] to [TERMINATED] Server BYE transaction [0xc52576a0] terminated Destroying op [0xd94e7c40] of type [SalOpCall] transaction [0xc52576a0]: ending transaction background task with id=[2b16]. I/mitek: bellesip_wake_lock_release(): Android wake lock released [ref=0x2b16] Transaction [0xc52576a0] deleted I/mitek: Changing [client] [INVITE] transaction [0xc5747e70], from state [COMPLETED] to [TERMINATED] Client INVITE transaction [0xc5747e70] terminated transaction [0xc5747e70]: ending transaction background task with id=[2b26]. I/mitek: bellesip_wake_lock_release(): Android wake lock released [ref=0x2b26] I/mitek: channel [0xc556ce00]: keep alive sent to [UDP://221.132.18.218:5060] I/mitek: Changing [client] [INVITE] transaction [0xc5290b00], from state [ACCEPTED] to [TERMINATED] Client INVITE transaction [0xc5290b00] terminated transaction [0xc5290b00]: ending transaction background task with id=[2b86]. I/mitek: bellesip_wake_lock_release(): Android wake lock released [ref=0x2b86] I/mitek: Changing [server] [BYE] transaction [0xc53ce840], from state [COMPLETED] to [TERMINATED] Server BYE transaction [0xc53ce840] terminated Destroying op [0xd94e9140] of type [SalOpCall] I/mitek: Transaction [0xc5747e70] deleted I/mitek: Transaction [0xc5290b00] deleted transaction [0xc53ce840]: ending transaction background task with id=[2bf6]. I/mitek: bellesip_wake_lock_release(): Android wake lock released [ref=0x2bf6] Transaction [0xc53ce840] deleted I/mitek: channel [0xc556ce00]: keep alive sent to [UDP://221.132.18.218:5060] I/mitek: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x2b2a] bellesip_wake_lock_acquire(): cast long of wakelock 11050 channel [0xc556ce00]: starting recv background task with id=[2b2a]. channel [0xc556ce00]: received [578] new bytes from [UDP://221.132.18.218:5060]: OPTIONS sip:8887@192.168.0.112:61214;transport=udp SIP/2.0 Via: SIP/2.0/UDP 221.132.18.218;rport;branch=z9hG4bK72249a24 Max-Forwards: 70 From: "Unknown" sip:Unknown@221.132.18.218;tag=as3192df22 To: sip:8887@192.168.0.112:61214 Contact: sip:Unknown@221.132.18.218:5060 Call-ID: 60de37210b4ba5fe530cde0332472b65@221.132.18.218:5060 CSeq: 102 OPTIONS I/mitek: User-Agent: FPBX-15.0.16.38(15.7.2) Date: Thu, 07 May 2020 08:02:16 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 I/mitek: channel [0xc556ce00] [578] bytes parsed I/mitek: channel [0xc556ce00]: message sent to [UDP://221.132.18.218:5060], size: [269] bytes SIP/2.0 200 Ok Via: SIP/2.0/UDP 221.132.18.218;rport;branch=z9hG4bK72249a24 From: "Unknown" sip:Unknown@221.132.18.218;tag=as3192df22 To: sip:8887@192.168.0.112:61214;tag=PPEF1

BelledonneCommunications / linphone-android

SIP : Unable to make outgoing call. #958