2017-12-06 17:05:25:250 MESSAGE Vtable [0x7c4d9263c0] unregistered on core [0x7c617a2e00] 2017-12-06 17:05:28:165 MESSAGE Found media local-ip from signaling. 2017-12-06 17:05:28:165 MESSAGE New LinphoneCall [0x7c4c02d200] initialized (LinphoneCore version: 3.12.0-236-gfba4cd41c) 2017-12-06 17:05:28:166 MESSAGE Call [0x7c4c02d200], stream type [audio], multicast role is [inactive] 2017-12-06 17:05:28:166 MESSAGE RtpSession bound to [0.0.0.0] ports [7076] [7077] 2017-12-06 17:05:28:167 MESSAGE rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2017-12-06 17:05:28:167 MESSAGE Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80 2017-12-06 17:05:28:167 MESSAGE Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32 2017-12-06 17:05:28:167 MESSAGE Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80 2017-12-06 17:05:28:167 MESSAGE Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32 2017-12-06 17:05:28:167 MESSAGE Creating ZRTP engine on rtp session [0x7c5d5bb000] ssrc 0xd3f25163 2017-12-06 17:05:28:170 MESSAGE Setting DSCP to 46 for MSAudio stream. 2017-12-06 17:05:28:170 MESSAGE Equalizer location: hp 2017-12-06 17:05:28:172 MESSAGE cannot set noise gate mode to [0] because no volume send 2017-12-06 17:05:28:172 MESSAGE Call [0x7c4c02d200], stream type [video], multicast role is [inactive] 2017-12-06 17:05:28:173 MESSAGE RtpSession bound to [0.0.0.0] ports [9078] [9079] 2017-12-06 17:05:28:173 MESSAGE rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2017-12-06 17:05:28:173 MESSAGE Initializing multistream ZRTP context on rtp session [0x7c5d5bc000] ssrc 0x75986820 2017-12-06 17:05:28:173 MESSAGE Setting DSCP to 0 for MSVideo stream. 2017-12-06 17:05:28:174 MESSAGE Call [0x7c4c02d200], stream type [text], multicast role is [inactive] 2017-12-06 17:05:28:174 MESSAGE RtpSession bound to [0.0.0.0] ports [11078] [11079] 2017-12-06 17:05:28:174 MESSAGE rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2017-12-06 17:05:28:180 MESSAGE Call 0x7c4c02d200: moving from state LinphoneCallIdle to LinphoneCallOutgoingInit 2017-12-06 17:05:28:180 MESSAGE acquireWifiLock() 2017-12-06 17:05:28:182 MESSAGE acquireMcastLock() 2017-12-06 17:05:28:184 MESSAGE acquireCpuLock() 2017-12-06 17:05:28:186 MESSAGE New call state [OutgoingInit] 2017-12-06 17:05:28:376 MESSAGE Linphone core [0x7c617a2e00] notified [call_state_changed] 2017-12-06 17:05:28:376 MESSAGE Contact has been fixed using proxy 2017-12-06 17:05:28:377 MESSAGE Found media local-ip from signaling. 2017-12-06 17:05:28:377 MESSAGE Don't put video stream on local offer for call [0x7c4c02d200] 2017-12-06 17:05:28:377 MESSAGE Don't put text stream on local offer for call [0x7c4c02d200] 2017-12-06 17:05:28:377 MESSAGE ms_filter_link: MSRtpRecv:0x7c4c2e2400,0-->MSVoidSink:0x7c4c2e2300,0 2017-12-06 17:05:28:380 MESSAGE Priority used: 99 2017-12-06 17:05:28:380 MESSAGE MSAudio MSTicker priority increased to maximum. 2017-12-06 17:05:28:380 MESSAGE Contacting sip:address@hidden 2017-12-06 17:05:28:381 MESSAGE Linphone core [0x7c617a2e00] notified [display_status] 2017-12-06 17:05:28:381 MESSAGE [sip:address@hidden calling [sip:address@hidden on op [0x7c3b57bc40] 2017-12-06 17:05:28:381 MESSAGE Skipping top route of initial route-set because same as request-uri. 2017-12-06 17:05:28:392 MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x200952] 2017-12-06 17:05:28:393 MESSAGE transaction [0x7c4d9f4f80]: starting transaction background task with id=[200952]. 2017-12-06 17:05:28:393 MESSAGE Changing [client] [INVITE] transaction [0x7c4d9f4f80], from state [INIT] to [CALLING] 2017-12-06 17:05:28:394 MESSAGE channel [0x7c4d99e500]: message sent to [TCP://ims.testoperator.com:5060], size: [1118] bytes INVITE sip:address@hidden SIP/2.0 Via: SIP/2.0/TCP 10.0.1.5:58135;branch=z9hG4bK.9OcRB8ul8;rport From: ;tag=KEPVID0md To: sip:address@hidden CSeq: 20 INVITE Call-ID: MDKhaD3rSq Max-Forwards: 70 Supported: replaces, outbound Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Content-Type: application/sdp Content-Length: 491 Contact: ;+sip.instance="" User-Agent: LinphoneAndroid/3.3.0 (belle-sip/1.6.3) v=0 o=23675735203 3711 3994 IN IP4 10.0.1.5 s=Talk c=IN IP4 10.0.1.5 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics m=audio 7076 RTP/AVP 96 97 98 0 8 18 101 99 100 a=rtpmap:96 opus/48000/2 a=fmtp:96 useinbandfec=1 a=rtpmap:97 speex/16000 a=fmtp:97 vbr=on a=rtpmap:98 speex/8000 a=fmtp:98 vbr=on a=fmtp:18 annexb=yes a=rtpmap:101 telephone-event/48000 a=rtpmap:99 telephone-event/16000 a=rtpmap:100 telephone-event/8000 a=rtcp-fb:* ccm tmmbr 2017-12-06 17:05:28:394 MESSAGE Call 0x7c4c02d200: moving from state LinphoneCallOutgoingInit to LinphoneCallOutgoingProgress 2017-12-06 17:05:28:394 MESSAGE New call state [OutgoingProgress] 2017-12-06 17:05:28:401 MESSAGE Call [0x7c4c14ae00] freed. 2017-12-06 17:05:28:413 MESSAGE Linphone core [0x7c617a2e00] notified [call_state_changed] 2017-12-06 17:05:28:415 MESSAGE Vtable [0x7c63423000] unregistered on core [0x7c617a2e00] 2017-12-06 17:05:28:415 MESSAGE Vtable [0x7c5d4c4840] unregistered on core [0x7c617a2e00] 2017-12-06 17:05:28:415 MESSAGE Activity paused:address@hidden 2017-12-06 17:05:28:415 MESSAGE runningActivities=0 2017-12-06 17:05:28:444 MESSAGE Activity created:address@hidden 2017-12-06 17:05:28:500 MESSAGE Activity started:address@hidden 2017-12-06 17:05:28:501 MESSAGE [Permission] Record audio permission is granted 2017-12-06 17:05:28:501 MESSAGE [Permission] Camera permission is denied 2017-12-06 17:05:28:502 MESSAGE Activity resumed:address@hidden 2017-12-06 17:05:28:502 MESSAGE runningActivities=1 2017-12-06 17:05:28:502 MESSAGE Core callbacks [0x7c3b436910] registered on core [0x7c617a2e00] 2017-12-06 17:05:28:503 MESSAGE Core callbacks [0x7c3b436aa0] registered on core [0x7c617a2e00] 2017-12-06 17:05:28:970 MESSAGE Activity stopped:address@hidden 2017-12-06 17:05:29:097 MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x100a42] 2017-12-06 17:05:29:097 MESSAGE channel [0x7c4d99e500]: starting recv background task with id=[100a42]. 2017-12-06 17:05:29:097 MESSAGE channel [0x7c4d99e500]: received [476] new bytes from [TCP://ims.testoperator.com:5060]: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/TCP 10.0.1.5:58135;branch=z9hG4bK.9OcRB8ul8;rport=48916;received=92.103.220.99 From: ;tag=KEPVID0md To: sip:address@hidden;tag=3898fa176cdd76e3f9f0c17f00d6625d.fe21 CSeq: 20 INVITE Call-ID: MDKhaD3rSq Proxy-Authenticate: Digest realm="ims.testoperator.com", nonce="WigT2looEq5yrRzTI9taT+cYe+D2EL4a" Server: kamailio (4.4.4 (x86_64/linux)) Content-Length: 0 2017-12-06 17:05:29:103 MESSAGE channel [0x7c4d99e500] [476] bytes parsed 2017-12-06 17:05:29:103 MESSAGE Found transaction matching response. 2017-12-06 17:05:29:103 MESSAGE Changing [client] [INVITE] transaction [0x7c4d9f4f80], from state [CALLING] to [PROCEEDING] 2017-12-06 17:05:29:103 MESSAGE Changing [client] [INVITE] transaction [0x7c4d9f4f80], from state [PROCEEDING] to [COMPLETED] 2017-12-06 17:05:29:104 MESSAGE channel [0x7c4d99e500]: message sent to [TCP://ims.testoperator.com:5060], size: [457] bytes ACK sip:address@hidden SIP/2.0 Via: SIP/2.0/TCP 10.0.1.5:58135;branch=z9hG4bK.9OcRB8ul8;rport Call-ID: MDKhaD3rSq From: ;tag=KEPVID0md To: ;tag=3898fa176cdd76e3f9f0c17f00d6625d.fe21 Contact: ;+sip.instance="" Max-Forwards: 70 CSeq: 20 ACK Content-Length: 0 2017-12-06 17:05:29:104 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675735203, realm=ims.testoperator.com 2017-12-06 17:05:29:104 MESSAGE Auth info found for [23675735203] realm [ims.testoperator.com] 2017-12-06 17:05:29:105 MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x200a22] 2017-12-06 17:05:29:105 MESSAGE transaction [0x7c4d9f53e0]: starting transaction background task with id=[200a22]. 2017-12-06 17:05:29:105 MESSAGE Changing [client] [INVITE] transaction [0x7c4d9f53e0], from state [INIT] to [CALLING] 2017-12-06 17:05:29:106 MESSAGE channel [0x7c4d99e500]: message sent to [TCP://ims.testoperator.com:5060], size: [1331] bytes INVITE sip:address@hidden SIP/2.0 Via: SIP/2.0/TCP 10.0.1.5:58135;branch=z9hG4bK.-l5dHTE2F;rport From: ;tag=KEPVID0md To: sip:address@hidden CSeq: 21 INVITE Call-ID: MDKhaD3rSq Max-Forwards: 70 Supported: replaces, outbound Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Content-Type: application/sdp Content-Length: 491 Contact: ;+sip.instance="" User-Agent: LinphoneAndroid/3.3.0 (belle-sip/1.6.3) Proxy-Authorization: Digest realm="ims.testoperator.com", nonce="WigT2looEq5yrRzTI9taT+cYe+D2EL4a", username="23675735203", uri="sip:address@hidden", response="88e0d7dc9c8a6332c585483d233c87aa" v=0 o=23675735203 3711 3994 IN IP4 10.0.1.5 s=Talk c=IN IP4 10.0.1.5 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics m=audio 7076 RTP/AVP 96 97 98 0 8 18 101 99 100 a=rtpmap:96 opus/48000/2 a=fmtp:96 useinbandfec=1 a=rtpmap:97 speex/16000 a=fmtp:97 vbr=on a=rtpmap:98 speex/8000 a=fmtp:98 vbr=on a=fmtp:18 annexb=yes a=rtpmap:101 telephone-event/48000 a=rtpmap:99 telephone-event/16000 a=rtpmap:100 telephone-event/8000 a=rtcp-fb:* ccm tmmbr 2017-12-06 17:05:29:106 MESSAGE channel [0x7c4d99e500]: ending recv background task with id=[100a42]. 2017-12-06 17:05:29:107 MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x100a42] 2017-12-06 17:05:29:836 MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x200a1e] 2017-12-06 17:05:29:836 MESSAGE channel [0x7c4d99e500]: starting recv background task with id=[200a1e]. 2017-12-06 17:05:29:837 MESSAGE channel [0x7c4d99e500]: received [345] new bytes from [TCP://ims.testoperator.com:5060]: SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/TCP 10.0.1.5:58135;branch=z9hG4bK.-l5dHTE2F;rport=48916;received=92.103.220.99 From: ;tag=KEPVID0md To: sip:address@hidden CSeq: 21 INVITE Call-ID: MDKhaD3rSq Server: kamailio (4.4.4 (x86_64/linux)) Content-Length: 0 2017-12-06 17:05:29:855 MESSAGE channel [0x7c4d99e500] [345] bytes parsed 2017-12-06 17:05:29:856 MESSAGE Found transaction matching response. 2017-12-06 17:05:29:856 MESSAGE Changing [client] [INVITE] transaction [0x7c4d9f53e0], from state [CALLING] to [PROCEEDING] 2017-12-06 17:05:29:856 MESSAGE op [0x7c3b57bc40] : set_or_update_dialog() current=[0x0] new=[0x0] 2017-12-06 17:05:29:856 MESSAGE Op [0x7c3b57bc40] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL] 2017-12-06 17:05:29:857 MESSAGE channel [0x7c4d99e500]: ending recv background task with id=[200a1e]. 2017-12-06 17:05:29:860 MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x200a1e] 2017-12-06 17:05:31:336 MESSAGE linphone_core_set_device_rotation : rotation=90 2017-12-06 17:05:31:776 MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x200a02] 2017-12-06 17:05:31:777 MESSAGE channel [0x7c4d99e500]: starting recv background task with id=[200a02]. 2017-12-06 17:05:31:778 MESSAGE channel [0x7c4d99e500]: received [430] new bytes from [TCP://ims.testoperator.com:5060]: SIP/2.0 180 Ringing Via: SIP/2.0/TCP 10.0.1.5:58135;received=92.103.220.99;branch=z9hG4bK.-l5dHTE2F;rport=48916 From: ;tag=KEPVID0md To: ;tag=~m6pCu7 Call-ID: MDKhaD3rSq CSeq: 21 INVITE User-Agent: LinphoneAndroid/3.3.1 (belle-sip/1.6.3) Supported: replaces, outbound Record-route: Content-Length: 0 2017-12-06 17:05:31:802 MESSAGE channel [0x7c4d99e500] [430] bytes parsed 2017-12-06 17:05:31:802 MESSAGE Found transaction matching response. 2017-12-06 17:05:31:802 MESSAGE New client dialog [0x7c3b4ca900] , local tag [KEPVID0md], remote tag [~m6pCu7] 2017-12-06 17:05:31:802 MESSAGE Dialog [0x7c3b4ca900]: now updated by transaction [0x7c4d9f53e0]. 2017-12-06 17:05:31:803 MESSAGE op [0x7c3b57bc40] : set_or_update_dialog() current=[0x0] new=[0x7c3b4ca900] 2017-12-06 17:05:31:803 MESSAGE Op [0x7c3b57bc40] receiving call response [180], dialog is [0x7c3b4ca900] in state [BELLE_SIP_DIALOG_EARLY] 2017-12-06 17:05:31:803 MESSAGE Doing SDP offer/answer process of type outgoing 2017-12-06 17:05:31:803 MESSAGE Remote ringing. 2017-12-06 17:05:31:803 MESSAGE Linphone core [0x7c617a2e00] notified [display_status] 2017-12-06 17:05:31:803 MESSAGE ms_filter_unlink: MSRtpRecv:0x7c4c2e2400,0-->MSVoidSink:0x7c4c2e2300,0 2017-12-06 17:05:31:803 MESSAGE speex_lib_ctl init with neon ? 1 2017-12-06 17:05:31:804 MESSAGE Configuring resampler input with rate=[8000], nchannels=[1] 2017-12-06 17:05:31:804 MESSAGE MSFilePlayer[0x7c4c2e2400]: /data/user/0/org.linphone/files/ringback.wav opened: rate=8000,channel=1 2017-12-06 17:05:31:804 MESSAGE configuring resampler output to rate=[48000], nchannels=[1] 2017-12-06 17:05:31:805 MESSAGE ms_filter_link: MSFilePlayer:0x7c4c2e2400,0-->MSDtmfGen:0x7c4c2e2300,0 2017-12-06 17:05:31:805 MESSAGE ms_filter_link: MSDtmfGen:0x7c4c2e2300,0-->MSResample:0x7c3b519600,0 2017-12-06 17:05:31:805 MESSAGE Priority used: 99 2017-12-06 17:05:31:805 MESSAGE ms_filter_link: MSResample:0x7c3b519600,0-->MSOpenSLESPlayer:0x7c3b519680,0 2017-12-06 17:05:31:805 MESSAGE Ring MSTicker priority increased to maximum. 2017-12-06 17:05:31:805 MESSAGE Initializing speex resampler in mode [voip] 2017-12-06 17:05:31:879 MESSAGE Remote ringing... 2017-12-06 17:05:31:880 MESSAGE Remote ringing... 2017-12-06 17:05:31:880 MESSAGE Linphone core [0x7c617a2e00] notified [display_status] 2017-12-06 17:05:31:880 MESSAGE Call 0x7c4c02d200: moving from state LinphoneCallOutgoingProgress to LinphoneCallOutgoingRinging 2017-12-06 17:05:31:880 MESSAGE New call state [OutgoingRinging] 2017-12-06 17:05:31:881 MESSAGE Linphone core [0x7c617a2e00] notified [call_state_changed] 2017-12-06 17:05:31:881 MESSAGE channel [0x7c4d99e500]: ending recv background task with id=[200a02]. 2017-12-06 17:05:31:884 MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x200a02] 2017-12-06 17:05:32:546 MESSAGE Vtable [0x7c63423000] unregistered on core [0x7c617a2e00] 2017-12-06 17:05:32:546 MESSAGE Vtable [0x7c4d92a100] unregistered on core [0x7c617a2e00] 2017-12-06 17:05:32:546 MESSAGE Activity paused:address@hidden 2017-12-06 17:05:32:546 MESSAGE runningActivities=0 2017-12-06 17:05:32:558 MESSAGE Activity stopped:address@hidden 2017-12-06 17:05:32:558 MESSAGE Activity destroyed:address@hidden 2017-12-06 17:05:32:576 MESSAGE Activity created:address@hidden 2017-12-06 17:05:32:586 MESSAGE Activity started:address@hidden 2017-12-06 17:05:32:586 MESSAGE [Permission] Record audio permission is granted 2017-12-06 17:05:32:586 MESSAGE [Permission] Camera permission is denied 2017-12-06 17:05:32:588 MESSAGE Activity resumed:address@hidden 2017-12-06 17:05:32:588 MESSAGE runningActivities=1 2017-12-06 17:05:32:589 MESSAGE Core callbacks [0x7c3b4374f0] registered on core [0x7c617a2e00] 2017-12-06 17:05:32:589 MESSAGE Core callbacks [0x7c3b437540] registered on core [0x7c617a2e00] 2017-12-06 17:05:33:407 MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x200932] 2017-12-06 17:05:33:407 MESSAGE channel [0x7c4d99e500]: starting recv background task with id=[200932]. 2017-12-06 17:05:33:407 MESSAGE channel [0x7c4d99e500]: received [1376] new bytes from [TCP://ims.testoperator.com:5060]: SIP/2.0 200 Ok Via: SIP/2.0/TCP 10.0.1.5:58135;received=92.103.220.99;branch=z9hG4bK.-l5dHTE2F;rport=48916 From: ;tag=KEPVID0md To: ;tag=~m6pCu7 Call-ID: MDKhaD3rSq CSeq: 21 INVITE User-Agent: LinphoneAndroid/3.3.1 (belle-sip/1.6.3) Supported: replaces, outbound Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Contact: ;+sip.instance="" Content-Type: application/sdp Content-Length: 673 Record-route: v=0 o=23675191979 3790 4044 IN IP4 10.0.1.10 s=Talk c=IN IP4 169.239.97.4 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics m=audio 30514 RTP/AVP 96 97 98 0 8 18 101 99 100 a=rtpmap:96 opus/48000/2 a=fmtp:96 useinbandfec=1 a=rtpmap:97 speex/16000 a=fmtp:97 vbr=on a=rtpmap:98 speex/8000 a=fmtp:98 vbr=on a=fmtp:18 annexb=yes a=rtpmap:101 telephone-event/48000 a=rtpmap:99 telephone-event/16000 a=rtpmap:100 telephone-event/8000 a=rtcp-fb:* ccm tmmbr a=sendrecv a=rtcp:30515 a=candidate:4eyEdHcs75nSdGD7 1 UDP 2130706431 169.239.97.4 30514 typ host a=candidate:4eyEdHcs75nSdGD7 2 UDP 2130706430 169.239.97.4 30515 typ host 2017-12-06 17:05:33:411 MESSAGE channel [0x7c4d99e500] [703] bytes parsed 2017-12-06 17:05:33:411 MESSAGE channel [0x7c4d99e500] read [673] bytes of body from [ims.testoperator.com:5060] 2017-12-06 17:05:33:411 MESSAGE Found transaction matching response. 2017-12-06 17:05:33:411 MESSAGE Changing [client] [INVITE] transaction [0x7c4d9f53e0], from state [PROCEEDING] to [ACCEPTED] 2017-12-06 17:05:33:411 MESSAGE Dialog [0x7c3b4ca900]: now updated by transaction [0x7c4d9f53e0]. 2017-12-06 17:05:33:412 MESSAGE op [0x7c3b57bc40] : set_or_update_dialog() current=[0x7c3b4ca900] new=[0x7c3b4ca900] 2017-12-06 17:05:33:412 MESSAGE Op [0x7c3b57bc40] receiving call response [200], dialog is [0x7c3b4ca900] in state [BELLE_SIP_DIALOG_CONFIRMED] 2017-12-06 17:05:33:414 MESSAGE Found payload opus/48000 fmtp=useinbandfec=1 2017-12-06 17:05:33:414 MESSAGE Found payload speex/16000 fmtp=vbr=on 2017-12-06 17:05:33:414 MESSAGE Found payload speex/8000 fmtp=vbr=on 2017-12-06 17:05:33:414 MESSAGE Found payload PCMU/8000 fmtp= 2017-12-06 17:05:33:414 MESSAGE Found payload PCMA/8000 fmtp= 2017-12-06 17:05:33:414 MESSAGE Found payload G729/8000 fmtp=annexb=yes 2017-12-06 17:05:33:414 MESSAGE Found payload telephone-event/48000 fmtp= 2017-12-06 17:05:33:414 MESSAGE Found payload telephone-event/16000 fmtp= 2017-12-06 17:05:33:414 MESSAGE Found payload telephone-event/8000 fmtp= 2017-12-06 17:05:33:415 MESSAGE Doing SDP offer/answer process of type outgoing 2017-12-06 17:05:33:415 MESSAGE Processing for stream 0 2017-12-06 17:05:33:415 MESSAGE Doing offer/answer processing with specific provider for codec [opus] 2017-12-06 17:05:33:415 MESSAGE Call 0x7c4c02d200: moving from state LinphoneCallOutgoingRinging to LinphoneCallConnected 2017-12-06 17:05:33:415 MESSAGE New call state [Connected] 2017-12-06 17:05:33:423 MESSAGE Linphone core [0x7c617a2e00] notified [call_state_changed] 2017-12-06 17:05:33:423 MESSAGE Call answered by sip:address@hidden 2017-12-06 17:05:33:423 MESSAGE Linphone core [0x7c617a2e00] notified [display_status] 2017-12-06 17:05:33:424 MESSAGE ms_filter_unlink: MSFilePlayer:0x7c4c2e2400,0-->MSDtmfGen:0x7c4c2e2300,0 2017-12-06 17:05:33:424 MESSAGE ms_filter_unlink: MSDtmfGen:0x7c4c2e2300,0-->MSResample:0x7c3b519600,0 2017-12-06 17:05:33:424 MESSAGE ms_filter_unlink: MSResample:0x7c3b519600,0-->MSOpenSLESPlayer:0x7c3b519680,0 2017-12-06 17:05:33:425 MESSAGE Ring MSTicker thread exiting 2017-12-06 17:05:33:426 MESSAGE Cleaning pending event of MSFilter [MSFilePlayer:0x7c4c2e2400] 2017-12-06 17:05:33:426 MESSAGE Cleaning pending event of MSFilter [MSFilePlayer:0x7c4c2e2400] 2017-12-06 17:05:33:426 MESSAGE linphone_call_start_media_streams() call=[0x7c4c02d200] local upload_bandwidth=[0] kbit/s; local download_bandwidth=[0] kbit/s 2017-12-06 17:05:33:426 MESSAGE Audio bandwidth for this call is 50 2017-12-06 17:05:33:426 MESSAGE LinphoneCall[0x7c4c02d200] - setting up advanced rate control. 2017-12-06 17:05:33:426 MESSAGE RtpSession [0x7c5d5bb000] sending to rtp 169.239.97.4:30514 rtcp 169.239.97.4:30515 2017-12-06 17:05:33:426 MESSAGE Stun packet sent for session [0x7c5d5bb000] 2017-12-06 17:05:33:426 MESSAGE Stun packet sent on rtcp for session [0x7c5d5bb000] 2017-12-06 17:05:33:427 MESSAGE opus stereo support is deactivated because of incompatible features targeted for this AudioStream 2017-12-06 17:05:33:427 MESSAGE speex_lib_ctl init with neon ? 1 2017-12-06 17:05:33:427 MESSAGE speex_lib_ctl init with neon ? 1 2017-12-06 17:05:33:427 MESSAGE target bitrate not set for stream [0x7c3b44ab80] using payload's bitrate is 50000 2017-12-06 17:05:33:427 MESSAGE Setting audio encoder network bitrate to [50000] on stream [0x7c3b44ab80] 2017-12-06 17:05:33:427 MESSAGE opus setbitrate to 50000 2017-12-06 17:05:33:427 MESSAGE Decoder has FEC capabilities 2017-12-06 17:05:33:429 MESSAGE This device (samsung/SM-G930F/exynos5) declares it has a built-in echo canceller. 2017-12-06 17:05:33:429 MESSAGE Found information for [samsung/SM-G930F/exynos5] from internal table 2017-12-06 17:05:33:429 MESSAGE Sound device information for [samsung/SM-G930F/exynos5] is: builtin=[yes], delay=[0] ms 2017-12-06 17:05:33:431 MESSAGE This device (samsung/SM-G930F/exynos5) declares it has a built-in echo canceller. 2017-12-06 17:05:33:431 MESSAGE Found information for [samsung/SM-G930F/exynos5] from internal table 2017-12-06 17:05:33:431 MESSAGE Sound device information for [samsung/SM-G930F/exynos5] is: builtin=[yes], delay=[0] ms 2017-12-06 17:05:33:431 MESSAGE MSVolume set gain to [0.000000 db], [1.000000] linear 2017-12-06 17:05:33:433 MESSAGE This device (samsung/SM-G930F/exynos5) declares it has a built-in echo canceller. 2017-12-06 17:05:33:433 MESSAGE Found information for [samsung/SM-G930F/exynos5] from internal table 2017-12-06 17:05:33:433 MESSAGE Sound device information for [samsung/SM-G930F/exynos5] is: builtin=[yes], delay=[0] ms 2017-12-06 17:05:33:433 MESSAGE MSVolume set gain to [0.000000 db], [1.000000] linear 2017-12-06 17:05:33:433 MESSAGE configuring MSOpenSLESRecorder:0x7c3b519680-->MSOpusEnc:0x7c4c2e2400 from rate [48000] to rate [48000] and from channel [1] to channel [1] 2017-12-06 17:05:33:433 MESSAGE configuring MSOpusDec:0x7c3b519500-->MSOpenSLESPlayer:0x7c4c2e2300 from rate [48000] to rate [48000] and from channel [1] to channel [1] 2017-12-06 17:05:33:433 MESSAGE ms_filter_link: MSOpenSLESRecorder:0x7c3b519680,0-->MSResample:0x7c3b519800,0 2017-12-06 17:05:33:433 MESSAGE ms_filter_link: MSResample:0x7c3b519800,0-->MSEqualizer:0x7c3b519d00,0 2017-12-06 17:05:33:433 MESSAGE ms_filter_link: MSEqualizer:0x7c3b519d00,0-->MSVolume:0x7c4c1dfa80,0 2017-12-06 17:05:33:433 MESSAGE ms_filter_link: MSVolume:0x7c4c1dfa80,0-->MSAudioMixer:0x7c3b519a80,0 2017-12-06 17:05:33:433 MESSAGE ms_filter_link: MSAudioMixer:0x7c3b519a80,0-->MSOpusEnc:0x7c4c2e2400,0 2017-12-06 17:05:33:433 MESSAGE ms_filter_link: MSOpusEnc:0x7c4c2e2400,0-->MSRtpSend:0x7c4c1df880,0 2017-12-06 17:05:33:434 MESSAGE ms_filter_link: MSRtpRecv:0x7c3b519600,0-->MSOpusDec:0x7c3b519500,0 2017-12-06 17:05:33:434 MESSAGE ms_filter_link: MSOpusDec:0x7c3b519500,0-->MSAudioFlowControl:0x7c3b519e00,0 2017-12-06 17:05:33:434 MESSAGE ms_filter_link: MSAudioFlowControl:0x7c3b519e00,0-->MSDtmfGen:0x7c3b519480,0 2017-12-06 17:05:33:434 MESSAGE ms_filter_link: MSDtmfGen:0x7c3b519480,0-->MSVolume:0x7c3b519580,0 2017-12-06 17:05:33:434 MESSAGE ms_filter_link: MSVolume:0x7c3b519580,0-->MSEqualizer:0x7c3b519d80,0 2017-12-06 17:05:33:434 MESSAGE ms_filter_link: MSEqualizer:0x7c3b519d80,0-->MSAudioMixer:0x7c3b519e80,0 2017-12-06 17:05:33:434 MESSAGE speex_lib_ctl init with neon ? 1 2017-12-06 17:05:33:434 MESSAGE ms_filter_link: MSFilePlayer:0x7c3b519f00,0-->MSResample:0x7c3b519f80,0 2017-12-06 17:05:33:434 MESSAGE ms_filter_link: MSResample:0x7c3b519f80,0-->MSAudioMixer:0x7c3b519e80,1 2017-12-06 17:05:33:434 MESSAGE ms_filter_link: MSAudioMixer:0x7c3b519e80,0-->MSResample:0x7c3b519980,0 2017-12-06 17:05:33:434 MESSAGE ms_filter_link: MSResample:0x7c3b519980,0-->MSOpenSLESPlayer:0x7c4c2e2300,0 2017-12-06 17:05:33:460 MESSAGE Initializing speex resampler in mode [voip] 2017-12-06 17:05:33:460 MESSAGE MSOpusEnc: codec bitrate set to [34000] with ptime [20] 2017-12-06 17:05:33:460 MESSAGE Setting opus codec bitrate to [34000] from network bitrate [50000] with ptime [20] 2017-12-06 17:05:33:460 MESSAGE Initializing speex resampler in mode [voip] 2017-12-06 17:05:33:460 MESSAGE Initializing speex resampler in mode [voip] 2017-12-06 17:05:33:481 MESSAGE Stun packet sent for session [0x7c5d5bb000] 2017-12-06 17:05:33:481 MESSAGE This device (samsung/SM-G930F/exynos5) declares it has a built-in echo canceller. 2017-12-06 17:05:33:481 MESSAGE Found information for [samsung/SM-G930F/exynos5] from internal table 2017-12-06 17:05:33:481 MESSAGE Sound device information for [samsung/SM-G930F/exynos5] is: builtin=[yes], delay=[0] ms 2017-12-06 17:05:33:481 MESSAGE MSVolume set gain to [0.000000 db], [1.000000] linear 2017-12-06 17:05:33:481 MESSAGE No valid video stream defined. 2017-12-06 17:05:33:481 MESSAGE LinphoneCall[0x7c4c02d200] : payload type 96 opus/48000 fmtp=useinbandfec=1 added to frozen list. 2017-12-06 17:05:33:481 MESSAGE Stun packet sent on rtcp for session [0x7c5d5bb000] 2017-12-06 17:05:33:481 MESSAGE LinphoneCall[0x7c4c02d200] : payload type 97 speex/16000 fmtp=vbr=on added to frozen list. 2017-12-06 17:05:33:481 MESSAGE LinphoneCall[0x7c4c02d200] : payload type 98 speex/8000 fmtp=vbr=on added to frozen list. 2017-12-06 17:05:33:481 MESSAGE LinphoneCall[0x7c4c02d200] : payload type 0 PCMU/8000 fmtp= added to frozen list. 2017-12-06 17:05:33:481 MESSAGE LinphoneCall[0x7c4c02d200] : payload type 8 PCMA/8000 fmtp= added to frozen list. 2017-12-06 17:05:33:481 MESSAGE LinphoneCall[0x7c4c02d200] : payload type 18 G729/8000 fmtp=annexb=yes added to frozen list. 2017-12-06 17:05:33:481 MESSAGE LinphoneCall[0x7c4c02d200] : payload type 101 telephone-event/48000 fmtp= added to frozen list. 2017-12-06 17:05:33:482 MESSAGE LinphoneCall[0x7c4c02d200] : payload type 99 telephone-event/16000 fmtp= added to frozen list. 2017-12-06 17:05:33:482 MESSAGE LinphoneCall[0x7c4c02d200] : payload type 100 telephone-event/8000 fmtp= added to frozen list. 2017-12-06 17:05:33:482 MESSAGE audio stream index found: 0, updating main audio stream index 2017-12-06 17:05:33:482 MESSAGE Call 0x7c4c02d200: moving from state LinphoneCallConnected to LinphoneCallStreamsRunning 2017-12-06 17:05:33:482 MESSAGE New call state [StreamsRunning] 2017-12-06 17:05:33:482 WARNING [AudioManager] already in MODE_IN_COMMUNICATION, skipping... 2017-12-06 17:05:33:482 MESSAGE Linphone core [0x7c617a2e00] notified [call_state_changed] 2017-12-06 17:05:33:482 MESSAGE Computing branch id z9hG4bK.J8jWPteZ4 for message sent statelessly 2017-12-06 17:05:33:483 MESSAGE channel [0x7c4d99e500]: message sent to [TCP://ims.testoperator.com:5060], size: [661] bytes ACK sip:address@hidden:42440;transport=tcp;alias=92.103.220.99~42440~2 SIP/2.0 Via: SIP/2.0/TCP 10.0.1.5:58135;rport;branch=z9hG4bK.J8jWPteZ4 From: ;tag=KEPVID0md To: ;tag=~m6pCu7 CSeq: 21 ACK Call-ID: MDKhaD3rSq Max-Forwards: 70 Route: Proxy-Authorization: Digest realm="ims.testoperator.com", nonce="WigT2looEq5yrRzTI9taT+cYe+D2EL4a", username="23675735203", uri="sip:address@hidden", response="88e0d7dc9c8a6332c585483d233c87aa" User-Agent: LinphoneAndroid/3.3.0 (belle-sip/1.6.3) Content-Length: 0 2017-12-06 17:05:33:483 MESSAGE channel [0x7c4d99e500]: ending recv background task with id=[200932]. 2017-12-06 17:05:33:483 MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x200932] 2017-12-06 17:05:33:483 MESSAGE Garbage collecting unowned object of type belle_sip_hop_t 2017-12-06 17:05:33:483 MESSAGE Garbage collecting unowned object of type belle_sdp_session_description_t 2017-12-06 17:05:33:484 MESSAGE Vtable [0x7c4d926c80] unregistered on core [0x7c617a2e00] 2017-12-06 17:05:33:484 MESSAGE Vtable [0x7c63423000] unregistered on core [0x7c617a2e00] 2017-12-06 17:05:33:484 MESSAGE Activity paused:address@hidden 2017-12-06 17:05:33:484 MESSAGE runningActivities=0 2017-12-06 17:05:33:491 MESSAGE MSAudioMixer [0x7c3b519a80] is entering bypass mode. 2017-12-06 17:05:33:491 MESSAGE Activity created:address@hidden 2017-12-06 17:05:33:566 MESSAGE Activity started:address@hidden 2017-12-06 17:05:33:567 MESSAGE Activity resumed:address@hidden 2017-12-06 17:05:33:567 MESSAGE runningActivities=1 2017-12-06 17:05:33:567 MESSAGE Core callbacks [0x7c3b437d10] registered on core [0x7c617a2e00] 2017-12-06 17:05:33:578 MESSAGE Core callbacks [0x7c3b438260] registered on core [0x7c617a2e00] 2017-12-06 17:05:33:584 MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:33:906 MESSAGE First estimation 2017-12-06 17:05:33:906 MESSAGE First estimation 2017-12-06 17:05:33:906 MESSAGE Bandwidth usage for call [0x7c4c02d200]: RTP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:33:907 MESSAGE Thread processing load: audio=4.316707 video=0.000000 text=0.000000 2017-12-06 17:05:33:922 MESSAGE Vtable [0x7c5d4c4700] unregistered on core [0x7c617a2e00] 2017-12-06 17:05:33:922 MESSAGE Vtable [0x7c5d4c4480] unregistered on core [0x7c617a2e00] 2017-12-06 17:05:33:923 MESSAGE Activity paused:address@hidden 2017-12-06 17:05:33:923 MESSAGE runningActivities=0 2017-12-06 17:05:33:936 MESSAGE Activity stopped:address@hidden 2017-12-06 17:05:33:936 MESSAGE Activity destroyed:address@hidden 2017-12-06 17:05:33:944 MESSAGE Activity stopped:address@hidden 2017-12-06 17:05:33:989 MESSAGE Stun packet sent on rtcp for session [0x7c5d5bb000] 2017-12-06 17:05:33:998 MESSAGE sound/wall clock skew is average=-10.965750 ms 2017-12-06 17:05:34:109 MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x2009ee] 2017-12-06 17:05:34:109 MESSAGE channel [0x7c4d99e500]: starting recv background task with id=[2009ee]. 2017-12-06 17:05:34:109 MESSAGE channel [0x7c4d99e500]: received [1376] new bytes from [TCP://ims.testoperator.com:5060]: SIP/2.0 200 Ok Via: SIP/2.0/TCP 10.0.1.5:58135;received=92.103.220.99;branch=z9hG4bK.-l5dHTE2F;rport=48916 From: ;tag=KEPVID0md To: ;tag=~m6pCu7 Call-ID: MDKhaD3rSq CSeq: 21 INVITE User-Agent: LinphoneAndroid/3.3.1 (belle-sip/1.6.3) Supported: replaces, outbound Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Contact: ;+sip.instance="" Content-Type: application/sdp Content-Length: 673 Record-route: v=0 o=23675191979 3790 4044 IN IP4 10.0.1.10 s=Talk c=IN IP4 169.239.97.4 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics m=audio 30514 RTP/AVP 96 97 98 0 8 18 101 99 100 a=rtpmap:96 opus/48000/2 a=fmtp:96 useinbandfec=1 a=rtpmap:97 speex/16000 a=fmtp:97 vbr=on a=rtpmap:98 speex/8000 a=fmtp:98 vbr=on a=fmtp:18 annexb=yes a=rtpmap:101 telephone-event/48000 a=rtpmap:99 telephone-event/16000 a=rtpmap:100 telephone-event/8000 a=rtcp-fb:* ccm tmmbr a=sendrecv a=rtcp:30515 a=candidate:4eyEdHcs75nSdGD7 1 UDP 2130706431 169.239.97.4 30514 typ host a=candidate:4eyEdHcs75nSdGD7 2 UDP 2130706430 169.239.97.4 30515 typ host 2017-12-06 17:05:34:113 MESSAGE channel [0x7c4d99e500] [703] bytes parsed 2017-12-06 17:05:34:113 MESSAGE channel [0x7c4d99e500] read [673] bytes of body from [ims.testoperator.com:5060] 2017-12-06 17:05:34:113 MESSAGE Found transaction matching response. 2017-12-06 17:05:34:113 MESSAGE Dialog [0x7c3b4ca900]: now updated by transaction [0x7c4d9f53e0]. 2017-12-06 17:05:34:113 MESSAGE Dialog retransmitting last ack automatically 2017-12-06 17:05:34:114 MESSAGE channel [0x7c4d99e500]: message sent to [TCP://ims.testoperator.com:5060], size: [661] bytes ACK sip:address@hidden:42440;transport=tcp;alias=92.103.220.99~42440~2 SIP/2.0 Via: SIP/2.0/TCP 10.0.1.5:58135;branch=z9hG4bK.J8jWPteZ4;rport From: ;tag=KEPVID0md To: ;tag=~m6pCu7 CSeq: 21 ACK Call-ID: MDKhaD3rSq Max-Forwards: 70 Route: Proxy-Authorization: Digest realm="ims.testoperator.com", nonce="WigT2looEq5yrRzTI9taT+cYe+D2EL4a", username="23675735203", uri="sip:address@hidden", response="88e0d7dc9c8a6332c585483d233c87aa" User-Agent: LinphoneAndroid/3.3.0 (belle-sip/1.6.3) Content-Length: 0 2017-12-06 17:05:34:114 MESSAGE Response [0x7c4c04afc0] absorbed by dialog [0x7c3b4ca900], skipped from transaction layer. 2017-12-06 17:05:34:114 MESSAGE channel [0x7c4d99e500]: ending recv background task with id=[2009ee]. 2017-12-06 17:05:34:114 MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x2009ee] 2017-12-06 17:05:34:114 MESSAGE Garbage collecting unowned object of type belle_sip_hop_t 2017-12-06 17:05:34:270 MESSAGE There is already a log collection handler, keep it 2017-12-06 17:05:34:270 MESSAGE [KeepAlive] Screen is off, disable 2017-12-06 17:05:34:469 MESSAGE MSAudioMixer [0x7c3b519e80] is entering bypass mode. 2017-12-06 17:05:34:488 MESSAGE Stun packet sent on rtcp for session [0x7c5d5bb000] 2017-12-06 17:05:34:498 MESSAGE sound/wall clock skew is average=-15.148074 ms 2017-12-06 17:05:34:582 MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:34:640 MESSAGE Sending RTCP SR compound message on session [0x7c5d5bb000]. 2017-12-06 17:05:34:652 MESSAGE MSAudio_stream_iterate[0x7c3b44ab80], local statistics available: Local current jitter buffer size: 24.0ms 2017-12-06 17:05:34:653 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:34:675 MESSAGE OrtpCongestionDetector: moving from state CongestionStateNormal to state CongestionStatePending 2017-12-06 17:05:34:896 MESSAGE Bandwidth usage for call [0x7c4c02d200]: RTP audio=[d= 55.9,u= 40.9], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 0.0,u= 1.2], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:34:897 MESSAGE Thread processing load: audio=32.832142 video=0.000000 text=0.000000 2017-12-06 17:05:35:000 MESSAGE Stun packet sent on rtcp for session [0x7c5d5bb000] 2017-12-06 17:05:35:002 MESSAGE sound/wall clock skew is average=-15.140959 ms 2017-12-06 17:05:35:086 MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x100a1e] 2017-12-06 17:05:35:087 MESSAGE channel [0x7c4d99e500]: starting recv background task with id=[100a1e]. 2017-12-06 17:05:35:088 MESSAGE channel [0x7c4d99e500]: received [1376] new bytes from [TCP://ims.testoperator.com:5060]: SIP/2.0 200 Ok Via: SIP/2.0/TCP 10.0.1.5:58135;received=92.103.220.99;branch=z9hG4bK.-l5dHTE2F;rport=48916 From: ;tag=KEPVID0md To: ;tag=~m6pCu7 Call-ID: MDKhaD3rSq CSeq: 21 INVITE User-Agent: LinphoneAndroid/3.3.1 (belle-sip/1.6.3) Supported: replaces, outbound Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Contact: ;+sip.instance="" Content-Type: application/sdp Content-Length: 673 Record-route: v=0 o=23675191979 3790 4044 IN IP4 10.0.1.10 s=Talk c=IN IP4 169.239.97.4 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics m=audio 30514 RTP/AVP 96 97 98 0 8 18 101 99 100 a=rtpmap:96 opus/48000/2 a=fmtp:96 useinbandfec=1 a=rtpmap:97 speex/16000 a=fmtp:97 vbr=on a=rtpmap:98 speex/8000 a=fmtp:98 vbr=on a=fmtp:18 annexb=yes a=rtpmap:101 telephone-event/48000 a=rtpmap:99 telephone-event/16000 a=rtpmap:100 telephone-event/8000 a=rtcp-fb:* ccm tmmbr a=sendrecv a=rtcp:30515 a=candidate:4eyEdHcs75nSdGD7 1 UDP 2130706431 169.239.97.4 30514 typ host a=candidate:4eyEdHcs75nSdGD7 2 UDP 2130706430 169.239.97.4 30515 typ host 2017-12-06 17:05:35:114 MESSAGE channel [0x7c4d99e500] [703] bytes parsed 2017-12-06 17:05:35:114 MESSAGE channel [0x7c4d99e500] read [673] bytes of body from [ims.testoperator.com:5060] 2017-12-06 17:05:35:114 MESSAGE Found transaction matching response. 2017-12-06 17:05:35:114 MESSAGE Dialog [0x7c3b4ca900]: now updated by transaction [0x7c4d9f53e0]. 2017-12-06 17:05:35:115 MESSAGE Dialog retransmitting last ack automatically 2017-12-06 17:05:35:116 MESSAGE channel [0x7c4d99e500]: message sent to [TCP://ims.testoperator.com:5060], size: [661] bytes ACK sip:address@hidden:42440;transport=tcp;alias=92.103.220.99~42440~2 SIP/2.0 Via: SIP/2.0/TCP 10.0.1.5:58135;branch=z9hG4bK.J8jWPteZ4;rport From: ;tag=KEPVID0md To: ;tag=~m6pCu7 CSeq: 21 ACK Call-ID: MDKhaD3rSq Max-Forwards: 70 Route: Proxy-Authorization: Digest realm="ims.testoperator.com", nonce="WigT2looEq5yrRzTI9taT+cYe+D2EL4a", username="23675735203", uri="sip:address@hidden", response="88e0d7dc9c8a6332c585483d233c87aa" User-Agent: LinphoneAndroid/3.3.0 (belle-sip/1.6.3) Content-Length: 0 2017-12-06 17:05:35:116 MESSAGE Response [0x7c3b414660] absorbed by dialog [0x7c3b4ca900], skipped from transaction layer. 2017-12-06 17:05:35:116 MESSAGE channel [0x7c4d99e500]: ending recv background task with id=[100a1e]. 2017-12-06 17:05:35:118 MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x100a1e] 2017-12-06 17:05:35:118 MESSAGE Garbage collecting unowned object of type belle_sip_hop_t 2017-12-06 17:05:35:309 MESSAGE MSAudio stream [0x7c3b44ab80]: receiving RTCP SR 2017-12-06 17:05:35:309 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:35:335 MESSAGE OrtpCongestionDetector: moving from state CongestionStatePending to state CongestionStateNormal 2017-12-06 17:05:35:502 MESSAGE sound/wall clock skew is average=-14.813378 ms 2017-12-06 17:05:35:591 MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:35:817 MESSAGE OrtpCongestionDetector: moving from state CongestionStateNormal to state CongestionStatePending 2017-12-06 17:05:35:900 MESSAGE Bandwidth usage for call [0x7c4c02d200]: RTP audio=[d= 33.8,u= 44.3], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 0.5,u= 3.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:35:900 MESSAGE Thread processing load: audio=43.916664 video=0.000000 text=0.000000 2017-12-06 17:05:35:922 MESSAGE App has entered background mode 2017-12-06 17:05:36:000 MESSAGE sound/wall clock skew is average=-14.863435 ms 2017-12-06 17:05:36:022 MESSAGE MSAudio stream [0x7c3b44ab80]: receiving RTCP SR 2017-12-06 17:05:36:023 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:36:056 MESSAGE Sending RTCP SR compound message on session [0x7c5d5bb000]. 2017-12-06 17:05:36:062 MESSAGE MSAudio_stream_iterate[0x7c3b44ab80], local statistics available: Local current jitter buffer size: 288.8ms 2017-12-06 17:05:36:063 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:36:502 MESSAGE sound/wall clock skew is average=-14.702880 ms 2017-12-06 17:05:36:591 MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:36:819 MESSAGE OrtpCongestionDetector: moving from state CongestionStatePending to state CongestionStateNormal 2017-12-06 17:05:36:897 MESSAGE Bandwidth usage for call [0x7c4c02d200]: RTP audio=[d= 45.2,u= 48.4], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 10.2,u= 2.2], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:36:898 MESSAGE Thread processing load: audio=44.370586 video=0.000000 text=0.000000 2017-12-06 17:05:36:898 MESSAGE MSAudio stream [0x7c3b44ab80]: receiving RTCP SR 2017-12-06 17:05:36:899 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:37:001 MESSAGE sound/wall clock skew is average=-14.911085 ms 2017-12-06 17:05:37:502 MESSAGE sound/wall clock skew is average=-14.477490 ms 2017-12-06 17:05:37:590 MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:37:696 MESSAGE Sending RTCP SR compound message on session [0x7c5d5bb000]. 2017-12-06 17:05:37:709 MESSAGE MSAudio_stream_iterate[0x7c3b44ab80], local statistics available: Local current jitter buffer size: 262.5ms 2017-12-06 17:05:37:710 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:37:912 MESSAGE Bandwidth usage for call [0x7c4c02d200]: RTP audio=[d= 41.8,u= 46.7], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 0.1,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:37:912 MESSAGE Thread processing load: audio=37.992645 video=0.000000 text=0.000000 2017-12-06 17:05:37:973 MESSAGE MSAudio stream [0x7c3b44ab80]: receiving RTCP SR 2017-12-06 17:05:37:974 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:38:002 MESSAGE sound/wall clock skew is average=-14.810310 ms 2017-12-06 17:05:38:370 MESSAGE sound/wall clock skew is average=-14.6828 ms 2017-12-06 17:05:38:502 MESSAGE sound/wall clock skew is average=-14.653405 ms 2017-12-06 17:05:38:588 MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:38:891 MESSAGE MSAudio stream [0x7c3b44ab80]: receiving RTCP SR 2017-12-06 17:05:38:891 MESSAGE MSQualityIndicator[0x7c3b644230][audio], remote statistics available: Loss rate : 1.1% Inter-arrival jitter: 7.6ms RT propagation : 1179.0ms 2017-12-06 17:05:38:892 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:38:912 MESSAGE Bandwidth usage for call [0x7c4c02d200]: RTP audio=[d= 43.9,u= 48.0], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 8.9,u= 2.2], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:38:912 MESSAGE Thread processing load: audio=46.380535 video=0.000000 text=0.000000 2017-12-06 17:05:39:000 MESSAGE sound/wall clock skew is average=-14.735346 ms 2017-12-06 17:05:39:059 MESSAGE jitter buffer stable: target-size: 250.000000 ms, effective-size: 262.531647 (min: 40 nom: 60, max: 250) 2017-12-06 17:05:39:060 MESSAGE jitter buffer rls stats: count=234, clockrate=48000, offset=-7616.55 clock_ratio=0.958497, capped_offset=-78943064 capped_clock_ratio=0.958497, max_ts_deviation=24204.000000 prev_max_ts_deviation=2880.000000, deviation=2252, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.015899 2017-12-06 17:05:39:377 MESSAGE Sending RTCP SR compound message on session [0x7c5d5bb000]. 2017-12-06 17:05:39:379 MESSAGE MSAudio_stream_iterate[0x7c3b44ab80], local statistics available: Local current jitter buffer size: 286.9ms 2017-12-06 17:05:39:380 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:39:500 MESSAGE sound/wall clock skew is average=-14.931383 ms 2017-12-06 17:05:39:583 MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:39:765 MESSAGE MSAudio stream [0x7c3b44ab80]: receiving RTCP SR 2017-12-06 17:05:39:765 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:39:907 MESSAGE Bandwidth usage for call [0x7c4c02d200]: RTP audio=[d= 45.2,u= 50.2], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 4.8,u= 2.2], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:39:908 MESSAGE Thread processing load: audio=28.576248 video=0.000000 text=0.000000 2017-12-06 17:05:40:002 MESSAGE sound/wall clock skew is average=-15.291442 ms 2017-12-06 17:05:40:049 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675735203, realm=ims.testoperator.com 2017-12-06 17:05:40:050 MESSAGE Auth info found for [23675735203] realm [ims.testoperator.com] 2017-12-06 17:05:40:056 MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x2009fa] 2017-12-06 17:05:40:056 MESSAGE transaction [0x7c5d55a200]: starting transaction background task with id=[2009fa]. 2017-12-06 17:05:40:057 MESSAGE Transaction [0x7c5d56b340] deleted 2017-12-06 17:05:40:058 MESSAGE Changing [client] [REGISTER] transaction [0x7c5d55a200], from state [INIT] to [TRYING] 2017-12-06 17:05:40:060 MESSAGE channel [0x7c4d99e500]: message sent to [TCP://ims.testoperator.com:5060], size: [1006] bytes REGISTER sip:ims.testoperator.com SIP/2.0 Via: SIP/2.0/TCP 10.0.1.5:58135;alias;branch=z9hG4bK.VIXVUx-C7;rport From: ;tag=GRybhOAr9 To: sip:address@hidden CSeq: 47 REGISTER Call-ID: Vurk4-fK-q Max-Forwards: 70 Supported: replaces, outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: LinphoneAndroid/3.3.0 (belle-sip/1.6.3) Content-Length: 0 Authorization: Digest realm="ims.testoperator.com", nonce="WigSyVooEZ13Pj2X4nrtn8t3CcJbecf9", username="23675735203", uri="sip:ims.testoperator.com", response="66ecc3397d14a97c1a7c62b2b09c24b5" 2017-12-06 17:05:40:502 MESSAGE sound/wall clock skew is average=-14.875914 ms 2017-12-06 17:05:40:590 MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:40:905 MESSAGE Bandwidth usage for call [0x7c4c02d200]: RTP audio=[d= 48.1,u= 48.1], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:40:905 MESSAGE Thread processing load: audio=43.883247 video=0.000000 text=0.000000 2017-12-06 17:05:40:956 MESSAGE Sending RTCP SR compound message on session [0x7c5d5bb000]. 2017-12-06 17:05:40:966 MESSAGE MSAudio_stream_iterate[0x7c3b44ab80], local statistics available: Local current jitter buffer size: 295.4ms 2017-12-06 17:05:40:967 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:41:000 MESSAGE sound/wall clock skew is average=-14.978493 ms 2017-12-06 17:05:41:502 MESSAGE sound/wall clock skew is average=-14.695855 ms 2017-12-06 17:05:41:557 MESSAGE MSAudio stream [0x7c3b44ab80]: receiving RTCP SR 2017-12-06 17:05:41:562 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:41:588 MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:41:903 MESSAGE Bandwidth usage for call [0x7c4c02d200]: RTP audio=[d= 48.8,u= 45.3], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 1.8,u= 2.2], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:41:904 MESSAGE Thread processing load: audio=29.958120 video=0.000000 text=0.000000 2017-12-06 17:05:42:002 MESSAGE sound/wall clock skew is average=-14.095276 ms 2017-12-06 17:05:42:038 MESSAGE Sending RTCP SR compound message on session [0x7c5d5bb000]. 2017-12-06 17:05:42:044 MESSAGE MSAudio_stream_iterate[0x7c3b44ab80], local statistics available: Local current jitter buffer size: 294.8ms 2017-12-06 17:05:42:045 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:42:502 MESSAGE sound/wall clock skew is average=-14.391679 ms 2017-12-06 17:05:42:592 MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:42:902 MESSAGE Bandwidth usage for call [0x7c4c02d200]: RTP audio=[d= 46.3,u= 46.7], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 0.0,u= 2.2], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:42:903 MESSAGE Thread processing load: audio=45.882286 video=0.000000 text=0.000000 2017-12-06 17:05:42:963 MESSAGE MSAudio stream [0x7c3b44ab80]: receiving RTCP SR 2017-12-06 17:05:42:964 MESSAGE MSQualityIndicator[0x7c3b644230][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 9.8ms RT propagation : 1165.3ms 2017-12-06 17:05:42:964 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:43:002 MESSAGE sound/wall clock skew is average=-14.536164 ms 2017-12-06 17:05:43:369 MESSAGE sound/wall clock skew is average=-14.5499 ms 2017-12-06 17:05:43:502 MESSAGE sound/wall clock skew is average=-14.553059 ms 2017-12-06 17:05:43:595 MESSAGE Sending RTCP SR compound message on session [0x7c5d5bb000]. 2017-12-06 17:05:43:605 MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:43:606 MESSAGE MSAudio_stream_iterate[0x7c3b44ab80], local statistics available: Local current jitter buffer size: 317.8ms 2017-12-06 17:05:43:607 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:43:905 MESSAGE Bandwidth usage for call [0x7c4c02d200]: RTP audio=[d= 50.0,u= 48.8], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 0.1,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:43:905 MESSAGE Thread processing load: audio=36.281322 video=0.000000 text=0.000000 2017-12-06 17:05:44:002 MESSAGE sound/wall clock skew is average=-14.758509 ms 2017-12-06 17:05:44:075 MESSAGE jitter buffer stable: target-size: 250.000000 ms, effective-size: 317.752808 (min: 40 nom: 60, max: 250) 2017-12-06 17:05:44:076 MESSAGE jitter buffer rls stats: count=493, clockrate=48000, offset=-11538.7 clock_ratio=0.991173, capped_offset=-78946986 capped_clock_ratio=0.991173, max_ts_deviation=13013.000000 prev_max_ts_deviation=12147.000000, deviation=8644, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.008026 2017-12-06 17:05:44:091 MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x1009ee] 2017-12-06 17:05:44:092 MESSAGE channel [0x7c4d99e500]: starting recv background task with id=[1009ee]. 2017-12-06 17:05:44:093 MESSAGE channel [0x7c4d99e500]: received [749] new bytes from [TCP://ims.testoperator.com:5060]: SIP/2.0 200 OK Via: SIP/2.0/TCP 10.0.1.5:58135;alias;branch=z9hG4bK.VIXVUx-C7;rport=48916;received=92.103.220.99 From: ;tag=GRybhOAr9 To: sip:address@hidden;tag=3898fa176cdd76e3f9f0c17f00d6625d.e47b CSeq: 47 REGISTER Call-ID: Vurk4-fK-q Contact: ;expires=60;received="sip:92.103.220.99:48916;transport=tcp";+sip.instance="" Server: kamailio (4.4.4 (x86_64/linux)) Content-Length: 0 2017-12-06 17:05:44:123 MESSAGE channel [0x7c4d99e500] [749] bytes parsed 2017-12-06 17:05:44:123 MESSAGE Found transaction matching response. 2017-12-06 17:05:44:123 MESSAGE Changing [client] [REGISTER] transaction [0x7c5d55a200], from state [TRYING] to [COMPLETED] 2017-12-06 17:05:44:124 MESSAGE Refresher [0x7c4d9f2c40]: has no contact for request [0x7c3b55bc80]. 2017-12-06 17:05:44:124 MESSAGE Refresher[0x7c4d9f2c40]: scheduling next timer in 54000 ms for purpose [normal refresh] 2017-12-06 17:05:44:124 MESSAGE Register refresher [200] reason [OK] for proxy [] 2017-12-06 17:05:44:125 MESSAGE Proxy config [0x7c5d4c2cc0] for identity [sip:address@hidden moving from state [LinphoneRegistrationOk] to [LinphoneRegistrationOk] on core [0x7c617a2e00] 2017-12-06 17:05:44:125 MESSAGE New registration state [RegistrationOk] 2017-12-06 17:05:44:136 MESSAGE Linphone core [0x7c617a2e00] notified [registration_state_changed] 2017-12-06 17:05:44:136 MESSAGE Registration on successful. 2017-12-06 17:05:44:137 MESSAGE Linphone core [0x7c617a2e00] notified [display_status] 2017-12-06 17:05:44:137 MESSAGE Changing [client] [REGISTER] transaction [0x7c5d55a200], from state [COMPLETED] to [TERMINATED] 2017-12-06 17:05:44:137 MESSAGE Client internal REGISTER transaction [0x7c5d55a200] terminated 2017-12-06 17:05:44:137 MESSAGE transaction [0x7c5d55a200]: ending transaction background task with id=[2009fa]. 2017-12-06 17:05:44:140 MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x2009fa] 2017-12-06 17:05:44:140 MESSAGE channel [0x7c4d99e500]: ending recv background task with id=[1009ee]. 2017-12-06 17:05:44:143 MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x1009ee] 2017-12-06 17:05:44:502 MESSAGE sound/wall clock skew is average=-15.159580 ms 2017-12-06 17:05:44:591 MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:44:802 MESSAGE MSAudio stream [0x7c3b44ab80]: receiving RTCP SR 2017-12-06 17:05:44:803 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:44:904 MESSAGE Bandwidth usage for call [0x7c4c02d200]: RTP audio=[d= 51.8,u= 46.9], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 5.9,u= 2.2], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:44:905 MESSAGE Thread processing load: audio=43.199490 video=0.000000 text=0.000000 2017-12-06 17:05:45:002 MESSAGE sound/wall clock skew is average=-14.810634 ms 2017-12-06 17:05:45:038 MESSAGE Sending RTCP SR compound message on session [0x7c5d5bb000]. 2017-12-06 17:05:45:045 MESSAGE MSAudio_stream_iterate[0x7c3b44ab80], local statistics available: Local current jitter buffer size: 410.3ms 2017-12-06 17:05:45:045 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:45:502 MESSAGE sound/wall clock skew is average=-14.631079 ms 2017-12-06 17:05:45:588 MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:45:902 MESSAGE Bandwidth usage for call [0x7c4c02d200]: RTP audio=[d= 51.5,u= 48.9], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 0.0,u= 2.2], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:45:903 MESSAGE Thread processing load: audio=47.304264 video=0.000000 text=0.000000 2017-12-06 17:05:45:912 MESSAGE linphone_core_set_device_rotation : rotation=0 2017-12-06 17:05:46:002 MESSAGE sound/wall clock skew is average=-14.562720 ms 2017-12-06 17:05:46:271 MESSAGE linphone_core_set_device_rotation : rotation=90 2017-12-06 17:05:46:502 MESSAGE sound/wall clock skew is average=-14.486182 ms 2017-12-06 17:05:46:535 MESSAGE MSAudio stream [0x7c3b44ab80]: receiving RTCP SR 2017-12-06 17:05:46:535 MESSAGE MSQualityIndicator[0x7c3b644230][audio], remote statistics available: Loss rate : 0.6% Inter-arrival jitter: 5.6ms RT propagation : 1174.2ms 2017-12-06 17:05:46:536 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:46:642 MESSAGE Activity started:address@hidden 2017-12-06 17:05:46:643 MESSAGE linphone_core_set_device_rotation : rotation=0 2017-12-06 17:05:46:650 MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:46:669 MESSAGE Activity resumed:address@hidden 2017-12-06 17:05:46:670 MESSAGE runningActivities=1 2017-12-06 17:05:46:670 MESSAGE App has left background mode 2017-12-06 17:05:46:672 MESSAGE Core callbacks [0x7c3b437680] registered on core [0x7c617a2e00] 2017-12-06 17:05:46:681 MESSAGE Core callbacks [0x7c3b436140] registered on core [0x7c617a2e00] 2017-12-06 17:05:46:712 MESSAGE Sending RTCP SR compound message on session [0x7c5d5bb000]. 2017-12-06 17:05:46:723 MESSAGE MSAudio_stream_iterate[0x7c3b44ab80], local statistics available: Local current jitter buffer size: 352.1ms 2017-12-06 17:05:46:723 MESSAGE Linphone core [0x7c617a2e00] notified [call_stats_updated] 2017-12-06 17:05:46:904 MESSAGE Bandwidth usage for call [0x7c4c02d200]: RTP audio=[d= 42.5,u= 49.4], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 1.4,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:46:905 MESSAGE Thread processing load: audio=13.747006 video=0.000000 text=0.000000 2017-12-06 17:05:46:940 MESSAGE There is already a log collection handler, keep it 2017-12-06 17:05:46:940 MESSAGE [KeepAlive] Screen is on, enable 2017-12-06 17:05:47:019 MESSAGE sound/wall clock skew is average=-15.173263 ms 2017-12-06 17:05:47:501 MESSAGE sound/wall clock skew is average=-14.497463 ms 2017-12-06 17:05:47:587 MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:47:738 MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x200a5a] 2017-12-06 17:05:47:739 MESSAGE channel [0x7c4d99e500]: starting recv background task with id=[200a5a]. 2017-12-06 17:05:47:739 MESSAGE channel [0x7c4d99e500]: received [491] new bytes from [TCP://ims.testoperator.com:5060]: BYE sip:address@hidden:48916;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 169.239.97.4;branch=z9hG4bKf5fc.cb25bf435585bd6023d4cc474c7f5bd0.0;i=0411 Via: SIP/2.0/TCP 10.0.1.10:40039;received=92.103.220.99;branch=z9hG4bK.XRcr9vJij;rport=42440 From: ;tag=~m6pCu7 To: ;tag=KEPVID0md CSeq: 111 BYE Call-ID: MDKhaD3rSq Max-Forwards: 69 User-Agent: LinphoneAndroid/3.3.1 (belle-sip/1.6.3) Content-Length: 0 2017-12-06 17:05:47:758 MESSAGE channel [0x7c4d99e500] [491] bytes parsed 2017-12-06 17:05:47:763 MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x200a62] 2017-12-06 17:05:47:763 MESSAGE transaction [0x7c3b46d6a0]: starting transaction background task with id=[200a62]. 2017-12-06 17:05:47:764 MESSAGE Changing [server] [BYE] transaction [0x7c3b46d6a0], from state [INIT] to [TRYING] 2017-12-06 17:05:47:764 MESSAGE Changing [server] [BYE] transaction [0x7c3b46d6a0], from state [TRYING] to [COMPLETED] 2017-12-06 17:05:47:765 MESSAGE channel [0x7c4d99e500]: message sent to [TCP://ims.testoperator.com:5060], size: [457] bytes SIP/2.0 200 Ok Via: SIP/2.0/TCP 169.239.97.4;branch=z9hG4bKf5fc.cb25bf435585bd6023d4cc474c7f5bd0.0;i=0411 Via: SIP/2.0/TCP 10.0.1.10:40039;received=92.103.220.99;branch=z9hG4bK.XRcr9vJij;rport=42440 From: ;tag=~m6pCu7 To: ;tag=KEPVID0md Call-ID: MDKhaD3rSq CSeq: 111 BYE User-Agent: LinphoneAndroid/3.3.0 (belle-sip/1.6.3) Supported: replaces, outbound Content-Length: 0 2017-12-06 17:05:47:765 MESSAGE Dialog [0x7c3b4ca900]: now updated by transaction [0x7c3b46d6a0]. 2017-12-06 17:05:47:765 MESSAGE Dialog [0x7c3b4ca900] deleted (is_expired=0) 2017-12-06 17:05:47:765 MESSAGE Current call terminated... 2017-12-06 17:05:47:797 MESSAGE ms_ticker_set_time_func: ticker's time method updated. 2017-12-06 17:05:47:798 MESSAGE opus decoder stats: fec 0 packets - plc 33 packets. 2017-12-06 17:05:47:809 MESSAGE =========================================================== 2017-12-06 17:05:47:810 MESSAGE AUDIO SESSION'S RTP STATISTICS 2017-12-06 17:05:47:810 MESSAGE ----------------------------------------------------------- 2017-12-06 17:05:47:810 MESSAGE sent 712 packets 2017-12-06 17:05:47:810 MESSAGE 0 duplicated packets 2017-12-06 17:05:47:810 MESSAGE 64183 bytes 2017-12-06 17:05:47:810 MESSAGE received 676 packets 2017-12-06 17:05:47:810 MESSAGE 0 duplicated packets 2017-12-06 17:05:47:811 MESSAGE 64602 bytes 2017-12-06 17:05:47:811 MESSAGE incoming delivered to the app 63014 bytes 2017-12-06 17:05:47:811 MESSAGE incoming cumulative lost 0 packets 2017-12-06 17:05:47:811 MESSAGE incoming received too late 5 packets 2017-12-06 17:05:47:811 MESSAGE incoming bad formatted 0 packets 2017-12-06 17:05:47:811 MESSAGE incoming discarded (queue overflow) 0 packets 2017-12-06 17:05:47:811 MESSAGE sent rtcp 9 packets 2017-12-06 17:05:47:811 MESSAGE received rtcp 10 packets 2017-12-06 17:05:47:811 MESSAGE =========================================================== 2017-12-06 17:05:47:811 MESSAGE ms_filter_unlink: MSOpenSLESRecorder:0x7c3b519680,0-->MSResample:0x7c3b519800,0 2017-12-06 17:05:47:812 MESSAGE ms_filter_unlink: MSResample:0x7c3b519800,0-->MSEqualizer:0x7c3b519d00,0 2017-12-06 17:05:47:812 MESSAGE ms_filter_unlink: MSEqualizer:0x7c3b519d00,0-->MSVolume:0x7c4c1dfa80,0 2017-12-06 17:05:47:812 MESSAGE ms_filter_unlink: MSVolume:0x7c4c1dfa80,0-->MSAudioMixer:0x7c3b519a80,0 2017-12-06 17:05:47:812 MESSAGE ms_filter_unlink: MSAudioMixer:0x7c3b519a80,0-->MSOpusEnc:0x7c4c2e2400,0 2017-12-06 17:05:47:812 MESSAGE ms_filter_unlink: MSOpusEnc:0x7c4c2e2400,0-->MSRtpSend:0x7c4c1df880,0 2017-12-06 17:05:47:812 MESSAGE ms_filter_unlink: MSRtpRecv:0x7c3b519600,0-->MSOpusDec:0x7c3b519500,0 2017-12-06 17:05:47:812 MESSAGE ms_filter_unlink: MSOpusDec:0x7c3b519500,0-->MSAudioFlowControl:0x7c3b519e00,0 2017-12-06 17:05:47:812 MESSAGE ms_filter_unlink: MSAudioFlowControl:0x7c3b519e00,0-->MSDtmfGen:0x7c3b519480,0 2017-12-06 17:05:47:812 MESSAGE ms_filter_unlink: MSDtmfGen:0x7c3b519480,0-->MSVolume:0x7c3b519580,0 2017-12-06 17:05:47:812 MESSAGE ms_filter_unlink: MSVolume:0x7c3b519580,0-->MSEqualizer:0x7c3b519d80,0 2017-12-06 17:05:47:813 MESSAGE ms_filter_unlink: MSEqualizer:0x7c3b519d80,0-->MSAudioMixer:0x7c3b519e80,0 2017-12-06 17:05:47:813 MESSAGE ms_filter_unlink: MSFilePlayer:0x7c3b519f00,0-->MSResample:0x7c3b519f80,0 2017-12-06 17:05:47:813 MESSAGE ms_filter_unlink: MSResample:0x7c3b519f80,0-->MSAudioMixer:0x7c3b519e80,1 2017-12-06 17:05:47:813 MESSAGE ms_filter_unlink: MSAudioMixer:0x7c3b519e80,0-->MSResample:0x7c3b519980,0 2017-12-06 17:05:47:813 MESSAGE ms_filter_unlink: MSResample:0x7c3b519980,0-->MSOpenSLESPlayer:0x7c4c2e2300,0 2017-12-06 17:05:47:813 MESSAGE =========================================================== 2017-12-06 17:05:47:813 MESSAGE FILTER USAGE STATISTICS 2017-12-06 17:05:47:813 MESSAGE Name Count Time/tick (ms) CPU Usage 2017-12-06 17:05:47:813 MESSAGE ----------------------------------------------------------- 2017-12-06 17:05:47:814 MESSAGE MSOpusEnc 713 5.16496 72.0611 2017-12-06 17:05:47:814 MESSAGE MSOpusDec 1433 0.403285 11.3005 2017-12-06 17:05:47:814 MESSAGE MSRtpSend 1430 0.341761 9.55648 2017-12-06 17:05:47:814 MESSAGE MSRtpRecv 1776 0.129398 4.49316 2017-12-06 17:05:47:814 MESSAGE MSVolume 1405 0.0338461 0.929885 2017-12-06 17:05:47:814 MESSAGE MSResample 1641 0.0180881 0.580366 2017-12-06 17:05:47:815 MESSAGE MSAudioMixer 2863 0.00695123 0.389018 2017-12-06 17:05:47:815 MESSAGE MSOpenSLESRecorder 1430 0.00864866 0.241838 2017-12-06 17:05:47:815 MESSAGE MSFilePlayer 1587 0.00377503 0.11714 2017-12-06 17:05:47:815 MESSAGE MSDtmfGen 1587 0.0034238 0.106242 2017-12-06 17:05:47:815 MESSAGE MSOpenSLESPlayer 928 0.0051735 0.0939151 2017-12-06 17:05:47:815 MESSAGE MSEqualizer 1405 0.00302266 0.0830443 2017-12-06 17:05:47:815 MESSAGE MSAudioFlowControl 692 0.00349757 0.0473625 2017-12-06 17:05:47:815 MESSAGE MSGenericPLC 0 0 0 2017-12-06 17:05:47:815 MESSAGE MSAlawDec 0 0 0 2017-12-06 17:05:47:815 MESSAGE MSAlawEnc 0 0 0 2017-12-06 17:05:47:816 MESSAGE MSMKVPlayer 0 0 0 2017-12-06 17:05:47:816 MESSAGE MSVoidSink 0 0 0 2017-12-06 17:05:47:816 MESSAGE MSItcSink 0 0 0 2017-12-06 17:05:47:816 MESSAGE MSTee 0 0 0 2017-12-06 17:05:47:816 MESSAGE MSWebRTCAECM 0 0 0 2017-12-06 17:05:47:816 MESSAGE =========================================================== 2017-12-06 17:05:47:817 MESSAGE =========================================================== 2017-12-06 17:05:47:817 MESSAGE FILTER USAGE STATISTICS 2017-12-06 17:05:47:817 MESSAGE Name Count Time/tick (ms) CPU Usage 2017-12-06 17:05:47:817 MESSAGE ----------------------------------------------------------- 2017-12-06 17:05:47:817 MESSAGE MSOpusEnc 713 5.16496 72.0611 2017-12-06 17:05:47:818 MESSAGE MSOpusDec 1433 0.403285 11.3005 2017-12-06 17:05:47:818 MESSAGE MSRtpSend 1430 0.341761 9.55648 2017-12-06 17:05:47:818 MESSAGE MSRtpRecv 1776 0.129398 4.49316 2017-12-06 17:05:47:818 MESSAGE MSVolume 1405 0.0338461 0.929885 2017-12-06 17:05:47:818 MESSAGE MSResample 1641 0.0180881 0.580366 2017-12-06 17:05:47:818 MESSAGE MSAudioMixer 2863 0.00695123 0.389018 2017-12-06 17:05:47:818 MESSAGE MSOpenSLESRecorder 1430 0.00864866 0.241838 2017-12-06 17:05:47:819 MESSAGE MSFilePlayer 1587 0.00377503 0.11714 2017-12-06 17:05:47:819 MESSAGE MSDtmfGen 1587 0.0034238 0.106242 2017-12-06 17:05:47:819 MESSAGE MSOpenSLESPlayer 928 0.0051735 0.0939151 2017-12-06 17:05:47:819 MESSAGE MSEqualizer 1405 0.00302266 0.0830443 2017-12-06 17:05:47:819 MESSAGE MSAudioFlowControl 692 0.00349757 0.0473625 2017-12-06 17:05:47:819 MESSAGE MSGenericPLC 0 0 0 2017-12-06 17:05:47:819 MESSAGE MSAlawDec 0 0 0 2017-12-06 17:05:47:820 MESSAGE MSAlawEnc 0 0 0 2017-12-06 17:05:47:820 MESSAGE MSMKVPlayer 0 0 0 2017-12-06 17:05:47:820 MESSAGE MSVoidSink 0 0 0 2017-12-06 17:05:47:820 MESSAGE MSItcSink 0 0 0 2017-12-06 17:05:47:820 MESSAGE MSTee 0 0 0 2017-12-06 17:05:47:820 MESSAGE MSWebRTCAECM 0 0 0 2017-12-06 17:05:47:820 MESSAGE =========================================================== 2017-12-06 17:05:47:821 MESSAGE Call terminated. 2017-12-06 17:05:47:821 MESSAGE Linphone core [0x7c617a2e00] notified [display_status] 2017-12-06 17:05:47:821 MESSAGE Call 0x7c4c02d200: moving from state LinphoneCallStreamsRunning to LinphoneCallEnd 2017-12-06 17:05:47:821 MESSAGE Notifying soundcard that we don't need it anymore for calls. 2017-12-06 17:05:47:822 MESSAGE Stopping ZRTP context on session [0x0] 2017-12-06 17:05:47:822 MESSAGE ZRTP context destroyed 2017-12-06 17:05:47:827 MESSAGE MSAudio MSTicker thread exiting 2017-12-06 17:05:47:828 MESSAGE Stopping ZRTP context on session [0x0] 2017-12-06 17:05:47:829 MESSAGE ZRTP context destroyed 2017-12-06 17:05:47:838 MESSAGE Resetting the current call 2017-12-06 17:05:47:839 MESSAGE New call state [CallEnd] 2017-12-06 17:05:47:913 WARNING Routing audio to earpiece, disabling bluetooth audio route 2017-12-06 17:05:47:922 MESSAGE Linphone core [0x7c617a2e00] notified [call_state_changed] 2017-12-06 17:05:47:922 MESSAGE channel [0x7c4d99e500]: ending recv background task with id=[200a5a]. 2017-12-06 17:05:47:923 MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x200a5a] 2017-12-06 17:05:47:923 MESSAGE Changing [server] [BYE] transaction [0x7c3b46d6a0], from state [COMPLETED] to [TERMINATED] 2017-12-06 17:05:47:923 MESSAGE Server BYE transaction [0x7c3b46d6a0] terminated 2017-12-06 17:05:47:923 MESSAGE transaction [0x7c3b46d6a0]: ending transaction background task with id=[200a62]. 2017-12-06 17:05:47:924 MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x200a62] 2017-12-06 17:05:47:924 MESSAGE Dialog [0x7c3b4ca900] terminated for op [0x7c3b57bc40] 2017-12-06 17:05:47:924 MESSAGE Call 0x7c4c02d200: moving from state LinphoneCallEnd to LinphoneCallReleased 2017-12-06 17:05:47:924 MESSAGE releaseWifiLock() 2017-12-06 17:05:47:924 MESSAGE releaseMcastLock() 2017-12-06 17:05:47:924 MESSAGE releaseCpuLock() 2017-12-06 17:05:47:925 MESSAGE New call state [Released] 2017-12-06 17:05:47:928 MESSAGE Linphone core [0x7c617a2e00] notified [call_state_changed] 2017-12-06 17:05:47:928 MESSAGE op [0x7c3b57bc40] : set_or_update_dialog() current=[0x7c3b4ca900] new=[0x0] 2017-12-06 17:05:47:928 MESSAGE Transaction [0x7c3b46d6a0] deleted 2017-12-06 17:05:47:930 MESSAGE Vtable [0x7c63425a80] unregistered on core [0x7c617a2e00] 2017-12-06 17:05:47:930 MESSAGE Vtable [0x7c63424cc0] unregistered on core [0x7c617a2e00] 2017-12-06 17:05:47:930 MESSAGE Activity paused:address@hidden 2017-12-06 17:05:47:930 MESSAGE runningActivities=0 2017-12-06 17:05:47:931 WARNING linphone_core_mic_enabled(): No current call! 2017-12-06 17:05:47:938 MESSAGE Activity stopped:address@hidden 2017-12-06 17:05:47:942 MESSAGE Activity destroyed:address@hidden 2017-12-06 17:05:47:951 MESSAGE Activity created:address@hidden 2017-12-06 17:05:48:005 MESSAGE Activity started:address@hidden 2017-12-06 17:05:48:013 MESSAGE Activity resumed:address@hidden 2017-12-06 17:05:48:013 MESSAGE runningActivities=1 2017-12-06 17:05:48:015 MESSAGE Core callbacks [0x7c3b438c10] registered on core [0x7c617a2e00] 2017-12-06 17:05:48:030 MESSAGE Core callbacks [0x7c3b438bc0] registered on core [0x7c617a2e00] 2017-12-06 17:05:48:034 MESSAGE Vtable [0x7c4d927b80] unregistered on core [0x7c617a2e00] 2017-12-06 17:05:48:034 MESSAGE Vtable [0x7c4d927cc0] unregistered on core [0x7c617a2e00] 2017-12-06 17:05:48:035 MESSAGE Activity paused:address@hidden 2017-12-06 17:05:48:035 MESSAGE runningActivities=0 2017-12-06 17:05:48:042 MESSAGE Activity created:address@hidden 2017-12-06 17:05:48:051 MESSAGE Activity started:address@hidden 2017-12-06 17:05:48:051 MESSAGE [Permission] Record audio permission is granted 2017-12-06 17:05:48:051 MESSAGE [Permission] Camera permission is denied 2017-12-06 17:05:48:052 MESSAGE Activity resumed:address@hidden 2017-12-06 17:05:48:052 MESSAGE runningActivities=1 2017-12-06 17:05:48:052 MESSAGE Core callbacks [0x7c3b436f00] registered on core [0x7c617a2e00] 2017-12-06 17:05:48:052 ERROR Couldn't find outgoing call 2017-12-06 17:05:48:054 MESSAGE Core callbacks [0x7c3b4377c0] registered on core [0x7c617a2e00] 2017-12-06 17:05:48:055 MESSAGE Vtable [0x7c4d927a40] unregistered on core [0x7c617a2e00] 2017-12-06 17:05:48:055 MESSAGE Vtable [0x7c4d927b80] unregistered on core [0x7c617a2e00] 2017-12-06 17:05:48:055 MESSAGE Activity paused:address@hidden 2017-12-06 17:05:48:056 MESSAGE runningActivities=0 2017-12-06 17:05:48:063 MESSAGE Activity started:address@hidden 2017-12-06 17:05:48:063 MESSAGE [Permission] Contacts permission is granted 2017-12-06 17:05:48:063 MESSAGE [Permission] Read phone state permission is granted 2017-12-06 17:05:48:063 MESSAGE [Permission] Read external storage for ring tone permission is denied 2017-12-06 17:05:48:063 MESSAGE Activity resumed:address@hidden 2017-12-06 17:05:48:063 MESSAGE runningActivities=1 2017-12-06 17:05:48:064 MESSAGE Core callbacks [0x7c3b437810] registered on core [0x7c617a2e00] 2017-12-06 17:05:48:064 MESSAGE Core callbacks [0x7c3b437860] registered on core [0x7c617a2e00] 2017-12-06 17:05:48:073 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:48:074 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:48:074 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675735203, realm=ims.testoperator.com 2017-12-06 17:05:48:075 MESSAGE linphone_core_find_auth_info(): returning auth info username=+33757791000, realm=ims.otheroperator.fr 2017-12-06 17:05:48:075 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:48:076 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:48:076 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675735203, realm=ims.testoperator.com 2017-12-06 17:05:48:076 MESSAGE linphone_core_find_auth_info(): returning auth info username=+33757791000, realm=ims.otheroperator.fr 2017-12-06 17:05:48:077 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:48:078 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:48:078 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675735203, realm=ims.testoperator.com 2017-12-06 17:05:48:078 MESSAGE linphone_core_find_auth_info(): returning auth info username=+33757791000, realm=ims.otheroperator.fr 2017-12-06 17:05:48:079 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:48:079 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:48:079 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675735203, realm=ims.testoperator.com 2017-12-06 17:05:48:080 MESSAGE linphone_core_find_auth_info(): returning auth info username=+33757791000, realm=ims.otheroperator.fr 2017-12-06 17:05:48:123 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:48:124 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:48:124 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675735203, realm=ims.testoperator.com 2017-12-06 17:05:48:125 MESSAGE linphone_core_find_auth_info(): returning auth info username=+33757791000, realm=ims.otheroperator.fr 2017-12-06 17:05:48:541 MESSAGE Activity stopped:address@hidden 2017-12-06 17:05:48:541 MESSAGE Activity destroyed:address@hidden 2017-12-06 17:05:48:542 MESSAGE Activity stopped:address@hidden 2017-12-06 17:05:48:545 MESSAGE Activity destroyed:address@hidden 2017-12-06 17:05:54:493 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:54:495 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:54:496 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675735203, realm=ims.testoperator.com 2017-12-06 17:05:54:497 MESSAGE linphone_core_find_auth_info(): returning auth info username=+33757791000, realm=ims.otheroperator.fr 2017-12-06 17:05:54:499 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:54:501 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:54:502 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675735203, realm=ims.testoperator.com 2017-12-06 17:05:54:503 MESSAGE linphone_core_find_auth_info(): returning auth info username=+33757791000, realm=ims.otheroperator.fr 2017-12-06 17:05:54:505 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:54:507 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:54:509 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675735203, realm=ims.testoperator.com 2017-12-06 17:05:54:510 MESSAGE linphone_core_find_auth_info(): returning auth info username=+33757791000, realm=ims.otheroperator.fr 2017-12-06 17:05:54:512 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:54:513 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:54:514 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675735203, realm=ims.testoperator.com 2017-12-06 17:05:54:515 MESSAGE linphone_core_find_auth_info(): returning auth info username=+33757791000, realm=ims.otheroperator.fr 2017-12-06 17:05:56:815 MESSAGE Core callbacks [0x7c3b437900] registered on core [0x7c617a2e00] 2017-12-06 17:05:57:235 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:57:237 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:57:238 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675735203, realm=ims.testoperator.com 2017-12-06 17:05:57:239 MESSAGE linphone_core_find_auth_info(): returning auth info username=+33757791000, realm=ims.otheroperator.fr 2017-12-06 17:05:57:241 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:57:243 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:57:244 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675735203, realm=ims.testoperator.com 2017-12-06 17:05:57:245 MESSAGE linphone_core_find_auth_info(): returning auth info username=+33757791000, realm=ims.otheroperator.fr 2017-12-06 17:05:57:247 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:57:249 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:57:251 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675735203, realm=ims.testoperator.com 2017-12-06 17:05:57:252 MESSAGE linphone_core_find_auth_info(): returning auth info username=+33757791000, realm=ims.otheroperator.fr 2017-12-06 17:05:57:254 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:57:255 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675710749, realm=ims.testoperator.com 2017-12-06 17:05:57:256 MESSAGE linphone_core_find_auth_info(): returning auth info username=23675735203, realm=ims.testoperator.com 2017-12-06 17:05:57:258 MESSAGE linphone_core_find_auth_info(): returning auth info username=+33757791000, realm=ims.otheroperator.fr