2017-12-06 17:04:58:838 [belle-sip] MESSAGE Changing [server] [INVITE] transaction [0x9abc70a0], from state [ACCEPTED] to [TERMINATED] 2017-12-06 17:04:58:838 [belle-sip] MESSAGE Server INVITE transaction [0x9abc70a0] terminated 2017-12-06 17:04:58:839 [liblinphone] MESSAGE Destroying op [0x9e6a9800] of type [SalOpCall] 2017-12-06 17:04:58:839 [belle-sip] MESSAGE Transaction [0x9e4ae580] deleted 2017-12-06 17:04:58:839 [belle-sip] MESSAGE transaction [0x9abc70a0]: ending transaction background task with id=[cea]. 2017-12-06 17:04:58:844 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0xcea] 2017-12-06 17:04:58:845 [belle-sip] MESSAGE Transaction [0x9abc70a0] deleted 2017-12-06 17:05:08:323 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:08:324 [belle-sip] MESSAGE Auth info found for [23675191979] realm [ims.testoperator.com] 2017-12-06 17:05:08:326 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x100d96] 2017-12-06 17:05:08:326 [belle-sip] MESSAGE transaction [0x9abc70a0]: starting transaction background task with id=[100d96]. 2017-12-06 17:05:08:326 [belle-sip] MESSAGE Transaction [0x99c314c0] deleted 2017-12-06 17:05:08:327 [belle-sip] MESSAGE Changing [client] [REGISTER] transaction [0x9abc70a0], from state [INIT] to [TRYING] 2017-12-06 17:05:08:328 [belle-sip] MESSAGE channel [0xa04d1000]: message sent to [TCP://ims.testoperator.com:5060], size: [1008] bytes REGISTER sip:ims.testoperator.com SIP/2.0 Via: SIP/2.0/TCP 10.0.1.10:40039;alias;branch=z9hG4bK.eybTjjbGp;rport From: ;tag=k9nLtk5up To: sip:address@hidden CSeq: 80 REGISTER Call-ID: ElG85oc8MC 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.1 (belle-sip/1.6.3) Content-Length: 0 Authorization: Digest realm="ims.testoperator.com", nonce="WigS01ooEaelLlneRDhMPrbDb/4ga2k+", username="23675191979", uri="sip:ims.testoperator.com", response="64100d4a678aad10b1f32b535cb19ddc" 2017-12-06 17:05:08:328 [mediastreamer] MESSAGE Refreshing on sip:address@hidden 2017-12-06 17:05:08:328 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [display_status] 2017-12-06 17:05:08:328 [liblinphone] MESSAGE Proxy config [0xb4497c40] for identity [sip:address@hidden moving from state [LinphoneRegistrationOk] to [LinphoneRegistrationProgress] on core [0xb459cb80] 2017-12-06 17:05:08:329 [mediastreamer] MESSAGE New registration state [RegistrationProgress] 2017-12-06 17:05:08:331 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [registration_state_changed] 2017-12-06 17:05:08:335 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0xcda] 2017-12-06 17:05:08:335 [belle-sip] MESSAGE transaction [0x99c314c0]: starting transaction background task with id=[cda]. 2017-12-06 17:05:08:337 [belle-sip] MESSAGE Transaction [0x9e4605e0] deleted 2017-12-06 17:05:08:337 [belle-sip] MESSAGE Changing [client] [REGISTER] transaction [0x99c314c0], from state [INIT] to [TRYING] 2017-12-06 17:05:08:338 [belle-sip] MESSAGE channel [0xa04d1000]: message sent to [TCP://ims.testoperator.com:5060], size: [813] bytes REGISTER sip:ims.testoperator.com SIP/2.0 Via: SIP/2.0/TCP 10.0.1.10:40039;alias;branch=z9hG4bK.hfBvyTas5;rport From: ;tag=yeEjxQyv3 To: sip:address@hidden CSeq: 83 REGISTER Call-ID: NHxrK-3LLI 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.1 (belle-sip/1.6.3) Content-Length: 0 2017-12-06 17:05:08:339 [mediastreamer] MESSAGE Refreshing on sip:address@hidden 2017-12-06 17:05:08:339 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [display_status] 2017-12-06 17:05:08:339 [liblinphone] MESSAGE Proxy config [0xb4497d00] for identity [sip:address@hidden moving from state [LinphoneRegistrationFailed] to [LinphoneRegistrationProgress] on core [0xb459cb80] 2017-12-06 17:05:08:339 [mediastreamer] MESSAGE New registration state [RegistrationProgress] 2017-12-06 17:05:08:342 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [registration_state_changed] 2017-12-06 17:05:08:350 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:08:354 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:08:358 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:08:360 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:08:364 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:08:999 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x100c9a] 2017-12-06 17:05:08:999 [belle-sip] MESSAGE channel [0xa04d1000]: starting recv background task with id=[100c9a]. 2017-12-06 17:05:08:999 [belle-sip] MESSAGE channel [0xa04d1000]: received [1217] new bytes from [TCP://ims.testoperator.com:5060]: SIP/2.0 200 OK Via: SIP/2.0/TCP 10.0.1.10:40039;alias;branch=z9hG4bK.eybTjjbGp;rport=42440;received=92.103.220.99 From: ;tag=k9nLtk5up To: sip:address@hidden;tag=3898fa176cdd76e3f9f0c17f00d6625d.3daa CSeq: 80 REGISTER Call-ID: ElG85oc8MC Contact: ;expires=60;received="sip:92.103.220.99:42440;transport=tcp";+sip.instance="" Server: kamailio (4.4.4 (x86_64/linux)) Content-Length: 0 SIP/2.0 401 Unauthorized Via: SIP/2.0/TCP 10.0.1.10:40039;alias;branch=z9hG4bK.hfBvyTas5;rport=42440;received=92.103.220.99 From: ;tag=yeEjxQyv3 To: sip:address@hidden;tag=3898fa176cdd76e3f9f0c17f00d6625d.d43c CSeq: 83 REGISTER Call-ID: NHxrK-3LLI WWW-Authenticate: Digest realm="ims.testoperator.com", nonce="WigTxlooEpqV6qVyzws+ofD2pvJNHjgy" Server: kamailio (4.4.4 (x86_64/linux)) Content-Length: 0 2017-12-06 17:05:09:014 [belle-sip] MESSAGE channel [0xa04d1000] [751] bytes parsed 2017-12-06 17:05:09:024 [belle-sip] MESSAGE channel [0xa04d1000] [466] bytes parsed 2017-12-06 17:05:09:024 [belle-sip] MESSAGE Found transaction matching response. 2017-12-06 17:05:09:024 [belle-sip] MESSAGE Changing [client] [REGISTER] transaction [0x9abc70a0], from state [TRYING] to [COMPLETED] 2017-12-06 17:05:09:024 [belle-sip] MESSAGE Refresher [0xb46e9c80]: has no contact for request [0x977e34c0]. 2017-12-06 17:05:09:024 [belle-sip] MESSAGE Refresher[0xb46e9c80]: scheduling next timer in 54000 ms for purpose [normal refresh] 2017-12-06 17:05:09:024 [liblinphone] MESSAGE Register refresher [200] reason [OK] for proxy [] 2017-12-06 17:05:09:025 [liblinphone] MESSAGE Proxy config [0xb4497c40] for identity [sip:address@hidden moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationOk] on core [0xb459cb80] 2017-12-06 17:05:09:025 [liblinphone] MESSAGE Updating friends for identity [sip:address@hidden on core [0xb459cb80] 2017-12-06 17:05:09:025 [mediastreamer] MESSAGE New registration state [RegistrationOk] 2017-12-06 17:05:09:027 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [registration_state_changed] 2017-12-06 17:05:09:028 [mediastreamer] MESSAGE Registration on successful. 2017-12-06 17:05:09:028 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [display_status] 2017-12-06 17:05:09:028 [belle-sip] MESSAGE Changing [client] [REGISTER] transaction [0x9abc70a0], from state [COMPLETED] to [TERMINATED] 2017-12-06 17:05:09:028 [belle-sip] MESSAGE Client internal REGISTER transaction [0x9abc70a0] terminated 2017-12-06 17:05:09:028 [belle-sip] MESSAGE transaction [0x9abc70a0]: ending transaction background task with id=[100d96]. 2017-12-06 17:05:09:029 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x100d96] 2017-12-06 17:05:09:029 [belle-sip] MESSAGE Found transaction matching response. 2017-12-06 17:05:09:030 [belle-sip] MESSAGE Changing [client] [REGISTER] transaction [0x99c314c0], from state [TRYING] to [COMPLETED] 2017-12-06 17:05:09:030 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:09:030 [belle-sip] MESSAGE Auth info found for [23675191979] realm [ims.testoperator.com] 2017-12-06 17:05:09:032 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x200c66] 2017-12-06 17:05:09:032 [belle-sip] MESSAGE transaction [0x99c316a0]: starting transaction background task with id=[200c66]. 2017-12-06 17:05:09:033 [belle-sip] MESSAGE Changing [client] [REGISTER] transaction [0x99c316a0], from state [INIT] to [TRYING] 2017-12-06 17:05:09:034 [belle-sip] MESSAGE channel [0xa04d1000]: message sent to [TCP://ims.testoperator.com:5060], size: [1008] bytes REGISTER sip:ims.testoperator.com SIP/2.0 Via: SIP/2.0/TCP 10.0.1.10:40039;alias;branch=z9hG4bK.N91Pkfobs;rport From: ;tag=yeEjxQyv3 To: sip:address@hidden CSeq: 84 REGISTER Call-ID: NHxrK-3LLI 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.1 (belle-sip/1.6.3) Content-Length: 0 Authorization: Digest realm="ims.testoperator.com", nonce="WigTxlooEpqV6qVyzws+ofD2pvJNHjgy", username="23675191979", uri="sip:ims.testoperator.com", response="d271d8f3e06384f2e9f389075c692878" 2017-12-06 17:05:09:034 [belle-sip] MESSAGE Changing [client] [REGISTER] transaction [0x99c314c0], from state [COMPLETED] to [TERMINATED] 2017-12-06 17:05:09:034 [belle-sip] MESSAGE Client internal REGISTER transaction [0x99c314c0] terminated 2017-12-06 17:05:09:034 [belle-sip] MESSAGE transaction [0x99c314c0]: ending transaction background task with id=[cda]. 2017-12-06 17:05:09:035 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0xcda] 2017-12-06 17:05:09:036 [belle-sip] MESSAGE Transaction [0x99c314c0] deleted 2017-12-06 17:05:09:036 [belle-sip] MESSAGE channel [0xa04d1000]: ending recv background task with id=[100c9a]. 2017-12-06 17:05:09:037 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x100c9a] 2017-12-06 17:05:09:043 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:09:046 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:09:048 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:09:051 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:09:058 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:10:627 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x200c9a] 2017-12-06 17:05:10:627 [belle-sip] MESSAGE channel [0xa04d1000]: starting recv background task with id=[200c9a]. 2017-12-06 17:05:10:628 [belle-sip] MESSAGE channel [0xa04d1000]: received [751] new bytes from [TCP://ims.testoperator.com:5060]: SIP/2.0 200 OK Via: SIP/2.0/TCP 10.0.1.10:40039;alias;branch=z9hG4bK.N91Pkfobs;rport=42440;received=92.103.220.99 From: ;tag=yeEjxQyv3 To: sip:address@hidden;tag=3898fa176cdd76e3f9f0c17f00d6625d.4c75 CSeq: 84 REGISTER Call-ID: NHxrK-3LLI Contact: ;expires=60;received="sip:92.103.220.99:42440;transport=tcp";+sip.instance="" Server: kamailio (4.4.4 (x86_64/linux)) Content-Length: 0 2017-12-06 17:05:10:649 [belle-sip] MESSAGE channel [0xa04d1000] [751] bytes parsed 2017-12-06 17:05:10:649 [belle-sip] MESSAGE Found transaction matching response. 2017-12-06 17:05:10:649 [belle-sip] MESSAGE Changing [client] [REGISTER] transaction [0x99c316a0], from state [TRYING] to [COMPLETED] 2017-12-06 17:05:10:649 [belle-sip] MESSAGE Refresher [0xb46e9dd0]: has no contact for request [0x9aba2040]. 2017-12-06 17:05:10:650 [belle-sip] MESSAGE Refresher[0xb46e9dd0]: scheduling next timer in 54000 ms for purpose [normal refresh] 2017-12-06 17:05:10:650 [liblinphone] MESSAGE Register refresher [200] reason [OK] for proxy [] 2017-12-06 17:05:10:650 [liblinphone] MESSAGE Proxy config [0xb4497d00] for identity [sip:address@hidden moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationOk] on core [0xb459cb80] 2017-12-06 17:05:10:650 [liblinphone] MESSAGE Updating friends for identity [sip:address@hidden on core [0xb459cb80] 2017-12-06 17:05:10:651 [mediastreamer] MESSAGE New registration state [RegistrationOk] 2017-12-06 17:05:10:662 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [registration_state_changed] 2017-12-06 17:05:10:662 [mediastreamer] MESSAGE Registration on successful. 2017-12-06 17:05:10:662 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [display_status] 2017-12-06 17:05:10:662 [belle-sip] MESSAGE Changing [client] [REGISTER] transaction [0x99c316a0], from state [COMPLETED] to [TERMINATED] 2017-12-06 17:05:10:662 [belle-sip] MESSAGE Client internal REGISTER transaction [0x99c316a0] terminated 2017-12-06 17:05:10:663 [belle-sip] MESSAGE transaction [0x99c316a0]: ending transaction background task with id=[200c66]. 2017-12-06 17:05:10:665 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x200c66] 2017-12-06 17:05:10:666 [belle-sip] MESSAGE channel [0xa04d1000]: ending recv background task with id=[200c9a]. 2017-12-06 17:05:10:668 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x200c9a] 2017-12-06 17:05:10:677 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:10:680 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:10:684 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:10:687 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:10:690 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:29:853 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x201162] 2017-12-06 17:05:29:854 [belle-sip] MESSAGE channel [0xa04d1000]: starting recv background task with id=[201162]. 2017-12-06 17:05:29:855 [belle-sip] MESSAGE channel [0xa04d1000]: received [1580] new bytes from [TCP://ims.testoperator.com:5060]: INVITE sip:address@hidden:40039;app-id=929724111839;pn-type=firebase;pn-tok=dB-qBQg9uCI:APA91bFD3KJJ5eSeIu09zyglEVtZKTOgDu3GyOrj2UAusnrxS58sharnsw92iCbKYbrKeDSXZLqNaqzXWdwNB0RypyvjUxeW00aeibhBUERW1rFBe5iqQEk96KNP5Uoy024u6-UnOGmG;pn-silent=1;transport=tcp SIP/2.0 Record-Route: Via: SIP/2.0/TCP 169.239.97.4;branch=z9hG4bKb24e.19b7dbaecfb21536313cff678aa30082.0;i=a411 Via: SIP/2.0/TCP 10.0.1.5:58135;received=92.103.220.99;branch=z9hG4bK.-l5dHTE2F;rport=48916 From: ;tag=KEPVID0md To: sip:address@hidden CSeq: 21 INVITE Call-ID: MDKhaD3rSq Max-Forwards: 69 Supported: replaces, outbound Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Content-Type: application/sdp Content-Length: 526 Contact: ;+sip.instance="" User-Agent: LinphoneAndroid/3.3.0 (belle-sip/1.6.3) v=0 o=23675735203 3711 3994 IN IP4 169.239.97.4 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 30496 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:30497 2017-12-06 17:05:30:808 [belle-sip] MESSAGE channel [0xa04d1000] [1054] bytes parsed 2017-12-06 17:05:30:808 [belle-sip] MESSAGE channel [0xa04d1000] read [526] bytes of body from [ims.testoperator.com:5060] 2017-12-06 17:05:30:817 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x101156] 2017-12-06 17:05:30:817 [belle-sip] MESSAGE transaction [0x99c314c0]: starting transaction background task with id=[101156]. 2017-12-06 17:05:30:817 [belle-sip] MESSAGE Changing [server] [INVITE] transaction [0x99c314c0], from state [INIT] to [PROCEEDING] 2017-12-06 17:05:30:818 [belle-sip] MESSAGE channel [0xa04d1000]: message sent to [TCP://ims.testoperator.com:5060], size: [364] bytes SIP/2.0 100 Trying Via: SIP/2.0/TCP 169.239.97.4;branch=z9hG4bKb24e.19b7dbaecfb21536313cff678aa30082.0;i=a411 Via: SIP/2.0/TCP 10.0.1.5:58135;received=92.103.220.99;branch=z9hG4bK.-l5dHTE2F;rport=48916 From: ;tag=KEPVID0md To: sip:address@hidden Call-ID: MDKhaD3rSq CSeq: 21 INVITE Content-Length: 0 2017-12-06 17:05:30:818 [belle-sip] MESSAGE New server dialog [0x9e50c980] , local tag [~m6pCu7], remote tag [KEPVID0md] 2017-12-06 17:05:30:818 [liblinphone] MESSAGE op [0x99c07400] : set_or_update_dialog() current=[0x0] new=[0x9e50c980] 2017-12-06 17:05:30:819 [liblinphone] MESSAGE new incoming call from [sip:address@hidden to [sip:address@hidden 2017-12-06 17:05:30:829 [liblinphone] MESSAGE Found payload opus/48000 fmtp=useinbandfec=1 2017-12-06 17:05:30:829 [liblinphone] MESSAGE Found payload speex/16000 fmtp=vbr=on 2017-12-06 17:05:30:830 [liblinphone] MESSAGE Found payload speex/8000 fmtp=vbr=on 2017-12-06 17:05:30:830 [liblinphone] MESSAGE Found payload PCMU/8000 fmtp= 2017-12-06 17:05:30:830 [liblinphone] MESSAGE Found payload PCMA/8000 fmtp= 2017-12-06 17:05:30:830 [liblinphone] MESSAGE Found payload G729/8000 fmtp=annexb=yes 2017-12-06 17:05:30:830 [liblinphone] MESSAGE Found payload telephone-event/48000 fmtp= 2017-12-06 17:05:30:830 [liblinphone] MESSAGE Found payload telephone-event/16000 fmtp= 2017-12-06 17:05:30:830 [liblinphone] MESSAGE Found payload telephone-event/8000 fmtp= 2017-12-06 17:05:30:832 [liblinphone] MESSAGE Searching for already_a_call_with_remote_address. 2017-12-06 17:05:30:832 [liblinphone] MESSAGE Found media local-ip from signaling. 2017-12-06 17:05:30:833 [liblinphone] MESSAGE New LinphoneCall [0xb44b2800] initialized (LinphoneCore version: 3.12.0-273-g20efb4ad4) 2017-12-06 17:05:30:833 [liblinphone] MESSAGE audio stream index found: 0, updating main audio stream index 2017-12-06 17:05:30:833 [liblinphone] MESSAGE Call [0xb44b2800], stream type [audio], multicast role is [inactive] 2017-12-06 17:05:30:834 [ortp] MESSAGE RtpSession bound to [0.0.0.0] ports [7076] [7077] 2017-12-06 17:05:30:834 [ortp] MESSAGE rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2017-12-06 17:05:30:834 [liblinphone] MESSAGE Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80 2017-12-06 17:05:30:834 [liblinphone] MESSAGE Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32 2017-12-06 17:05:30:834 [liblinphone] MESSAGE Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80 2017-12-06 17:05:30:834 [liblinphone] MESSAGE Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32 2017-12-06 17:05:30:834 [mediastreamer] MESSAGE Creating ZRTP engine on rtp session [0x99df5e00] ssrc 0x435668f9 2017-12-06 17:05:30:839 [mediastreamer] MESSAGE Setting DSCP to 46 for MSAudio stream. 2017-12-06 17:05:30:840 [liblinphone] MESSAGE Equalizer location: hp 2017-12-06 17:05:30:843 [mediastreamer] MESSAGE cannot set noise gate mode to [0] because no volume send 2017-12-06 17:05:30:843 [liblinphone] MESSAGE Call [0xb44b2800], stream type [video], multicast role is [inactive] 2017-12-06 17:05:30:844 [ortp] MESSAGE RtpSession bound to [0.0.0.0] ports [9078] [9079] 2017-12-06 17:05:30:845 [ortp] MESSAGE rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2017-12-06 17:05:30:845 [mediastreamer] MESSAGE Initializing multistream ZRTP context on rtp session [0x99df6c00] ssrc 0xf59203f9 2017-12-06 17:05:30:845 [mediastreamer] MESSAGE Setting DSCP to 0 for MSVideo stream. 2017-12-06 17:05:30:845 [liblinphone] MESSAGE Call [0xb44b2800], stream type [text], multicast role is [inactive] 2017-12-06 17:05:30:846 [ortp] MESSAGE RtpSession bound to [0.0.0.0] ports [11078] [11079] 2017-12-06 17:05:30:846 [ortp] MESSAGE rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2017-12-06 17:05:30:846 [liblinphone] MESSAGE Found media local-ip from signaling. 2017-12-06 17:05:30:846 [liblinphone] MESSAGE Don't put video stream on local offer for call [0xb44b2800] 2017-12-06 17:05:30:846 [liblinphone] MESSAGE Don't put text stream on local offer for call [0xb44b2800] 2017-12-06 17:05:30:846 [liblinphone] MESSAGE Doing SDP offer/answer process of type incoming 2017-12-06 17:05:30:847 [liblinphone] MESSAGE Doing offer/answer processing with specific provider for codec [opus] 2017-12-06 17:05:30:849 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x101112] 2017-12-06 17:05:30:850 [mediastreamer] MESSAGE sip:address@hidden is contacting you. 2017-12-06 17:05:30:850 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [display_status] 2017-12-06 17:05:30:850 [liblinphone] MESSAGE Call 0xb44b2800: moving from state LinphoneCallIdle to LinphoneCallIncomingReceived 2017-12-06 17:05:30:850 [mediastreamer] MESSAGE acquireWifiLock() 2017-12-06 17:05:30:852 [mediastreamer] MESSAGE acquireMcastLock() 2017-12-06 17:05:30:853 [mediastreamer] MESSAGE acquireCpuLock() 2017-12-06 17:05:30:857 [mediastreamer] MESSAGE New call state [IncomingReceived] 2017-12-06 17:05:30:863 [mediastreamer] WARNING Routing audio to speaker, disabling bluetooth audio route 2017-12-06 17:05:31:088 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_state_changed] 2017-12-06 17:05:31:090 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x101112] 2017-12-06 17:05:31:090 [liblinphone] MESSAGE Contact has been fixed using proxy 2017-12-06 17:05:31:091 [belle-sip] MESSAGE channel [0xa04d1000]: message sent to [TCP://ims.testoperator.com:5060], size: [522] bytes SIP/2.0 180 Ringing Via: SIP/2.0/TCP 169.239.97.4;branch=z9hG4bKb24e.19b7dbaecfb21536313cff678aa30082.0;i=a411 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:091 [belle-sip] MESSAGE Dialog [0x9e50c980]: now updated by transaction [0x99c314c0]. 2017-12-06 17:05:31:091 [belle-sip] MESSAGE channel [0xa04d1000]: ending recv background task with id=[201162]. 2017-12-06 17:05:31:093 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x201162] 2017-12-06 17:05:31:094 [liblinphone] MESSAGE incoming call ringing for 1 seconds 2017-12-06 17:05:31:094 [liblinphone] MESSAGE incoming call ringing for 1 seconds 2017-12-06 17:05:31:104 [liblinphone] MESSAGE Vtable [0x9e4bc380] unregistered on core [0xb459cb80] 2017-12-06 17:05:31:104 [liblinphone] MESSAGE Vtable [0x9e4bc420] unregistered on core [0xb459cb80] 2017-12-06 17:05:31:105 [liblinphone] MESSAGE Vtable [0x9e4bc240] unregistered on core [0xb459cb80] 2017-12-06 17:05:31:105 [mediastreamer] MESSAGE Activity paused:address@hidden 2017-12-06 17:05:31:105 [mediastreamer] MESSAGE runningActivities=0 2017-12-06 17:05:31:139 [mediastreamer] MESSAGE Activity resumed:address@hidden 2017-12-06 17:05:31:140 [mediastreamer] MESSAGE runningActivities=1 2017-12-06 17:05:31:141 [liblinphone] MESSAGE Core callbacks [0x9e49bb28] registered on core [0xb459cb80] 2017-12-06 17:05:31:178 [liblinphone] MESSAGE Core callbacks [0x9e4af1a0] registered on core [0xb459cb80] 2017-12-06 17:05:31:180 [liblinphone] MESSAGE Core callbacks [0x9e4af1f0] registered on core [0xb459cb80] 2017-12-06 17:05:31:190 [liblinphone] MESSAGE Vtable [0x9e4bc420] unregistered on core [0xb459cb80] 2017-12-06 17:05:31:190 [liblinphone] MESSAGE Vtable [0x9e4bc380] unregistered on core [0xb459cb80] 2017-12-06 17:05:31:190 [liblinphone] MESSAGE Vtable [0x9e4bc240] unregistered on core [0xb459cb80] 2017-12-06 17:05:31:190 [mediastreamer] MESSAGE Activity paused:address@hidden 2017-12-06 17:05:31:191 [mediastreamer] MESSAGE runningActivities=0 2017-12-06 17:05:31:231 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:31:239 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:31:243 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:31:249 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:31:253 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:31:271 [mediastreamer] MESSAGE Activity created:address@hidden 2017-12-06 17:05:31:315 [mediastreamer] MESSAGE Activity created:address@hidden 2017-12-06 17:05:31:316 [mediastreamer] MESSAGE Activity started:address@hidden 2017-12-06 17:05:31:317 [mediastreamer] MESSAGE [Permission] Record audio permission is granted 2017-12-06 17:05:31:318 [mediastreamer] MESSAGE [Permission] Camera permission is granted 2017-12-06 17:05:31:319 [mediastreamer] MESSAGE Activity resumed:address@hidden 2017-12-06 17:05:31:319 [mediastreamer] MESSAGE runningActivities=1 2017-12-06 17:05:31:319 [liblinphone] MESSAGE Core callbacks [0x9e4aff10] registered on core [0xb459cb80] 2017-12-06 17:05:31:321 [liblinphone] MESSAGE Core callbacks [0x9e4aff38] registered on core [0xb459cb80] 2017-12-06 17:05:31:389 [liblinphone] MESSAGE linphone_core_set_device_rotation : rotation=270 2017-12-06 17:05:31:539 [liblinphone] MESSAGE linphone_core_set_device_rotation : rotation=0 2017-12-06 17:05:31:638 [mediastreamer] MESSAGE Activity stopped:address@hidden 2017-12-06 17:05:31:739 [liblinphone] MESSAGE linphone_core_set_device_rotation : rotation=270 2017-12-06 17:05:31:931 [liblinphone] MESSAGE incoming call ringing for 1 seconds 2017-12-06 17:05:31:939 [liblinphone] MESSAGE linphone_core_set_device_rotation : rotation=0 2017-12-06 17:05:32:788 [liblinphone] MESSAGE Found media local-ip from signaling. 2017-12-06 17:05:32:788 [liblinphone] MESSAGE Don't put video stream on local offer for call [0xb44b2800] 2017-12-06 17:05:32:788 [liblinphone] MESSAGE Don't put text stream on local offer for call [0xb44b2800] 2017-12-06 17:05:32:789 [liblinphone] MESSAGE Configuring prefered card sampling rate to [48000] 2017-12-06 17:05:32:789 [mediastreamer] MESSAGE ms_filter_link: MSRtpRecv:0x9e427a88,0-->MSVoidSink:0x9e427af8,0 2017-12-06 17:05:32:790 [liblinphone] MESSAGE Accepting server transaction [0x99c314c0] on op [0x99c07400] 2017-12-06 17:05:32:790 [mediastreamer] MESSAGE Priority used: 99 2017-12-06 17:05:32:790 [liblinphone] MESSAGE Doing SDP offer/answer process of type incoming 2017-12-06 17:05:32:790 [liblinphone] MESSAGE Doing offer/answer processing with specific provider for codec [opus] 2017-12-06 17:05:32:792 [mediastreamer] MESSAGE MSAudio MSTicker priority increased to maximum. 2017-12-06 17:05:32:792 [belle-sip] MESSAGE channel [0xa04d1000]: message sent to [TCP://ims.testoperator.com:5060], size: [1260] bytes SIP/2.0 200 Ok Via: SIP/2.0/TCP 169.239.97.4;branch=z9hG4bKb24e.19b7dbaecfb21536313cff678aa30082.0;i=a411 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: 493 Record-route: v=0 o=23675191979 3790 4044 IN IP4 10.0.1.10 s=Talk c=IN IP4 10.0.1.10 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:32:792 [belle-sip] MESSAGE Changing [server] [INVITE] transaction [0x99c314c0], from state [PROCEEDING] to [ACCEPTED] 2017-12-06 17:05:32:793 [belle-sip] MESSAGE Dialog [0x9e50c980]: now updated by transaction [0x99c314c0]. 2017-12-06 17:05:32:793 [mediastreamer] MESSAGE Connected. 2017-12-06 17:05:32:793 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [display_status] 2017-12-06 17:05:32:793 [liblinphone] MESSAGE Call 0xb44b2800: moving from state LinphoneCallIncomingReceived to LinphoneCallConnected 2017-12-06 17:05:32:793 [mediastreamer] MESSAGE New call state [Connected] 2017-12-06 17:05:32:847 [mediastreamer] WARNING Routing audio to earpiece, disabling bluetooth audio route 2017-12-06 17:05:33:146 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_state_changed] 2017-12-06 17:05:33:146 [liblinphone] MESSAGE linphone_call_start_media_streams() call=[0xb44b2800] local upload_bandwidth=[0] kbit/s; local download_bandwidth=[0] kbit/s 2017-12-06 17:05:33:147 [liblinphone] MESSAGE Audio bandwidth for this call is 50 2017-12-06 17:05:33:147 [liblinphone] MESSAGE LinphoneCall[0xb44b2800] - setting up advanced rate control. 2017-12-06 17:05:33:148 [ortp] MESSAGE RtpSession [0x99df5e00] sending to rtp 169.239.97.4:30496 rtcp 169.239.97.4:30497 2017-12-06 17:05:33:148 [mediastreamer] MESSAGE Stun packet sent for session [0x99df5e00] 2017-12-06 17:05:33:148 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x99df5e00] 2017-12-06 17:05:33:149 [mediastreamer] MESSAGE ms_filter_unlink: MSRtpRecv:0x9e427a88,0-->MSVoidSink:0x9e427af8,0 2017-12-06 17:05:33:149 [mediastreamer] MESSAGE opus stereo support is deactivated because of incompatible features targeted for this AudioStream 2017-12-06 17:05:33:149 [mediastreamer] MESSAGE speex_lib_ctl init with neon ? 1 2017-12-06 17:05:33:150 [mediastreamer] MESSAGE target bitrate not set for stream [0x977de8c0] using payload's bitrate is 50000 2017-12-06 17:05:33:150 [mediastreamer] MESSAGE Setting audio encoder network bitrate to [50000] on stream [0x977de8c0] 2017-12-06 17:05:33:150 [mediastreamer] MESSAGE opus setbitrate to 50000 2017-12-06 17:05:33:150 [mediastreamer] MESSAGE Decoder has FEC capabilities 2017-12-06 17:05:33:153 [mediastreamer] MESSAGE This device (HUAWEI/HUAWEI TIT-U02/mt6582) declares it has a built-in echo canceller. 2017-12-06 17:05:33:153 [mediastreamer] MESSAGE No information available for [HUAWEI/HUAWEI TIT-U02/mt6582], 2017-12-06 17:05:33:153 [mediastreamer] MESSAGE Sound device information for [HUAWEI/HUAWEI TIT-U02/mt6582] is: builtin=[yes], delay=[0] ms 2017-12-06 17:05:33:156 [mediastreamer] MESSAGE This device (HUAWEI/HUAWEI TIT-U02/mt6582) declares it has a built-in echo canceller. 2017-12-06 17:05:33:156 [mediastreamer] MESSAGE No information available for [HUAWEI/HUAWEI TIT-U02/mt6582], 2017-12-06 17:05:33:156 [mediastreamer] MESSAGE Sound device information for [HUAWEI/HUAWEI TIT-U02/mt6582] is: builtin=[yes], delay=[0] ms 2017-12-06 17:05:33:156 [mediastreamer] MESSAGE MSVolume set gain to [0.000000 db], [1.000000] linear 2017-12-06 17:05:33:159 [mediastreamer] MESSAGE This device (HUAWEI/HUAWEI TIT-U02/mt6582) declares it has a built-in echo canceller. 2017-12-06 17:05:33:159 [mediastreamer] MESSAGE No information available for [HUAWEI/HUAWEI TIT-U02/mt6582], 2017-12-06 17:05:33:160 [mediastreamer] MESSAGE Sound device information for [HUAWEI/HUAWEI TIT-U02/mt6582] is: builtin=[yes], delay=[0] ms 2017-12-06 17:05:33:160 [mediastreamer] MESSAGE MSVolume set gain to [0.000000 db], [1.000000] linear 2017-12-06 17:05:33:160 [mediastreamer] MESSAGE configuring MSOpusDec:0x9e4baa50-->MSOpenSLESPlayer:0x9e427af8 from rate [48000] to rate [44100] and from channel [1] to channel [1] 2017-12-06 17:05:33:160 [mediastreamer] MESSAGE ms_filter_link: MSAndSoundRead:0x9e427a88,0-->MSEqualizer:0x9e4bac10,0 2017-12-06 17:05:33:160 [mediastreamer] MESSAGE ms_filter_link: MSEqualizer:0x9e4bac10,0-->MSVolume:0x9e427158,0 2017-12-06 17:05:33:160 [mediastreamer] MESSAGE ms_filter_link: MSVolume:0x9e427158,0-->MSAudioMixer:0x9e4bab30,0 2017-12-06 17:05:33:160 [mediastreamer] MESSAGE ms_filter_link: MSAudioMixer:0x9e4bab30,0-->MSOpusEnc:0x9e4ba9e0,0 2017-12-06 17:05:33:160 [mediastreamer] MESSAGE ms_filter_link: MSOpusEnc:0x9e4ba9e0,0-->MSRtpSend:0x9e4272e0,0 2017-12-06 17:05:33:160 [mediastreamer] MESSAGE ms_filter_link: MSRtpRecv:0x9e4ba970,0-->MSOpusDec:0x9e4baa50,0 2017-12-06 17:05:33:160 [mediastreamer] MESSAGE ms_filter_link: MSOpusDec:0x9e4baa50,0-->MSAudioFlowControl:0x9e4bac80,0 2017-12-06 17:05:33:161 [mediastreamer] MESSAGE ms_filter_link: MSAudioFlowControl:0x9e4bac80,0-->MSDtmfGen:0x9e4ba9a8,0 2017-12-06 17:05:33:161 [mediastreamer] MESSAGE ms_filter_link: MSDtmfGen:0x9e4ba9a8,0-->MSVolume:0x9e4baa88,0 2017-12-06 17:05:33:161 [mediastreamer] MESSAGE ms_filter_link: MSVolume:0x9e4baa88,0-->MSEqualizer:0x9e4bac48,0 2017-12-06 17:05:33:161 [mediastreamer] MESSAGE ms_filter_link: MSEqualizer:0x9e4bac48,0-->MSAudioMixer:0x9e4bacb8,0 2017-12-06 17:05:33:161 [mediastreamer] MESSAGE speex_lib_ctl init with neon ? 1 2017-12-06 17:05:33:161 [mediastreamer] MESSAGE ms_filter_link: MSFilePlayer:0x9e4bad28,0-->MSResample:0x9e4bad98,0 2017-12-06 17:05:33:161 [mediastreamer] MESSAGE ms_filter_link: MSResample:0x9e4bad98,0-->MSAudioMixer:0x9e4bacb8,1 2017-12-06 17:05:33:161 [mediastreamer] MESSAGE ms_filter_link: MSAudioMixer:0x9e4bacb8,0-->MSResample:0x9e4baaf8,0 2017-12-06 17:05:33:161 [mediastreamer] MESSAGE ms_filter_link: MSResample:0x9e4baaf8,0-->MSOpenSLESPlayer:0x9e427af8,0 2017-12-06 17:05:33:161 [mediastreamer] MESSAGE Configuring recorder with [16] bits rate [48000] nchanels [1] buff size [7680], chunk size [960] 2017-12-06 17:05:33:189 [mediastreamer] MESSAGE ms_ticker_set_time_func: ticker's time method updated. 2017-12-06 17:05:33:189 [mediastreamer] MESSAGE AudioRecord.getAudioSessionId() returned 749 2017-12-06 17:05:33:190 [mediastreamer] MESSAGE Start recording 2017-12-06 17:05:33:194 [mediastreamer] MESSAGE AcousticEchoCanceler successfully created. 2017-12-06 17:05:33:195 [mediastreamer] MESSAGE AcousticEchoCanceler enabled: 0 2017-12-06 17:05:33:195 [mediastreamer] MESSAGE AcousticEchoCanceler enabled 2017-12-06 17:05:33:195 [mediastreamer] MESSAGE MSOpusEnc: codec bitrate set to [34000] with ptime [20] 2017-12-06 17:05:33:195 [mediastreamer] MESSAGE Setting opus codec bitrate to [34000] from network bitrate [50000] with ptime [20] 2017-12-06 17:05:33:196 [mediastreamer] MESSAGE Initializing speex resampler in mode [voip] 2017-12-06 17:05:33:196 [mediastreamer] MESSAGE Initializing speex resampler in mode [voip] 2017-12-06 17:05:33:209 [mediastreamer] MESSAGE Stun packet sent for session [0x99df5e00] 2017-12-06 17:05:33:209 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x99df5e00] 2017-12-06 17:05:33:211 [mediastreamer] MESSAGE This device (HUAWEI/HUAWEI TIT-U02/mt6582) declares it has a built-in echo canceller. 2017-12-06 17:05:33:211 [mediastreamer] MESSAGE No information available for [HUAWEI/HUAWEI TIT-U02/mt6582], 2017-12-06 17:05:33:211 [mediastreamer] MESSAGE Sound device information for [HUAWEI/HUAWEI TIT-U02/mt6582] is: builtin=[yes], delay=[0] ms 2017-12-06 17:05:33:211 [mediastreamer] MESSAGE MSVolume set gain to [0.000000 db], [1.000000] linear 2017-12-06 17:05:33:211 [liblinphone] MESSAGE No valid video stream defined. 2017-12-06 17:05:33:211 [liblinphone] MESSAGE LinphoneCall[0xb44b2800] : payload type 96 opus/48000 fmtp=useinbandfec=1 added to frozen list. 2017-12-06 17:05:33:211 [liblinphone] MESSAGE LinphoneCall[0xb44b2800] : payload type 97 speex/16000 fmtp=vbr=on added to frozen list. 2017-12-06 17:05:33:211 [liblinphone] MESSAGE LinphoneCall[0xb44b2800] : payload type 98 speex/8000 fmtp=vbr=on added to frozen list. 2017-12-06 17:05:33:212 [liblinphone] MESSAGE LinphoneCall[0xb44b2800] : payload type 0 PCMU/8000 fmtp= added to frozen list. 2017-12-06 17:05:33:212 [liblinphone] MESSAGE LinphoneCall[0xb44b2800] : payload type 8 PCMA/8000 fmtp= added to frozen list. 2017-12-06 17:05:33:212 [liblinphone] MESSAGE LinphoneCall[0xb44b2800] : payload type 18 G729/8000 fmtp=annexb=yes added to frozen list. 2017-12-06 17:05:33:212 [liblinphone] MESSAGE LinphoneCall[0xb44b2800] : payload type 101 telephone-event/48000 fmtp= added to frozen list. 2017-12-06 17:05:33:212 [liblinphone] MESSAGE LinphoneCall[0xb44b2800] : payload type 99 telephone-event/16000 fmtp= added to frozen list. 2017-12-06 17:05:33:212 [liblinphone] MESSAGE LinphoneCall[0xb44b2800] : payload type 100 telephone-event/8000 fmtp= added to frozen list. 2017-12-06 17:05:33:212 [liblinphone] MESSAGE Call 0xb44b2800: moving from state LinphoneCallConnected to LinphoneCallStreamsRunning 2017-12-06 17:05:33:212 [mediastreamer] MESSAGE New call state [StreamsRunning] 2017-12-06 17:05:33:213 [mediastreamer] WARNING [AudioManager] already in MODE_IN_COMMUNICATION, skipping... 2017-12-06 17:05:33:213 [mediastreamer] ERROR CallIncommingActivity - onCreate - State.StreamsRunning - speaker = false 2017-12-06 17:05:33:214 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_state_changed] 2017-12-06 17:05:33:214 [liblinphone] MESSAGE Call answered 2017-12-06 17:05:33:214 [mediastreamer] WARNING Routing audio to earpiece, disabling bluetooth audio route 2017-12-06 17:05:33:219 [mediastreamer] MESSAGE MSAudioMixer [0x9e4bab30] is entering bypass mode. 2017-12-06 17:05:33:233 [ortp] MESSAGE First estimation 2017-12-06 17:05:33:233 [ortp] MESSAGE First estimation 2017-12-06 17:05:33:233 [liblinphone] MESSAGE Bandwidth usage for call [0xb44b2800]: 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:234 [liblinphone] MESSAGE Thread processing load: audio=1.797909 video=0.000000 text=0.000000 2017-12-06 17:05:33:235 [liblinphone] MESSAGE Vtable [0x9e543480] unregistered on core [0xb459cb80] 2017-12-06 17:05:33:235 [liblinphone] MESSAGE Vtable [0x9e5433e0] unregistered on core [0xb459cb80] 2017-12-06 17:05:33:236 [mediastreamer] MESSAGE Activity paused:address@hidden 2017-12-06 17:05:33:236 [mediastreamer] MESSAGE runningActivities=0 2017-12-06 17:05:33:256 [mediastreamer] MESSAGE Activity created:address@hidden 2017-12-06 17:05:33:456 [mediastreamer] MESSAGE Activity started:address@hidden 2017-12-06 17:05:33:457 [mediastreamer] MESSAGE Activity resumed:address@hidden 2017-12-06 17:05:33:457 [mediastreamer] MESSAGE runningActivities=1 2017-12-06 17:05:33:457 [liblinphone] MESSAGE Core callbacks [0x9e563100] registered on core [0xb459cb80] 2017-12-06 17:05:33:486 [liblinphone] MESSAGE Core callbacks [0x9e563150] registered on core [0xb459cb80] 2017-12-06 17:05:33:499 [belle-sip] MESSAGE Dialog sending retransmission of 200Ok 2017-12-06 17:05:33:501 [belle-sip] MESSAGE channel [0xa04d1000]: message sent to [TCP://ims.testoperator.com:5060], size: [1260] bytes SIP/2.0 200 Ok Via: SIP/2.0/TCP 169.239.97.4;branch=z9hG4bKb24e.19b7dbaecfb21536313cff678aa30082.0;i=a411 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: 493 Record-route: v=0 o=23675191979 3790 4044 IN IP4 10.0.1.10 s=Talk c=IN IP4 10.0.1.10 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:33:510 [belle-sip] MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:33:719 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x99df5e00] 2017-12-06 17:05:33:792 [mediastreamer] MESSAGE Activity stopped:address@hidden 2017-12-06 17:05:33:927 [liblinphone] MESSAGE Bandwidth usage for call [0xb44b2800]: RTP audio=[d= 0.0,u= 22.8], 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.8], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:33:927 [liblinphone] MESSAGE Thread processing load: audio=17.456961 video=0.000000 text=0.000000 2017-12-06 17:05:34:208 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x99df5e00] 2017-12-06 17:05:34:313 [belle-sip] MESSAGE Dialog sending retransmission of 200Ok 2017-12-06 17:05:34:315 [belle-sip] MESSAGE channel [0xa04d1000]: message sent to [TCP://ims.testoperator.com:5060], size: [1260] bytes SIP/2.0 200 Ok Via: SIP/2.0/TCP 169.239.97.4;branch=z9hG4bKb24e.19b7dbaecfb21536313cff678aa30082.0;i=a411 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: 493 Record-route: v=0 o=23675191979 3790 4044 IN IP4 10.0.1.10 s=Talk c=IN IP4 10.0.1.10 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:34:386 [mediastreamer] MESSAGE sound/wall clock skew is average=-12.088445 ms 2017-12-06 17:05:34:500 [mediastreamer] WARNING MSAudio MSTicker: We are late of 122 miliseconds. 2017-12-06 17:05:34:508 [mediastreamer] WARNING Routing audio to speaker, disabling bluetooth audio route 2017-12-06 17:05:34:526 [mediastreamer] MESSAGE MSAudioMixer [0x9e4bacb8] is entering bypass mode. 2017-12-06 17:05:34:531 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x201222] 2017-12-06 17:05:34:531 [belle-sip] MESSAGE channel [0xa04d1000]: starting recv background task with id=[201222]. 2017-12-06 17:05:34:532 [belle-sip] MESSAGE channel [0xa04d1000]: received [702] new bytes from [TCP://ims.testoperator.com:5060]: ACK sip:address@hidden:42440;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 169.239.97.4;branch=z9hG4bKb24e.5c0c2fa6d75b0070a422de5307d41d96.0;i=a411 Via: SIP/2.0/TCP 10.0.1.5:58135;received=92.103.220.99;rport=48916;branch=z9hG4bK.J8jWPteZ4 From: ;tag=KEPVID0md To: ;tag=~m6pCu7 CSeq: 21 ACK Call-ID: MDKhaD3rSq Max-Forwards: 69 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:575 [belle-sip] MESSAGE channel [0xa04d1000] [702] bytes parsed 2017-12-06 17:05:34:575 [belle-sip] MESSAGE Incoming INVITE has ACK, dialog is happy 2017-12-06 17:05:34:575 [belle-sip] MESSAGE channel [0xa04d1000]: ending recv background task with id=[201222]. 2017-12-06 17:05:34:576 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x201222] 2017-12-06 17:05:34:595 [belle-sip] MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:34:718 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x99df5e00] 2017-12-06 17:05:34:732 [ortp] MESSAGE Sending RTCP SR compound message on session [0x99df5e00]. 2017-12-06 17:05:34:757 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x10123e] 2017-12-06 17:05:34:757 [belle-sip] MESSAGE channel [0xa04d1000]: starting recv background task with id=[10123e]. 2017-12-06 17:05:34:758 [belle-sip] MESSAGE channel [0xa04d1000]: received [702] new bytes from [TCP://ims.testoperator.com:5060]: ACK sip:address@hidden:42440;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 169.239.97.4;branch=z9hG4bKb24e.5c0c2fa6d75b0070a422de5307d41d96.0;i=a411 Via: SIP/2.0/TCP 10.0.1.5:58135;received=92.103.220.99;branch=z9hG4bK.J8jWPteZ4;rport=48916 From: ;tag=KEPVID0md To: ;tag=~m6pCu7 CSeq: 21 ACK Call-ID: MDKhaD3rSq Max-Forwards: 69 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:789 [belle-sip] MESSAGE channel [0xa04d1000] [702] bytes parsed 2017-12-06 17:05:34:790 [belle-sip] MESSAGE Dialog ignoring incoming ACK (surely a retransmission) 2017-12-06 17:05:34:790 [belle-sip] MESSAGE channel [0xa04d1000]: ending recv background task with id=[10123e]. 2017-12-06 17:05:34:792 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x10123e] 2017-12-06 17:05:34:793 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x977de8c0], local statistics available: Local current jitter buffer size: 213.1ms 2017-12-06 17:05:34:793 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_stats_updated] 2017-12-06 17:05:34:924 [liblinphone] MESSAGE Bandwidth usage for call [0xb44b2800]: RTP audio=[d= 52.4,u= 44.2], 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= 3.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:34:925 [liblinphone] MESSAGE Thread processing load: audio=12.351664 video=0.000000 text=0.000000 2017-12-06 17:05:35:258 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x99df5e00] 2017-12-06 17:05:35:302 [mediastreamer] MESSAGE MSAudio stream [0x977de8c0]: receiving RTCP SR 2017-12-06 17:05:35:303 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_stats_updated] 2017-12-06 17:05:35:442 [ortp] MESSAGE Sending RTCP SR compound message on session [0x99df5e00]. 2017-12-06 17:05:35:443 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x977de8c0], local statistics available: Local current jitter buffer size: 211.1ms 2017-12-06 17:05:35:443 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_stats_updated] 2017-12-06 17:05:35:463 [belle-sip] MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:35:520 [mediastreamer] MESSAGE sound/wall clock skew is average=61.808226 ms 2017-12-06 17:05:35:746 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x201192] 2017-12-06 17:05:35:746 [belle-sip] MESSAGE channel [0xa04d1000]: starting recv background task with id=[201192]. 2017-12-06 17:05:35:747 [belle-sip] MESSAGE channel [0xa04d1000]: received [702] new bytes from [TCP://ims.testoperator.com:5060]: ACK sip:address@hidden:42440;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 169.239.97.4;branch=z9hG4bKb24e.5c0c2fa6d75b0070a422de5307d41d96.0;i=a411 Via: SIP/2.0/TCP 10.0.1.5:58135;received=92.103.220.99;branch=z9hG4bK.J8jWPteZ4;rport=48916 From: ;tag=KEPVID0md To: ;tag=~m6pCu7 CSeq: 21 ACK Call-ID: MDKhaD3rSq Max-Forwards: 69 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:772 [belle-sip] MESSAGE channel [0xa04d1000] [702] bytes parsed 2017-12-06 17:05:35:773 [belle-sip] MESSAGE Dialog ignoring incoming ACK (surely a retransmission) 2017-12-06 17:05:35:773 [belle-sip] MESSAGE channel [0xa04d1000]: ending recv background task with id=[201192]. 2017-12-06 17:05:35:774 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x201192] 2017-12-06 17:05:35:929 [liblinphone] MESSAGE Bandwidth usage for call [0xb44b2800]: RTP audio=[d= 42.5,u= 37.9], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 0.4,u= 2.5], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:35:929 [liblinphone] MESSAGE Thread processing load: audio=25.402216 video=0.000000 text=0.000000 2017-12-06 17:05:36:299 [ortp] MESSAGE Sending RTCP SR compound message on session [0x99df5e00]. 2017-12-06 17:05:36:313 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x977de8c0], local statistics available: Local current jitter buffer size: 171.2ms 2017-12-06 17:05:36:314 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_stats_updated] 2017-12-06 17:05:36:460 [belle-sip] MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:36:516 [mediastreamer] MESSAGE sound/wall clock skew is average=96.461268 ms 2017-12-06 17:05:36:675 [mediastreamer] MESSAGE MSAudio stream [0x977de8c0]: receiving RTCP SR 2017-12-06 17:05:36:675 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_stats_updated] 2017-12-06 17:05:36:915 [liblinphone] MESSAGE Bandwidth usage for call [0xb44b2800]: RTP audio=[d= 43.9,u= 49.0], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 3.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:36:915 [liblinphone] MESSAGE Thread processing load: audio=23.943920 video=0.000000 text=0.000000 2017-12-06 17:05:37:395 [ortp] MESSAGE Sending RTCP SR compound message on session [0x99df5e00]. 2017-12-06 17:05:37:397 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x977de8c0], local statistics available: Local current jitter buffer size: 133.6ms 2017-12-06 17:05:37:398 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_stats_updated] 2017-12-06 17:05:37:415 [ortp] MESSAGE OrtpCongestionDetector: moving from state CongestionStateNormal to state CongestionStatePending 2017-12-06 17:05:37:465 [belle-sip] MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:37:526 [mediastreamer] MESSAGE sound/wall clock skew is average=108.263123 ms 2017-12-06 17:05:37:723 [ortp] MESSAGE OrtpCongestionDetector: moving from state CongestionStatePending to state CongestionStateNormal 2017-12-06 17:05:37:924 [liblinphone] MESSAGE Bandwidth usage for call [0xb44b2800]: RTP audio=[d= 41.5,u= 49.5], 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:37:925 [liblinphone] MESSAGE Thread processing load: audio=37.586040 video=0.000000 text=0.000000 2017-12-06 17:05:38:305 [ortp] MESSAGE Sending RTCP SR compound message on session [0x99df5e00]. 2017-12-06 17:05:38:309 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x977de8c0], local statistics available: Local current jitter buffer size: 125.5ms 2017-12-06 17:05:38:309 [mediastreamer] MESSAGE MSAudio stream [0x977de8c0]: receiving RTCP SR 2017-12-06 17:05:38:309 [mediastreamer] MESSAGE MSQualityIndicator[0x99c31100][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 7.6ms RT propagation : 1189.2ms 2017-12-06 17:05:38:310 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_stats_updated] 2017-12-06 17:05:38:310 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_stats_updated] 2017-12-06 17:05:38:346 [mediastreamer] WARNING Flow controlled bufferizer of max 40 ms is filled with 755 ms at the end of the 5000 ms interval, asking to drop 357 ms 2017-12-06 17:05:38:348 [mediastreamer] WARNING Too much buffered audio signal, throwing out 357 ms 2017-12-06 17:05:38:475 [belle-sip] MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:38:520 [mediastreamer] MESSAGE sound/wall clock skew is average=116.086414 ms 2017-12-06 17:05:38:539 [liblinphone] MESSAGE linphone_core_set_device_rotation : rotation=90 2017-12-06 17:05:38:917 [liblinphone] MESSAGE Bandwidth usage for call [0xb44b2800]: RTP audio=[d= 46.5,u= 49.7], 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= 2.2], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:38:917 [liblinphone] MESSAGE Thread processing load: audio=35.315304 video=0.000000 text=0.000000 2017-12-06 17:05:39:185 [ortp] MESSAGE Sending RTCP SR compound message on session [0x99df5e00]. 2017-12-06 17:05:39:202 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x977de8c0], local statistics available: Local current jitter buffer size: 118.6ms 2017-12-06 17:05:39:203 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_stats_updated] 2017-12-06 17:05:39:476 [belle-sip] MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:39:502 [ortp] MESSAGE jitter buffer stable: target-size: 177.145839 ms, effective-size: 118.636368 (min: 40 nom: 60, max: 500) 2017-12-06 17:05:39:502 [ortp] MESSAGE jitter buffer rls stats: count=266, clockrate=48000, offset=9651.12 clock_ratio=1.04148, capped_offset=807069677 capped_clock_ratio=1.041479, max_ts_deviation=8503.000000 prev_max_ts_deviation=7920.000000, deviation=2360, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.011530 2017-12-06 17:05:39:520 [mediastreamer] MESSAGE sound/wall clock skew is average=118.343690 ms 2017-12-06 17:05:39:917 [liblinphone] MESSAGE Bandwidth usage for call [0xb44b2800]: RTP audio=[d= 47.0,u= 50.6], 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:39:918 [liblinphone] MESSAGE Thread processing load: audio=53.020512 video=0.000000 text=0.000000 2017-12-06 17:05:39:998 [mediastreamer] MESSAGE MSAudio stream [0x977de8c0]: receiving RTCP SR 2017-12-06 17:05:39:999 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_stats_updated] 2017-12-06 17:05:40:467 [belle-sip] MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:40:548 [mediastreamer] MESSAGE sound/wall clock skew is average=122.369148 ms 2017-12-06 17:05:40:917 [liblinphone] MESSAGE Bandwidth usage for call [0xb44b2800]: RTP audio=[d= 43.7,u= 49.9], 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:40:917 [liblinphone] MESSAGE Thread processing load: audio=49.455421 video=0.000000 text=0.000000 2017-12-06 17:05:40:995 [ortp] MESSAGE Sending RTCP SR compound message on session [0x99df5e00]. 2017-12-06 17:05:40:996 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x977de8c0], local statistics available: Local current jitter buffer size: 109.9ms 2017-12-06 17:05:40:997 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_stats_updated] 2017-12-06 17:05:41:339 [liblinphone] MESSAGE linphone_core_set_device_rotation : rotation=0 2017-12-06 17:05:41:469 [belle-sip] MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:41:520 [mediastreamer] MESSAGE sound/wall clock skew is average=121.392542 ms 2017-12-06 17:05:41:564 [mediastreamer] MESSAGE MSAudio stream [0x977de8c0]: receiving RTCP SR 2017-12-06 17:05:41:564 [mediastreamer] MESSAGE MSQualityIndicator[0x99c31100][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 7.3ms RT propagation : 1169.0ms 2017-12-06 17:05:41:564 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_stats_updated] 2017-12-06 17:05:41:928 [liblinphone] MESSAGE Bandwidth usage for call [0xb44b2800]: RTP audio=[d= 39.8,u= 50.1], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 1.7,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:928 [liblinphone] MESSAGE Thread processing load: audio=48.345257 video=0.000000 text=0.000000 2017-12-06 17:05:42:393 [ortp] MESSAGE Sending RTCP SR compound message on session [0x99df5e00]. 2017-12-06 17:05:42:396 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x977de8c0], local statistics available: Local current jitter buffer size: 111.6ms 2017-12-06 17:05:42:396 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_stats_updated] 2017-12-06 17:05:42:476 [belle-sip] MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:42:520 [mediastreamer] MESSAGE sound/wall clock skew is average=121.387728 ms 2017-12-06 17:05:42:658 [mediastreamer] MESSAGE MSAudio stream [0x977de8c0]: receiving RTCP SR 2017-12-06 17:05:42:658 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_stats_updated] 2017-12-06 17:05:42:909 [mediastreamer] WARNING Excess of audio samples in capture side bytes=20160 (210 ms) 2017-12-06 17:05:42:928 [liblinphone] MESSAGE Bandwidth usage for call [0xb44b2800]: RTP audio=[d= 40.5,u= 50.1], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 2.5,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:928 [liblinphone] MESSAGE Thread processing load: audio=42.267593 video=0.000000 text=0.000000 2017-12-06 17:05:43:361 [mediastreamer] WARNING Flow controlled bufferizer of max 40 ms was filled with at least 69 ms in the last 5000 ms, asking to drop 29 ms 2017-12-06 17:05:43:377 [mediastreamer] WARNING Too much buffered audio signal, throwing out 29 ms 2017-12-06 17:05:43:482 [belle-sip] MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:43:534 [mediastreamer] MESSAGE sound/wall clock skew is average=121.984172 ms 2017-12-06 17:05:43:922 [liblinphone] MESSAGE Bandwidth usage for call [0xb44b2800]: RTP audio=[d= 47.7,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= 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:43:923 [liblinphone] MESSAGE Thread processing load: audio=26.751656 video=0.000000 text=0.000000 2017-12-06 17:05:44:115 [ortp] MESSAGE Sending RTCP SR compound message on session [0x99df5e00]. 2017-12-06 17:05:44:124 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x977de8c0], local statistics available: Local current jitter buffer size: 109.0ms 2017-12-06 17:05:44:125 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_stats_updated] 2017-12-06 17:05:44:139 [liblinphone] MESSAGE linphone_core_set_device_rotation : rotation=270 2017-12-06 17:05:44:204 [mediastreamer] MESSAGE MSAudio stream [0x977de8c0]: receiving RTCP SR 2017-12-06 17:05:44:205 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_stats_updated] 2017-12-06 17:05:44:467 [belle-sip] MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:44:519 [ortp] MESSAGE jitter buffer stable: target-size: 157.145828 ms, effective-size: 108.965508 (min: 40 nom: 60, max: 500) 2017-12-06 17:05:44:519 [ortp] MESSAGE jitter buffer rls stats: count=517, clockrate=48000, offset=12201 clock_ratio=1.01564, capped_offset=807072226 capped_clock_ratio=1.015644, max_ts_deviation=4171.000000 prev_max_ts_deviation=3684.000000, deviation=1972, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.006661 2017-12-06 17:05:44:520 [mediastreamer] MESSAGE sound/wall clock skew is average=121.435460 ms 2017-12-06 17:05:44:934 [liblinphone] MESSAGE Bandwidth usage for call [0xb44b2800]: RTP audio=[d= 42.9,u= 50.0], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 0.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:44:934 [liblinphone] MESSAGE Thread processing load: audio=37.570396 video=0.000000 text=0.000000 2017-12-06 17:05:45:140 [liblinphone] MESSAGE linphone_core_set_device_rotation : rotation=0 2017-12-06 17:05:45:473 [belle-sip] MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:45:526 [mediastreamer] MESSAGE sound/wall clock skew is average=120.276309 ms 2017-12-06 17:05:45:647 [mediastreamer] MESSAGE MSAudio stream [0x977de8c0]: receiving RTCP SR 2017-12-06 17:05:45:647 [mediastreamer] MESSAGE MSQualityIndicator[0x99c31100][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 10.7ms RT propagation : 1286.3ms 2017-12-06 17:05:45:647 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_stats_updated] 2017-12-06 17:05:45:928 [liblinphone] MESSAGE Bandwidth usage for call [0xb44b2800]: RTP audio=[d= 47.3,u= 50.4], video=[d= 0.0,u= 0.0, ed= 0.0], text=[d= 0.0,u= 0.0] kbits/sec RTCP audio=[d= 2.6,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec 2017-12-06 17:05:45:928 [liblinphone] MESSAGE Thread processing load: audio=42.160713 video=0.000000 text=0.000000 2017-12-06 17:05:45:945 [ortp] MESSAGE Sending RTCP SR compound message on session [0x99df5e00]. 2017-12-06 17:05:45:958 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x977de8c0], local statistics available: Local current jitter buffer size: 91.8ms 2017-12-06 17:05:45:959 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_stats_updated] 2017-12-06 17:05:46:485 [belle-sip] MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:46:520 [mediastreamer] MESSAGE sound/wall clock skew is average=121.060748 ms 2017-12-06 17:05:46:918 [liblinphone] MESSAGE Bandwidth usage for call [0xb44b2800]: RTP audio=[d= 46.9,u= 49.3], 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:46:919 [liblinphone] MESSAGE Thread processing load: audio=36.956242 video=0.000000 text=0.000000 2017-12-06 17:05:47:164 [liblinphone] MESSAGE Terminate call [0xb44b2800] which is currently in state LinphoneCallStreamsRunning 2017-12-06 17:05:47:166 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x201216] 2017-12-06 17:05:47:166 [belle-sip] MESSAGE transaction [0x99c31340]: starting transaction background task with id=[201216]. 2017-12-06 17:05:47:166 [belle-sip] MESSAGE Dialog [0x9e50c980]: now updated by transaction [0x99c31340]. 2017-12-06 17:05:47:167 [belle-sip] MESSAGE Changing [client] [BYE] transaction [0x99c31340], from state [INIT] to [TRYING] 2017-12-06 17:05:47:168 [belle-sip] MESSAGE channel [0xa04d1000]: message sent to [TCP://ims.testoperator.com:5060], size: [450] bytes BYE sip:address@hidden:48916;transport=tcp;alias=92.103.220.99~48916~2 SIP/2.0 Via: SIP/2.0/TCP 10.0.1.10:40039;branch=z9hG4bK.XRcr9vJij;rport From: ;tag=~m6pCu7 To: ;tag=KEPVID0md CSeq: 111 BYE Call-ID: MDKhaD3rSq Max-Forwards: 70 Route: User-Agent: LinphoneAndroid/3.3.1 (belle-sip/1.6.3) Content-Length: 0 2017-12-06 17:05:47:168 [mediastreamer] MESSAGE ms_ticker_set_time_func: ticker's time method updated. 2017-12-06 17:05:47:170 [mediastreamer] MESSAGE Thread end, detaching jvm from current thread 2017-12-06 17:05:47:235 [mediastreamer] MESSAGE opus decoder stats: fec 0 packets - plc 0 packets. 2017-12-06 17:05:47:242 [ortp] MESSAGE =========================================================== 2017-12-06 17:05:47:242 [ortp] MESSAGE AUDIO SESSION'S RTP STATISTICS 2017-12-06 17:05:47:242 [ortp] MESSAGE ----------------------------------------------------------- 2017-12-06 17:05:47:242 [ortp] MESSAGE sent 677 packets 2017-12-06 17:05:47:243 [ortp] MESSAGE 0 duplicated packets 2017-12-06 17:05:47:243 [ortp] MESSAGE 64652 bytes 2017-12-06 17:05:47:243 [ortp] MESSAGE received 653 packets 2017-12-06 17:05:47:243 [ortp] MESSAGE 0 duplicated packets 2017-12-06 17:05:47:243 [ortp] MESSAGE 59022 bytes 2017-12-06 17:05:47:243 [ortp] MESSAGE incoming delivered to the app 58381 bytes 2017-12-06 17:05:47:243 [ortp] MESSAGE incoming cumulative lost 3 packets 2017-12-06 17:05:47:243 [ortp] MESSAGE incoming received too late 2 packets 2017-12-06 17:05:47:243 [ortp] MESSAGE incoming bad formatted 0 packets 2017-12-06 17:05:47:243 [ortp] MESSAGE incoming discarded (queue overflow) 0 packets 2017-12-06 17:05:47:243 [ortp] MESSAGE sent rtcp 10 packets 2017-12-06 17:05:47:243 [ortp] MESSAGE received rtcp 8 packets 2017-12-06 17:05:47:243 [ortp] MESSAGE =========================================================== 2017-12-06 17:05:47:244 [mediastreamer] MESSAGE ms_filter_unlink: MSAndSoundRead:0x9e427a88,0-->MSEqualizer:0x9e4bac10,0 2017-12-06 17:05:47:244 [mediastreamer] MESSAGE ms_filter_unlink: MSEqualizer:0x9e4bac10,0-->MSVolume:0x9e427158,0 2017-12-06 17:05:47:244 [mediastreamer] MESSAGE ms_filter_unlink: MSVolume:0x9e427158,0-->MSAudioMixer:0x9e4bab30,0 2017-12-06 17:05:47:244 [mediastreamer] MESSAGE ms_filter_unlink: MSAudioMixer:0x9e4bab30,0-->MSOpusEnc:0x9e4ba9e0,0 2017-12-06 17:05:47:244 [mediastreamer] MESSAGE ms_filter_unlink: MSOpusEnc:0x9e4ba9e0,0-->MSRtpSend:0x9e4272e0,0 2017-12-06 17:05:47:244 [mediastreamer] MESSAGE ms_filter_unlink: MSRtpRecv:0x9e4ba970,0-->MSOpusDec:0x9e4baa50,0 2017-12-06 17:05:47:244 [mediastreamer] MESSAGE ms_filter_unlink: MSOpusDec:0x9e4baa50,0-->MSAudioFlowControl:0x9e4bac80,0 2017-12-06 17:05:47:244 [mediastreamer] MESSAGE ms_filter_unlink: MSAudioFlowControl:0x9e4bac80,0-->MSDtmfGen:0x9e4ba9a8,0 2017-12-06 17:05:47:244 [mediastreamer] MESSAGE ms_filter_unlink: MSDtmfGen:0x9e4ba9a8,0-->MSVolume:0x9e4baa88,0 2017-12-06 17:05:47:244 [mediastreamer] MESSAGE ms_filter_unlink: MSVolume:0x9e4baa88,0-->MSEqualizer:0x9e4bac48,0 2017-12-06 17:05:47:244 [mediastreamer] MESSAGE ms_filter_unlink: MSEqualizer:0x9e4bac48,0-->MSAudioMixer:0x9e4bacb8,0 2017-12-06 17:05:47:244 [mediastreamer] MESSAGE ms_filter_unlink: MSFilePlayer:0x9e4bad28,0-->MSResample:0x9e4bad98,0 2017-12-06 17:05:47:244 [mediastreamer] MESSAGE ms_filter_unlink: MSResample:0x9e4bad98,0-->MSAudioMixer:0x9e4bacb8,1 2017-12-06 17:05:47:245 [mediastreamer] MESSAGE ms_filter_unlink: MSAudioMixer:0x9e4bacb8,0-->MSResample:0x9e4baaf8,0 2017-12-06 17:05:47:245 [mediastreamer] MESSAGE ms_filter_unlink: MSResample:0x9e4baaf8,0-->MSOpenSLESPlayer:0x9e427af8,0 2017-12-06 17:05:47:245 [mediastreamer] MESSAGE =========================================================== 2017-12-06 17:05:47:245 [mediastreamer] MESSAGE FILTER USAGE STATISTICS 2017-12-06 17:05:47:245 [mediastreamer] MESSAGE Name Count Time/tick (ms) CPU Usage 2017-12-06 17:05:47:245 [mediastreamer] MESSAGE ----------------------------------------------------------- 2017-12-06 17:05:47:245 [mediastreamer] MESSAGE MSOpusEnc 665 4.66915 63.1406 2017-12-06 17:05:47:245 [mediastreamer] MESSAGE MSResample 744 0.8718 13.1877 2017-12-06 17:05:47:245 [mediastreamer] MESSAGE MSAudioFlowControl 645 0.655319 8.59573 2017-12-06 17:05:47:245 [mediastreamer] MESSAGE MSOpusDec 1391 0.280739 7.93486 2017-12-06 17:05:47:245 [mediastreamer] MESSAGE MSRtpSend 1384 0.10849 3.05095 2017-12-06 17:05:47:245 [mediastreamer] MESSAGE MSRtpRecv 1427 0.0783704 2.27236 2017-12-06 17:05:47:246 [mediastreamer] MESSAGE MSVolume 1310 0.029176 0.776651 2017-12-06 17:05:47:246 [mediastreamer] MESSAGE MSAndSoundRead 1384 0.0169501 0.476671 2017-12-06 17:05:47:246 [mediastreamer] MESSAGE MSAudioMixer 2775 0.00527187 0.297154 2017-12-06 17:05:47:246 [mediastreamer] MESSAGE MSOpenSLESPlayer 744 0.00581199 0.0879181 2017-12-06 17:05:47:246 [mediastreamer] MESSAGE MSDtmfGen 1391 0.00245932 0.0695106 2017-12-06 17:05:47:246 [mediastreamer] MESSAGE MSFilePlayer 1391 0.00210749 0.0595665 2017-12-06 17:05:47:246 [mediastreamer] MESSAGE MSEqualizer 1310 0.00188904 0.0502853 2017-12-06 17:05:47:246 [mediastreamer] MESSAGE MSSpeexDec 0 0 0 2017-12-06 17:05:47:246 [mediastreamer] MESSAGE MSSpeexEnc 0 0 0 2017-12-06 17:05:47:246 [mediastreamer] MESSAGE MSGenericPLC 0 0 0 2017-12-06 17:05:47:246 [mediastreamer] MESSAGE MSAlawDec 0 0 0 2017-12-06 17:05:47:246 [mediastreamer] MESSAGE MSAlawEnc 0 0 0 2017-12-06 17:05:47:246 [mediastreamer] MESSAGE MSVoidSink 0 0 0 2017-12-06 17:05:47:247 [mediastreamer] MESSAGE MSItcSink 0 0 0 2017-12-06 17:05:47:247 [mediastreamer] MESSAGE MSTee 0 0 0 2017-12-06 17:05:47:247 [mediastreamer] MESSAGE MSWebRTCAECM 0 0 0 2017-12-06 17:05:47:247 [mediastreamer] MESSAGE =========================================================== 2017-12-06 17:05:47:247 [mediastreamer] MESSAGE =========================================================== 2017-12-06 17:05:47:247 [mediastreamer] MESSAGE FILTER USAGE STATISTICS 2017-12-06 17:05:47:248 [mediastreamer] MESSAGE Name Count Time/tick (ms) CPU Usage 2017-12-06 17:05:47:248 [mediastreamer] MESSAGE ----------------------------------------------------------- 2017-12-06 17:05:47:248 [mediastreamer] MESSAGE MSOpusEnc 665 4.66915 63.1406 2017-12-06 17:05:47:248 [mediastreamer] MESSAGE MSResample 744 0.8718 13.1877 2017-12-06 17:05:47:248 [mediastreamer] MESSAGE MSAudioFlowControl 645 0.655319 8.59573 2017-12-06 17:05:47:248 [mediastreamer] MESSAGE MSOpusDec 1391 0.280739 7.93486 2017-12-06 17:05:47:248 [mediastreamer] MESSAGE MSRtpSend 1384 0.10849 3.05095 2017-12-06 17:05:47:248 [mediastreamer] MESSAGE MSRtpRecv 1427 0.0783704 2.27236 2017-12-06 17:05:47:248 [mediastreamer] MESSAGE MSVolume 1310 0.029176 0.776651 2017-12-06 17:05:47:248 [mediastreamer] MESSAGE MSAndSoundRead 1384 0.0169501 0.476671 2017-12-06 17:05:47:248 [mediastreamer] MESSAGE MSAudioMixer 2775 0.00527187 0.297154 2017-12-06 17:05:47:248 [mediastreamer] MESSAGE MSOpenSLESPlayer 744 0.00581199 0.0879181 2017-12-06 17:05:47:248 [mediastreamer] MESSAGE MSDtmfGen 1391 0.00245932 0.0695106 2017-12-06 17:05:47:249 [mediastreamer] MESSAGE MSFilePlayer 1391 0.00210749 0.0595665 2017-12-06 17:05:47:249 [mediastreamer] MESSAGE MSEqualizer 1310 0.00188904 0.0502853 2017-12-06 17:05:47:249 [mediastreamer] MESSAGE MSSpeexDec 0 0 0 2017-12-06 17:05:47:249 [mediastreamer] MESSAGE MSSpeexEnc 0 0 0 2017-12-06 17:05:47:249 [mediastreamer] MESSAGE MSGenericPLC 0 0 0 2017-12-06 17:05:47:249 [mediastreamer] MESSAGE MSAlawDec 0 0 0 2017-12-06 17:05:47:249 [mediastreamer] MESSAGE MSAlawEnc 0 0 0 2017-12-06 17:05:47:249 [mediastreamer] MESSAGE MSVoidSink 0 0 0 2017-12-06 17:05:47:249 [mediastreamer] MESSAGE MSItcSink 0 0 0 2017-12-06 17:05:47:249 [mediastreamer] MESSAGE MSTee 0 0 0 2017-12-06 17:05:47:249 [mediastreamer] MESSAGE MSWebRTCAECM 0 0 0 2017-12-06 17:05:47:249 [mediastreamer] MESSAGE =========================================================== 2017-12-06 17:05:47:250 [mediastreamer] MESSAGE Call ended 2017-12-06 17:05:47:250 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [display_status] 2017-12-06 17:05:47:250 [liblinphone] MESSAGE Call 0xb44b2800: moving from state LinphoneCallStreamsRunning to LinphoneCallEnd 2017-12-06 17:05:47:250 [liblinphone] MESSAGE Notifying soundcard that we don't need it anymore for calls. 2017-12-06 17:05:47:250 [mediastreamer] MESSAGE Stopping ZRTP context on session [0x0] 2017-12-06 17:05:47:250 [mediastreamer] MESSAGE ZRTP context destroyed 2017-12-06 17:05:47:258 [mediastreamer] MESSAGE MSAudio MSTicker thread exiting 2017-12-06 17:05:47:259 [mediastreamer] MESSAGE Stopping ZRTP context on session [0x0] 2017-12-06 17:05:47:259 [mediastreamer] MESSAGE ZRTP context destroyed 2017-12-06 17:05:47:266 [liblinphone] MESSAGE Resetting the current call 2017-12-06 17:05:47:267 [mediastreamer] MESSAGE New call state [CallEnd] 2017-12-06 17:05:47:296 [mediastreamer] WARNING Routing audio to earpiece, disabling bluetooth audio route 2017-12-06 17:05:47:309 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_state_changed] 2017-12-06 17:05:47:312 [liblinphone] MESSAGE Vtable [0xb47965c0] unregistered on core [0xb459cb80] 2017-12-06 17:05:47:313 [liblinphone] MESSAGE Vtable [0xb4796340] unregistered on core [0xb459cb80] 2017-12-06 17:05:47:313 [mediastreamer] MESSAGE Activity paused:address@hidden 2017-12-06 17:05:47:313 [mediastreamer] MESSAGE runningActivities=0 2017-12-06 17:05:47:356 [mediastreamer] MESSAGE Activity started:address@hidden 2017-12-06 17:05:47:358 [mediastreamer] MESSAGE [Permission] Record audio permission is granted 2017-12-06 17:05:47:359 [mediastreamer] MESSAGE [Permission] Camera permission is granted 2017-12-06 17:05:47:359 [mediastreamer] MESSAGE Activity resumed:address@hidden 2017-12-06 17:05:47:359 [mediastreamer] MESSAGE runningActivities=1 2017-12-06 17:05:47:360 [liblinphone] MESSAGE Core callbacks [0x9e49bbc8] registered on core [0xb459cb80] 2017-12-06 17:05:47:367 [liblinphone] MESSAGE Core callbacks [0x9e49bcb8] registered on core [0xb459cb80] 2017-12-06 17:05:47:380 [liblinphone] MESSAGE Vtable [0xb47965c0] unregistered on core [0xb459cb80] 2017-12-06 17:05:47:380 [liblinphone] MESSAGE Vtable [0xb4796340] unregistered on core [0xb459cb80] 2017-12-06 17:05:47:380 [mediastreamer] MESSAGE Activity paused:address@hidden 2017-12-06 17:05:47:380 [mediastreamer] MESSAGE runningActivities=0 2017-12-06 17:05:47:395 [mediastreamer] MESSAGE Activity created:address@hidden 2017-12-06 17:05:47:430 [mediastreamer] MESSAGE Activity created:address@hidden 2017-12-06 17:05:47:431 [mediastreamer] MESSAGE Activity started:address@hidden 2017-12-06 17:05:47:431 [mediastreamer] MESSAGE [Permission] Record audio permission is granted 2017-12-06 17:05:47:432 [mediastreamer] MESSAGE [Permission] Camera permission is granted 2017-12-06 17:05:47:432 [mediastreamer] MESSAGE Activity resumed:address@hidden 2017-12-06 17:05:47:432 [mediastreamer] MESSAGE runningActivities=1 2017-12-06 17:05:47:433 [liblinphone] MESSAGE Core callbacks [0x9a1c3ce0] registered on core [0xb459cb80] 2017-12-06 17:05:47:438 [liblinphone] MESSAGE Core callbacks [0x9a1c3d30] registered on core [0xb459cb80] 2017-12-06 17:05:47:441 [liblinphone] MESSAGE Vtable [0x9adadd40] unregistered on core [0xb459cb80] 2017-12-06 17:05:47:441 [liblinphone] MESSAGE Vtable [0x9adad8e0] unregistered on core [0xb459cb80] 2017-12-06 17:05:47:441 [mediastreamer] MESSAGE Activity paused:address@hidden 2017-12-06 17:05:47:441 [mediastreamer] MESSAGE runningActivities=0 2017-12-06 17:05:47:454 [mediastreamer] MESSAGE Activity created:address@hidden 2017-12-06 17:05:47:490 [mediastreamer] MESSAGE Activity created:address@hidden 2017-12-06 17:05:47:491 [mediastreamer] MESSAGE Activity started:address@hidden 2017-12-06 17:05:47:491 [mediastreamer] MESSAGE [Permission] Record audio permission is granted 2017-12-06 17:05:47:492 [mediastreamer] MESSAGE [Permission] Camera permission is granted 2017-12-06 17:05:47:492 [mediastreamer] MESSAGE Activity resumed:address@hidden 2017-12-06 17:05:47:492 [mediastreamer] MESSAGE runningActivities=1 2017-12-06 17:05:47:493 [liblinphone] MESSAGE Core callbacks [0x9a84fda8] registered on core [0xb459cb80] 2017-12-06 17:05:47:496 [liblinphone] MESSAGE Core callbacks [0x9a84fe98] registered on core [0xb459cb80] 2017-12-06 17:05:47:507 [belle-sip] MESSAGE Garbage collecting unowned object of type LinphoneCallStats 2017-12-06 17:05:47:507 [liblinphone] MESSAGE Vtable [0x9e4bca60] unregistered on core [0xb459cb80] 2017-12-06 17:05:47:507 [liblinphone] MESSAGE Vtable [0x9e4bc920] unregistered on core [0xb459cb80] 2017-12-06 17:05:47:507 [mediastreamer] MESSAGE Activity paused:address@hidden 2017-12-06 17:05:47:508 [mediastreamer] MESSAGE runningActivities=0 2017-12-06 17:05:47:527 [mediastreamer] MESSAGE Activity started:address@hidden 2017-12-06 17:05:47:529 [mediastreamer] MESSAGE [Permission] Contacts permission is granted 2017-12-06 17:05:47:529 [mediastreamer] MESSAGE [Permission] Read phone state permission is granted 2017-12-06 17:05:47:530 [mediastreamer] MESSAGE [Permission] Read external storage for ring tone permission is granted 2017-12-06 17:05:47:530 [mediastreamer] MESSAGE Activity resumed:address@hidden 2017-12-06 17:05:47:531 [mediastreamer] MESSAGE runningActivities=1 2017-12-06 17:05:47:531 [liblinphone] MESSAGE Core callbacks [0x9b4263d0] registered on core [0xb459cb80] 2017-12-06 17:05:47:532 [liblinphone] MESSAGE Core callbacks [0x9b4263f8] registered on core [0xb459cb80] 2017-12-06 17:05:47:534 [liblinphone] MESSAGE Core callbacks [0x9b426510] registered on core [0xb459cb80] 2017-12-06 17:05:47:547 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:47:552 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:47:556 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:47:560 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:47:577 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:47:673 [mediastreamer] MESSAGE Activity stopped:address@hidden 2017-12-06 17:05:47:673 [mediastreamer] MESSAGE Activity destroyed:address@hidden 2017-12-06 17:05:47:684 [mediastreamer] MESSAGE Activity stopped:address@hidden 2017-12-06 17:05:47:684 [mediastreamer] MESSAGE Activity destroyed:address@hidden 2017-12-06 17:05:47:686 [mediastreamer] MESSAGE Activity stopped:address@hidden 2017-12-06 17:05:47:686 [mediastreamer] MESSAGE Activity destroyed:address@hidden 2017-12-06 17:05:47:818 [mediastreamer] MESSAGE Activity stopped:address@hidden 2017-12-06 17:05:47:820 [mediastreamer] MESSAGE Activity destroyed:address@hidden 2017-12-06 17:05:48:382 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock acquired [ref=0x20113e] 2017-12-06 17:05:48:383 [belle-sip] MESSAGE channel [0xa04d1000]: starting recv background task with id=[20113e]. 2017-12-06 17:05:48:383 [belle-sip] MESSAGE channel [0xa04d1000]: received [365] new bytes from [TCP://ims.testoperator.com:5060]: SIP/2.0 200 Ok 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:48:400 [belle-sip] MESSAGE channel [0xa04d1000] [365] bytes parsed 2017-12-06 17:05:48:401 [belle-sip] MESSAGE Found transaction matching response. 2017-12-06 17:05:48:401 [belle-sip] MESSAGE Changing [client] [BYE] transaction [0x99c31340], from state [TRYING] to [COMPLETED] 2017-12-06 17:05:48:401 [belle-sip] MESSAGE Dialog [0x9e50c980]: now updated by transaction [0x99c31340]. 2017-12-06 17:05:48:401 [belle-sip] MESSAGE Dialog [0x9e50c980] deleted (is_expired=0) 2017-12-06 17:05:48:401 [liblinphone] MESSAGE op [0x99c07400] : set_or_update_dialog() current=[0x9e50c980] new=[0x9e50c980] 2017-12-06 17:05:48:401 [liblinphone] MESSAGE Op [0x99c07400] receiving call response [200], dialog is [0x9e50c980] in state [BELLE_SIP_DIALOG_TERMINATED] 2017-12-06 17:05:48:401 [belle-sip] MESSAGE Changing [client] [BYE] transaction [0x99c31340], from state [COMPLETED] to [TERMINATED] 2017-12-06 17:05:48:402 [belle-sip] MESSAGE Client BYE transaction [0x99c31340] terminated 2017-12-06 17:05:48:402 [belle-sip] MESSAGE transaction [0x99c31340]: ending transaction background task with id=[201216]. 2017-12-06 17:05:48:403 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x201216] 2017-12-06 17:05:48:404 [belle-sip] MESSAGE channel [0xa04d1000]: ending recv background task with id=[20113e]. 2017-12-06 17:05:48:405 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x20113e] 2017-12-06 17:05:48:406 [liblinphone] MESSAGE Dialog [0x9e50c980] terminated for op [0x99c07400] 2017-12-06 17:05:48:421 [liblinphone] MESSAGE Call 0xb44b2800: moving from state LinphoneCallEnd to LinphoneCallReleased 2017-12-06 17:05:48:421 [mediastreamer] MESSAGE releaseWifiLock() 2017-12-06 17:05:48:423 [mediastreamer] MESSAGE releaseMcastLock() 2017-12-06 17:05:48:423 [mediastreamer] MESSAGE releaseCpuLock() 2017-12-06 17:05:48:425 [mediastreamer] MESSAGE New call state [Released] 2017-12-06 17:05:48:426 [liblinphone] MESSAGE Linphone core [0xb459cb80] notified [call_state_changed] 2017-12-06 17:05:48:427 [liblinphone] MESSAGE op [0x99c07400] : set_or_update_dialog() current=[0x9e50c980] new=[0x0] 2017-12-06 17:05:55:116 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:55:119 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:55:121 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:55:123 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:57:761 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:57:764 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:57:767 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com 2017-12-06 17:05:57:769 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=23675191979, realm=ims.testoperator.com