2019-01-09 17:18:40:987 [belle-sip] MESSAGE Changing [client] [INVITE] transaction [0x1c419a8f0], from state [INIT] to [CALLING] 2019-01-09 17:18:40:989 [belle-sip] MESSAGE channel [0x1083e8000]: message sent to [UDP://sip.peoplefone.at:5060], size: [2254] bytes INVITE sip:+ address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.254.71:49268;branch=z9hG4bK.bvt89R~bc;rport From: "peoplefone" ;tag=3-E0YDHEP To: "+ 4935xxxxxx26" CSeq: 20 INVITE Call-ID: uWJP4jAB0t Max-Forwards: 70 Supported: replaces, outbound, gruu Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Content-Type: application/sdp Content-Length: 1560 Contact: ;+org.linphone.specs=groupchat User-Agent: Linphone_iPhone.SE_iOS11.4.1/4.0.2-2-gf18fb2a09 (belle-sip/1.6.3) v=0 o=90772518119 1939 1688 IN IP4 192.168.254.71 s=Talk c=IN IP4 192.168.254.71 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics m=audio 7218 RTP/AVP 96 97 98 0 8 3 9 99 18 100 102 103 104 105 101 106 107 108 109 110 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=rtpmap:99 iLBC/8000 a=fmtp:99 mode=30 a=fmtp:18 annexb=yes a=rtpmap:100 mpeg4-generic/22050 a=fmtp:100 config=F8EE2000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5 a=rtpmap:102 mpeg4-generic/32000 a=fmtp:102 config=F8E82000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5 a=rtpmap:103 mpeg4-generic/44100 a=fmtp:103 config=F8E82000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5 a=rtpmap:104 mpeg4-generic/48000 a=fmtp:104 config=F8EE2000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5 a=rtpmap:105 iSAC/16000 a=rtpmap:101 telephone-event/48000 a=rtpmap:106 telephone-event/16000 a=rtpmap:107 telephone-event/8000 a=rtpmap:108 telephone-event/22050 a=rtpmap:109 telephone-event/32000 a=rtpmap:110 telephone-event/44100 a=zrtp-hash:1.10 a8277f66021fe4f1426cc24082ab360d360dd2e2160919785f23a87f556c16e8 a=rtcp-fb:* trr-int 1000 a=rtcp-fb:* ccm tmmbr 2019-01-09 17:18:40:989 [bctbx] MESSAGE CallSession [0x1c40ba1d8] moving from state State::OutgoingInit to State::OutgoingProgress 2019-01-09 17:18:40:990 [liblinphone] MESSAGE Linphone core [0x104823600] notified [call_state_changed] 2019-01-09 17:18:40:991 [belle-sip] MESSAGE Garbage collecting unowned object of type belle_sdp_session_description_t 2019-01-09 17:18:41:007 [ios] MESSAGE (LinphoneManager.m:2471) Current audio route is [Receiver] 2019-01-09 17:18:41:127 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:18:41:127 [belle-sip] MESSAGE channel [0x1083e8000]: starting recv background task with id=[4b1]. 2019-01-09 17:18:41:127 [belle-sip] MESSAGE channel [0x1083e8000]: received [326] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 100 TRYING Via: SIP/2.0/UDP 192.168.254.71:49268;branch=z9hG4bK.bvt89R~bc;rport=27054;received=89.144.213.192 From: "peoplefone" ;tag=3-E0YDHEP To: "+ 4935xxxxxx26" CSeq: 20 INVITE Call-ID: uWJP4jAB0t Server: VOIP 1.1 Content-Length: 0 2019-01-09 17:18:41:128 [belle-sip] MESSAGE channel [0x1083e8000] [326] bytes parsed 2019-01-09 17:18:41:128 [belle-sip] MESSAGE Found transaction matching response. 2019-01-09 17:18:41:128 [belle-sip] MESSAGE Changing [client] [INVITE] transaction [0x1c419a8f0], from state [CALLING] to [PROCEEDING] 2019-01-09 17:18:41:128 [bctbx] MESSAGE op [0x10852e0f0] : set_or_update_dialog() current=[0x0] new=[0x0] 2019-01-09 17:18:41:128 [bctbx] MESSAGE Op [0x10852e0f0] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL] 2019-01-09 17:18:41:128 [belle-sip] MESSAGE channel [0x1083e8000]: ending recv background task with id=[4b1]. 2019-01-09 17:18:41:147 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:18:41:147 [belle-sip] MESSAGE channel [0x1083e8000]: starting recv background task with id=[4b3]. 2019-01-09 17:18:41:147 [belle-sip] MESSAGE channel [0x1083e8000]: received [326] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 100 TRYING Via: SIP/2.0/UDP 192.168.254.71:49268;branch=z9hG4bK.bvt89R~bc;rport=27054;received=89.144.213.192 From: "peoplefone" ;tag=3-E0YDHEP To: "+ 4935xxxxxx26" CSeq: 20 INVITE Call-ID: uWJP4jAB0t Server: VOIP 1.1 Content-Length: 0 2019-01-09 17:18:41:148 [belle-sip] MESSAGE channel [0x1083e8000] [326] bytes parsed 2019-01-09 17:18:41:148 [belle-sip] MESSAGE Found transaction matching response. 2019-01-09 17:18:41:148 [bctbx] MESSAGE op [0x10852e0f0] : set_or_update_dialog() current=[0x0] new=[0x0] 2019-01-09 17:18:41:148 [bctbx] MESSAGE Op [0x10852e0f0] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL] 2019-01-09 17:18:41:148 [belle-sip] MESSAGE channel [0x1083e8000]: ending recv background task with id=[4b3]. 2019-01-09 17:18:41:167 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:18:41:167 [belle-sip] MESSAGE channel [0x1083e8000]: starting recv background task with id=[4b5]. 2019-01-09 17:18:41:167 [belle-sip] MESSAGE channel [0x1083e8000]: received [487] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.254.71:49268;branch=z9hG4bK.bvt89R~bc;rport=27054;received=89.144.213.192 From: "peoplefone" ;tag=3-E0YDHEP To: "+ 4935xxxxxx26" ;tag=c8461efb6a64f848fae3487c0da16cdc.aa71 CSeq: 20 INVITE Call-ID: uWJP4jAB0t Proxy-Authenticate: Digest realm="sip.peoplefone.at", nonce="XDYgC1w2Ht95rFLuX6Wgotx6fWch4AVq" Server: VOIP 1.1 Content-Length: 0 2019-01-09 17:18:41:168 [belle-sip] MESSAGE channel [0x1083e8000] [487] bytes parsed 2019-01-09 17:18:41:168 [belle-sip] MESSAGE Found transaction matching response. 2019-01-09 17:18:41:168 [belle-sip] MESSAGE Changing [client] [INVITE] transaction [0x1c419a8f0], from state [PROCEEDING] to [COMPLETED] 2019-01-09 17:18:41:169 [belle-sip] MESSAGE channel [0x1083e8000]: message sent to [UDP://sip.peoplefone.at:5060], size: [470] bytes ACK sip:+ address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.254.71:49268;branch=z9hG4bK.bvt89R~bc;rport Call-ID: uWJP4jAB0t From: "peoplefone" ;tag=3-E0YDHEP To: "+ 4935xxxxxx26" ;tag=c8461efb6a64f848fae3487c0da16cdc.aa71 Contact: ;+org.linphone.specs=groupchat Max-Forwards: 70 CSeq: 20 ACK 2019-01-09 17:18:41:169 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=90772518119, realm=sip.peoplefone.at 2019-01-09 17:18:41:169 [belle-sip] MESSAGE Auth info found for [90772518119] realm [sip.peoplefone.at] 2019-01-09 17:18:41:169 [belle-sip] MESSAGE Background task belle-sip transaction(0x1c419a410) started. Unknown remaining time since application is not fully in background. 2019-01-09 17:18:41:169 [belle-sip] MESSAGE transaction [0x1c419a410]: starting transaction background task with id=[4b6]. 2019-01-09 17:18:41:169 [belle-sip] MESSAGE Changing [client] [INVITE] transaction [0x1c419a410], from state [INIT] to [CALLING] 2019-01-09 17:18:41:170 [belle-sip] MESSAGE channel [0x1083e8000]: message sent to [UDP://sip.peoplefone.at:5060], size: [2465] bytes INVITE sip:+ address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.254.71:49268;branch=z9hG4bK.xEZZuwbrr;rport From: "peoplefone" ;tag=3-E0YDHEP To: "+ 4935xxxxxx26" CSeq: 21 INVITE Call-ID: uWJP4jAB0t Max-Forwards: 70 Supported: replaces, outbound, gruu Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Content-Type: application/sdp Content-Length: 1560 Contact: ;+org.linphone.specs=groupchat User-Agent: Linphone_iPhone.SE_iOS11.4.1/4.0.2-2-gf18fb2a09 (belle-sip/1.6.3) Proxy-Authorization: Digest realm="sip.peoplefone.at", nonce="XDYgC1w2Ht95rFLuX6Wgotx6fWch4AVq", username="90772518119", uri="sip:+ address@hidden", response="cde4dea64b8b4270a800086f7e6026ac" v=0 o=90772518119 1939 1688 IN IP4 192.168.254.71 s=Talk c=IN IP4 192.168.254.71 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics m=audio 7218 RTP/AVP 96 97 98 0 8 3 9 99 18 100 102 103 104 105 101 106 107 108 109 110 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=rtpmap:99 iLBC/8000 a=fmtp:99 mode=30 a=fmtp:18 annexb=yes a=rtpmap:100 mpeg4-generic/22050 a=fmtp:100 config=F8EE2000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5 a=rtpmap:102 mpeg4-generic/32000 a=fmtp:102 config=F8E82000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5 a=rtpmap:103 mpeg4-generic/44100 a=fmtp:103 config=F8E82000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5 a=rtpmap:104 mpeg4-generic/48000 a=fmtp:104 config=F8EE2000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5 a=rtpmap:105 iSAC/16000 a=rtpmap:101 telephone-event/48000 a=rtpmap:106 telephone-event/16000 a=rtpmap:107 telephone-event/8000 a=rtpmap:108 telephone-event/22050 a=rtpmap:109 telephone-event/32000 a=rtpmap:110 telephone-event/44100 a=zrtp-hash:1.10 a8277f66021fe4f1426cc24082ab360d360dd2e2160919785f23a87f556c16e8 a=rtcp-fb:* trr-int 1000 a=rtcp-fb:* ccm tmmbr 2019-01-09 17:18:41:170 [belle-sip] MESSAGE channel [0x1083e8000]: ending recv background task with id=[4b5]. 2019-01-09 17:18:41:286 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:18:41:286 [belle-sip] MESSAGE channel [0x1083e8000]: starting recv background task with id=[4bd]. 2019-01-09 17:18:41:287 [belle-sip] MESSAGE channel [0x1083e8000]: received [326] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 100 TRYING Via: SIP/2.0/UDP 192.168.254.71:49268;branch=z9hG4bK.xEZZuwbrr;rport=27054;received=89.144.213.192 From: "peoplefone" ;tag=3-E0YDHEP To: "+ 4935xxxxxx26" CSeq: 21 INVITE Call-ID: uWJP4jAB0t Server: VOIP 1.1 Content-Length: 0 2019-01-09 17:18:41:288 [belle-sip] MESSAGE channel [0x1083e8000] [326] bytes parsed 2019-01-09 17:18:41:288 [belle-sip] MESSAGE Found transaction matching response. 2019-01-09 17:18:41:288 [belle-sip] MESSAGE Changing [client] [INVITE] transaction [0x1c419a410], from state [CALLING] to [PROCEEDING] 2019-01-09 17:18:41:288 [bctbx] MESSAGE op [0x10852e0f0] : set_or_update_dialog() current=[0x0] new=[0x0] 2019-01-09 17:18:41:288 [bctbx] MESSAGE Op [0x10852e0f0] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL] 2019-01-09 17:18:41:288 [belle-sip] MESSAGE channel [0x1083e8000]: ending recv background task with id=[4bd]. 2019-01-09 17:18:41:307 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:18:41:307 [belle-sip] MESSAGE channel [0x1083e8000]: starting recv background task with id=[4bf]. 2019-01-09 17:18:41:307 [belle-sip] MESSAGE channel [0x1083e8000]: received [326] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 100 TRYING Via: SIP/2.0/UDP 192.168.254.71:49268;branch=z9hG4bK.xEZZuwbrr;rport=27054;received=89.144.213.192 From: "peoplefone" ;tag=3-E0YDHEP To: "+ 4935xxxxxx26" CSeq: 21 INVITE Call-ID: uWJP4jAB0t Server: VOIP 1.1 Content-Length: 0 2019-01-09 17:18:41:308 [belle-sip] MESSAGE channel [0x1083e8000] [326] bytes parsed 2019-01-09 17:18:41:308 [belle-sip] MESSAGE Found transaction matching response. 2019-01-09 17:18:41:308 [bctbx] MESSAGE op [0x10852e0f0] : set_or_update_dialog() current=[0x0] new=[0x0] 2019-01-09 17:18:41:308 [bctbx] MESSAGE Op [0x10852e0f0] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL] 2019-01-09 17:18:41:308 [belle-sip] MESSAGE channel [0x1083e8000]: ending recv background task with id=[4bf]. 2019-01-09 17:18:41:327 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:18:41:327 [belle-sip] MESSAGE channel [0x1083e8000]: starting recv background task with id=[4c1]. 2019-01-09 17:18:41:327 [belle-sip] MESSAGE channel [0x1083e8000]: received [358] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 192.168.254.71:49268;branch=z9hG4bK.xEZZuwbrr;rport=27054;received=89.144.213.192 From: "peoplefone" ;tag=3-E0YDHEP To: "+ 4935xxxxxx26" CSeq: 21 INVITE Call-ID: uWJP4jAB0t Server: VOIP 1.1 Content-Length: 0 2019-01-09 17:18:41:328 [belle-sip] MESSAGE channel [0x1083e8000] [358] bytes parsed 2019-01-09 17:18:41:328 [belle-sip] MESSAGE Found transaction matching response. 2019-01-09 17:18:41:328 [bctbx] MESSAGE op [0x10852e0f0] : set_or_update_dialog() current=[0x0] new=[0x0] 2019-01-09 17:18:41:328 [bctbx] MESSAGE Op [0x10852e0f0] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL] 2019-01-09 17:18:41:328 [belle-sip] MESSAGE channel [0x1083e8000]: ending recv background task with id=[4c1]. 2019-01-09 17:18:41:667 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:18:41:667 [belle-sip] MESSAGE channel [0x1083e8000]: starting recv background task with id=[4d2]. 2019-01-09 17:18:41:668 [belle-sip] MESSAGE channel [0x1083e8000]: received [243] new bytes from [UDP://sip.peoplefone.at:5060]: OPTIONS sip:89.144.213.192:27054 SIP/2.0 Via: SIP/2.0/UDP 95.128.80.5:5060;branch=0 From: sip:address@hidden;tag=0f77e8c2 To: sip:89.144.213.192:27054 Call-ID: address@hidden CSeq: 1 OPTIONS Content-Length: 0 2019-01-09 17:18:41:699 [belle-sip] MESSAGE channel [0x1083e8000] [243] bytes parsed 2019-01-09 17:18:41:699 [belle-sip] MESSAGE Message from old RFC2543 stack, computed branch is PkmTJkww5 2019-01-09 17:18:41:700 [belle-sip] MESSAGE channel [0x1083e8000]: message sent to [UDP://sip.peoplefone.at:5060], size: [212] bytes SIP/2.0 200 Ok Via: SIP/2.0/UDP 95.128.80.5:5060;branch=0 From: ;tag=0f77e8c2 To: ;tag=LVNsC Call-ID: address@hidden CSeq: 1 OPTIONS 2019-01-09 17:18:41:701 [belle-sip] MESSAGE channel [0x1083e8000]: ending recv background task with id=[4d2]. 2019-01-09 17:18:42:788 [belle-sip] MESSAGE channel [0x1083e8000]: keep alive sent to [UDP://sip.peoplefone.at:5060] 2019-01-09 17:18:43:888 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:18:43:888 [belle-sip] MESSAGE channel [0x1083e8000]: starting recv background task with id=[541]. 2019-01-09 17:18:43:891 [belle-sip] MESSAGE channel [0x1083e8000]: received [780] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 180 Ringing To: "+ 4935xxxxxx26" ;tag=11d896f4 Via: SIP/2.0/UDP 192.168.254.71:49268;received=89.144.213.192;branch=z9hG4bK.xEZZuwbrr;rport=27054 Record-Route: CSeq: 21 INVITE Call-ID: uWJP4jAB0t From: "peoplefone" ;tag=3-E0YDHEP Content-Type: application/sdp Content-Length: 362 X-src-ip: 95.128.80.93 v=0 o=hiQ9200 2407920190009171841 307363947 IN IP4 95.128.80.5 s=Phone Call via hiQ9200 SIPCA c=IN IP4 95.128.80.5 t=0 0 m=audio 23450 RTP/AVP 0 8 18 107 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=yes a=rtpmap:107 telephone-event/8000 a=fmtp:107 0-15 a=sqn: 0 a=cdsc: 1 image udptl t38 a=sendrecv a=ptime:20 2019-01-09 17:18:43:896 [belle-sip] MESSAGE channel [0x1083e8000] [418] bytes parsed 2019-01-09 17:18:43:897 [belle-sip] MESSAGE channel [0x1083e8000] read [362] bytes of body from [sip.peoplefone.at:5060] 2019-01-09 17:18:43:897 [belle-sip] MESSAGE Found transaction matching response. 2019-01-09 17:18:43:897 [belle-sip] MESSAGE New client dialog [0x1c01d9410] , local tag [3-E0YDHEP], remote tag [11d896f4] 2019-01-09 17:18:43:897 [belle-sip] MESSAGE Dialog [0x1c01d9410]: now updated by transaction [0x1c419a410]. 2019-01-09 17:18:43:897 [bctbx] MESSAGE op [0x10852e0f0] : set_or_update_dialog() current=[0x0] new=[0x1c01d9410] 2019-01-09 17:18:43:898 [bctbx] MESSAGE Op [0x10852e0f0] receiving call response [180], dialog is [0x1c01d9410] in state [BELLE_SIP_DIALOG_EARLY] 2019-01-09 17:18:43:915 [liblinphone] MESSAGE Found payload PCMU/8000 fmtp= 2019-01-09 17:18:43:915 [liblinphone] MESSAGE Found payload PCMA/8000 fmtp= 2019-01-09 17:18:43:915 [liblinphone] MESSAGE Found payload G729/8000 fmtp=annexb=yes 2019-01-09 17:18:43:915 [liblinphone] MESSAGE Found payload telephone-event/8000 fmtp=0-15 2019-01-09 17:18:43:916 [bctbx] MESSAGE Doing SDP offer/answer process of type outgoing 2019-01-09 17:18:43:916 [liblinphone] MESSAGE Processing for stream 0 2019-01-09 17:18:43:916 [liblinphone] MESSAGE Adding opus/48000 for compatibility, just in case. 2019-01-09 17:18:43:916 [liblinphone] MESSAGE Adding speex/16000 for compatibility, just in case. 2019-01-09 17:18:43:916 [liblinphone] MESSAGE Adding speex/8000 for compatibility, just in case. 2019-01-09 17:18:43:917 [liblinphone] MESSAGE Adding GSM/8000 for compatibility, just in case. 2019-01-09 17:18:43:917 [liblinphone] MESSAGE Adding G722/8000 for compatibility, just in case. 2019-01-09 17:18:43:917 [liblinphone] MESSAGE Adding iLBC/8000 for compatibility, just in case. 2019-01-09 17:18:43:917 [liblinphone] MESSAGE Adding mpeg4-generic/22050 for compatibility, just in case. 2019-01-09 17:18:43:917 [liblinphone] MESSAGE Adding mpeg4-generic/32000 for compatibility, just in case. 2019-01-09 17:18:43:918 [liblinphone] MESSAGE Adding mpeg4-generic/44100 for compatibility, just in case. 2019-01-09 17:18:43:918 [liblinphone] MESSAGE Adding mpeg4-generic/48000 for compatibility, just in case. 2019-01-09 17:18:43:918 [liblinphone] MESSAGE Adding iSAC/16000 for compatibility, just in case. 2019-01-09 17:18:43:918 [liblinphone] MESSAGE Adding telephone-event/48000 for compatibility, just in case. 2019-01-09 17:18:43:919 [liblinphone] MESSAGE Adding telephone-event/16000 for compatibility, just in case. 2019-01-09 17:18:43:919 [liblinphone] MESSAGE Adding telephone-event/22050 for compatibility, just in case. 2019-01-09 17:18:43:919 [liblinphone] MESSAGE Adding telephone-event/32000 for compatibility, just in case. 2019-01-09 17:18:43:920 [liblinphone] MESSAGE Adding telephone-event/44100 for compatibility, just in case. 2019-01-09 17:18:43:920 [bctbx] MESSAGE CallSession [0x1c40ba1d8] moving from state State::OutgoingProgress to State::OutgoingEarlyMedia 2019-01-09 17:18:43:921 [liblinphone] MESSAGE Linphone core [0x104823600] notified [call_state_changed] 2019-01-09 17:18:43:921 [bctbx] MESSAGE Doing early media... 2019-01-09 17:18:43:923 [bctbx] MESSAGE startStreams() CallSession=[0x1c40ba1d8] local upload_bandwidth=[0] kbit/s; local download_bandwidth=[0] kbit/s 2019-01-09 17:18:43:923 [bctbx] MESSAGE Audio bandwidth for CallSession [0x1c40ba1d8] is 80 2019-01-09 17:18:43:923 [bctbx] MESSAGE Payload type [opus/48000] has explicit bitrate [64] kbit/s 2019-01-09 17:18:43:924 [bctbx] MESSAGE Payload type [speex/16000] has explicit bitrate [64] kbit/s 2019-01-09 17:18:43:924 [bctbx] MESSAGE Payload type [speex/8000] has explicit bitrate [64] kbit/s 2019-01-09 17:18:43:924 [bctbx] MESSAGE Payload type [mpeg4-generic/22050] has explicit bitrate [64] kbit/s 2019-01-09 17:18:43:924 [bctbx] MESSAGE Payload type [mpeg4-generic/32000] has explicit bitrate [64] kbit/s 2019-01-09 17:18:43:924 [bctbx] MESSAGE Payload type [mpeg4-generic/44100] has explicit bitrate [64] kbit/s 2019-01-09 17:18:43:925 [bctbx] MESSAGE Payload type [mpeg4-generic/48000] has explicit bitrate [64] kbit/s 2019-01-09 17:18:43:925 [bctbx] MESSAGE Payload type [iSAC/16000] has explicit bitrate [64] kbit/s 2019-01-09 17:18:43:925 [bctbx] WARNING CallSession [0x1c40ba1d8] - advanced adaptive rate control requested but avpf is not activated in this stream. Reverting to basic rate control instead 2019-01-09 17:18:43:926 [ortp] MESSAGE RtpSession [0x104102c00] sending to rtp [::ffff:95.128.80.5]:23450 rtcp [::ffff:95.128.80.5]:23451 2019-01-09 17:18:43:928 [mediastreamer] MESSAGE Stun packet sent for session [0x104102c00] 2019-01-09 17:18:43:929 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:43:930 [mediastreamer] MESSAGE ms_filter_unlink: MSRtpRecv:0x1c0328a20,0-->MSAUWrite:0x1c0328b60,0 2019-01-09 17:18:43:931 [mediastreamer] MESSAGE configure_audio_session(): AudioUnit is already started, skipping this process. 2019-01-09 17:18:43:932 [mediastreamer] MESSAGE speex_lib_ctl init with neon ? 1 2019-01-09 17:18:43:932 [mediastreamer] MESSAGE configure_audio_session(): AudioUnit is already started, skipping this process. 2019-01-09 17:18:43:932 [mediastreamer] MESSAGE speex_lib_ctl init with neon ? 1 2019-01-09 17:18:43:932 [mediastreamer] MESSAGE target bitrate not set for stream [0x1085062d0] using payload's bitrate is 80000 2019-01-09 17:18:43:932 [mediastreamer] MESSAGE Setting audio encoder network bitrate to [80000] on stream [0x1085062d0] 2019-01-09 17:18:43:933 [mediastreamer] MESSAGE MSUlawEnc: got ptime=20 2019-01-09 17:18:43:933 [mediastreamer] MESSAGE configure_audio_session(): AudioUnit is already started, skipping this process. 2019-01-09 17:18:43:933 [mediastreamer] MESSAGE configuring MSAURead:0x1c0328ac0-->MSUlawEnc:0x1c0328a20 from rate [48000] to rate [8000] and from channel [1] to channel [1] 2019-01-09 17:18:43:933 [mediastreamer] MESSAGE configure_audio_session(): AudioUnit is already started, skipping this process. 2019-01-09 17:18:43:933 [mediastreamer] MESSAGE configuring MSUlawDec:0x1c03285c0-->MSAUWrite:0x1c0328b60 from rate [8000] to rate [48000] and from channel [1] to channel [1] 2019-01-09 17:18:43:934 [mediastreamer] MESSAGE ms_filter_link: MSAURead:0x1c0328ac0,0-->MSResample:0x1c032ac80,0 2019-01-09 17:18:43:934 [mediastreamer] MESSAGE ms_filter_link: MSResample:0x1c032ac80,0-->MSEqualizer:0x1c032afa0,0 2019-01-09 17:18:43:934 [mediastreamer] MESSAGE ms_filter_link: MSEqualizer:0x1c032afa0,0-->MSVolume:0x1c0328160,0 2019-01-09 17:18:43:935 [mediastreamer] MESSAGE ms_filter_link: MSVolume:0x1c0328160,0-->MSAudioMixer:0x1c032adc0,0 2019-01-09 17:18:43:935 [mediastreamer] MESSAGE ms_filter_link: MSAudioMixer:0x1c032adc0,0-->MSUlawEnc:0x1c0328a20,0 2019-01-09 17:18:43:935 [mediastreamer] MESSAGE ms_filter_link: MSUlawEnc:0x1c0328a20,0-->MSRtpSend:0x1c03280c0,0 2019-01-09 17:18:43:935 [mediastreamer] MESSAGE ms_filter_link: MSRtpRecv:0x1c0328660,0-->MSUlawDec:0x1c03285c0,0 2019-01-09 17:18:43:935 [mediastreamer] MESSAGE ms_filter_link: MSUlawDec:0x1c03285c0,0-->MSGenericPLC:0x1c032b0e0,0 2019-01-09 17:18:43:936 [mediastreamer] MESSAGE ms_filter_link: MSGenericPLC:0x1c032b0e0,0-->MSAudioFlowControl:0x1c032b180,0 2019-01-09 17:18:43:936 [mediastreamer] MESSAGE ms_filter_link: MSAudioFlowControl:0x1c032b180,0-->MSDtmfGen:0x1c0328480,0 2019-01-09 17:18:43:936 [mediastreamer] MESSAGE ms_filter_link: MSDtmfGen:0x1c0328480,0-->MSVolume:0x1c032abe0,0 2019-01-09 17:18:43:936 [mediastreamer] MESSAGE ms_filter_link: MSVolume:0x1c032abe0,0-->MSEqualizer:0x1c032b040,0 2019-01-09 17:18:43:936 [mediastreamer] MESSAGE ms_filter_link: MSEqualizer:0x1c032b040,0-->MSAudioMixer:0x1c032b220,0 2019-01-09 17:18:43:936 [mediastreamer] MESSAGE speex_lib_ctl init with neon ? 1 2019-01-09 17:18:43:937 [mediastreamer] MESSAGE ms_filter_link: MSFilePlayer:0x1c032b400,0-->MSResample:0x1c032b4a0,0 2019-01-09 17:18:43:937 [mediastreamer] MESSAGE ms_filter_link: MSResample:0x1c032b4a0,0-->MSAudioMixer:0x1c032b220,1 2019-01-09 17:18:43:937 [mediastreamer] MESSAGE ms_filter_link: MSAudioMixer:0x1c032b220,0-->MSResample:0x1c032ad20,0 2019-01-09 17:18:43:937 [mediastreamer] MESSAGE ms_filter_link: MSResample:0x1c032ad20,0-->MSAUWrite:0x1c0328b60,0 2019-01-09 17:18:43:937 [mediastreamer] MESSAGE configure_audio_session(): AudioUnit is already started, skipping this process. 2019-01-09 17:18:43:938 [mediastreamer] MESSAGE check_audio_unit_is_up(): audio unit is started. 2019-01-09 17:18:43:938 [mediastreamer] MESSAGE ms_ticker_set_time_func: ticker's time method updated. 2019-01-09 17:18:43:938 [mediastreamer] MESSAGE Initializing speex resampler in mode [voip] 2019-01-09 17:18:43:940 [mediastreamer] MESSAGE Initializing speex resampler in mode [voip] 2019-01-09 17:18:43:940 [mediastreamer] MESSAGE Initializing speex resampler in mode [voip] 2019-01-09 17:18:43:940 [mediastreamer] MESSAGE configure_audio_session(): AudioUnit is already started, skipping this process. 2019-01-09 17:18:43:940 [mediastreamer] MESSAGE check_audio_unit_is_up(): audio unit is started. 2019-01-09 17:18:43:941 [mediastreamer] MESSAGE MSVolume set gain to [0.000000 db], [1.000000] linear 2019-01-09 17:18:43:941 [mediastreamer] MESSAGE Stun packet sent for session [0x104102c00] 2019-01-09 17:18:43:941 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:43:942 [bctbx] WARNING 'eq_active' linphonerc parameter has no effect anymore. Please use 'mic_eq_active' or 'spk_eq_active' instead 2019-01-09 17:18:43:942 [bctbx] WARNING 'eq_gains' linphonerc parameter has no effect anymore. Please use 'mic_eq_gains' or 'spk_eq_gains' instead 2019-01-09 17:18:43:942 [mediastreamer] MESSAGE Starting ZRTP engine on rtp session [0x104102c00] ssrc 0x5c85af3d 2019-01-09 17:18:43:942 [bctbx] MESSAGE No valid video stream defined 2019-01-09 17:18:43:943 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 0 PCMU/8000 fmtp= added to frozen list 2019-01-09 17:18:43:943 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 8 PCMA/8000 fmtp= added to frozen list 2019-01-09 17:18:43:943 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 18 G729/8000 fmtp=annexb=yes added to frozen list 2019-01-09 17:18:43:943 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 107 telephone-event/8000 fmtp= added to frozen list 2019-01-09 17:18:43:944 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 96 opus/48000 fmtp=useinbandfec=1 added to frozen list 2019-01-09 17:18:43:944 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 97 speex/16000 fmtp=vbr=on added to frozen list 2019-01-09 17:18:43:944 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 98 speex/8000 fmtp=vbr=on added to frozen list 2019-01-09 17:18:43:944 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 3 GSM/8000 fmtp= added to frozen list 2019-01-09 17:18:43:944 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 9 G722/8000 fmtp= added to frozen list 2019-01-09 17:18:43:945 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 99 iLBC/8000 fmtp=mode=30 added to frozen list 2019-01-09 17:18:43:945 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 100 mpeg4-generic/22050 fmtp=config=F8EE2000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5 added to frozen list 2019-01-09 17:18:43:945 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 102 mpeg4-generic/32000 fmtp=config=F8E82000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5 added to frozen list 2019-01-09 17:18:43:945 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 103 mpeg4-generic/44100 fmtp=config=F8E82000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5 added to frozen list 2019-01-09 17:18:43:945 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 104 mpeg4-generic/48000 fmtp=config=F8EE2000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5 added to frozen list 2019-01-09 17:18:43:946 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 105 iSAC/16000 fmtp= added to frozen list 2019-01-09 17:18:43:946 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 101 telephone-event/48000 fmtp= added to frozen list 2019-01-09 17:18:43:946 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 106 telephone-event/16000 fmtp= added to frozen list 2019-01-09 17:18:43:946 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 108 telephone-event/22050 fmtp= added to frozen list 2019-01-09 17:18:43:946 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 109 telephone-event/32000 fmtp= added to frozen list 2019-01-09 17:18:43:950 [mediastreamer] MESSAGE MSAudioMixer [0x1c032adc0] is entering bypass mode. 2019-01-09 17:18:43:951 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:43:951 [bctbx] MESSAGE CallSession[0x1c40ba1d8] : payload type 110 telephone-event/44100 fmtp= added to frozen list 2019-01-09 17:18:43:952 [belle-sip] MESSAGE channel [0x1083e8000]: ending recv background task with id=[541]. 2019-01-09 17:18:43:952 [belle-sip] MESSAGE Garbage collecting unowned object of type belle_sdp_session_description_t 2019-01-09 17:18:44:049 [ortp] MESSAGE First estimation 2019-01-09 17:18:44:059 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:44:271 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:44:348 [ortp] MESSAGE First estimation 2019-01-09 17:18:44:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=49.37,u=0.00], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:18:44:348 [bctbx] MESSAGE Thread processing load: audio=3.93067 video=0 text=0 2019-01-09 17:18:44:448 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:44:478 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:44:679 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:44:879 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:44:939 [mediastreamer] MESSAGE MSAudioMixer [0x1c032b220] is entering bypass mode. 2019-01-09 17:18:44:949 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:45:079 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:45:278 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:45:347 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=81.93,u=87.42], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.98], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:18:45:347 [bctbx] MESSAGE Thread processing load: audio=2.1074 video=0 text=0 2019-01-09 17:18:45:458 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:45:479 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:45:679 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:45:878 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:45:969 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:46:068 [mediastreamer] MESSAGE sound/wall clock skew is average=1.827899 ms 2019-01-09 17:18:46:079 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:46:149 [ortp] MESSAGE Sending RTCP SR compound message on session [0x104102c00]. 2019-01-09 17:18:46:170 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x1085062d0], local statistics available: Local current jitter buffer size: 58.7ms 2019-01-09 17:18:46:279 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:46:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=65.95,u=86.18], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.76], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:18:46:348 [bctbx] MESSAGE Thread processing load: audio=4.15626 video=0 text=0 2019-01-09 17:18:46:478 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:46:479 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:46:681 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:46:889 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:46:989 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:47:089 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:47:288 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:47:347 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=81.34,u=88.59], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=2.35], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:18:47:347 [bctbx] MESSAGE Thread processing load: audio=3.32295 video=0 text=0 2019-01-09 17:18:47:488 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:47:489 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:47:688 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:47:889 [mediastreamer] MESSAGE ZRTP Send packet type Hello on rtp session [0x104102c00] 2019-01-09 17:18:47:989 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:48:180 [ortp] MESSAGE Sending RTCP SR compound message on session [0x104102c00]. 2019-01-09 17:18:48:188 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x1085062d0], local statistics available: Local current jitter buffer size: 64.3ms 2019-01-09 17:18:48:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=78.08,u=86.57], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.77], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:18:48:348 [bctbx] MESSAGE Thread processing load: audio=2.64582 video=0 text=0 2019-01-09 17:18:48:388 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:18:48:388 [belle-sip] MESSAGE channel [0x1083e8000]: starting recv background task with id=[61a]. 2019-01-09 17:18:48:391 [belle-sip] MESSAGE channel [0x1083e8000]: received [820] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 200 OK To: "+ 4935xxxxxx26" ;tag=11d896f4 Via: SIP/2.0/UDP 192.168.254.71:49268;received=89.144.213.192;branch=z9hG4bK.xEZZuwbrr;rport=27054 Record-Route: CSeq: 21 INVITE Call-ID: uWJP4jAB0t From: "peoplefone" ;tag=3-E0YDHEP Content-Type: application/sdp Contact: Content-Length: 362 X-src-ip: 95.128.80.93 v=0 o=hiQ9200 2407920190009171841 307363947 IN IP4 95.128.80.5 s=Phone Call via hiQ9200 SIPCA c=IN IP4 95.128.80.5 t=0 0 m=audio 23450 RTP/AVP 0 8 18 107 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=yes a=rtpmap:107 telephone-event/8000 a=fmtp:107 0-15 a=sqn: 0 a=cdsc: 1 image udptl t38 a=sendrecv a=ptime:20 2019-01-09 17:18:48:397 [belle-sip] MESSAGE channel [0x1083e8000] [458] bytes parsed 2019-01-09 17:18:48:397 [belle-sip] MESSAGE channel [0x1083e8000] read [362] bytes of body from [sip.peoplefone.at:5060] 2019-01-09 17:18:48:397 [belle-sip] MESSAGE Found transaction matching response. 2019-01-09 17:18:48:397 [belle-sip] MESSAGE Changing [client] [INVITE] transaction [0x1c419a410], from state [PROCEEDING] to [ACCEPTED] 2019-01-09 17:18:48:398 [belle-sip] MESSAGE Dialog [0x1c01d9410]: now updated by transaction [0x1c419a410]. 2019-01-09 17:18:48:398 [bctbx] MESSAGE op [0x10852e0f0] : set_or_update_dialog() current=[0x1c01d9410] new=[0x1c01d9410] 2019-01-09 17:18:48:398 [bctbx] MESSAGE Op [0x10852e0f0] receiving call response [200], dialog is [0x1c01d9410] in state [BELLE_SIP_DIALOG_CONFIRMED] 2019-01-09 17:18:48:401 [liblinphone] MESSAGE Found payload PCMU/8000 fmtp= 2019-01-09 17:18:48:401 [liblinphone] MESSAGE Found payload PCMA/8000 fmtp= 2019-01-09 17:18:48:401 [liblinphone] MESSAGE Found payload G729/8000 fmtp=annexb=yes 2019-01-09 17:18:48:401 [liblinphone] MESSAGE Found payload telephone-event/8000 fmtp=0-15 2019-01-09 17:18:48:401 [bctbx] MESSAGE Doing SDP offer/answer process of type outgoing 2019-01-09 17:18:48:402 [liblinphone] MESSAGE Processing for stream 0 2019-01-09 17:18:48:402 [liblinphone] MESSAGE Adding opus/48000 for compatibility, just in case. 2019-01-09 17:18:48:402 [liblinphone] MESSAGE Adding speex/16000 for compatibility, just in case. 2019-01-09 17:18:48:402 [liblinphone] MESSAGE Adding speex/8000 for compatibility, just in case. 2019-01-09 17:18:48:402 [liblinphone] MESSAGE Adding GSM/8000 for compatibility, just in case. 2019-01-09 17:18:48:403 [liblinphone] MESSAGE Adding G722/8000 for compatibility, just in case. 2019-01-09 17:18:48:403 [liblinphone] MESSAGE Adding iLBC/8000 for compatibility, just in case. 2019-01-09 17:18:48:403 [liblinphone] MESSAGE Adding mpeg4-generic/22050 for compatibility, just in case. 2019-01-09 17:18:48:403 [liblinphone] MESSAGE Adding mpeg4-generic/32000 for compatibility, just in case. 2019-01-09 17:18:48:403 [liblinphone] MESSAGE Adding mpeg4-generic/44100 for compatibility, just in case. 2019-01-09 17:18:48:403 [liblinphone] MESSAGE Adding mpeg4-generic/48000 for compatibility, just in case. 2019-01-09 17:18:48:404 [liblinphone] MESSAGE Adding iSAC/16000 for compatibility, just in case. 2019-01-09 17:18:48:404 [liblinphone] MESSAGE Adding telephone-event/48000 for compatibility, just in case. 2019-01-09 17:18:48:404 [liblinphone] MESSAGE Adding telephone-event/16000 for compatibility, just in case. 2019-01-09 17:18:48:404 [liblinphone] MESSAGE Adding telephone-event/22050 for compatibility, just in case. 2019-01-09 17:18:48:405 [liblinphone] MESSAGE Adding telephone-event/32000 for compatibility, just in case. 2019-01-09 17:18:48:405 [liblinphone] MESSAGE Adding telephone-event/44100 for compatibility, just in case. 2019-01-09 17:18:48:405 [bctbx] MESSAGE CallSession [0x1c40ba1d8] moving from state State::OutgoingEarlyMedia to State::Connected 2019-01-09 17:18:48:406 [ios] MESSAGE (LinphoneManager.m:2952) Adding CT call center listener [0x1c4249e10] 2019-01-09 17:18:48:408 [liblinphone] MESSAGE Linphone core [0x104823600] notified [call_state_changed] 2019-01-09 17:18:48:408 [bctbx] MESSAGE No need to restart streams, SDP is unchanged 2019-01-09 17:18:48:408 [bctbx] MESSAGE audio stream index found: 0, updating main audio stream index 2019-01-09 17:18:48:409 [bctbx] MESSAGE CallSession [0x1c40ba1d8] moving from state State::Connected to State::StreamsRunning 2019-01-09 17:18:48:414 [ios] MESSAGE ( PhoneMainView.m:681 ) Change current view to CallView 2019-01-09 17:18:48:489 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:48:622 [liblinphone] MESSAGE Linphone core [0x104823600] notified [call_state_changed] 2019-01-09 17:18:48:623 [belle-sip] MESSAGE Computing branch id z9hG4bK.oNzW4JayA for message sent statelessly 2019-01-09 17:18:48:623 [belle-sip] MESSAGE channel [0x1083e8000]: message sent to [UDP://sip.peoplefone.at:5060], size: [635] bytes ACK sip:address@hidden:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.71:49268;rport;branch=z9hG4bK.oNzW4JayA From: "peoplefone" ;tag=3-E0YDHEP To: "+ 4935xxxxxx26" ;tag=11d896f4 CSeq: 21 ACK Call-ID: uWJP4jAB0t Max-Forwards: 70 Route: Proxy-Authorization: Digest realm="sip.peoplefone.at", nonce="XDYgC1w2Ht95rFLuX6Wgotx6fWch4AVq", username="90772518119", uri="sip:+ address@hidden", response="cde4dea64b8b4270a800086f7e6026ac" User-Agent: Linphone_iPhone.SE_iOS11.4.1/4.0.2-2-gf18fb2a09 (belle-sip/1.6.3) 2019-01-09 17:18:48:624 [belle-sip] MESSAGE channel [0x1083e8000]: ending recv background task with id=[61a]. 2019-01-09 17:18:48:624 [belle-sip] MESSAGE Garbage collecting unowned object of type belle_sip_hop_t 2019-01-09 17:18:48:624 [belle-sip] MESSAGE Garbage collecting unowned object of type belle_sdp_session_description_t 2019-01-09 17:18:48:630 [ios] DEBUG (roviderDelegate.m:278 ) CallKit : Call changed 2019-01-09 17:18:48:927 [ios] DEBUG ( CallView.m:696 ) Recentering preview to {{207, 396}, {108, 145}} 2019-01-09 17:18:48:928 [ios] DEBUG ( CallView.m:696 ) Recentering preview to {{222.5, 308.5}, {92.5, 113.5}} 2019-01-09 17:18:48:988 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:49:048 [ortp] MESSAGE jitter buffer stable: target-size: 59.999999 ms, effective-size: 64.313728 (min: 40 nom: 60, max: 500) 2019-01-09 17:18:49:048 [ortp] MESSAGE jitter buffer rls stats: count=250, clockrate=8000, offset=-13.4854 clock_ratio=1.00141, capped_offset=-1074577490 capped_clock_ratio=1.001411, max_ts_deviation=480.000000 prev_max_ts_deviation=480.000000, deviation=45, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.011618 2019-01-09 17:18:49:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.90,u=77.86], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=2.34], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:18:49:348 [bctbx] MESSAGE Thread processing load: audio=2.52843 video=0 text=0 2019-01-09 17:18:49:489 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:49:989 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:50:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=78.29,u=81.66], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.77], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:18:50:348 [bctbx] MESSAGE Thread processing load: audio=3.06351 video=0 text=0 2019-01-09 17:18:50:499 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:50:979 [ortp] MESSAGE Sending RTCP SR compound message on session [0x104102c00]. 2019-01-09 17:18:50:987 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x1085062d0], local statistics available: Local current jitter buffer size: 46.7ms 2019-01-09 17:18:51:009 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:51:258 [mediastreamer] MESSAGE sound/wall clock skew is average=-3.458954 ms 2019-01-09 17:18:51:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.56,u=81.49], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.77], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:18:51:348 [bctbx] MESSAGE Thread processing load: audio=3.40765 video=0 text=0 2019-01-09 17:18:51:519 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:52:028 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:52:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.01,u=81.54], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.95], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:18:52:348 [bctbx] MESSAGE Thread processing load: audio=2.53257 video=0 text=0 2019-01-09 17:18:52:539 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:53:039 [mediastreamer] MESSAGE Stun packet sent on rtcp for session [0x104102c00] 2019-01-09 17:18:53:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=76.79,u=75.29], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.77], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:18:53:348 [bctbx] MESSAGE Thread processing load: audio=3.10392 video=0 text=0 2019-01-09 17:18:53:367 [mediastreamer] MESSAGE MSAudio stream [0x1085062d0]: receiving RTCP SR 2019-01-09 17:18:53:779 [ortp] MESSAGE Sending RTCP SR compound message on session [0x104102c00]. 2019-01-09 17:18:53:787 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x1085062d0], local statistics available: Local current jitter buffer size: 38.4ms 2019-01-09 17:18:53:959 [mediastreamer] WARNING Flow controlled bufferizer of max 40 ms is filled with 180 ms at the end of the 5000 ms interval, asking to drop 70 ms 2019-01-09 17:18:53:967 [mediastreamer] WARNING Too much buffered audio signal, throwing out 70 ms 2019-01-09 17:18:54:059 [ortp] MESSAGE jitter buffer stable: target-size: 39.999999 ms, effective-size: 38.428570 (min: 40 nom: 60, max: 500) 2019-01-09 17:18:54:059 [ortp] MESSAGE jitter buffer rls stats: count=501, clockrate=8000, offset=-7.22216 clock_ratio=1.00122, capped_offset=-1074577484 capped_clock_ratio=1.001224, max_ts_deviation=170.000000 prev_max_ts_deviation=161.000000, deviation=12, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.007179 2019-01-09 17:18:54:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=70.66,u=81.54], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.03,u=0.77], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:18:54:348 [bctbx] MESSAGE Thread processing load: audio=2.91716 video=0 text=0 2019-01-09 17:18:55:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=78.37,u=83.20], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.57], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:18:55:348 [bctbx] MESSAGE Thread processing load: audio=2.53519 video=0 text=0 2019-01-09 17:18:56:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.85,u=75.22], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:18:56:348 [bctbx] MESSAGE Thread processing load: audio=2.65937 video=0 text=0 2019-01-09 17:18:56:729 [ortp] MESSAGE Sending RTCP SR compound message on session [0x104102c00]. 2019-01-09 17:18:56:748 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x1085062d0], local statistics available: Local current jitter buffer size: 36.9ms 2019-01-09 17:18:56:867 [mediastreamer] MESSAGE MSAudio stream [0x1085062d0]: receiving RTCP SR 2019-01-09 17:18:56:868 [mediastreamer] MESSAGE MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=26.375000 ms, rt_prop=0.063126 sec 2019-01-09 17:18:56:868 [mediastreamer] MESSAGE MSSimpleQosAnalyzer: everything is fine. 2019-01-09 17:18:56:868 [mediastreamer] MESSAGE MSBitrateController: current state is Init 2019-01-09 17:18:56:869 [mediastreamer] MESSAGE MSQualityIndicator[0x1c02d2d70][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 26.4ms RT propagation : 63.1ms 2019-01-09 17:18:57:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.98,u=81.55], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.34,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:18:57:348 [bctbx] MESSAGE Thread processing load: audio=3.17426 video=0 text=0 2019-01-09 17:18:58:347 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=92.49,u=81.63], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.57], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:18:58:347 [bctbx] MESSAGE Thread processing load: audio=3.11749 video=0 text=0 2019-01-09 17:18:59:058 [ortp] MESSAGE jitter buffer stable: target-size: 121.250004 ms, effective-size: 36.870747 (min: 40 nom: 60, max: 500) 2019-01-09 17:18:59:059 [ortp] MESSAGE jitter buffer rls stats: count=751, clockrate=8000, offset=63.5463 clock_ratio=0.999203, capped_offset=-1074577414 capped_clock_ratio=0.999203, max_ts_deviation=970.000000 prev_max_ts_deviation=254.000000, deviation=113, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.004994 2019-01-09 17:18:59:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=50.25,u=81.51], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:18:59:348 [bctbx] MESSAGE Thread processing load: audio=2.42853 video=0 text=0 2019-01-09 17:18:59:789 [mediastreamer] MESSAGE sound/wall clock skew is average=-9.923889 ms 2019-01-09 17:19:00:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=57.00,u=75.23], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:00:348 [bctbx] MESSAGE Thread processing load: audio=2.60933 video=0 text=0 2019-01-09 17:19:01:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=69.75,u=82.47], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:01:348 [bctbx] MESSAGE Thread processing load: audio=3.3317 video=0 text=0 2019-01-09 17:19:01:927 [mediastreamer] MESSAGE MSAudio stream [0x1085062d0]: receiving RTCP SR 2019-01-09 17:19:01:928 [mediastreamer] MESSAGE MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=28.500000 ms, rt_prop=0.124222 sec 2019-01-09 17:19:01:928 [mediastreamer] MESSAGE MSSimpleQosAnalyzer: everything is fine. 2019-01-09 17:19:01:928 [mediastreamer] MESSAGE MSBitrateController: current state is Init 2019-01-09 17:19:01:929 [mediastreamer] MESSAGE MSQualityIndicator[0x1c02d2d70][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 28.5ms RT propagation : 124.2ms 2019-01-09 17:19:02:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=92.76,u=81.55], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.45,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:02:348 [bctbx] MESSAGE Thread processing load: audio=2.5056 video=0 text=0 2019-01-09 17:19:02:829 [ortp] MESSAGE Sending RTCP SR compound message on session [0x104102c00]. 2019-01-09 17:19:02:848 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x1085062d0], local statistics available: Local current jitter buffer size: 88.8ms 2019-01-09 17:19:03:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.44,u=75.23], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:03:348 [bctbx] MESSAGE Thread processing load: audio=2.77815 video=0 text=0 2019-01-09 17:19:03:949 [mediastreamer] WARNING Flow controlled bufferizer of max 40 ms is filled with 222 ms at the end of the 5000 ms interval, asking to drop 91 ms 2019-01-09 17:19:03:968 [mediastreamer] WARNING Too much buffered audio signal, throwing out 91 ms 2019-01-09 17:19:04:059 [ortp] MESSAGE jitter buffer stable: target-size: 101.250000 ms, effective-size: 88.786888 (min: 40 nom: 60, max: 500) 2019-01-09 17:19:04:060 [ortp] MESSAGE jitter buffer rls stats: count=1001, clockrate=8000, offset=108.805 clock_ratio=0.998001, capped_offset=-1074577369 capped_clock_ratio=0.998001, max_ts_deviation=247.000000 prev_max_ts_deviation=168.000000, deviation=212, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.003813 2019-01-09 17:19:04:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.18,u=83.18], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.57], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:04:348 [bctbx] MESSAGE Thread processing load: audio=2.94192 video=0 text=0 2019-01-09 17:19:05:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.80,u=81.58], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:05:348 [bctbx] MESSAGE Thread processing load: audio=3.29812 video=0 text=0 2019-01-09 17:19:05:867 [mediastreamer] MESSAGE MSAudio stream [0x1085062d0]: receiving RTCP SR 2019-01-09 17:19:05:868 [mediastreamer] MESSAGE MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=26.250000 ms, rt_prop=0.062012 sec 2019-01-09 17:19:05:868 [mediastreamer] MESSAGE MSSimpleQosAnalyzer: everything is fine. 2019-01-09 17:19:05:868 [mediastreamer] MESSAGE MSBitrateController: current state is Init 2019-01-09 17:19:05:869 [mediastreamer] MESSAGE MSQualityIndicator[0x1c02d2d70][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 26.2ms RT propagation : 62.0ms 2019-01-09 17:19:06:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=70.06,u=81.20], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.34,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:06:348 [bctbx] MESSAGE Thread processing load: audio=2.52962 video=0 text=0 2019-01-09 17:19:07:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.59,u=75.58], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:07:348 [bctbx] MESSAGE Thread processing load: audio=2.78787 video=0 text=0 2019-01-09 17:19:08:319 [mediastreamer] MESSAGE sound/wall clock skew is average=-12.399542 ms 2019-01-09 17:19:08:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.41,u=81.57], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:08:348 [bctbx] MESSAGE Thread processing load: audio=3.28383 video=0 text=0 2019-01-09 17:19:08:919 [ortp] MESSAGE Sending RTCP SR compound message on session [0x104102c00]. 2019-01-09 17:19:08:928 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x1085062d0], local statistics available: Local current jitter buffer size: 117.3ms 2019-01-09 17:19:08:958 [mediastreamer] WARNING Flow controlled bufferizer of max 40 ms is filled with 182 ms at the end of the 5000 ms interval, asking to drop 71 ms 2019-01-09 17:19:08:967 [mediastreamer] WARNING Too much buffered audio signal, throwing out 71 ms 2019-01-09 17:19:09:069 [ortp] MESSAGE jitter buffer stable: target-size: 81.249997 ms, effective-size: 117.302635 (min: 40 nom: 60, max: 500) 2019-01-09 17:19:09:069 [ortp] MESSAGE jitter buffer rls stats: count=1251, clockrate=8000, offset=42.0754 clock_ratio=0.999156, capped_offset=-1074577435 capped_clock_ratio=0.999156, max_ts_deviation=144.000000 prev_max_ts_deviation=247.000000, deviation=47, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.003085 2019-01-09 17:19:09:347 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.45,u=81.56], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:09:347 [bctbx] MESSAGE Thread processing load: audio=2.6055 video=0 text=0 2019-01-09 17:19:10:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.81,u=75.22], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.57], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:10:348 [bctbx] MESSAGE Thread processing load: audio=2.5791 video=0 text=0 2019-01-09 17:19:11:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.86,u=81.60], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:11:348 [bctbx] MESSAGE Thread processing load: audio=2.93646 video=0 text=0 2019-01-09 17:19:11:748 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:19:11:748 [belle-sip] MESSAGE channel [0x1083e8000]: starting recv background task with id=[a99]. 2019-01-09 17:19:11:749 [belle-sip] MESSAGE channel [0x1083e8000]: received [243] new bytes from [UDP://sip.peoplefone.at:5060]: OPTIONS sip:89.144.213.192:27054 SIP/2.0 Via: SIP/2.0/UDP 95.128.80.5:5060;branch=0 From: sip:address@hidden;tag=6b99e8c2 To: sip:89.144.213.192:27054 Call-ID: address@hidden CSeq: 1 OPTIONS Content-Length: 0 2019-01-09 17:19:11:753 [belle-sip] MESSAGE channel [0x1083e8000] [243] bytes parsed 2019-01-09 17:19:11:753 [belle-sip] MESSAGE Message from old RFC2543 stack, computed branch is ZT0uHGNY0 2019-01-09 17:19:11:755 [belle-sip] MESSAGE channel [0x1083e8000]: message sent to [UDP://sip.peoplefone.at:5060], size: [212] bytes SIP/2.0 200 Ok Via: SIP/2.0/UDP 95.128.80.5:5060;branch=0 From: ;tag=6b99e8c2 To: ;tag=NXgBn Call-ID: address@hidden CSeq: 1 OPTIONS 2019-01-09 17:19:11:755 [belle-sip] MESSAGE channel [0x1083e8000]: ending recv background task with id=[a99]. 2019-01-09 17:19:12:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.91,u=83.22], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:12:348 [bctbx] MESSAGE Thread processing load: audio=3.0291 video=0 text=0 2019-01-09 17:19:12:367 [mediastreamer] MESSAGE MSAudio stream [0x1085062d0]: receiving RTCP SR 2019-01-09 17:19:12:368 [mediastreamer] MESSAGE MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=27.625000 ms, rt_prop=0.067307 sec 2019-01-09 17:19:12:368 [mediastreamer] MESSAGE MSSimpleQosAnalyzer: everything is fine. 2019-01-09 17:19:12:368 [mediastreamer] MESSAGE MSBitrateController: current state is Init 2019-01-09 17:19:12:368 [mediastreamer] MESSAGE MSQualityIndicator[0x1c02d2d70][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 27.6ms RT propagation : 67.3ms 2019-01-09 17:19:12:788 [belle-sip] MESSAGE channel [0x1083e8000]: keep alive sent to [UDP://sip.peoplefone.at:5060] 2019-01-09 17:19:12:979 [ortp] MESSAGE Sending RTCP SR compound message on session [0x104102c00]. 2019-01-09 17:19:12:987 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x1085062d0], local statistics available: Local current jitter buffer size: 96.8ms 2019-01-09 17:19:13:188 [belle-sip] MESSAGE Changing [client] [INVITE] transaction [0x1c419a8f0], from state [COMPLETED] to [TERMINATED] 2019-01-09 17:19:13:188 [belle-sip] MESSAGE Client INVITE transaction [0x1c419a8f0] terminated 2019-01-09 17:19:13:188 [belle-sip] MESSAGE transaction [0x1c419a8f0]: ending transaction background task with id=[4a8]. 2019-01-09 17:19:13:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.61,u=81.29], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.03,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:13:348 [bctbx] MESSAGE Thread processing load: audio=2.61711 video=0 text=0 2019-01-09 17:19:14:078 [ortp] MESSAGE jitter buffer stable: target-size: 61.250001 ms, effective-size: 96.764709 (min: 40 nom: 60, max: 500) 2019-01-09 17:19:14:079 [ortp] MESSAGE jitter buffer rls stats: count=1502, clockrate=8000, offset=8.84406 clock_ratio=0.999622, capped_offset=-1074577469 capped_clock_ratio=0.999622, max_ts_deviation=91.000000 prev_max_ts_deviation=144.000000, deviation=83, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.002587 2019-01-09 17:19:14:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.36,u=75.45], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.57], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:14:348 [bctbx] MESSAGE Thread processing load: audio=2.79159 video=0 text=0 2019-01-09 17:19:15:347 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.55,u=81.57], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:15:347 [bctbx] MESSAGE Thread processing load: audio=1.69713 video=0 text=0 2019-01-09 17:19:16:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.09,u=81.64], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:16:348 [bctbx] MESSAGE Thread processing load: audio=3.52573 video=0 text=0 2019-01-09 17:19:16:367 [mediastreamer] MESSAGE MSAudio stream [0x1085062d0]: receiving RTCP SR 2019-01-09 17:19:16:368 [mediastreamer] MESSAGE MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=25.625000 ms, rt_prop=0.062347 sec 2019-01-09 17:19:16:368 [mediastreamer] MESSAGE MSSimpleQosAnalyzer: everything is fine. 2019-01-09 17:19:16:368 [mediastreamer] MESSAGE MSBitrateController: current state is Init 2019-01-09 17:19:16:369 [mediastreamer] MESSAGE MSQualityIndicator[0x1c02d2d70][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 25.6ms RT propagation : 62.3ms 2019-01-09 17:19:16:852 [mediastreamer] MESSAGE sound/wall clock skew is average=-13.622450 ms 2019-01-09 17:19:17:348 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.42,u=75.17], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.03,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:17:348 [bctbx] MESSAGE Thread processing load: audio=2.43938 video=0 text=0 2019-01-09 17:19:18:407 [bctbx] MESSAGE Bandwidth usage for CallSession [0x1c40ba1d8]: RTP audio=[d=77.64,u=81.61], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-01-09 17:19:18:407 [bctbx] MESSAGE Thread processing load: audio=1.92103 video=0 text=0 2019-01-09 17:19:18:469 [mediastreamer] MESSAGE Adjusting output timestamp by 1840 2019-01-09 17:19:18:999 [mediastreamer] WARNING Flow controlled bufferizer of max 40 ms was filled with at least 109 ms in the last 5000 ms, asking to drop 69 ms 2019-01-09 17:19:19:007 [mediastreamer] WARNING Too much buffered audio signal, throwing out 69 ms 2019-01-09 17:19:19:009 [ortp] MESSAGE Sending RTCP SR compound message on session [0x104102c00]. 2019-01-09 17:19:19:027 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x1085062d0], local statistics available: Local current jitter buffer size: 67.5ms 2019-01-09 17:19:19:139 [ortp] MESSAGE jitter buffer stable: target-size: 55.374999 ms, effective-size: 67.508308 (min: 40 nom: 60, max: 500) 2019-01-09 17:19:19:139 [ortp] MESSAGE jitter buffer rls stats: count=1755, clockrate=8000, offset=-17.1027 clock_ratio=0.999921, capped_offset=-1074577494 capped_clock_ratio=0.999921, max_ts_deviation=443.000000 prev_max_ts_deviation=368.000000, deviation=439, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.002224 2019-01-09 17:19:19:323 [bctbx] MESSAGE Terminate CallSession [0x1c40ba1d8] which is currently in state [State::StreamsRunning] 2019-01-09 17:19:19:324 [belle-sip] MESSAGE Background task belle-sip transaction(0x1c436ffc0) started. Unknown remaining time since application is not fully in background. 2019-01-09 17:19:19:324 [belle-sip] MESSAGE transaction [0x1c436ffc0]: starting transaction background task with id=[c0e]. 2019-01-09 17:19:19:324 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=90772518119, realm=sip.peoplefone.at 2019-01-09 17:19:19:324 [belle-sip] MESSAGE Auth info found for [90772518119] realm [sip.peoplefone.at] 2019-01-09 17:19:19:325 [belle-sip] MESSAGE Dialog [0x1c01d9410]: now updated by transaction [0x1c436ffc0]. 2019-01-09 17:19:19:325 [belle-sip] MESSAGE Changing [client] [BYE] transaction [0x1c436ffc0], from state [INIT] to [TRYING] 2019-01-09 17:19:19:327 [belle-sip] MESSAGE channel [0x1083e8000]: message sent to [UDP://sip.peoplefone.at:5060], size: [632] bytes BYE sip:address@hidden:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.71:49268;branch=z9hG4bK.TUDVkPNYx;rport From: "peoplefone" ;tag=3-E0YDHEP To: "+ 4935xxxxxx26" ;tag=11d896f4 CSeq: 22 BYE Call-ID: uWJP4jAB0t Max-Forwards: 70 Route: User-Agent: Linphone_iPhone.SE_iOS11.4.1/4.0.2-2-gf18fb2a09 (belle-sip/1.6.3) Proxy-Authorization: Digest realm="sip.peoplefone.at", nonce="XDYgC1w2Ht95rFLuX6Wgotx6fWch4AVq", username="90772518119", uri="sip:address@hidden:5060", response="61e93efb22b02523b2277ca1eedcd4ab" 2019-01-09 17:19:19:327 [mediastreamer] MESSAGE ms_ticker_set_time_func: ticker's time method updated. 2019-01-09 17:19:19:328 [ortp] MESSAGE =========================================================== 2019-01-09 17:19:19:328 [ortp] MESSAGE AUDIO SESSION'S RTP STATISTICS 2019-01-09 17:19:19:328 [ortp] MESSAGE ----------------------------------------------------------- 2019-01-09 17:19:19:328 [ortp] MESSAGE sent 1758 packets 2019-01-09 17:19:19:328 [ortp] MESSAGE 0 duplicated packets 2019-01-09 17:19:19:329 [ortp] MESSAGE 302376 bytes 2019-01-09 17:19:19:329 [ortp] MESSAGE received 1765 packets 2019-01-09 17:19:19:329 [ortp] MESSAGE 0 duplicated packets 2019-01-09 17:19:19:329 [ortp] MESSAGE 303580 bytes 2019-01-09 17:19:19:329 [ortp] MESSAGE incoming delivered to the app 301516 bytes 2019-01-09 17:19:19:329 [ortp] MESSAGE incoming cumulative lost 0 packets 2019-01-09 17:19:19:330 [ortp] MESSAGE incoming received too late 4 packets 2019-01-09 17:19:19:330 [ortp] MESSAGE incoming bad formatted 0 packets 2019-01-09 17:19:19:330 [ortp] MESSAGE incoming discarded (queue overflow) 0 packets 2019-01-09 17:19:19:330 [ortp] MESSAGE sent rtcp 9 packets 2019-01-09 17:19:19:331 [ortp] MESSAGE received rtcp 6 packets 2019-01-09 17:19:19:331 [ortp] MESSAGE =========================================================== 2019-01-09 17:19:19:331 [mediastreamer] MESSAGE ms_filter_unlink: MSAURead:0x1c0328ac0,0-->MSResample:0x1c032ac80,0 2019-01-09 17:19:19:331 [mediastreamer] MESSAGE ms_filter_unlink: MSResample:0x1c032ac80,0-->MSEqualizer:0x1c032afa0,0 2019-01-09 17:19:19:332 [mediastreamer] MESSAGE ms_filter_unlink: MSEqualizer:0x1c032afa0,0-->MSVolume:0x1c0328160,0 2019-01-09 17:19:19:332 [mediastreamer] MESSAGE ms_filter_unlink: MSVolume:0x1c0328160,0-->MSAudioMixer:0x1c032adc0,0 2019-01-09 17:19:19:332 [mediastreamer] MESSAGE ms_filter_unlink: MSAudioMixer:0x1c032adc0,0-->MSUlawEnc:0x1c0328a20,0 2019-01-09 17:19:19:332 [mediastreamer] MESSAGE ms_filter_unlink: MSUlawEnc:0x1c0328a20,0-->MSRtpSend:0x1c03280c0,0 2019-01-09 17:19:19:333 [mediastreamer] MESSAGE ms_filter_unlink: MSRtpRecv:0x1c0328660,0-->MSUlawDec:0x1c03285c0,0 2019-01-09 17:19:19:333 [mediastreamer] MESSAGE ms_filter_unlink: MSUlawDec:0x1c03285c0,0-->MSGenericPLC:0x1c032b0e0,0 2019-01-09 17:19:19:333 [mediastreamer] MESSAGE ms_filter_unlink: MSGenericPLC:0x1c032b0e0,0-->MSAudioFlowControl:0x1c032b180,0 2019-01-09 17:19:19:333 [mediastreamer] MESSAGE ms_filter_unlink: MSAudioFlowControl:0x1c032b180,0-->MSDtmfGen:0x1c0328480,0 2019-01-09 17:19:19:334 [mediastreamer] MESSAGE ms_filter_unlink: MSDtmfGen:0x1c0328480,0-->MSVolume:0x1c032abe0,0 2019-01-09 17:19:19:334 [mediastreamer] MESSAGE ms_filter_unlink: MSVolume:0x1c032abe0,0-->MSEqualizer:0x1c032b040,0 2019-01-09 17:19:19:334 [mediastreamer] MESSAGE ms_filter_unlink: MSEqualizer:0x1c032b040,0-->MSAudioMixer:0x1c032b220,0 2019-01-09 17:19:19:335 [mediastreamer] MESSAGE ms_filter_unlink: MSFilePlayer:0x1c032b400,0-->MSResample:0x1c032b4a0,0 2019-01-09 17:19:19:335 [mediastreamer] MESSAGE ms_filter_unlink: MSResample:0x1c032b4a0,0-->MSAudioMixer:0x1c032b220,1 2019-01-09 17:19:19:335 [mediastreamer] MESSAGE ms_filter_unlink: MSAudioMixer:0x1c032b220,0-->MSResample:0x1c032ad20,0 2019-01-09 17:19:19:335 [mediastreamer] MESSAGE ms_filter_unlink: MSResample:0x1c032ad20,0-->MSAUWrite:0x1c0328b60,0 2019-01-09 17:19:19:336 [mediastreamer] MESSAGE =========================================================== 2019-01-09 17:19:19:336 [mediastreamer] MESSAGE FILTER USAGE STATISTICS 2019-01-09 17:19:19:336 [mediastreamer] MESSAGE Name Count Time/tick (ms) CPU Usage 2019-01-09 17:19:19:336 [mediastreamer] MESSAGE ----------------------------------------------------------- 2019-01-09 17:19:19:337 [mediastreamer] MESSAGE MSRtpRecv 3827 0.0935737 35.7742 2019-01-09 17:19:19:337 [mediastreamer] MESSAGE MSRtpSend 3533 0.0798792 28.1932 2019-01-09 17:19:19:337 [mediastreamer] MESSAGE MSResample 2378 0.108005 25.6615 2019-01-09 17:19:19:337 [mediastreamer] MESSAGE MSUlawDec 1753 0.0102092 1.78841 2019-01-09 17:19:19:337 [mediastreamer] MESSAGE MSAudioMixer 7066 0.0024579 1.73478 2019-01-09 17:19:19:338 [mediastreamer] MESSAGE MSUlawEnc 557 0.0237724 1.3248 2019-01-09 17:19:19:338 [mediastreamer] MESSAGE MSAURead 3533 0.00338398 1.19437 2019-01-09 17:19:19:338 [mediastreamer] MESSAGE MSGenericPLC 3533 0.00328098 1.15802 2019-01-09 17:19:19:338 [mediastreamer] MESSAGE MSVolume 2322 0.00447611 1.03847 2019-01-09 17:19:19:338 [mediastreamer] MESSAGE MSAudioFlowControl 1765 0.00337429 0.595138 2019-01-09 17:19:19:339 [mediastreamer] MESSAGE MSAUWrite 1821 0.00312953 0.569471 2019-01-09 17:19:19:339 [mediastreamer] MESSAGE MSDtmfGen 3533 0.00115676 0.408277 2019-01-09 17:19:19:339 [mediastreamer] MESSAGE MSFilePlayer 3533 0.000842671 0.297419 2019-01-09 17:19:19:339 [mediastreamer] MESSAGE MSEqualizer 2322 0.00112914 0.261964 2019-01-09 17:19:19:340 [mediastreamer] MESSAGE MSItcSink 0 0 0 2019-01-09 17:19:19:340 [mediastreamer] MESSAGE MSTee 0 0 0 2019-01-09 17:19:19:340 [mediastreamer] MESSAGE MSSpeexEC 0 0 0 2019-01-09 17:19:19:340 [mediastreamer] MESSAGE =========================================================== 2019-01-09 17:19:19:341 [mediastreamer] MESSAGE =========================================================== 2019-01-09 17:19:19:341 [mediastreamer] MESSAGE FILTER USAGE STATISTICS 2019-01-09 17:19:19:341 [mediastreamer] MESSAGE Name Count Time/tick (ms) CPU Usage 2019-01-09 17:19:19:341 [mediastreamer] MESSAGE ----------------------------------------------------------- 2019-01-09 17:19:19:342 [mediastreamer] MESSAGE MSRtpRecv 3827 0.0935737 35.7742 2019-01-09 17:19:19:342 [mediastreamer] MESSAGE MSRtpSend 3533 0.0798792 28.1932 2019-01-09 17:19:19:342 [mediastreamer] MESSAGE MSResample 2378 0.108005 25.6615 2019-01-09 17:19:19:342 [mediastreamer] MESSAGE MSUlawDec 1753 0.0102092 1.78841 2019-01-09 17:19:19:342 [mediastreamer] MESSAGE MSAudioMixer 7066 0.0024579 1.73478 2019-01-09 17:19:19:343 [mediastreamer] MESSAGE MSUlawEnc 557 0.0237724 1.3248 2019-01-09 17:19:19:343 [mediastreamer] MESSAGE MSAURead 3533 0.00338398 1.19437 2019-01-09 17:19:19:343 [mediastreamer] MESSAGE MSGenericPLC 3533 0.00328098 1.15802 2019-01-09 17:19:19:343 [mediastreamer] MESSAGE MSVolume 2322 0.00447611 1.03847 2019-01-09 17:19:19:343 [mediastreamer] MESSAGE MSAudioFlowControl 1765 0.00337429 0.595138 2019-01-09 17:19:19:344 [mediastreamer] MESSAGE MSAUWrite 1821 0.00312953 0.569471 2019-01-09 17:19:19:344 [mediastreamer] MESSAGE MSDtmfGen 3533 0.00115676 0.408277 2019-01-09 17:19:19:344 [mediastreamer] MESSAGE MSFilePlayer 3533 0.000842671 0.297419 2019-01-09 17:19:19:345 [mediastreamer] MESSAGE MSEqualizer 2322 0.00112914 0.261964 2019-01-09 17:19:19:345 [mediastreamer] MESSAGE MSItcSink 0 0 0 2019-01-09 17:19:19:345 [mediastreamer] MESSAGE MSTee 0 0 0 2019-01-09 17:19:19:345 [mediastreamer] MESSAGE MSSpeexEC 0 0 0 2019-01-09 17:19:19:346 [mediastreamer] MESSAGE =========================================================== 2019-01-09 17:19:19:346 [bctbx] MESSAGE CallSession [0x1c40ba1d8] moving from state State::StreamsRunning to State::End 2019-01-09 17:19:19:346 [bctbx] MESSAGE Notifying soundcard that we don't need it anymore for calls 2019-01-09 17:19:19:373 [mediastreamer] MESSAGE AudioUnit stopped 2019-01-09 17:19:19:481 [mediastreamer] MESSAGE AudioUnit destroyed 2019-01-09 17:19:19:482 [mediastreamer] MESSAGE Stopping ZRTP context on session [0x0] 2019-01-09 17:19:19:482 [mediastreamer] MESSAGE ZRTP context destroyed 2019-01-09 17:19:19:488 [mediastreamer] MESSAGE MSAudio MSTicker thread exiting 2019-01-09 17:19:19:489 [mediastreamer] MESSAGE Stopping ZRTP context on session [0x0] 2019-01-09 17:19:19:489 [mediastreamer] MESSAGE ZRTP context destroyed 2019-01-09 17:19:19:508 [bctbx] MESSAGE Resetting the current call 2019-01-09 17:19:19:515 [ios] MESSAGE (LinphoneManager.m:2942) Removing CT call center listener [0x1c4249e10] 2019-01-09 17:19:19:516 [ios] MESSAGE ( PhoneMainView.m:664 ) PhoneMainView: Popping view CallView, going to CallOutgoingView 2019-01-09 17:19:19:516 [ios] MESSAGE ( PhoneMainView.m:681 ) Change current view to CallOutgoingView 2019-01-09 17:19:19:546 [ios] MESSAGE (LinphoneManager.m:2471) Current audio route is [Receiver] 2019-01-09 17:19:19:559 [ios] MESSAGE ( PhoneMainView.m:664 ) PhoneMainView: Popping view CallOutgoingView, going to CallOutgoingView 2019-01-09 17:19:19:559 [ios] MESSAGE ( PhoneMainView.m:664 ) PhoneMainView: Popping view CallOutgoingView, going to DialerView 2019-01-09 17:19:19:560 [ios] MESSAGE ( PhoneMainView.m:681 ) Change current view to DialerView 2019-01-09 17:19:19:574 [liblinphone] MESSAGE Linphone core [0x104823600] notified [call_state_changed] 2019-01-09 17:19:19:591 [belle-sip] MESSAGE Background task belle-sip send channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:19:19:591 [belle-sip] MESSAGE channel [0x108600000]: starting send background task with id=[c0f]. 2019-01-09 17:19:19:591 [belle-sip] MESSAGE channel [0x108600000]: starting resolution of subscribe.linphone.org 2019-01-09 17:19:19:591 [belle-sip] MESSAGE channel 0x108600000: state RES_IN_PROGRESS 2019-01-09 17:19:19:592 [belle-sip] MESSAGE Resolver is using DNS server(s): 2019-01-09 17:19:19:592 [belle-sip] MESSAGE 192.168.254.254 2019-01-09 17:19:19:593 [belle-sip] MESSAGE resolver_process_data dns_res_check() in progress 2019-01-09 17:19:19:593 [belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop 2019-01-09 17:19:19:593 [belle-sip] MESSAGE Resolver is using DNS server(s): 2019-01-09 17:19:19:594 [belle-sip] MESSAGE 192.168.254.254 2019-01-09 17:19:19:594 [belle-sip] MESSAGE resolver_process_data dns_res_check() in progress 2019-01-09 17:19:19:594 [belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop 2019-01-09 17:19:19:595 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:19:19:595 [belle-sip] MESSAGE channel [0x1083e8000]: starting recv background task with id=[c11]. 2019-01-09 17:19:19:596 [belle-sip] MESSAGE channel [0x1083e8000]: received [336] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 100 TRYING Via: SIP/2.0/UDP 192.168.254.71:49268;branch=z9hG4bK.TUDVkPNYx;rport=27054;received=89.144.213.192 From: "peoplefone" ;tag=3-E0YDHEP To: "+ 4935xxxxxx26" ;tag=11d896f4 CSeq: 22 BYE Call-ID: uWJP4jAB0t Server: VOIP 1.1 Content-Length: 0 2019-01-09 17:19:19:598 [belle-sip] MESSAGE channel [0x1083e8000] [336] bytes parsed 2019-01-09 17:19:19:598 [belle-sip] MESSAGE Found transaction matching response. 2019-01-09 17:19:19:598 [belle-sip] MESSAGE Changing [client] [BYE] transaction [0x1c436ffc0], from state [TRYING] to [PROCEEDING] 2019-01-09 17:19:19:598 [belle-sip] MESSAGE Dialog [0x1c01d9410]: now updated by transaction [0x1c436ffc0]. 2019-01-09 17:19:19:598 [bctbx] MESSAGE op [0x10852e0f0] : set_or_update_dialog() current=[0x1c01d9410] new=[0x1c01d9410] 2019-01-09 17:19:19:599 [bctbx] MESSAGE Op [0x10852e0f0] receiving call response [100], dialog is [0x1c01d9410] in state [BELLE_SIP_DIALOG_CONFIRMED] 2019-01-09 17:19:19:599 [belle-sip] MESSAGE Background task belle-sip transaction(0x1c436a140) started. Unknown remaining time since application is not fully in background. 2019-01-09 17:19:19:599 [belle-sip] MESSAGE transaction [0x1c436a140]: starting transaction background task with id=[c12]. 2019-01-09 17:19:19:599 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=90772518119, realm=sip.peoplefone.at 2019-01-09 17:19:19:599 [belle-sip] MESSAGE Auth info found for [90772518119] realm [sip.peoplefone.at] 2019-01-09 17:19:19:600 [belle-sip] MESSAGE Dialog [0x1c01d9410]: now updated by transaction [0x1c436a140]. 2019-01-09 17:19:19:600 [belle-sip] MESSAGE Changing [client] [BYE] transaction [0x1c436a140], from state [INIT] to [TRYING] 2019-01-09 17:19:19:601 [belle-sip] MESSAGE channel [0x1083e8000]: message sent to [UDP://sip.peoplefone.at:5060], size: [632] bytes BYE sip:address@hidden:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.71:49268;branch=z9hG4bK.5CxLlTIyZ;rport From: "peoplefone" ;tag=3-E0YDHEP To: "+ 4935xxxxxx26" ;tag=11d896f4 CSeq: 23 BYE Call-ID: uWJP4jAB0t Max-Forwards: 70 Route: User-Agent: Linphone_iPhone.SE_iOS11.4.1/4.0.2-2-gf18fb2a09 (belle-sip/1.6.3) Proxy-Authorization: Digest realm="sip.peoplefone.at", nonce="XDYgC1w2Ht95rFLuX6Wgotx6fWch4AVq", username="90772518119", uri="sip:address@hidden:5060", response="61e93efb22b02523b2277ca1eedcd4ab" 2019-01-09 17:19:19:601 [belle-sip] MESSAGE channel [0x1083e8000]: ending recv background task with id=[c11]. 2019-01-09 17:19:19:605 [ios] DEBUG (roviderDelegate.m:145 ) CallKit : Ending the Call 2019-01-09 17:19:19:676 [ios] DEBUG (roviderDelegate.m:278 ) CallKit : Call changed 2019-01-09 17:19:19:678 [belle-sip] MESSAGE subscribe.linphone.org resolved to 2001:41d0:8:6e48:: 2019-01-09 17:19:19:678 [belle-sip] MESSAGE subscribe.linphone.org resolved to 37.59.51.72 2019-01-09 17:19:19:678 [belle-sip] MESSAGE channel 0x108600000: state RES_DONE 2019-01-09 17:19:19:678 [belle-sip] MESSAGE channel 0x108600000: state CONNECTING 2019-01-09 17:19:19:678 [belle-sip] MESSAGE Trying to connect to [TLS://2001:41d0:8:6e48:::444] 2019-01-09 17:19:19:684 [belle-sip] ERROR stream connect failed No route to host 2019-01-09 17:19:19:684 [belle-sip] ERROR Cannot connect to [TLS://subscribe.linphone.org:444] 2019-01-09 17:19:19:684 [belle-sip] MESSAGE channel 0x108600000: state ERROR 2019-01-09 17:19:19:684 [belle-sip] MESSAGE channel 0x108600000: state RETRY 2019-01-09 17:19:19:685 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:19:19:685 [belle-sip] MESSAGE channel [0x1083e8000]: starting recv background task with id=[c14]. 2019-01-09 17:19:19:686 [belle-sip] MESSAGE channel [0x1083e8000]: received [336] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 100 TRYING Via: SIP/2.0/UDP 192.168.254.71:49268;branch=z9hG4bK.TUDVkPNYx;rport=27054;received=89.144.213.192 From: "peoplefone" ;tag=3-E0YDHEP To: "+ 4935xxxxxx26" ;tag=11d896f4 CSeq: 22 BYE Call-ID: uWJP4jAB0t Server: VOIP 1.1 Content-Length: 0 2019-01-09 17:19:19:689 [belle-sip] MESSAGE channel [0x1083e8000] [336] bytes parsed 2019-01-09 17:19:19:690 [belle-sip] MESSAGE Found transaction matching response. 2019-01-09 17:19:19:690 [belle-sip] MESSAGE channel [0x1083e8000]: ending recv background task with id=[c14]. 2019-01-09 17:19:19:706 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:19:19:707 [belle-sip] MESSAGE channel [0x1083e8000]: starting recv background task with id=[c16]. 2019-01-09 17:19:19:707 [belle-sip] MESSAGE channel [0x1083e8000]: received [314] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 200 OK To: "+ 4935xxxxxx26" ;tag=11d896f4 Via: SIP/2.0/UDP 192.168.254.71:49268;received=89.144.213.192;branch=z9hG4bK.TUDVkPNYx;rport=27054 CSeq: 22 BYE Call-ID: uWJP4jAB0t From: "peoplefone" ;tag=3-E0YDHEP Content-Length: 0 2019-01-09 17:19:19:709 [belle-sip] MESSAGE channel [0x1083e8000] [314] bytes parsed 2019-01-09 17:19:19:709 [belle-sip] MESSAGE Found transaction matching response. 2019-01-09 17:19:19:709 [belle-sip] MESSAGE Changing [client] [BYE] transaction [0x1c436ffc0], from state [PROCEEDING] to [COMPLETED] 2019-01-09 17:19:19:709 [belle-sip] MESSAGE Dialog [0x1c01d9410]: now updated by transaction [0x1c436ffc0]. 2019-01-09 17:19:19:709 [belle-sip] MESSAGE Dialog [0x1c01d9410] deleted (is_expired=0) 2019-01-09 17:19:19:710 [bctbx] MESSAGE op [0x10852e0f0] : set_or_update_dialog() current=[0x1c01d9410] new=[0x1c01d9410] 2019-01-09 17:19:19:710 [bctbx] MESSAGE Op [0x10852e0f0] receiving call response [200], dialog is [0x1c01d9410] in state [BELLE_SIP_DIALOG_TERMINATED] 2019-01-09 17:19:19:710 [belle-sip] MESSAGE channel [0x1083e8000]: ending recv background task with id=[c16]. 2019-01-09 17:19:19:726 [belle-sip] MESSAGE channel 0x108600000: state CONNECTING 2019-01-09 17:19:19:726 [belle-sip] MESSAGE Trying to connect to [TLS://::ffff:37.59.51.72:444] 2019-01-09 17:19:19:728 [ios] MESSAGE (LinphoneManager.m:2471) Current audio route is [Speaker] 2019-01-09 17:19:19:757 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:19:19:757 [belle-sip] MESSAGE channel [0x1083e8000]: starting recv background task with id=[c18]. 2019-01-09 17:19:19:758 [belle-sip] MESSAGE channel [0x1083e8000]: received [336] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 100 TRYING Via: SIP/2.0/UDP 192.168.254.71:49268;branch=z9hG4bK.5CxLlTIyZ;rport=27054;received=89.144.213.192 From: "peoplefone" ;tag=3-E0YDHEP To: "+ 4935xxxxxx26" ;tag=11d896f4 CSeq: 23 BYE Call-ID: uWJP4jAB0t Server: VOIP 1.1 Content-Length: 0 2019-01-09 17:19:19:759 [belle-sip] MESSAGE channel [0x1083e8000] [336] bytes parsed 2019-01-09 17:19:19:759 [belle-sip] MESSAGE Found transaction matching response. 2019-01-09 17:19:19:759 [belle-sip] MESSAGE Changing [client] [BYE] transaction [0x1c436a140], from state [TRYING] to [PROCEEDING] 2019-01-09 17:19:19:759 [belle-sip] MESSAGE Dialog [0x1c01d9410]: now updated by transaction [0x1c436a140]. 2019-01-09 17:19:19:759 [bctbx] MESSAGE op [0x10852e0f0] : set_or_update_dialog() current=[0x1c01d9410] new=[0x1c01d9410] 2019-01-09 17:19:19:759 [bctbx] MESSAGE Op [0x10852e0f0] receiving call response [100], dialog is [0x1c01d9410] in state [BELLE_SIP_DIALOG_TERMINATED] 2019-01-09 17:19:19:759 [belle-sip] MESSAGE channel [0x1083e8000]: ending recv background task with id=[c18]. 2019-01-09 17:19:19:761 [bctbx] MESSAGE Dialog [0x1c01d9410] terminated for op [0x10852e0f0] 2019-01-09 17:19:19:913 [belle-sip] MESSAGE Channel [0x108600000]: Connected at TCP level, now doing TLS handshake with cname=subscribe.linphone.org 2019-01-09 17:19:19:914 [belle-sip] MESSAGE Channel [0x108600000]: SSL handshake in progress... 2019-01-09 17:19:19:914 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:19:19:914 [belle-sip] MESSAGE channel [0x1083e8000]: starting recv background task with id=[c1a]. 2019-01-09 17:19:19:914 [belle-sip] MESSAGE channel [0x1083e8000]: received [336] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 100 TRYING Via: SIP/2.0/UDP 192.168.254.71:49268;branch=z9hG4bK.5CxLlTIyZ;rport=27054;received=89.144.213.192 From: "peoplefone" ;tag=3-E0YDHEP To: "+ 4935xxxxxx26" ;tag=11d896f4 CSeq: 23 BYE Call-ID: uWJP4jAB0t Server: VOIP 1.1 Content-Length: 0 2019-01-09 17:19:19:915 [belle-sip] MESSAGE channel [0x1083e8000] [336] bytes parsed 2019-01-09 17:19:19:915 [belle-sip] MESSAGE Found transaction matching response. 2019-01-09 17:19:19:915 [belle-sip] MESSAGE channel [0x1083e8000]: ending recv background task with id=[c1a]. 2019-01-09 17:19:19:915 [bctbx] MESSAGE CallSession [0x1c40ba1d8] moving from state State::End to State::Released 2019-01-09 17:19:19:916 [liblinphone] MESSAGE Linphone core [0x104823600] notified [call_state_changed] 2019-01-09 17:19:19:917 [bctbx] MESSAGE op [0x10852e0f0] : set_or_update_dialog() current=[0x1c01d9410] new=[0x0] 2019-01-09 17:19:19:917 [ios] DEBUG (roviderDelegate.m:258 ) CallKit : Audio session deactivated 2019-01-09 17:19:19:926 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:19:19:926 [belle-sip] MESSAGE channel [0x1083e8000]: starting recv background task with id=[c1c]. 2019-01-09 17:19:19:927 [belle-sip] MESSAGE channel [0x1083e8000]: received [314] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 200 OK To: "+ 4935xxxxxx26" ;tag=11d896f4 Via: SIP/2.0/UDP 192.168.254.71:49268;received=89.144.213.192;branch=z9hG4bK.5CxLlTIyZ;rport=27054 CSeq: 23 BYE Call-ID: uWJP4jAB0t From: "peoplefone" ;tag=3-E0YDHEP Content-Length: 0 2019-01-09 17:19:19:928 [belle-sip] MESSAGE channel [0x1083e8000] [314] bytes parsed 2019-01-09 17:19:19:928 [belle-sip] MESSAGE Found transaction matching response. 2019-01-09 17:19:19:928 [belle-sip] MESSAGE Changing [client] [BYE] transaction [0x1c436a140], from state [PROCEEDING] to [COMPLETED] 2019-01-09 17:19:19:928 [bctbx] MESSAGE Op [0x10852e0f0] is terminated, nothing to do with this [200] 2019-01-09 17:19:19:928 [belle-sip] MESSAGE channel [0x1083e8000]: ending recv background task with id=[c1c]. 2019-01-09 17:19:19:989 [belle-sip] MESSAGE Found certificate depth=[2], flags=[]: cert. version : 3 serial number : 01:FD:6D:30:FC:A3:CA:51:A8:1B:BC:64:0E:35:03:2D issuer name : C=US, ST=New Jersey, L=Jersey City, O=The USERTRUST Network, CN=USERTrust RSA Certification Authority subject name : C=US, ST=New Jersey, L=Jersey City, O=The USERTRUST Network, CN=USERTrust RSA Certification Authority issued on : 2010-02-01 00:00:00 expires on : 2038-01-18 23:59:59 signed using : RSA with SHA-384 RSA key size : 4096 bits basic constraints : CA=true key usage : Key Cert Sign, CRL Sign 2019-01-09 17:19:19:989 [belle-sip] MESSAGE Found certificate depth=[1], flags=[]: cert. version : 3 serial number : 05:E4:DC:3B:94:38:AB:3B:85:97:CB:A6:A1:98:50:E3 issuer name : C=US, ST=New Jersey, L=Jersey City, O=The USERTRUST Network, CN=USERTrust RSA Certification Authority subject name : C=FR, ST=Paris, L=Paris, O=Gandi, CN=Gandi Standard SSL CA 2 issued on : 2014-09-12 00:00:00 expires on : 2024-09-11 23:59:59 signed using : RSA with SHA-384 RSA key size : 2048 bits basic constraints : CA=true, max_pathlen=0 key usage : Digital Signature, Key Cert Sign, CRL Sign ext key usage : TLS Web Server Authentication, TLS Web Client Authentication 2019-01-09 17:19:19:990 [belle-sip] MESSAGE Found certificate depth=[0], flags=[]: cert. version : 3 serial number : D5:03:16:62:5A:5D:5D:B9:F1:21:B9:64:E6:2A:09:7A issuer name : C=FR, ST=Paris, L=Paris, O=Gandi, CN=Gandi Standard SSL CA 2 subject name : OU=Domain Control Validated, OU=Gandi Standard Wildcard SSL, CN=*.linphone.org issued on : 2018-01-04 00:00:00 expires on : 2020-01-28 23:59:59 signed using : RSA with SHA-256 RSA key size : 2048 bits basic constraints : CA=false subject alt name : *.linphone.org, linphone.org key usage : Digital Signature, Key Encipherment ext key usage : TLS Web Server Authentication, TLS Web Client Authentication 2019-01-09 17:19:19:996 [belle-sip] MESSAGE Channel [0x108600000]: SSL handshake in progress... 2019-01-09 17:19:20:047 [belle-sip] MESSAGE Channel [0x108600000]: SSL handshake finished, SSL version is [TLSv1.2], selected ciphersuite is [TLS-ECDHE-RSA-WITH-AES-256-GCM-SHA384] 2019-01-09 17:19:20:047 [belle-sip] MESSAGE Channel has local address 192.168.254.71:52002 2019-01-09 17:19:20:047 [belle-sip] MESSAGE channel 0x108600000: state READY 2019-01-09 17:19:20:047 [belle-sip] MESSAGE channel [0x108600000]: message sent to [TLS://subscribe.linphone.org:444], size: [409] bytes POST /wizard.php HTTP/1.1 Content-Type: text/xml Host: subscribe.linphone.org:444 Content-Length: 301 get_phone_number_for_account 90772518119 sip.linphone.org 2019-01-09 17:19:20:047 [belle-sip] MESSAGE channel [0x108600000]: ending send background task with id=[c0f]. 2019-01-09 17:19:20:126 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:19:20:127 [belle-sip] MESSAGE channel [0x108600000]: starting recv background task with id=[c27]. 2019-01-09 17:19:20:127 [belle-sip] MESSAGE channel [0x108600000]: received [193] new bytes from [TLS://subscribe.linphone.org:444]: HTTP/1.1 200 OK Date: Wed, 09 Jan 2019 16:19:20 GMT Server: Apache/2.2.22 (Debian) X-Powered-By: PHP/5.4.45-0+deb7u14 Vary: Accept-Encoding Content-Length: 185 Content-Type: text/html 2019-01-09 17:19:20:128 [belle-sip] MESSAGE channel [0x108600000] [193] bytes parsed 2019-01-09 17:19:20:147 [belle-sip] MESSAGE channel [0x108600000]: received [185] new bytes from [TLS://subscribe.linphone.org:444]: ERROR_ACCOUNT_DOESNT_EXIST 2019-01-09 17:19:20:147 [belle-sip] MESSAGE channel [0x108600000] read [185] bytes of body from [subscribe.linphone.org:444] 2019-01-09 17:19:20:148 [belle-sip] MESSAGE channel [0x108600000]: ending recv background task with id=[c27]. 2019-01-09 17:19:20:407 [belle-sip] MESSAGE Changing [client] [INVITE] transaction [0x1c419a410], from state [ACCEPTED] to [TERMINATED] 2019-01-09 17:19:20:408 [belle-sip] MESSAGE Client INVITE transaction [0x1c419a410] terminated 2019-01-09 17:19:20:408 [belle-sip] MESSAGE transaction [0x1c419a410]: ending transaction background task with id=[4b6]. 2019-01-09 17:19:20:410 [belle-sip] MESSAGE Transaction [0x1c419a410] deleted 2019-01-09 17:19:24:727 [belle-sip] MESSAGE Changing [client] [BYE] transaction [0x1c436ffc0], from state [COMPLETED] to [TERMINATED] 2019-01-09 17:19:24:728 [belle-sip] MESSAGE Client BYE transaction [0x1c436ffc0] terminated 2019-01-09 17:19:24:728 [belle-sip] MESSAGE transaction [0x1c436ffc0]: ending transaction background task with id=[c0e]. 2019-01-09 17:19:24:733 [belle-sip] MESSAGE Transaction [0x1c436ffc0] deleted 2019-01-09 17:19:24:948 [belle-sip] MESSAGE Changing [client] [BYE] transaction [0x1c436a140], from state [COMPLETED] to [TERMINATED] 2019-01-09 17:19:24:948 [belle-sip] MESSAGE Client BYE transaction [0x1c436a140] terminated 2019-01-09 17:19:24:949 [bctbx] MESSAGE Destroying op [0x10852e0f0] of type [SalOpCall] 2019-01-09 17:19:24:949 [belle-sip] MESSAGE Transaction [0x1c419a8f0] deleted 2019-01-09 17:19:24:949 [belle-sip] MESSAGE transaction [0x1c436a140]: ending transaction background task with id=[c12]. 2019-01-09 17:19:24:953 [belle-sip] MESSAGE Transaction [0x1c436a140] deleted 2019-01-09 17:19:25:128 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-09 17:19:25:128 [belle-sip] MESSAGE channel [0x108600000]: starting recv background task with id=[d22]. 2019-01-09 17:19:25:128 [belle-sip] MESSAGE channel 0x108600000: state DISCONNECTED 2019-01-09 17:19:25:128 [belle-sip] MESSAGE channel [0x108600000]: ending recv background task with id=[d22]. 2019-01-09 17:19:25:134 [belle-sip] MESSAGE channel [0x108600000] removed from http provider. 2019-01-09 17:19:25:136 [belle-sip] MESSAGE Channel [0x108600000] destroyed 2019-01-09 17:19:26:573 [ios] MESSAGE (deMenuTableView.m:136 ) Entry Settings has been tapped 2019-01-09 17:19:26:573 [ios] MESSAGE ( PhoneMainView.m:681 ) Change current view to SettingsView 2019-01-09 17:19:26:575 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=6599142, realm= 2019-01-09 17:19:26:575 [ios] WARNING (reSettingsStore.m:137 ) Codec speex/32000 supported by core is not shown in iOS app config view. 2019-01-09 17:19:26:575 [ios] WARNING (reSettingsStore.m:137 ) Codec L16/44100 supported by core is not shown in iOS app config view. 2019-01-09 17:19:26:575 [ios] WARNING (reSettingsStore.m:137 ) Codec L16/44100 supported by core is not shown in iOS app config view. 2019-01-09 17:19:26:587 [liblinphone] MESSAGE linphone_core_set_playback_gain_db(): no active call. 2019-01-09 17:19:26:587 [liblinphone] MESSAGE linphone_core_set_mic_gain_db(): no active call. 2019-01-09 17:19:26:588 [liblinphone] MESSAGE Starting stun server resolution [stun.linphone.org] 2019-01-09 17:19:26:589 [belle-sip] MESSAGE Resolver is using DNS server(s): 2019-01-09 17:19:26:589 [belle-sip] MESSAGE 192.168.254.254 2019-01-09 17:19:26:589 [belle-sip] MESSAGE resolver_process_data dns_res_check() in progress 2019-01-09 17:19:26:590 [belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop 2019-01-09 17:19:26:590 [bctbx] MESSAGE Sal nat helper [enabled] 2019-01-09 17:19:26:590 [bctbx] MESSAGE Sal use rport [enabled] 2019-01-09 17:19:26:618 [belle-sip] MESSAGE SRV _stun._udp.stun.linphone.org resolved to [target:sip1.linphone.org. port:3478 prio:10 weight:100] 2019-01-09 17:19:26:618 [belle-sip] MESSAGE SRV _stun._udp.stun.linphone.org resolved to [target:stun.linphone.org. port:3478 prio:0 weight:100] 2019-01-09 17:19:26:618 [belle-sip] MESSAGE Starting A/AAAA query for srv result [stun.linphone.org.] 2019-01-09 17:19:26:619 [belle-sip] MESSAGE Resolver is using DNS server(s): 2019-01-09 17:19:26:619 [belle-sip] MESSAGE 192.168.254.254 2019-01-09 17:19:26:619 [belle-sip] MESSAGE resolver_process_data dns_res_check() in progress 2019-01-09 17:19:26:619 [belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop 2019-01-09 17:19:26:620 [belle-sip] MESSAGE Resolver is using DNS server(s): 2019-01-09 17:19:26:620 [belle-sip] MESSAGE 192.168.254.254 2019-01-09 17:19:26:620 [belle-sip] MESSAGE resolver_process_data dns_res_check() in progress 2019-01-09 17:19:26:620 [belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop 2019-01-09 17:19:26:620 [belle-sip] MESSAGE Starting A/AAAA query for srv result [sip1.linphone.org.] 2019-01-09 17:19:26:621 [belle-sip] MESSAGE Resolver is using DNS server(s): 2019-01-09 17:19:26:621 [belle-sip] MESSAGE 192.168.254.254 2019-01-09 17:19:26:621 [belle-sip] MESSAGE resolver_process_data dns_res_check() in progress 2019-01-09 17:19:26:621 [belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop 2019-01-09 17:19:26:622 [belle-sip] MESSAGE Resolver is using DNS server(s): 2019-01-09 17:19:26:622 [belle-sip] MESSAGE 192.168.254.254 2019-01-09 17:19:26:622 [belle-sip] MESSAGE resolver_process_data dns_res_check() in progress 2019-01-09 17:19:26:622 [belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop 2019-01-09 17:19:26:626 [belle-sip] MESSAGE sip1.linphone.org. resolved to 91.121.209.194 2019-01-09 17:19:26:626 [belle-sip] MESSAGE A query finished for srv result [sip1.linphone.org.] 2019-01-09 17:19:26:626 [belle-sip] MESSAGE stun.linphone.org. resolved to 37.59.51.72 2019-01-09 17:19:26:626 [belle-sip] MESSAGE A query finished for srv result [stun.linphone.org.] 2019-01-09 17:19:26:626 [belle-sip] MESSAGE All A/AAAA results for combined resolution have arrived. 2019-01-09 17:19:26:626 [liblinphone] MESSAGE Stun server resolution successful.