2019-01-08 09:49:26:625 [belle-sip] MESSAGE Changing [client] [INVITE] transaction [0x1c0190e90], from state [INIT] to [CALLING] 2019-01-08 09:49:26:626 [belle-sip] MESSAGE channel [0x1096bc000]: message sent to [UDP://sip.peoplefone.at:5060], size: [2441] bytes INVITE sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.254.71:63615;branch=z9hG4bK.sPI5xZQjP;rport From: "peoplefone" ;tag=EmS~BpAtv To: "+4935xxxxx26" CSeq: 20 INVITE Call-ID: 6VNdbXh85P 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: 1747 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 1941 3859 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 7286 RTP/AVP 96 97 98 0 8 3 9 99 18 100 102 103 104 105 106 101 107 108 109 110 111 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/16000 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/22050 a=fmtp:102 config=F8EE2000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5 a=rtpmap:103 mpeg4-generic/32000 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/44100 a=fmtp:104 config=F8E82000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5 a=rtpmap:105 mpeg4-generic/48000 a=fmtp:105 config=F8EE2000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5 a=rtpmap:106 iSAC/16000 a=rtpmap:101 telephone-event/48000 a=rtpmap:107 telephone-event/16000 a=rtpmap:108 telephone-event/8000 a=rtpmap:109 telephone-event/22050 a=rtpmap:110 telephone-event/32000 a=rtpmap:111 telephone-event/44100 a=zrtp-hash:1.10 72a0422ad206ea7bfe7f3c173fd94c65f7210dd1c56b4228aafa86ecc7f68c2f a=rtcp-fb:* trr-int 1000 a=rtcp-fb:* ccm tmmbr 2019-01-08 09:49:26:626 [bctbx] MESSAGE CallSession [0x1c42a9198] moving from state State::OutgoingInit to State::OutgoingProgress 2019-01-08 09:49:26:627 [liblinphone] MESSAGE Linphone core [0x10601f400] notified [call_state_changed] 2019-01-08 09:49:26:628 [belle-sip] MESSAGE Garbage collecting unowned object of type belle_sdp_session_description_t 2019-01-08 09:49:26:642 [ios] MESSAGE (LinphoneManager.m:2471) Current audio route is [Receiver] 2019-01-08 09:49:26:694 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-08 09:49:26:694 [belle-sip] MESSAGE channel [0x1096bc000]: starting recv background task with id=[136b9]. 2019-01-08 09:49:26:695 [belle-sip] MESSAGE channel [0x1096bc000]: received [323] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 100 TRYING Via: SIP/2.0/UDP 192.168.254.71:63615;branch=z9hG4bK.sPI5xZQjP;rport=63615;received=46.75.33.19 From: "peoplefone" ;tag=EmS~BpAtv To: "+4935xxxxx26" CSeq: 20 INVITE Call-ID: 6VNdbXh85P Server: VOIP 1.1 Content-Length: 0 2019-01-08 09:49:26:696 [belle-sip] MESSAGE channel [0x1096bc000] [323] bytes parsed 2019-01-08 09:49:26:696 [belle-sip] MESSAGE Found transaction matching response. 2019-01-08 09:49:26:696 [belle-sip] MESSAGE Changing [client] [INVITE] transaction [0x1c0190e90], from state [CALLING] to [PROCEEDING] 2019-01-08 09:49:26:696 [bctbx] MESSAGE op [0x10b001e20] : set_or_update_dialog() current=[0x0] new=[0x0] 2019-01-08 09:49:26:696 [bctbx] MESSAGE Op [0x10b001e20] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL] 2019-01-08 09:49:26:696 [belle-sip] MESSAGE channel [0x1096bc000]: ending recv background task with id=[136b9]. 2019-01-08 09:49:26:714 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-08 09:49:26:714 [belle-sip] MESSAGE channel [0x1096bc000]: starting recv background task with id=[136bb]. 2019-01-08 09:49:26:715 [belle-sip] MESSAGE channel [0x1096bc000]: received [323] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 100 TRYING Via: SIP/2.0/UDP 192.168.254.71:63615;branch=z9hG4bK.sPI5xZQjP;rport=63615;received=46.75.33.19 From: "peoplefone" ;tag=EmS~BpAtv To: "+4935xxxxx26" CSeq: 20 INVITE Call-ID: 6VNdbXh85P Server: VOIP 1.1 Content-Length: 0 2019-01-08 09:49:26:716 [belle-sip] MESSAGE channel [0x1096bc000] [323] bytes parsed 2019-01-08 09:49:26:716 [belle-sip] MESSAGE Found transaction matching response. 2019-01-08 09:49:26:716 [bctbx] MESSAGE op [0x10b001e20] : set_or_update_dialog() current=[0x0] new=[0x0] 2019-01-08 09:49:26:716 [bctbx] MESSAGE Op [0x10b001e20] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL] 2019-01-08 09:49:26:716 [belle-sip] MESSAGE channel [0x1096bc000]: ending recv background task with id=[136bb]. 2019-01-08 09:49:26:734 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-08 09:49:26:734 [belle-sip] MESSAGE channel [0x1096bc000]: starting recv background task with id=[136bd]. 2019-01-08 09:49:26:735 [belle-sip] MESSAGE channel [0x1096bc000]: received [484] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.254.71:63615;branch=z9hG4bK.sPI5xZQjP;rport=63615;received=46.75.33.19 From: "peoplefone" ;tag=EmS~BpAtv To: "+4935xxxxx26" ;tag=c8461efb6a64f848fae3487c0da16cdc.0669 CSeq: 20 INVITE Call-ID: 6VNdbXh85P Proxy-Authenticate: Digest realm="sip.peoplefone.at", nonce="XDRlQFw0ZBQBoQNfPfw1fEhCYP+CnhS/" Server: VOIP 1.1 Content-Length: 0 2019-01-08 09:49:26:736 [belle-sip] MESSAGE channel [0x1096bc000] [484] bytes parsed 2019-01-08 09:49:26:736 [belle-sip] MESSAGE Found transaction matching response. 2019-01-08 09:49:26:736 [belle-sip] MESSAGE Changing [client] [INVITE] transaction [0x1c0190e90], from state [PROCEEDING] to [COMPLETED] 2019-01-08 09:49:26:737 [belle-sip] MESSAGE channel [0x1096bc000]: 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:63615;branch=z9hG4bK.sPI5xZQjP;rport Call-ID: 6VNdbXh85P From: "peoplefone" ;tag=EmS~BpAtv To: "+4935xxxxx26" ;tag=c8461efb6a64f848fae3487c0da16cdc.0669 Contact: ;+org.linphone.specs=groupchat Max-Forwards: 70 CSeq: 20 ACK 2019-01-08 09:49:26:737 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=90772518119, realm=sip.peoplefone.at 2019-01-08 09:49:26:737 [belle-sip] MESSAGE Auth info found for [90772518119] realm [sip.peoplefone.at] 2019-01-08 09:49:26:737 [belle-sip] MESSAGE Background task belle-sip transaction(0x1c4191510) started. Unknown remaining time since application is not fully in background. 2019-01-08 09:49:26:737 [belle-sip] MESSAGE transaction [0x1c4191510]: starting transaction background task with id=[136be]. 2019-01-08 09:49:26:737 [belle-sip] MESSAGE Changing [client] [INVITE] transaction [0x1c4191510], from state [INIT] to [CALLING] 2019-01-08 09:49:26:738 [belle-sip] MESSAGE channel [0x1096bc000]: message sent to [UDP://sip.peoplefone.at:5060], size: [2652] bytes INVITE sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.254.71:63615;branch=z9hG4bK.lKNYwlWAH;rport From: "peoplefone" ;tag=EmS~BpAtv To: "+4935xxxxx26" CSeq: 21 INVITE Call-ID: 6VNdbXh85P 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: 1747 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="XDRlQFw0ZBQBoQNfPfw1fEhCYP+CnhS/", username="90772518119", uri="sip:address@hidden", response="d6491f0f1e868093ef534377d919f463" v=0 o=90772518119 1941 3859 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 7286 RTP/AVP 96 97 98 0 8 3 9 99 18 100 102 103 104 105 106 101 107 108 109 110 111 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/16000 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/22050 a=fmtp:102 config=F8EE2000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5 a=rtpmap:103 mpeg4-generic/32000 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/44100 a=fmtp:104 config=F8E82000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5 a=rtpmap:105 mpeg4-generic/48000 a=fmtp:105 config=F8EE2000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5 a=rtpmap:106 iSAC/16000 a=rtpmap:101 telephone-event/48000 a=rtpmap:107 telephone-event/16000 a=rtpmap:108 telephone-event/8000 a=rtpmap:109 telephone-event/22050 a=rtpmap:110 telephone-event/32000 a=rtpmap:111 telephone-event/44100 a=zrtp-hash:1.10 72a0422ad206ea7bfe7f3c173fd94c65f7210dd1c56b4228aafa86ecc7f68c2f a=rtcp-fb:* trr-int 1000 a=rtcp-fb:* ccm tmmbr 2019-01-08 09:49:26:738 [belle-sip] MESSAGE channel [0x1096bc000]: ending recv background task with id=[136bd]. 2019-01-08 09:49:26:834 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-08 09:49:26:834 [belle-sip] MESSAGE channel [0x1096bc000]: starting recv background task with id=[136c4]. 2019-01-08 09:49:26:835 [belle-sip] MESSAGE channel [0x1096bc000]: received [323] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 100 TRYING Via: SIP/2.0/UDP 192.168.254.71:63615;branch=z9hG4bK.lKNYwlWAH;rport=63615;received=46.75.33.19 From: "peoplefone" ;tag=EmS~BpAtv To: "+4935xxxxx26" CSeq: 21 INVITE Call-ID: 6VNdbXh85P Server: VOIP 1.1 Content-Length: 0 2019-01-08 09:49:26:836 [belle-sip] MESSAGE channel [0x1096bc000] [323] bytes parsed 2019-01-08 09:49:26:836 [belle-sip] MESSAGE Found transaction matching response. 2019-01-08 09:49:26:836 [belle-sip] MESSAGE Changing [client] [INVITE] transaction [0x1c4191510], from state [CALLING] to [PROCEEDING] 2019-01-08 09:49:26:836 [bctbx] MESSAGE op [0x10b001e20] : set_or_update_dialog() current=[0x0] new=[0x0] 2019-01-08 09:49:26:836 [bctbx] MESSAGE Op [0x10b001e20] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL] 2019-01-08 09:49:26:836 [belle-sip] MESSAGE channel [0x1096bc000]: ending recv background task with id=[136c4]. 2019-01-08 09:49:26:894 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-08 09:49:26:894 [belle-sip] MESSAGE channel [0x1096bc000]: starting recv background task with id=[136c8]. 2019-01-08 09:49:26:895 [belle-sip] MESSAGE channel [0x1096bc000]: received [323] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 100 TRYING Via: SIP/2.0/UDP 192.168.254.71:63615;branch=z9hG4bK.lKNYwlWAH;rport=63615;received=46.75.33.19 From: "peoplefone" ;tag=EmS~BpAtv To: "+4935xxxxx26" CSeq: 21 INVITE Call-ID: 6VNdbXh85P Server: VOIP 1.1 Content-Length: 0 2019-01-08 09:49:26:896 [belle-sip] MESSAGE channel [0x1096bc000] [323] bytes parsed 2019-01-08 09:49:26:896 [belle-sip] MESSAGE Found transaction matching response. 2019-01-08 09:49:26:896 [bctbx] MESSAGE op [0x10b001e20] : set_or_update_dialog() current=[0x0] new=[0x0] 2019-01-08 09:49:26:896 [bctbx] MESSAGE Op [0x10b001e20] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL] 2019-01-08 09:49:26:896 [belle-sip] MESSAGE channel [0x1096bc000]: ending recv background task with id=[136c8]. 2019-01-08 09:49:26:914 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-08 09:49:26:914 [belle-sip] MESSAGE channel [0x1096bc000]: starting recv background task with id=[136ca]. 2019-01-08 09:49:26:915 [belle-sip] MESSAGE channel [0x1096bc000]: received [355] 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:63615;branch=z9hG4bK.lKNYwlWAH;rport=63615;received=46.75.33.19 From: "peoplefone" ;tag=EmS~BpAtv To: "+4935xxxxx26" CSeq: 21 INVITE Call-ID: 6VNdbXh85P Server: VOIP 1.1 Content-Length: 0 2019-01-08 09:49:26:916 [belle-sip] MESSAGE channel [0x1096bc000] [355] bytes parsed 2019-01-08 09:49:26:916 [belle-sip] MESSAGE Found transaction matching response. 2019-01-08 09:49:26:916 [bctbx] MESSAGE op [0x10b001e20] : set_or_update_dialog() current=[0x0] new=[0x0] 2019-01-08 09:49:26:916 [bctbx] MESSAGE Op [0x10b001e20] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL] 2019-01-08 09:49:26:916 [belle-sip] MESSAGE channel [0x1096bc000]: ending recv background task with id=[136ca]. 2019-01-08 09:49:27:014 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-08 09:49:27:014 [belle-sip] MESSAGE channel [0x1096bc000]: starting recv background task with id=[136d0]. 2019-01-08 09:49:27:015 [belle-sip] MESSAGE channel [0x1096bc000]: received [411] new bytes from [UDP://sip.peoplefone.at:5060]: SIP/2.0 488 Not Acceptable Here [Media Descriptions Syntax error while parsing the SDP] To: "+4935xxxxx26" ;tag=1530706b Via: SIP/2.0/UDP 192.168.254.71:63615;received=46.75.33.19;branch=z9hG4bK.lKNYwlWAH;rport=63615 CSeq: 21 INVITE Call-ID: 6VNdbXh85P From: "peoplefone" ;tag=EmS~BpAtv Content-Length: 0 X-src-ip: 95.128.80.93 2019-01-08 09:49:27:016 [belle-sip] MESSAGE channel [0x1096bc000] [411] bytes parsed 2019-01-08 09:49:27:016 [belle-sip] MESSAGE Found transaction matching response. 2019-01-08 09:49:27:016 [belle-sip] MESSAGE Changing [client] [INVITE] transaction [0x1c4191510], from state [PROCEEDING] to [COMPLETED] 2019-01-08 09:49:27:016 [belle-sip] MESSAGE channel [0x1096bc000]: message sent to [UDP://sip.peoplefone.at:5060], size: [441] bytes ACK sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.254.71:63615;branch=z9hG4bK.lKNYwlWAH;rport Call-ID: 6VNdbXh85P From: "peoplefone" ;tag=EmS~BpAtv To: "+4935xxxxx26" ;tag=1530706b Contact: ;+org.linphone.specs=groupchat Max-Forwards: 70 CSeq: 21 ACK 2019-01-08 09:49:27:017 [bctbx] MESSAGE op [0x10b001e20] : set_or_update_dialog() current=[0x0] new=[0x0] 2019-01-08 09:49:27:017 [bctbx] MESSAGE Op [0x10b001e20] receiving call response [488], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL] 2019-01-08 09:49:27:017 [bctbx] MESSAGE Outgoing CallSession [0x1c42a9198] failed with SRTP and/or AVPF enabled 2019-01-08 09:49:27:017 [bctbx] MESSAGE CallSession [0x1c42a9198] moving from state State::OutgoingProgress to State::Error 2019-01-08 09:49:27:017 [mediastreamer] MESSAGE ms_filter_unlink: MSRtpRecv:0x1c0138240,0-->MSAUWrite:0x1c01381a0,0 2019-01-08 09:49:27:017 [mediastreamer] MESSAGE =========================================================== 2019-01-08 09:49:27:017 [mediastreamer] MESSAGE FILTER USAGE STATISTICS 2019-01-08 09:49:27:017 [mediastreamer] MESSAGE Name Count Time/tick (ms) CPU Usage 2019-01-08 09:49:27:017 [mediastreamer] MESSAGE ----------------------------------------------------------- 2019-01-08 09:49:27:017 [mediastreamer] MESSAGE MSRtpRecv 39 0.028525 99.9999 2019-01-08 09:49:27:017 [mediastreamer] MESSAGE MSUlawEnc 0 0 0 2019-01-08 09:49:27:017 [mediastreamer] MESSAGE MSAQWrite 0 0 0 2019-01-08 09:49:27:017 [mediastreamer] MESSAGE MSUlawDec 0 0 0 2019-01-08 09:49:27:017 [mediastreamer] MESSAGE MSFilePlayer 0 0 0 2019-01-08 09:49:27:017 [mediastreamer] MESSAGE MSAudioFlowControl 0 0 0 2019-01-08 09:49:27:017 [mediastreamer] MESSAGE MSGenericPLC 0 0 0 2019-01-08 09:49:27:017 [mediastreamer] MESSAGE MSEqualizer 0 0 0 2019-01-08 09:49:27:017 [mediastreamer] MESSAGE MSAudioMixer 0 0 0 2019-01-08 09:49:27:017 [mediastreamer] MESSAGE MSResample 0 0 0 2019-01-08 09:49:27:017 [mediastreamer] MESSAGE MSVolume 0 0 0 2019-01-08 09:49:27:017 [mediastreamer] MESSAGE MSAlawDec 0 0 0 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE MSAlawEnc 0 0 0 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE MSDtmfGen 0 0 0 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE MSAUWrite 0 0 0 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE MSAURead 0 0 0 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE MSItcSink 0 0 0 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE MSTee 0 0 0 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE MSSpeexEC 0 0 0 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE MSRtpSend 0 0 0 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE =========================================================== 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE =========================================================== 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE FILTER USAGE STATISTICS 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE Name Count Time/tick (ms) CPU Usage 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE ----------------------------------------------------------- 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE MSRtpRecv 39 0.028525 99.9999 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE MSUlawEnc 0 0 0 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE MSAQWrite 0 0 0 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE MSUlawDec 0 0 0 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE MSFilePlayer 0 0 0 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE MSAudioFlowControl 0 0 0 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE MSGenericPLC 0 0 0 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE MSEqualizer 0 0 0 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE MSAudioMixer 0 0 0 2019-01-08 09:49:27:018 [mediastreamer] MESSAGE MSResample 0 0 0 2019-01-08 09:49:27:019 [mediastreamer] MESSAGE MSVolume 0 0 0 2019-01-08 09:49:27:019 [mediastreamer] MESSAGE MSAlawDec 0 0 0 2019-01-08 09:49:27:019 [mediastreamer] MESSAGE MSAlawEnc 0 0 0 2019-01-08 09:49:27:019 [mediastreamer] MESSAGE MSDtmfGen 0 0 0 2019-01-08 09:49:27:019 [mediastreamer] MESSAGE MSAUWrite 0 0 0 2019-01-08 09:49:27:019 [mediastreamer] MESSAGE MSAURead 0 0 0 2019-01-08 09:49:27:019 [mediastreamer] MESSAGE MSItcSink 0 0 0 2019-01-08 09:49:27:019 [mediastreamer] MESSAGE MSTee 0 0 0 2019-01-08 09:49:27:019 [mediastreamer] MESSAGE MSSpeexEC 0 0 0 2019-01-08 09:49:27:019 [mediastreamer] MESSAGE MSRtpSend 0 0 0 2019-01-08 09:49:27:019 [mediastreamer] MESSAGE =========================================================== 2019-01-08 09:49:27:019 [bctbx] MESSAGE Notifying soundcard that we don't need it anymore for calls 2019-01-08 09:49:27:030 [mediastreamer] MESSAGE AudioUnit stopped 2019-01-08 09:49:27:076 [mediastreamer] MESSAGE AudioUnit destroyed 2019-01-08 09:49:27:077 [mediastreamer] MESSAGE Stopping ZRTP context on session [0x0] 2019-01-08 09:49:27:077 [mediastreamer] MESSAGE ZRTP context destroyed 2019-01-08 09:49:27:080 [mediastreamer] MESSAGE MSAudio MSTicker thread exiting 2019-01-08 09:49:27:080 [mediastreamer] MESSAGE Stopping ZRTP context on session [0x0] 2019-01-08 09:49:27:080 [mediastreamer] MESSAGE ZRTP context destroyed 2019-01-08 09:49:27:092 [bctbx] MESSAGE Resetting the current call 2019-01-08 09:49:27:093 [ios] MESSAGE (LinphoneManager.m:2471) Current audio route is [Receiver] 2019-01-08 09:49:27:096 [ios] MESSAGE ( PhoneMainView.m:664 ) PhoneMainView: Popping view CallOutgoingView, going to DialerView 2019-01-08 09:49:27:096 [ios] MESSAGE ( PhoneMainView.m:681 ) Change current view to DialerView 2019-01-08 09:49:27:112 [liblinphone] MESSAGE Linphone core [0x10601f400] notified [call_state_changed] 2019-01-08 09:49:27:112 [bctbx] MESSAGE Notifying soundcard that we don't need it anymore for calls 2019-01-08 09:49:27:112 [bctbx] MESSAGE CallSession [0x1c42a9198] moving from state State::Error to State::Released 2019-01-08 09:49:27:112 [liblinphone] MESSAGE Linphone core [0x10601f400] notified [call_state_changed] 2019-01-08 09:49:27:112 [bctbx] MESSAGE op [0x10b001e20] : set_or_update_dialog() current=[0x0] new=[0x0] 2019-01-08 09:49:27:112 [belle-sip] MESSAGE channel [0x1096bc000]: ending recv background task with id=[136d0]. 2019-01-08 09:49:27:131 [ios] DEBUG (roviderDelegate.m:145 ) CallKit : Ending the Call 2019-01-08 09:49:27:161 [belle-sip] MESSAGE Background task belle-sip send channel started. Unknown remaining time since application is not fully in background. 2019-01-08 09:49:27:161 [belle-sip] MESSAGE channel [0x10a150000]: starting send background task with id=[136d2]. 2019-01-08 09:49:27:161 [belle-sip] MESSAGE channel [0x10a150000]: starting resolution of subscribe.linphone.org 2019-01-08 09:49:27:161 [belle-sip] MESSAGE channel 0x10a150000: state RES_IN_PROGRESS 2019-01-08 09:49:27:162 [belle-sip] MESSAGE Resolver is using DNS server(s): 2019-01-08 09:49:27:162 [belle-sip] MESSAGE 192.168.254.254 2019-01-08 09:49:27:162 [belle-sip] MESSAGE resolver_process_data dns_res_check() in progress 2019-01-08 09:49:27:162 [belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop 2019-01-08 09:49:27:163 [belle-sip] MESSAGE Resolver is using DNS server(s): 2019-01-08 09:49:27:163 [belle-sip] MESSAGE 192.168.254.254 2019-01-08 09:49:27:163 [belle-sip] MESSAGE resolver_process_data dns_res_check() in progress 2019-01-08 09:49:27:163 [belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop 2019-01-08 09:49:27:167 [ios] DEBUG (roviderDelegate.m:278 ) CallKit : Call changed 2019-01-08 09:49:27:176 [belle-sip] MESSAGE subscribe.linphone.org resolved to 2001:41d0:8:6e48:: 2019-01-08 09:49:27:176 [belle-sip] MESSAGE subscribe.linphone.org resolved to 37.59.51.72 2019-01-08 09:49:27:176 [belle-sip] MESSAGE channel 0x10a150000: state RES_DONE 2019-01-08 09:49:27:176 [belle-sip] MESSAGE channel 0x10a150000: state CONNECTING 2019-01-08 09:49:27:176 [belle-sip] MESSAGE Trying to connect to [TLS://2001:41d0:8:6e48:::444] 2019-01-08 09:49:27:180 [belle-sip] ERROR stream connect failed No route to host 2019-01-08 09:49:27:180 [belle-sip] ERROR Cannot connect to [TLS://subscribe.linphone.org:444] 2019-01-08 09:49:27:180 [belle-sip] MESSAGE channel 0x10a150000: state ERROR 2019-01-08 09:49:27:180 [belle-sip] MESSAGE channel 0x10a150000: state RETRY 2019-01-08 09:49:27:202 [belle-sip] MESSAGE channel 0x10a150000: state CONNECTING 2019-01-08 09:49:27:202 [belle-sip] MESSAGE Trying to connect to [TLS://::ffff:37.59.51.72:444] 2019-01-08 09:49:27:265 [ios] MESSAGE (LinphoneManager.m:2471) Current audio route is [Speaker] 2019-01-08 09:49:27:370 [ios] DEBUG (roviderDelegate.m:258 ) CallKit : Audio session deactivated 2019-01-08 09:49:27:394 [belle-sip] MESSAGE Channel [0x10a150000]: Connected at TCP level, now doing TLS handshake with cname=subscribe.linphone.org 2019-01-08 09:49:27:395 [belle-sip] MESSAGE Channel [0x10a150000]: SSL handshake in progress... 2019-01-08 09:49:27:457 [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-08 09:49:27:458 [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-08 09:49:27:458 [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-08 09:49:27:466 [belle-sip] MESSAGE Channel [0x10a150000]: SSL handshake in progress... 2019-01-08 09:49:27:514 [belle-sip] MESSAGE Channel [0x10a150000]: SSL handshake finished, SSL version is [TLSv1.2], selected ciphersuite is [TLS-ECDHE-RSA-WITH-AES-256-GCM-SHA384] 2019-01-08 09:49:27:515 [belle-sip] MESSAGE Channel has local address 192.168.254.71:51391 2019-01-08 09:49:27:515 [belle-sip] MESSAGE channel 0x10a150000: state READY 2019-01-08 09:49:27:515 [belle-sip] MESSAGE channel [0x10a150000]: 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-08 09:49:27:515 [belle-sip] MESSAGE channel [0x10a150000]: ending send background task with id=[136d2]. 2019-01-08 09:49:27:594 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-08 09:49:27:595 [belle-sip] MESSAGE channel [0x10a150000]: starting recv background task with id=[136e3]. 2019-01-08 09:49:27:595 [belle-sip] MESSAGE channel [0x10a150000]: received [193] new bytes from [TLS://subscribe.linphone.org:444]: HTTP/1.1 200 OK Date: Tue, 08 Jan 2019 08:49:27 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-08 09:49:27:596 [belle-sip] MESSAGE channel [0x10a150000] [193] bytes parsed 2019-01-08 09:49:27:615 [belle-sip] MESSAGE channel [0x10a150000]: received [185] new bytes from [TLS://subscribe.linphone.org:444]: ERROR_ACCOUNT_DOESNT_EXIST 2019-01-08 09:49:27:615 [belle-sip] MESSAGE channel [0x10a150000] read [185] bytes of body from [subscribe.linphone.org:444] 2019-01-08 09:49:27:616 [belle-sip] MESSAGE channel [0x10a150000]: ending recv background task with id=[136e3]. 2019-01-08 09:49:32:615 [belle-sip] MESSAGE Background task belle-sip recv channel started. Unknown remaining time since application is not fully in background. 2019-01-08 09:49:32:615 [belle-sip] MESSAGE channel [0x10a150000]: starting recv background task with id=[137df]. 2019-01-08 09:49:32:616 [belle-sip] MESSAGE channel 0x10a150000: state DISCONNECTED 2019-01-08 09:49:32:616 [belle-sip] MESSAGE channel [0x10a150000]: ending recv background task with id=[137df]. 2019-01-08 09:49:32:622 [belle-sip] MESSAGE channel [0x10a150000] removed from http provider. 2019-01-08 09:49:32:624 [belle-sip] MESSAGE Channel [0x10a150000] destroyed 2019-01-08 09:49:32:835 [ios] MESSAGE (deMenuTableView.m:136 ) Entry Settings has been tapped 2019-01-08 09:49:32:835 [ios] MESSAGE ( PhoneMainView.m:681 ) Change current view to SettingsView 2019-01-08 09:49:32:839 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=6599142, realm=localphone.com 2019-01-08 09:49:32:840 [ios] WARNING (reSettingsStore.m:137 ) Codec speex/32000 supported by core is not shown in iOS app config view. 2019-01-08 09:49:32:840 [ios] WARNING (reSettingsStore.m:137 ) Codec L16/44100 supported by core is not shown in iOS app config view. 2019-01-08 09:49:32:840 [ios] WARNING (reSettingsStore.m:137 ) Codec L16/44100 supported by core is not shown in iOS app config view. 2019-01-08 09:49:32:852 [liblinphone] MESSAGE linphone_core_set_playback_gain_db(): no active call. 2019-01-08 09:49:32:852 [liblinphone] MESSAGE linphone_core_set_mic_gain_db(): no active call. 2019-01-08 09:49:32:853 [liblinphone] MESSAGE Starting stun server resolution [stun.linphone.org] 2019-01-08 09:49:32:855 [belle-sip] MESSAGE Resolver is using DNS server(s): 2019-01-08 09:49:32:856 [belle-sip] MESSAGE 192.168.254.254 2019-01-08 09:49:32:857 [belle-sip] MESSAGE resolver_process_data dns_res_check() in progress 2019-01-08 09:49:32:857 [belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop 2019-01-08 09:49:32:857 [bctbx] MESSAGE Sal nat helper [enabled] 2019-01-08 09:49:32:857 [bctbx] MESSAGE Sal use rport [enabled] 2019-01-08 09:49:32:887 [belle-sip] MESSAGE SRV _stun._udp.stun.linphone.org resolved to [target:sip1.linphone.org. port:3478 prio:10 weight:100] 2019-01-08 09:49:32:888 [belle-sip] MESSAGE SRV _stun._udp.stun.linphone.org resolved to [target:stun.linphone.org. port:3478 prio:0 weight:100] 2019-01-08 09:49:32:888 [belle-sip] MESSAGE Starting A/AAAA query for srv result [stun.linphone.org.] 2019-01-08 09:49:32:889 [belle-sip] MESSAGE Resolver is using DNS server(s): 2019-01-08 09:49:32:889 [belle-sip] MESSAGE 192.168.254.254 2019-01-08 09:49:32:890 [belle-sip] MESSAGE resolver_process_data dns_res_check() in progress 2019-01-08 09:49:32:890 [belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop 2019-01-08 09:49:32:890 [belle-sip] MESSAGE Resolver is using DNS server(s): 2019-01-08 09:49:32:891 [belle-sip] MESSAGE 192.168.254.254 2019-01-08 09:49:32:891 [belle-sip] MESSAGE resolver_process_data dns_res_check() in progress 2019-01-08 09:49:32:891 [belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop 2019-01-08 09:49:32:891 [belle-sip] MESSAGE Starting A/AAAA query for srv result [sip1.linphone.org.] 2019-01-08 09:49:32:892 [belle-sip] MESSAGE Resolver is using DNS server(s): 2019-01-08 09:49:32:892 [belle-sip] MESSAGE 192.168.254.254 2019-01-08 09:49:32:893 [belle-sip] MESSAGE resolver_process_data dns_res_check() in progress 2019-01-08 09:49:32:893 [belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop 2019-01-08 09:49:32:894 [belle-sip] MESSAGE Resolver is using DNS server(s): 2019-01-08 09:49:32:894 [belle-sip] MESSAGE 192.168.254.254 2019-01-08 09:49:32:895 [belle-sip] MESSAGE resolver_process_data dns_res_check() in progress 2019-01-08 09:49:32:895 [belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop 2019-01-08 09:49:32:915 [belle-sip] MESSAGE sip1.linphone.org. resolved to 91.121.209.194 2019-01-08 09:49:32:915 [belle-sip] MESSAGE A query finished for srv result [sip1.linphone.org.] 2019-01-08 09:49:32:915 [belle-sip] MESSAGE stun.linphone.org. resolved to 37.59.51.72 2019-01-08 09:49:32:915 [belle-sip] MESSAGE A query finished for srv result [stun.linphone.org.] 2019-01-08 09:49:32:915 [belle-sip] MESSAGE All A/AAAA results for combined resolution have arrived. 2019-01-08 09:49:32:916 [liblinphone] MESSAGE Stun server resolution successful.