2019-05-02 07:09:28:310 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x319a] 2019-05-02 07:09:28:310 [belle-sip] MESSAGE channel [0x7520d40a00]: starting recv background task with id=[319a]. 2019-05-02 07:09:28:311 [belle-sip] MESSAGE channel [0x7520d40a00]: received [986] new bytes from [TLS://sip.linphone.org:5223]: INVITE sip:my_account@[2001:1234:5678:abcd:5453:af89:8529:61af]:44542;pn-silent=1;transport=tls SIP/2.0 Via: SIP/2.0/TLS sip6.linphone.org:5223;rport;branch=z9hG4bK.tpeaDmt3SayFt6jty2NU5eNS6B Record-Route: Record-Route: Via: SIP/2.0/TCP 10.75.22.8:5060;rport=35149;branch=z9hG4bKPj03c2af33-8b93-48cf-9c59-b410e5e55e26;alias;received=67.193.227.1 Max-Forwards: 69 From: "[redacted]" ;tag=8600d669-ea22-4ea6-a663-182d8b0c583c To: Call-ID: 258fc975-5ce2-4158-88de-b7bf68308ca2 CSeq: 11856 INVITE Contact: User-Agent: Asterisk PBX 13.26.0 Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE Supported: 100rel, timer, replaces, norefersub Session-Expires: 1800 Min-SE: 90 Content-Type: application/sdp Content-Length: 3745 2019-05-02 07:09:28:321 [belle-sip] MESSAGE channel [0x7520d40a00] [986] bytes parsed 2019-05-02 07:09:28:329 [belle-sip] MESSAGE channel [0x7520d40a00]: received [3745] new bytes from [TLS://sip.linphone.org:5223]: v=0 o=- 1942824279 1942824279 IN IP4 10.75.22.8 s=Asterisk c=IN IP4 10.75.22.8 t=0 0 a=nortpproxy:yes m=audio 27160 RTP/AVP 4 0 8 3 111 112 5 10 118 7 18 110 117 119 97 9 102 115 116 107 c=IN IP6 2001:41d0:700:789::2020 a=rtpmap:4 G723/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:111 G726-32/8000 a=rtpmap:112 AAL2-G726-32/8000 a=rtpmap:5 DVI4/8000 a=rtpmap:10 L16/8000 a=rtpmap:118 L16/16000 a=rtpmap:7 LPC/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:110 speex/8000 a=rtpmap:117 speex/16000 a=rtpmap:119 speex/32000 a=rtpmap:97 iLBC/8000 a=rtpmap:9 G722/8000 a=rtpmap:102 G7221/16000 a=fmtp:102 bitrate=32000 a=rtpmap:115 G7221/32000 a=fmtp:115 bitrate=48000 a=rtpmap:116 G719/48000 a=rtpmap:107 opus/48000/2 a=ice-ufrag:362e99eb758dd8cc63f3ed2d779b34ec a=ice-pwd:792b1d7b088203a575236f2373480972 a=candidate:H67c355d6 1 UDP 2130706431 fd31:aeb1:48df::2 11110 typ host a=candidate:Hdccea0f2 1 UDP 2130706431 2001:123:ab:123::2 11110 typ host a=candidate:Hf5a91683 1 UDP 2130706431 2001:1234:5678:abcd::2 11110 typ host a=candidate:Hcbb5ed22 1 UDP 2130706431 fe80::21f:c6ff:fec4:926a 11110 typ host a=candidate:Ha4b16f7 1 UDP 2130706431 10.75.22.247 11110 typ host a=candidate:Ha4b1605 1 UDP 2130706431 10.75.22.5 11110 typ host a=candidate:Ha4b1608 1 UDP 2130706431 10.75.22.8 11110 typ host a=candidate:Ha4b1609 1 UDP 2130706431 10.75.22.9 11110 typ host a=candidate:S43c1e301 1 UDP 1694498815 67.193.227.1 11110 typ srflx raddr 10.75.22.247 rport 11110 a=ptime:20 a=maxptime:20 a=candidate:2161c1713ffb139a 1 UDP 16777215 2001:41d0:700:789::2020 27160 typ relay raddr 10.75.22.8 rport 11110 a=candidate:2161c1713ffb139a 2 UDP 16777214 2001:41d0:700:789::2020 27161 typ relay raddr 10.75.22.8 rport 11111 a=nortpproxy:yes m=video 56424 RTP/AVP 31 34 103 99 104 100 108 c=IN IP6 2001:41d0:700:789::2020 a=rtpmap:31 H261/90000 a=rtpmap:34 H263/90000 a=rtpmap:103 h263-1998/90000 a=rtpmap:99 H264/90000 a=rtpmap:104 MP4V-ES/90000 a=rtpmap:100 VP8/90000 a=rtpmap:108 VP9/90000 a=ice-ufrag:59d6efb74fd0f7535c4cc72b7652044c a=ice-pwd:02cd3c372978394e6000398d0bc3cebc a=candidate:H67c355d6 1 UDP 2130706431 fd31:aeb1:48df::2 14588 typ host a=candidate:Hdccea0f2 1 UDP 2130706431 2001:123:ab:123::2 14588 typ host a=candidate:Hf5a91683 1 UDP 2130706431 2001:1234:5678:abcd::2 14588 typ host a=candidate:Hcbb5ed22 1 UDP 2130706431 fe80::21f:c6ff:fec4:926a 14588 typ host a=candidate:Ha4b16f7 1 UDP 2130706431 10.75.22.247 14588 typ host a=candidate:Ha4b1605 1 UDP 2130706431 10.75.22.5 14588 typ host a=candidate:Ha4b1608 1 UDP 2130706431 10.75.22.8 14588 typ host a=candidate:Ha4b1609 1 UDP 2130706431 10.75.22.9 14588 typ host a=candidate:S43c1e301 1 UDP 1694498815 67.193.227.1 14588 typ srflx raddr 10.75.22.247 rport 14588 a=candidate:H67c355d6 2 UDP 2130706430 fd31:aeb1:48df::2 14589 typ host a=candidate:Hdccea0f2 2 UDP 2130706430 2001:123:ab:123::2 14589 typ host a=candidate:Hf5a91683 2 UDP 2130706430 2001:1234:5678:abcd::2 14589 typ host a=candidate:Hcbb5ed22 2 UDP 2130706430 fe80::21f:c6ff:fec4:926a 14589 typ host a=candidate:Ha4b16f7 2 UDP 2130706430 10.75.22.247 14589 typ host a=candidate:Ha4b1605 2 UDP 2130706430 10.75.22.5 14589 typ host a=candidate:Ha4b1608 2 UDP 2130706430 10.75.22.8 14589 typ host a=candidate:Ha4b1609 2 UDP 2130706430 10.75.22.9 14589 typ host a=candidate:S43c1e301 2 UDP 1694498814 67.193.227.1 14589 typ srflx raddr 10.75.22.247 rport 14589 a=candidate:2161c1713ffb139a 1 UDP 16777215 2001:41d0:700:789::2020 56424 typ relay raddr 10.75.22.8 rport 14588 a=candidate:2161c1713ffb139a 2 UDP 16777214 2001:41d0:700:789::2020 56425 typ relay raddr 10.75.22.8 rport 14589 a=nortpproxy:yes 2019-05-02 07:09:28:334 [belle-sip] MESSAGE channel [0x7520d40a00] read [3745] bytes of body from [sip.linphone.org:5223] 2019-05-02 07:09:28:338 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x7520ad7840)] acquired [ref=0x3172] 2019-05-02 07:09:28:338 [belle-sip] MESSAGE transaction [0x7520ad7840]: starting transaction background task with id=[3172]. 2019-05-02 07:09:28:339 [belle-sip] MESSAGE Changing [server] [INVITE] transaction [0x7520ad7840], from state [INIT] to [PROCEEDING] 2019-05-02 07:09:28:340 [belle-sip] MESSAGE channel [0x7520d40a00]: message sent to [TLS://sip.linphone.org:5223], size: [486] bytes SIP/2.0 100 Trying Via: SIP/2.0/TLS sip6.linphone.org:5223;received=2001:41d0:700:789::2020;rport;branch=z9hG4bK.tpeaDmt3SayFt6jty2NU5eNS6B Via: SIP/2.0/TCP 10.75.22.8:5060;received=67.193.227.1;rport=35149;branch=z9hG4bKPj03c2af33-8b93-48cf-9c59-b410e5e55e26;alias From: "[redacted]" ;tag=8600d669-ea22-4ea6-a663-182d8b0c583c To: sip:my_account@sip.linphone.org Call-ID: 258fc975-5ce2-4158-88de-b7bf68308ca2 CSeq: 11856 INVITE Content-Length: 0 2019-05-02 07:09:28:340 [belle-sip] MESSAGE New server dialog [0x752616e300] , local tag [nRl7QO6], remote tag [8600d669-ea22-4ea6-a663-182d8b0c583c] 2019-05-02 07:09:28:340 [liblinphone] MESSAGE op [0x752617b100] : set_or_update_dialog() current=[0x0] new=[0x752616e300] 2019-05-02 07:09:28:340 [liblinphone] MESSAGE New incoming call from ["[redacted]" ] to [sip:my_account@sip.linphone.org] 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload G723/8000 fmtp= 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload PCMU/8000 fmtp= 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload PCMA/8000 fmtp= 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload GSM/8000 fmtp= 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload G726-32/8000 fmtp= 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload AAL2-G726-32/8000 fmtp= 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload DVI4/8000 fmtp= 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload L16/8000 fmtp= 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload L16/16000 fmtp= 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload LPC/8000 fmtp= 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload G729/8000 fmtp=annexb=no 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload speex/8000 fmtp= 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload speex/16000 fmtp= 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload speex/32000 fmtp= 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload iLBC/8000 fmtp= 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload G722/8000 fmtp= 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload G7221/16000 fmtp=bitrate=32000 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload G7221/32000 fmtp=bitrate=48000 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload G719/48000 fmtp= 2019-05-02 07:09:28:362 [liblinphone] MESSAGE Found payload opus/48000 fmtp= 2019-05-02 07:09:28:363 [liblinphone] MESSAGE Found payload H261/90000 fmtp= 2019-05-02 07:09:28:363 [liblinphone] MESSAGE Found payload H263/90000 fmtp= 2019-05-02 07:09:28:363 [liblinphone] MESSAGE Found payload h263-1998/90000 fmtp= 2019-05-02 07:09:28:363 [liblinphone] MESSAGE Found payload H264/90000 fmtp= 2019-05-02 07:09:28:363 [liblinphone] MESSAGE Found payload MP4V-ES/90000 fmtp= 2019-05-02 07:09:28:363 [liblinphone] MESSAGE Found payload VP8/90000 fmtp= 2019-05-02 07:09:28:363 [liblinphone] MESSAGE Found payload VP9/90000 fmtp= 2019-05-02 07:09:28:364 [liblinphone] MESSAGE New MediaSession [0x75135541b8] initialized (LinphoneCore version: 3.12.0-3392-g153a89ea8) 2019-05-02 07:09:28:364 [liblinphone] MESSAGE Found media local-ip from signaling. 2019-05-02 07:09:28:364 [liblinphone] MESSAGE audio stream index found: 0, updating main audio stream index 2019-05-02 07:09:28:364 [liblinphone] MESSAGE video stream index found: 1, updating main video stream index 2019-05-02 07:09:28:364 [liblinphone] MESSAGE CallSession [0x75135541b8], stream type [audio], multicast role is [inactive] 2019-05-02 07:09:28:365 [ortp] MESSAGE RtpSession bound to [::0] ports [7078] [7079] 2019-05-02 07:09:28:365 [ortp] MESSAGE rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2019-05-02 07:09:28:365 [liblinphone] MESSAGE Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80 2019-05-02 07:09:28:365 [liblinphone] MESSAGE Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32 2019-05-02 07:09:28:365 [liblinphone] MESSAGE Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80 2019-05-02 07:09:28:365 [liblinphone] MESSAGE Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32 2019-05-02 07:09:28:365 [mediastreamer] MESSAGE Creating ZRTP engine on rtp session [0x751f6c1000] ssrc 0xf5b70f10 2019-05-02 07:09:28:367 [mediastreamer] MESSAGE Setting DSCP to 46 for MSAudio stream. 2019-05-02 07:09:28:367 [liblinphone] MESSAGE Equalizer location: hp 2019-05-02 07:09:28:368 [mediastreamer] MESSAGE cannot set noise gate mode to [0] because no volume send 2019-05-02 07:09:28:368 [liblinphone] MESSAGE CallSession [0x75135541b8], stream type [video], multicast role is [inactive] 2019-05-02 07:09:28:368 [ortp] MESSAGE RtpSession bound to [::0] ports [9078] [9079] 2019-05-02 07:09:28:371 [mediastreamer] MESSAGE This device (HUAWEI/FRD-L04/hi3650) says it has no built-in echo canceller. 2019-05-02 07:09:28:371 [mediastreamer] MESSAGE No information available for [HUAWEI/FRD-L04/hi3650], 2019-05-02 07:09:28:371 [mediastreamer] MESSAGE Using android >= 8 sound device descriptor. 2019-05-02 07:09:28:371 [mediastreamer] MESSAGE Sound device information for [HUAWEI/FRD-L04/hi3650] is: builtin=[yes], delay=[0] ms 2019-05-02 07:09:28:371 [ortp] MESSAGE rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2019-05-02 07:09:28:371 [mediastreamer] MESSAGE Initializing multistream ZRTP context on rtp session [0x751f6c3800] ssrc 0xbecfea70 2019-05-02 07:09:28:372 [mediastreamer] MESSAGE Setting DSCP to 0 for MSVideo stream. 2019-05-02 07:09:28:372 [liblinphone] MESSAGE CallSession [0x75135541b8], stream type [text], multicast role is [inactive] 2019-05-02 07:09:28:372 [ortp] MESSAGE RtpSession bound to [::0] ports [11078] [11079] 2019-05-02 07:09:28:372 [ortp] MESSAGE rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2019-05-02 07:09:28:372 [liblinphone] MESSAGE Created new ICE check list for stream [0] 2019-05-02 07:09:28:372 [liblinphone] MESSAGE Created new ICE check list for stream [1] 2019-05-02 07:09:28:372 [mediastreamer] MESSAGE ms_filter_link: MSRtpRecv:0x7520db5500,0-->MSVoidSink:0x7520b0cc00,0 2019-05-02 07:09:28:373 [mediastreamer] MESSAGE ms_filter_link: MSRtpRecv:0x7520b0cd80,0-->MSVoidSink:0x7520b15300,0 2019-05-02 07:09:28:374 [mediastreamer] MESSAGE MSVideo MSTicker priority left to normal. 2019-05-02 07:09:28:374 [mediastreamer] MESSAGE Priority used: 99 2019-05-02 07:09:28:374 [mediastreamer] MESSAGE MSAudio MSTicker priority increased to maximum. 2019-05-02 07:09:28:377 [liblinphone] MESSAGE ICE: gathering candidates from [stun.linphone.org] using STUN 2019-05-02 07:09:28:377 [mediastreamer] MESSAGE ice: Send STUN binding request: [::]:7078 --> 37.59.51.72:3478 [6b2e35f37fd6ca8ad72c3e61] 2019-05-02 07:09:28:378 [liblinphone] MESSAGE Found media local-ip from signaling. 2019-05-02 07:09:28:378 [liblinphone] MESSAGE Don't put video stream on local offer for CallSession [0x75135541b8] 2019-05-02 07:09:28:378 [liblinphone] MESSAGE Don't put text stream on local offer for CallSession [0x75135541b8] 2019-05-02 07:09:28:379 [liblinphone] MESSAGE Appending Ik parameter to SDP attributes 2019-05-02 07:09:28:379 [liblinphone] MESSAGE CallSession [0x75135541b8] New ICE state: audio: [IceStateInProgress] video: [IceStateInProgress] text: [IceStateNotActivated] 2019-05-02 07:09:28:379 [liblinphone] MESSAGE Doing SDP offer/answer process of type incoming 2019-05-02 07:09:28:379 [liblinphone] MESSAGE No match for G723/8000/1 2019-05-02 07:09:28:379 [liblinphone] MESSAGE No match for GSM/8000/1 2019-05-02 07:09:28:379 [liblinphone] MESSAGE No match for G726-32/8000/1 2019-05-02 07:09:28:379 [liblinphone] MESSAGE No match for AAL2-G726-32/8000/1 2019-05-02 07:09:28:379 [liblinphone] MESSAGE No match for DVI4/8000/1 2019-05-02 07:09:28:379 [liblinphone] MESSAGE No match for L16/8000/1 2019-05-02 07:09:28:379 [liblinphone] MESSAGE No match for L16/16000/1 2019-05-02 07:09:28:379 [liblinphone] MESSAGE No match for LPC/8000/1 2019-05-02 07:09:28:379 [liblinphone] MESSAGE No match for G729/8000/1 2019-05-02 07:09:28:379 [liblinphone] MESSAGE No match for speex/32000/1 2019-05-02 07:09:28:380 [liblinphone] MESSAGE No match for iLBC/8000/1 2019-05-02 07:09:28:380 [liblinphone] MESSAGE No match for G722/8000/1 2019-05-02 07:09:28:380 [liblinphone] MESSAGE No match for G7221/16000/1 2019-05-02 07:09:28:380 [liblinphone] MESSAGE No match for G7221/32000/1 2019-05-02 07:09:28:380 [liblinphone] MESSAGE No match for G719/48000/1 2019-05-02 07:09:28:380 [liblinphone] MESSAGE Doing offer/answer processing with specific provider for codec [opus] 2019-05-02 07:09:28:380 [liblinphone] MESSAGE No match for H261/90000 2019-05-02 07:09:28:380 [liblinphone] MESSAGE No match for H263/90000 2019-05-02 07:09:28:380 [liblinphone] MESSAGE No match for h263-1998/90000 2019-05-02 07:09:28:380 [liblinphone] MESSAGE No match for H264/90000 2019-05-02 07:09:28:380 [liblinphone] MESSAGE No match for MP4V-ES/90000 2019-05-02 07:09:28:380 [liblinphone] MESSAGE No match for VP8/90000 2019-05-02 07:09:28:380 [liblinphone] MESSAGE No match for VP9/90000 2019-05-02 07:09:28:380 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_created] 2019-05-02 07:09:28:383 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [Liblinphone call notification] acquired [ref=0x3132] 2019-05-02 07:09:28:383 [liblinphone] MESSAGE Starting background task [12594] with name: [Liblinphone call notification] and expiration of [30] 2019-05-02 07:09:28:383 [liblinphone] MESSAGE Defer incoming notification 2019-05-02 07:09:28:383 [belle-sip] MESSAGE channel [0x7520d40a00]: ending recv background task with id=[319a]. 2019-05-02 07:09:28:384 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x319a] 2019-05-02 07:09:28:428 [mediastreamer] MESSAGE ice: Send STUN binding request: [::]:7079 --> 37.59.51.72:3478 [ee20bfc9557974dca28b24b7] 2019-05-02 07:09:28:468 [mediastreamer] MESSAGE ice: Send STUN binding request: [::]:9078 --> 37.59.51.72:3478 [077008b4b8ff5ea2c75708de] 2019-05-02 07:09:28:509 [mediastreamer] MESSAGE ice: Send STUN binding request: [::]:9079 --> 37.59.51.72:3478 [a38d8c14ad3cdec7eb6606e8] 2019-05-02 07:09:28:589 [mediastreamer] MESSAGE ice: Send STUN binding request: [::]:7078 --> 37.59.51.72:3478 [f3bec88331246f3515514e14] 2019-05-02 07:09:28:605 [ortp] MESSAGE First estimation 2019-05-02 07:09:28:610 [mediastreamer] MESSAGE ice: Recv binding response: 10.75.22.32:7078 <-- 37.59.51.72:3478 [6b2e35f37fd6ca8ad72c3e61] 2019-05-02 07:09:28:611 [mediastreamer] MESSAGE ice: Add candidate obtained by STUN/TURN: 67.193.227.1:7078:srflx 2019-05-02 07:09:28:631 [mediastreamer] MESSAGE ice: Send STUN binding request: [::]:7079 --> 37.59.51.72:3478 [05bf431e599166ab6a933f87] 2019-05-02 07:09:28:632 [mediastreamer] MESSAGE ice: Recv binding response: 10.75.22.32:7079 <-- 37.59.51.72:3478 [ee20bfc9557974dca28b24b7] 2019-05-02 07:09:28:632 [mediastreamer] MESSAGE ice: Add candidate obtained by STUN/TURN: 67.193.227.1:7079:srflx 2019-05-02 07:09:28:633 [mediastreamer] MESSAGE ice: Finished candidates gathering for check list 0x752616eb00 2019-05-02 07:09:28:633 [mediastreamer] MESSAGE Local candidates: 2019-05-02 07:09:28:633 [mediastreamer] MESSAGE [0x751e3aee60]: type=host ip=2001:1234:5678:abcd:5453:af89:8529:61af port=7078 componentID=1 priority=2130706431 foundation= base=0x751e3aee60 2019-05-02 07:09:28:633 [mediastreamer] MESSAGE [0x751e3aef00]: type=host ip=2001:1234:5678:abcd:5453:af89:8529:61af port=7079 componentID=2 priority=2130706430 foundation= base=0x751e3aef00 2019-05-02 07:09:28:633 [mediastreamer] MESSAGE [0x751e3af0e0]: type=host ip=10.75.22.32 port=7078 componentID=1 priority=2130706303 foundation= base=0x751e3af0e0 2019-05-02 07:09:28:633 [mediastreamer] MESSAGE [0x751e3af180]: type=host ip=10.75.22.32 port=7079 componentID=2 priority=2130706302 foundation= base=0x751e3af180 2019-05-02 07:09:28:633 [mediastreamer] MESSAGE [0x751e3af360]: type=srflx ip=67.193.227.1 port=7078 componentID=1 priority=1694498687 foundation= base=0x751e3af0e0 2019-05-02 07:09:28:633 [mediastreamer] MESSAGE [0x751e3af400]: type=srflx ip=67.193.227.1 port=7079 componentID=2 priority=1694498686 foundation= base=0x751e3af180 2019-05-02 07:09:28:634 [mediastreamer] MESSAGE Remote candidates: 2019-05-02 07:09:28:634 [mediastreamer] MESSAGE [0x752033c740]: type=host ip=fd31:aeb1:48df::2 port=11110 componentID=1 priority=2130706431 foundation=H67c355d6 base=0x752033c740 2019-05-02 07:09:28:634 [mediastreamer] MESSAGE [0x752033c880]: type=host ip=2001:123:ab:123::2 port=11110 componentID=1 priority=2130706431 foundation=Hdccea0f2 base=0x752033c880 2019-05-02 07:09:28:634 [mediastreamer] MESSAGE [0x752033c920]: type=host ip=2001:1234:5678:abcd::2 port=11110 componentID=1 priority=2130706431 foundation=Hf5a91683 base=0x752033c920 2019-05-02 07:09:28:634 [mediastreamer] MESSAGE [0x752033c9c0]: type=host ip=fe80::21f:c6ff:fec4:926a port=11110 componentID=1 priority=2130706431 foundation=Hcbb5ed22 base=0x752033c9c0 2019-05-02 07:09:28:634 [mediastreamer] MESSAGE [0x752033ca60]: type=host ip=10.75.22.247 port=11110 componentID=1 priority=2130706431 foundation=Ha4b16f7 base=0x752033ca60 2019-05-02 07:09:28:634 [mediastreamer] MESSAGE [0x752033cb00]: type=host ip=10.75.22.5 port=11110 componentID=1 priority=2130706431 foundation=Ha4b1605 base=0x752033cb00 2019-05-02 07:09:28:634 [mediastreamer] MESSAGE [0x752033cba0]: type=host ip=10.75.22.8 port=11110 componentID=1 priority=2130706431 foundation=Ha4b1608 base=0x752033cba0 2019-05-02 07:09:28:635 [ortp] MESSAGE First estimation 2019-05-02 07:09:28:635 [mediastreamer] MESSAGE [0x752033cc40]: type=host ip=10.75.22.9 port=11110 componentID=1 priority=2130706431 foundation=Ha4b1609 base=0x752033cc40 2019-05-02 07:09:28:635 [mediastreamer] MESSAGE [0x752033cce0]: type=srflx ip=67.193.227.1 port=11110 componentID=1 priority=1694498815 foundation=S43c1e301 base=0x0 2019-05-02 07:09:28:635 [mediastreamer] MESSAGE [0x752033cd80]: * type=relay ip=2001:41d0:700:789::2020 port=27160 componentID=1 priority=16777215 foundation=2161c1713ffb139a base=0x0 2019-05-02 07:09:28:635 [mediastreamer] MESSAGE [0x752033ce20]: * type=relay ip=2001:41d0:700:789::2020 port=27161 componentID=2 priority=16777214 foundation=2161c1713ffb139a base=0x0 2019-05-02 07:09:28:636 [mediastreamer] MESSAGE ice: Recv binding response: 10.75.22.32:9078 <-- 37.59.51.72:3478 [077008b4b8ff5ea2c75708de] 2019-05-02 07:09:28:636 [mediastreamer] MESSAGE ice: Add candidate obtained by STUN/TURN: 67.193.227.1:9078:srflx 2019-05-02 07:09:28:713 [mediastreamer] MESSAGE ice: Send STUN binding request: [::]:9079 --> 37.59.51.72:3478 [15bbb6a50c4de10dc8e0346f] 2019-05-02 07:09:28:774 [mediastreamer] MESSAGE ice: Recv binding response: 10.75.22.32:7078 <-- 37.59.51.72:3478 [f3bec88331246f3515514e14] 2019-05-02 07:09:28:775 [mediastreamer] WARNING ice: Received a binding response for an unknown transaction ID: f3bec88331246f3515514e14 2019-05-02 07:09:28:775 [mediastreamer] MESSAGE ice: Recv binding response: 10.75.22.32:9079 <-- 37.59.51.72:3478 [a38d8c14ad3cdec7eb6606e8] 2019-05-02 07:09:28:775 [mediastreamer] MESSAGE ice: Add candidate obtained by STUN/TURN: 67.193.227.1:9079:srflx 2019-05-02 07:09:28:775 [mediastreamer] MESSAGE ice: Finished candidates gathering for check list 0x752616ec00 2019-05-02 07:09:28:776 [mediastreamer] MESSAGE Local candidates: 2019-05-02 07:09:28:776 [mediastreamer] MESSAGE [0x751e3aefa0]: type=host ip=2001:1234:5678:abcd:5453:af89:8529:61af port=9078 componentID=1 priority=2130706431 foundation= base=0x751e3aefa0 2019-05-02 07:09:28:776 [mediastreamer] MESSAGE [0x751e3af040]: type=host ip=2001:1234:5678:abcd:5453:af89:8529:61af port=9079 componentID=2 priority=2130706430 foundation= base=0x751e3af040 2019-05-02 07:09:28:776 [mediastreamer] MESSAGE [0x751e3af220]: type=host ip=10.75.22.32 port=9078 componentID=1 priority=2130706303 foundation= base=0x751e3af220 2019-05-02 07:09:28:776 [mediastreamer] MESSAGE [0x751e3af2c0]: type=host ip=10.75.22.32 port=9079 componentID=2 priority=2130706302 foundation= base=0x751e3af2c0 2019-05-02 07:09:28:777 [mediastreamer] MESSAGE [0x751e3af4a0]: type=srflx ip=67.193.227.1 port=9078 componentID=1 priority=1694498687 foundation= base=0x751e3af220 2019-05-02 07:09:28:777 [mediastreamer] MESSAGE [0x751e3af540]: type=srflx ip=67.193.227.1 port=9079 componentID=2 priority=1694498686 foundation= base=0x751e3af2c0 2019-05-02 07:09:28:777 [mediastreamer] MESSAGE Remote candidates: 2019-05-02 07:09:28:777 [mediastreamer] MESSAGE [0x752033cec0]: type=host ip=fd31:aeb1:48df::2 port=14588 componentID=1 priority=2130706431 foundation=H67c355d6 base=0x752033cec0 2019-05-02 07:09:28:777 [mediastreamer] MESSAGE [0x752033cf60]: type=host ip=2001:123:ab:123::2 port=14588 componentID=1 priority=2130706431 foundation=Hdccea0f2 base=0x752033cf60 2019-05-02 07:09:28:777 [mediastreamer] MESSAGE [0x751e3ae000]: type=host ip=2001:1234:5678:abcd::2 port=14588 componentID=1 priority=2130706431 foundation=Hf5a91683 base=0x751e3ae000 2019-05-02 07:09:28:778 [mediastreamer] MESSAGE [0x751e3ae0a0]: type=host ip=fe80::21f:c6ff:fec4:926a port=14588 componentID=1 priority=2130706431 foundation=Hcbb5ed22 base=0x751e3ae0a0 2019-05-02 07:09:28:778 [mediastreamer] MESSAGE [0x751e3ae140]: type=host ip=10.75.22.247 port=14588 componentID=1 priority=2130706431 foundation=Ha4b16f7 base=0x751e3ae140 2019-05-02 07:09:28:778 [mediastreamer] MESSAGE [0x751e3ae1e0]: type=host ip=10.75.22.5 port=14588 componentID=1 priority=2130706431 foundation=Ha4b1605 base=0x751e3ae1e0 2019-05-02 07:09:28:778 [mediastreamer] MESSAGE [0x751e3ae280]: type=host ip=10.75.22.8 port=14588 componentID=1 priority=2130706431 foundation=Ha4b1608 base=0x751e3ae280 2019-05-02 07:09:28:778 [mediastreamer] MESSAGE [0x751e3ae320]: type=host ip=10.75.22.9 port=14588 componentID=1 priority=2130706431 foundation=Ha4b1609 base=0x751e3ae320 2019-05-02 07:09:28:778 [mediastreamer] MESSAGE [0x751e3ae3c0]: type=srflx ip=67.193.227.1 port=14588 componentID=1 priority=1694498815 foundation=S43c1e301 base=0x0 2019-05-02 07:09:28:778 [mediastreamer] MESSAGE [0x751e3ae460]: type=host ip=fd31:aeb1:48df::2 port=14589 componentID=2 priority=2130706430 foundation=H67c355d6 base=0x751e3ae460 2019-05-02 07:09:28:779 [mediastreamer] MESSAGE [0x751e3ae500]: type=host ip=2001:123:ab:123::2 port=14589 componentID=2 priority=2130706430 foundation=Hdccea0f2 base=0x751e3ae500 2019-05-02 07:09:28:779 [mediastreamer] MESSAGE [0x751e3ae5a0]: type=host ip=2001:1234:5678:abcd::2 port=14589 componentID=2 priority=2130706430 foundation=Hf5a91683 base=0x751e3ae5a0 2019-05-02 07:09:28:779 [mediastreamer] MESSAGE [0x751e3ae640]: type=host ip=fe80::21f:c6ff:fec4:926a port=14589 componentID=2 priority=2130706430 foundation=Hcbb5ed22 base=0x751e3ae640 2019-05-02 07:09:28:779 [mediastreamer] MESSAGE [0x751e3ae6e0]: type=host ip=10.75.22.247 port=14589 componentID=2 priority=2130706430 foundation=Ha4b16f7 base=0x751e3ae6e0 2019-05-02 07:09:28:779 [mediastreamer] MESSAGE [0x751e3ae780]: type=host ip=10.75.22.5 port=14589 componentID=2 priority=2130706430 foundation=Ha4b1605 base=0x751e3ae780 2019-05-02 07:09:28:779 [mediastreamer] MESSAGE [0x751e3ae960]: type=host ip=10.75.22.8 port=14589 componentID=2 priority=2130706430 foundation=Ha4b1608 base=0x751e3ae960 2019-05-02 07:09:28:780 [mediastreamer] MESSAGE [0x751e3aebe0]: type=host ip=10.75.22.9 port=14589 componentID=2 priority=2130706430 foundation=Ha4b1609 base=0x751e3aebe0 2019-05-02 07:09:28:780 [mediastreamer] MESSAGE [0x751e3aec80]: type=srflx ip=67.193.227.1 port=14589 componentID=2 priority=1694498814 foundation=S43c1e301 base=0x0 2019-05-02 07:09:28:780 [mediastreamer] MESSAGE [0x751e3aed20]: * type=relay ip=2001:41d0:700:789::2020 port=56424 componentID=1 priority=16777215 foundation=2161c1713ffb139a base=0x0 2019-05-02 07:09:28:780 [mediastreamer] MESSAGE [0x751e3aedc0]: * type=relay ip=2001:41d0:700:789::2020 port=56425 componentID=2 priority=16777214 foundation=2161c1713ffb139a base=0x0 2019-05-02 07:09:28:781 [mediastreamer] MESSAGE ms_filter_unlink: MSRtpRecv:0x7520db5500,0-->MSVoidSink:0x7520b0cc00,0 2019-05-02 07:09:28:781 [mediastreamer] MESSAGE ms_filter_unlink: MSRtpRecv:0x7520b0cd80,0-->MSVoidSink:0x7520b15300,0 2019-05-02 07:09:28:781 [liblinphone] MESSAGE CallSession [0x75135541b8] New ICE state: audio: [IceStateInProgress] video: [IceStateInProgress] text: [IceStateNotActivated] 2019-05-02 07:09:28:784 [liblinphone] MESSAGE CallSession [0x75135541b8] moving from state State::Idle to State::IncomingReceived 2019-05-02 07:09:28:785 [linphone-android] MESSAGE [Platform Helper] acquireWifiLock() 2019-05-02 07:09:28:795 [linphone-android] MESSAGE [Platform Helper] acquireMcastLock() 2019-05-02 07:09:28:803 [linphone-android] MESSAGE [Platform Helper] acquireCpuLock() 2019-05-02 07:09:28:807 [linphone-android] MESSAGE [Manager] New call state [IncomingReceived] 2019-05-02 07:09:28:938 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_state_changed] 2019-05-02 07:09:28:938 [liblinphone] MESSAGE Ending background task [12594] with name: [Liblinphone call notification] 2019-05-02 07:09:28:939 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x3132] 2019-05-02 07:09:28:939 [liblinphone] MESSAGE Contact has been fixed using proxy 2019-05-02 07:09:28:940 [belle-sip] MESSAGE channel [0x7520d40a00]: message sent to [TLS://sip.linphone.org:5223], size: [748] bytes SIP/2.0 180 Ringing Via: SIP/2.0/TLS sip6.linphone.org:5223;received=2001:41d0:700:789::2020;rport;branch=z9hG4bK.tpeaDmt3SayFt6jty2NU5eNS6B Via: SIP/2.0/TCP 10.75.22.8:5060;received=67.193.227.1;rport=35149;branch=z9hG4bKPj03c2af33-8b93-48cf-9c59-b410e5e55e26;alias From: "[redacted]" ;tag=8600d669-ea22-4ea6-a663-182d8b0c583c To: ;tag=nRl7QO6 Call-ID: 258fc975-5ce2-4158-88de-b7bf68308ca2 CSeq: 11856 INVITE User-Agent: LinphoneAndroid/4.1-debug (FRD-L04) LinphoneSDK/4.1-366-g1b22291 (master) (belle-sip/1.6.3) Supported: replaces, outbound, gruu Record-route: Record-route: Content-Length: 0 2019-05-02 07:09:28:940 [belle-sip] MESSAGE Dialog [0x752616e300]: now updated by transaction [0x7520ad7840]. 2019-05-02 07:09:28:940 [liblinphone] MESSAGE Incoming call ringing for 0 seconds 2019-05-02 07:09:28:944 [liblinphone] MESSAGE Callbacks [0x7520bf6760] unregistered on core [0x754549e800] 2019-05-02 07:09:28:945 [liblinphone] MESSAGE Callbacks [0x7520bf6850] unregistered on core [0x754549e800] 2019-05-02 07:09:28:945 [liblinphone] MESSAGE Callbacks [0x7520bf6710] unregistered on core [0x754549e800] 2019-05-02 07:09:28:945 [linphone-android] MESSAGE [Service] Activity paused:org.linphone.LinphoneActivity@a85a962 2019-05-02 07:09:28:945 [linphone-android] MESSAGE [Service] runningActivities=0 2019-05-02 07:09:28:970 [linphone-android] MESSAGE [Service] Activity created:org.linphone.call.CallIncomingActivity@dd80f80 2019-05-02 07:09:28:988 [linphone-android] MESSAGE Activity started:org.linphone.call.CallIncomingActivity@dd80f80 2019-05-02 07:09:28:989 [linphone-android] MESSAGE [Permission] Record audio permission is granted 2019-05-02 07:09:28:989 [linphone-android] MESSAGE [Permission] Camera permission is denied 2019-05-02 07:09:28:990 [linphone-android] MESSAGE [Service] Activity resumed:org.linphone.call.CallIncomingActivity@dd80f80 2019-05-02 07:09:28:990 [linphone-android] MESSAGE [Service] runningActivities=1 2019-05-02 07:09:28:990 [liblinphone] MESSAGE Core callbacks [0x7520bf6670] registered on core [0x754549e800] 2019-05-02 07:09:28:991 [liblinphone] MESSAGE Core callbacks [0x7520bf6e90] registered on core [0x754549e800] 2019-05-02 07:09:29:436 [linphone-android] MESSAGE [Service] Activity stopped:org.linphone.LinphoneActivity@a85a962 2019-05-02 07:09:29:865 [liblinphone] MESSAGE Incoming call ringing for 1 seconds 2019-05-02 07:09:30:521 [linphone-android] WARNING Directory /storage/emulated/0/Linphone/recordings doesn't seem to exists yet, let's create it 2019-05-02 07:09:30:522 [linphone-android] MESSAGE [MediaScanner] Scanning file /storage/emulated/0/Linphone/recordings with MIME file/null 2019-05-02 07:09:30:530 [liblinphone] MESSAGE Found media local-ip from signaling. 2019-05-02 07:09:30:530 [liblinphone] MESSAGE Don't put video stream on local offer for CallSession [0x75135541b8] 2019-05-02 07:09:30:531 [liblinphone] MESSAGE Don't put text stream on local offer for CallSession [0x75135541b8] 2019-05-02 07:09:30:531 [liblinphone] MESSAGE Appending Ik parameter to SDP attributes 2019-05-02 07:09:30:531 [liblinphone] MESSAGE CallSession [0x75135541b8] New ICE state: audio: [IceStateInProgress] video: [IceStateInProgress] text: [IceStateNotActivated] 2019-05-02 07:09:30:532 [liblinphone] MESSAGE Configuring prefered card sampling rate to [48000] 2019-05-02 07:09:30:532 [mediastreamer] MESSAGE ms_filter_link: MSRtpRecv:0x753afd0100,0-->MSVoidSink:0x753aeecd80,0 2019-05-02 07:09:30:532 [liblinphone] MESSAGE Accepting server transaction [0x7520ad7840] on op [0x752617b100] 2019-05-02 07:09:30:532 [liblinphone] MESSAGE Doing SDP offer/answer process of type incoming 2019-05-02 07:09:30:532 [liblinphone] MESSAGE No match for G723/8000/1 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for GSM/8000/1 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for G726-32/8000/1 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for AAL2-G726-32/8000/1 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for DVI4/8000/1 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for L16/8000/1 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for L16/16000/1 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for LPC/8000/1 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for G729/8000/1 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for speex/32000/1 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for iLBC/8000/1 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for G722/8000/1 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for G7221/16000/1 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for G7221/32000/1 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for G719/48000/1 2019-05-02 07:09:30:533 [liblinphone] MESSAGE Doing offer/answer processing with specific provider for codec [opus] 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for H261/90000 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for H263/90000 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for h263-1998/90000 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for H264/90000 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for MP4V-ES/90000 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for VP8/90000 2019-05-02 07:09:30:533 [liblinphone] MESSAGE No match for VP9/90000 2019-05-02 07:09:30:536 [belle-sip] MESSAGE channel [0x7520d40a00]: message sent to [TLS://sip.linphone.org:5223], size: [1956] bytes SIP/2.0 200 Ok Via: SIP/2.0/TLS sip6.linphone.org:5223;received=2001:41d0:700:789::2020;rport;branch=z9hG4bK.tpeaDmt3SayFt6jty2NU5eNS6B Via: SIP/2.0/TCP 10.75.22.8:5060;received=67.193.227.1;rport=35149;branch=z9hG4bKPj03c2af33-8b93-48cf-9c59-b410e5e55e26;alias From: "[redacted]" ;tag=8600d669-ea22-4ea6-a663-182d8b0c583c To: ;tag=nRl7QO6 Call-ID: 258fc975-5ce2-4158-88de-b7bf68308ca2 CSeq: 11856 INVITE User-Agent: LinphoneAndroid/4.1-debug (FRD-L04) LinphoneSDK/4.1-366-g1b22291 (master) (belle-sip/1.6.3) Supported: replaces, outbound, gruu Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Contact: ;+org.linphone.specs="groupchat,lime" Content-Type: application/sdp Content-Length: 956 Record-route: Record-route: v=0 o=my_account 3740 1048 IN IP6 2001:1234:5678:abcd:5453:af89:8529:61af s=Talk c=IN IP6 2001:1234:5678:abcd:5453:af89:8529:61af t=0 0 a=ice-pwd:d46cbb4f04a5c17feecd6f69 a=ice-ufrag:096d5d3c a=Ik:jT4cMZ8SBu8oQ4V8J7KlEoGWq0tHAe/LJSDffZ1mAeQ= m=audio 7078 RTP/AVP 0 8 110 117 107 c=IN IP4 67.193.227.1 a=rtpmap:110 speex/8000 a=fmtp:110 vbr=on a=rtpmap:117 speex/16000 a=fmtp:117 vbr=on a=rtpmap:107 opus/48000/2 a=fmtp:107 useinbandfec=1 a=candidate:1 1 UDP 2130706431 2001:1234:5678:abcd:5453:af89:8529:61af 7078 typ host a=candidate:1 2 UDP 2130706430 2001:1234:5678:abcd:5453:af89:8529:61af 7079 typ host a=candidate:2 1 UDP 2130706303 10.75.22.32 7078 typ host a=candidate:2 2 UDP 2130706302 10.75.22.32 7079 typ host a=candidate:3 1 UDP 1694498687 67.193.227.1 7078 typ srflx raddr 10.75.22.32 rport 7078 a=candidate:3 2 UDP 1694498686 67.193.227.1 7079 typ srflx raddr 10.75.22.32 rport 7079 m=video 0 RTP/AVP 0 a=inactive 2019-05-02 07:09:30:536 [belle-sip] MESSAGE Changing [server] [INVITE] transaction [0x7520ad7840], from state [PROCEEDING] to [ACCEPTED] 2019-05-02 07:09:30:536 [belle-sip] MESSAGE Dialog [0x752616e300]: now updated by transaction [0x7520ad7840]. 2019-05-02 07:09:30:536 [liblinphone] MESSAGE CallSession [0x75135541b8] moving from state State::IncomingReceived to State::Connected 2019-05-02 07:09:30:537 [linphone-android] MESSAGE [Manager] New call state [Connected] 2019-05-02 07:09:30:565 [linphone-android] WARNING [Manager] Routing audio to earpiece, disabling bluetooth audio route 2019-05-02 07:09:30:591 [linphone-android] MESSAGE [MediaScanner] Scan completed : /storage/emulated/0/Linphone/recordings => null 2019-05-02 07:09:30:832 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_state_changed] 2019-05-02 07:09:30:832 [liblinphone] MESSAGE CallSession [0x75135541b8] New ICE state: audio: [IceStateInProgress] video: [IceStateInProgress] text: [IceStateNotActivated] 2019-05-02 07:09:30:832 [liblinphone] MESSAGE startStreams() CallSession=[0x75135541b8] local upload_bandwidth=[0] kbit/s; local download_bandwidth=[0] kbit/s 2019-05-02 07:09:30:832 [liblinphone] MESSAGE Audio bandwidth for CallSession [0x75135541b8] is 80 2019-05-02 07:09:30:832 [liblinphone] WARNING CallSession [0x75135541b8] - advanced adaptive rate control requested but avpf is not activated in this stream. Reverting to basic rate control instead 2019-05-02 07:09:30:832 [ortp] MESSAGE RtpSession [0x751f6c1000] sending to rtp [2001:41d0:700:789::2020]:27160 rtcp [2001:41d0:700:789::2020]:27161 2019-05-02 07:09:30:833 [mediastreamer] MESSAGE ms_filter_unlink: MSRtpRecv:0x753afd0100,0-->MSVoidSink:0x753aeecd80,0 2019-05-02 07:09:30:833 [mediastreamer] MESSAGE speex_lib_ctl init with neon ? 1 2019-05-02 07:09:30:833 [mediastreamer] MESSAGE speex_lib_ctl init with neon ? 1 2019-05-02 07:09:30:833 [mediastreamer] MESSAGE MKVRecorder: initialisation 2019-05-02 07:09:30:833 [mediastreamer] MESSAGE speex_lib_ctl init with neon ? 1 2019-05-02 07:09:30:833 [mediastreamer] MESSAGE Configuring av recorder with audio format type=audio;encoding=opus;rate=48000;channels=1;fmtp='' 2019-05-02 07:09:30:833 [mediastreamer] MESSAGE MKVRecorder: set pin #1 format. type=audio;encoding=opus;rate=48000;channels=1;fmtp='' 2019-05-02 07:09:30:833 [mediastreamer] MESSAGE target bitrate not set for stream [0x7526193480] using payload's bitrate is 80000 2019-05-02 07:09:30:833 [mediastreamer] MESSAGE Setting audio encoder network bitrate to [80000] on stream [0x7526193480] 2019-05-02 07:09:30:833 [mediastreamer] MESSAGE MSUlawEnc: got ptime=20 2019-05-02 07:09:30:834 [mediastreamer] MESSAGE This device (HUAWEI/FRD-L04/hi3650) says it has no built-in echo canceller. 2019-05-02 07:09:30:834 [mediastreamer] MESSAGE No information available for [HUAWEI/FRD-L04/hi3650], 2019-05-02 07:09:30:834 [mediastreamer] MESSAGE Using android >= 8 sound device descriptor. 2019-05-02 07:09:30:834 [mediastreamer] MESSAGE Sound device information for [HUAWEI/FRD-L04/hi3650] is: builtin=[yes], delay=[0] ms 2019-05-02 07:09:30:835 [mediastreamer] MESSAGE This device (HUAWEI/FRD-L04/hi3650) says it has no built-in echo canceller. 2019-05-02 07:09:30:835 [mediastreamer] MESSAGE No information available for [HUAWEI/FRD-L04/hi3650], 2019-05-02 07:09:30:835 [mediastreamer] MESSAGE Using android >= 8 sound device descriptor. 2019-05-02 07:09:30:835 [mediastreamer] MESSAGE Sound device information for [HUAWEI/FRD-L04/hi3650] is: builtin=[yes], delay=[0] ms 2019-05-02 07:09:30:835 [mediastreamer] MESSAGE MSVolume set gain to [0.000000 db], [1.000000] linear 2019-05-02 07:09:30:836 [mediastreamer] MESSAGE This device (HUAWEI/FRD-L04/hi3650) says it has no built-in echo canceller. 2019-05-02 07:09:30:836 [mediastreamer] MESSAGE No information available for [HUAWEI/FRD-L04/hi3650], 2019-05-02 07:09:30:836 [mediastreamer] MESSAGE Using android >= 8 sound device descriptor. 2019-05-02 07:09:30:836 [mediastreamer] MESSAGE Sound device information for [HUAWEI/FRD-L04/hi3650] is: builtin=[yes], delay=[0] ms 2019-05-02 07:09:30:836 [mediastreamer] MESSAGE MSVolume set gain to [0.000000 db], [1.000000] linear 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE configuring MSOpenSLESRecorder:0x753afd0100-->MSUlawEnc:0x7520344d80 from rate [48000] to rate [8000] and from channel [1] to channel [1] 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE configuring MSUlawDec:0x7520344e00-->MSOpenSLESPlayer:0x753aeecd80 from rate [8000] to rate [48000] and from channel [1] to channel [1] 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSOpenSLESRecorder:0x753afd0100,0-->MSResample:0x752036c000,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSResample:0x752036c000,0-->MSEqualizer:0x752036c800,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSEqualizer:0x752036c800,0-->MSVolume:0x7520db5900,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSVolume:0x7520db5900,0-->MSDtmfGen:0x7520344480,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSDtmfGen:0x7520344480,0-->MSAudioMixer:0x752036c180,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSAudioMixer:0x752036c180,0-->MSUlawEnc:0x7520344d80,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSUlawEnc:0x7520344d80,0-->MSRtpSend:0x7520db5800,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSRtpRecv:0x7520329b80,0-->MSUlawDec:0x7520344e00,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSUlawDec:0x7520344e00,0-->MSGenericPLC:0x752036c900,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSGenericPLC:0x752036c900,0-->MSAudioFlowControl:0x752036c980,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSAudioFlowControl:0x752036c980,0-->MSDtmfGen:0x7520344280,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSDtmfGen:0x7520344280,0-->MSVolume:0x7520344e80,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSVolume:0x7520344e80,0-->MSTee:0x752036c300,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSTee:0x752036c300,0-->MSEqualizer:0x752036c880,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSEqualizer:0x752036c880,0-->MSAudioMixer:0x752036ca00,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE speex_lib_ctl init with neon ? 1 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSFilePlayer:0x752036ca80,0-->MSResample:0x752036cb00,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSResample:0x752036cb00,0-->MSAudioMixer:0x752036ca00,1 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSAudioMixer:0x752036ca00,0-->MSResample:0x752036c080,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSResample:0x752036c080,0-->MSOpenSLESPlayer:0x753aeecd80,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSAudioMixer:0x752036c280,1-->MSResample:0x752036c700,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSResample:0x752036c700,0-->MSOpusEnc:0x752036c780,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSOpusEnc:0x752036c780,0-->MSMKVRecorder:0x752036c400,1 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSItcSource:0x752036c480,0-->MSMKVRecorder:0x752036c400,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSAudioMixer:0x752036c180,1-->MSAudioMixer:0x752036c280,0 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSTee:0x752036c300,1-->MSAudioMixer:0x752036c280,1 2019-05-02 07:09:30:837 [mediastreamer] MESSAGE ms_filter_link: MSAudioMixer:0x752036c280,0-->MSFileRec:0x752036c200,0 2019-05-02 07:09:30:907 [mediastreamer] MESSAGE Initializing speex resampler in mode [voip] 2019-05-02 07:09:30:907 [mediastreamer] MESSAGE Initializing speex resampler in mode [voip] 2019-05-02 07:09:30:907 [mediastreamer] MESSAGE Initializing speex resampler in mode [voip] 2019-05-02 07:09:30:922 [mediastreamer] MESSAGE Initializing speex resampler in mode [voip] 2019-05-02 07:09:30:922 [mediastreamer] MESSAGE MSOpusEnc: codec bitrate set to [30000] with ptime [20] 2019-05-02 07:09:30:922 [mediastreamer] MESSAGE Setting opus codec bitrate to [30000] from network bitrate [46000] with ptime [20] 2019-05-02 07:09:30:922 [mediastreamer] MESSAGE Filter MSRtpRecv is already being scheduled; nothing to do. 2019-05-02 07:09:30:923 [mediastreamer] MESSAGE This device (HUAWEI/FRD-L04/hi3650) says it has no built-in echo canceller. 2019-05-02 07:09:30:923 [mediastreamer] MESSAGE No information available for [HUAWEI/FRD-L04/hi3650], 2019-05-02 07:09:30:923 [mediastreamer] MESSAGE Using android >= 8 sound device descriptor. 2019-05-02 07:09:30:923 [mediastreamer] MESSAGE Sound device information for [HUAWEI/FRD-L04/hi3650] is: builtin=[yes], delay=[0] ms 2019-05-02 07:09:30:923 [mediastreamer] MESSAGE MSVolume set gain to [0.000000 db], [1.000000] linear 2019-05-02 07:09:30:924 [mediastreamer] MESSAGE This device (HUAWEI/FRD-L04/hi3650) says it has no built-in echo canceller. 2019-05-02 07:09:30:924 [mediastreamer] MESSAGE No information available for [HUAWEI/FRD-L04/hi3650], 2019-05-02 07:09:30:924 [mediastreamer] MESSAGE Using android >= 8 sound device descriptor. 2019-05-02 07:09:30:924 [mediastreamer] MESSAGE Sound device information for [HUAWEI/FRD-L04/hi3650] is: builtin=[yes], delay=[0] ms 2019-05-02 07:09:30:924 [mediastreamer] MESSAGE MSVolume set gain to [0.000000 db], [1.000000] linear 2019-05-02 07:09:30:924 [mediastreamer] MESSAGE audio_stream_link_video() connecting itc filters 2019-05-02 07:09:30:924 [liblinphone] MESSAGE No valid video stream defined 2019-05-02 07:09:30:924 [mediastreamer] MESSAGE ICE: connectivity checks are going to start for check list 0x752616eb00 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Candidate pairs foundations: 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 1 Hcbb5ed22 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 1 Hf5a91683 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 1 Hdccea0f2 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 1 H67c355d6 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 2 Ha4b1609 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 2 Ha4b1608 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 2 Ha4b1605 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 2 Ha4b16f7 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 2 S43c1e301 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 1 2161c1713ffb139a 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Candidate pairs: 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 1 [0x7520bf8060]: state=Waiting use=0 nominated=0 priority=9151314442783293438 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Local: [0x751e3aee60]: type=host ip=2001:1234:5678:abcd:5453:af89:8529:61af port=7078 componentID=1 priority=2130706431 foundation=1 base=0x751e3aee60 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Remote: [0x752033c740]: type=host ip=fd31:aeb1:48df::2 port=11110 componentID=1 priority=2130706431 foundation=H67c355d6 base=0x752033c740 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 2 [0x7520bf80b0]: state=Waiting use=0 nominated=0 priority=9151314442783293438 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Local: [0x751e3aee60]: type=host ip=2001:1234:5678:abcd:5453:af89:8529:61af port=7078 componentID=1 priority=2130706431 foundation=1 base=0x751e3aee60 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Remote: [0x752033c880]: type=host ip=2001:123:ab:123::2 port=11110 componentID=1 priority=2130706431 foundation=Hdccea0f2 base=0x752033c880 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 3 [0x7520bf8790]: state=Waiting use=0 nominated=0 priority=9151314442783293438 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Local: [0x751e3aee60]: type=host ip=2001:1234:5678:abcd:5453:af89:8529:61af port=7078 componentID=1 priority=2130706431 foundation=1 base=0x751e3aee60 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Remote: [0x752033c920]: type=host ip=2001:1234:5678:abcd::2 port=11110 componentID=1 priority=2130706431 foundation=Hf5a91683 base=0x752033c920 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 4 [0x7520bf87e0]: state=Waiting use=0 nominated=0 priority=9151314442783293438 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Local: [0x751e3aee60]: type=host ip=2001:1234:5678:abcd:5453:af89:8529:61af port=7078 componentID=1 priority=2130706431 foundation=1 base=0x751e3aee60 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Remote: [0x752033c9c0]: type=host ip=fe80::21f:c6ff:fec4:926a port=11110 componentID=1 priority=2130706431 foundation=Hcbb5ed22 base=0x752033c9c0 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 5 [0x7520bf8830]: state=Waiting use=0 nominated=0 priority=72057594004373502 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Local: [0x751e3aee60]: type=host ip=2001:1234:5678:abcd:5453:af89:8529:61af port=7078 componentID=1 priority=2130706431 foundation=1 base=0x751e3aee60 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Remote: [0x752033cd80]: * type=relay ip=2001:41d0:700:789::2020 port=27160 componentID=1 priority=16777215 foundation=2161c1713ffb139a base=0x0 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 6 [0x7520bf8880]: state=Frozen use=0 nominated=0 priority=72057589709406204 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Local: [0x751e3aef00]: type=host ip=2001:1234:5678:abcd:5453:af89:8529:61af port=7079 componentID=2 priority=2130706430 foundation=1 base=0x751e3aef00 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Remote: [0x752033ce20]: * type=relay ip=2001:41d0:700:789::2020 port=27161 componentID=2 priority=16777214 foundation=2161c1713ffb139a base=0x0 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 7 [0x7520bf88d0]: state=Waiting use=0 nominated=0 priority=9151313893027479551 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Local: [0x751e3af0e0]: type=host ip=10.75.22.32 port=7078 componentID=1 priority=2130706303 foundation=2 base=0x751e3af0e0 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Remote: [0x752033ca60]: type=host ip=10.75.22.247 port=11110 componentID=1 priority=2130706431 foundation=Ha4b16f7 base=0x752033ca60 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 8 [0x75141fcfb0]: state=Waiting use=0 nominated=0 priority=9151313893027479551 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Local: [0x751e3af0e0]: type=host ip=10.75.22.32 port=7078 componentID=1 priority=2130706303 foundation=2 base=0x751e3af0e0 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Remote: [0x752033cb00]: type=host ip=10.75.22.5 port=11110 componentID=1 priority=2130706431 foundation=Ha4b1605 base=0x752033cb00 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 9 [0x75209dc870]: state=Waiting use=0 nominated=0 priority=9151313893027479551 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Local: [0x751e3af0e0]: type=host ip=10.75.22.32 port=7078 componentID=1 priority=2130706303 foundation=2 base=0x751e3af0e0 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Remote: [0x752033cba0]: type=host ip=10.75.22.8 port=11110 componentID=1 priority=2130706431 foundation=Ha4b1608 base=0x752033cba0 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 10 [0x75209dc8c0]: state=Waiting use=0 nominated=0 priority=9151313893027479551 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Local: [0x751e3af0e0]: type=host ip=10.75.22.32 port=7078 componentID=1 priority=2130706303 foundation=2 base=0x751e3af0e0 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Remote: [0x752033cc40]: type=host ip=10.75.22.9 port=11110 componentID=1 priority=2130706431 foundation=Ha4b1609 base=0x752033cc40 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 11 [0x75209dc910]: state=Waiting use=0 nominated=0 priority=7277816997797166846 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Local: [0x751e3af0e0]: type=host ip=10.75.22.32 port=7078 componentID=1 priority=2130706303 foundation=2 base=0x751e3af0e0 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Remote: [0x752033cce0]: type=srflx ip=67.193.227.1 port=11110 componentID=1 priority=1694498815 foundation=S43c1e301 base=0x0 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Check list: 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 1 [0x7520bf87e0]: state=Waiting use=0 nominated=0 priority=9151314442783293438 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Local: [0x751e3aee60]: type=host ip=2001:1234:5678:abcd:5453:af89:8529:61af port=7078 componentID=1 priority=2130706431 foundation=1 base=0x751e3aee60 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Remote: [0x752033c9c0]: type=host ip=fe80::21f:c6ff:fec4:926a port=11110 componentID=1 priority=2130706431 foundation=Hcbb5ed22 base=0x752033c9c0 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 2 [0x7520bf8790]: state=Waiting use=0 nominated=0 priority=9151314442783293438 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Local: [0x751e3aee60]: type=host ip=2001:1234:5678:abcd:5453:af89:8529:61af port=7078 componentID=1 priority=2130706431 foundation=1 base=0x751e3aee60 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE Remote: [0x752033c920]: type=host ip=2001:1234:5678:abcd::2 port=11110 componentID=1 priority=2130706431 foundation=Hf5a91683 base=0x752033c920 2019-05-02 07:09:30:925 [mediastreamer] MESSAGE 3 [0x7520bf80b0]: state=Waiting use=0 nominated=0 priority=9151314442783293438 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE Local: [0x751e3aee60]: type=host ip=2001:1234:5678:abcd:5453:af89:8529:61af port=7078 componentID=1 priority=2130706431 foundation=1 base=0x751e3aee60 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE Remote: [0x752033c880]: type=host ip=2001:123:ab:123::2 port=11110 componentID=1 priority=2130706431 foundation=Hdccea0f2 base=0x752033c880 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE 4 [0x7520bf8060]: state=Waiting use=0 nominated=0 priority=9151314442783293438 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE Local: [0x751e3aee60]: type=host ip=2001:1234:5678:abcd:5453:af89:8529:61af port=7078 componentID=1 priority=2130706431 foundation=1 base=0x751e3aee60 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE Remote: [0x752033c740]: type=host ip=fd31:aeb1:48df::2 port=11110 componentID=1 priority=2130706431 foundation=H67c355d6 base=0x752033c740 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE 5 [0x75209dc8c0]: state=Waiting use=0 nominated=0 priority=9151313893027479551 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE Local: [0x751e3af0e0]: type=host ip=10.75.22.32 port=7078 componentID=1 priority=2130706303 foundation=2 base=0x751e3af0e0 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE Remote: [0x752033cc40]: type=host ip=10.75.22.9 port=11110 componentID=1 priority=2130706431 foundation=Ha4b1609 base=0x752033cc40 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE 6 [0x75209dc870]: state=Waiting use=0 nominated=0 priority=9151313893027479551 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE Local: [0x751e3af0e0]: type=host ip=10.75.22.32 port=7078 componentID=1 priority=2130706303 foundation=2 base=0x751e3af0e0 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE Remote: [0x752033cba0]: type=host ip=10.75.22.8 port=11110 componentID=1 priority=2130706431 foundation=Ha4b1608 base=0x752033cba0 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE 7 [0x75141fcfb0]: state=Waiting use=0 nominated=0 priority=9151313893027479551 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE Local: [0x751e3af0e0]: type=host ip=10.75.22.32 port=7078 componentID=1 priority=2130706303 foundation=2 base=0x751e3af0e0 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE Remote: [0x752033cb00]: type=host ip=10.75.22.5 port=11110 componentID=1 priority=2130706431 foundation=Ha4b1605 base=0x752033cb00 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE 8 [0x7520bf88d0]: state=Waiting use=0 nominated=0 priority=9151313893027479551 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE Local: [0x751e3af0e0]: type=host ip=10.75.22.32 port=7078 componentID=1 priority=2130706303 foundation=2 base=0x751e3af0e0 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE Remote: [0x752033ca60]: type=host ip=10.75.22.247 port=11110 componentID=1 priority=2130706431 foundation=Ha4b16f7 base=0x752033ca60 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE 9 [0x75209dc910]: state=Waiting use=0 nominated=0 priority=7277816997797166846 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE Local: [0x751e3af0e0]: type=host ip=10.75.22.32 port=7078 componentID=1 priority=2130706303 foundation=2 base=0x751e3af0e0 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE Remote: [0x752033cce0]: type=srflx ip=67.193.227.1 port=11110 componentID=1 priority=1694498815 foundation=S43c1e301 base=0x0 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE 10 [0x7520bf8830]: state=Waiting use=0 nominated=0 priority=72057594004373502 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE Local: [0x751e3aee60]: type=host ip=2001:1234:5678:abcd:5453:af89:8529:61af port=7078 componentID=1 priority=2130706431 foundation=1 base=0x751e3aee60 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE Remote: [0x752033cd80]: * type=relay ip=2001:41d0:700:789::2020 port=27160 componentID=1 priority=16777215 foundation=2161c1713ffb139a base=0x0 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE 11 [0x7520bf8880]: state=Frozen use=0 nominated=0 priority=72057589709406204 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE Local: [0x751e3aef00]: type=host ip=2001:1234:5678:abcd:5453:af89:8529:61af port=7079 componentID=2 priority=2130706430 foundation=1 base=0x751e3aef00 2019-05-02 07:09:30:926 [mediastreamer] MESSAGE Remote: [0x752033ce20]: * type=relay ip=2001:41d0:700:789::2020 port=27161 componentID=2 priority=16777214 foundation=2161c1713ffb139a base=0x0 2019-05-02 07:09:30:926 [liblinphone] MESSAGE CallSession[0x75135541b8] : payload type 0 PCMU/8000 fmtp= added to frozen list 2019-05-02 07:09:30:926 [liblinphone] MESSAGE CallSession[0x75135541b8] : payload type 8 PCMA/8000 fmtp= added to frozen list 2019-05-02 07:09:30:926 [liblinphone] MESSAGE CallSession[0x75135541b8] : payload type 110 speex/8000 fmtp=vbr=on added to frozen list 2019-05-02 07:09:30:926 [liblinphone] MESSAGE CallSession[0x75135541b8] : payload type 117 speex/16000 fmtp=vbr=on added to frozen list 2019-05-02 07:09:30:926 [liblinphone] MESSAGE CallSession[0x75135541b8] : payload type 107 opus/48000 fmtp=useinbandfec=1 added to frozen list 2019-05-02 07:09:30:926 [liblinphone] MESSAGE CallSession [0x75135541b8] moving from state State::Connected to State::StreamsRunning 2019-05-02 07:09:30:926 [linphone-android] MESSAGE [Manager] New call state [StreamsRunning] 2019-05-02 07:09:30:931 [linphone-android] WARNING [Manager][AudioManager] already in MODE_IN_COMMUNICATION, skipping... 2019-05-02 07:09:30:933 [linphone-android] ERROR CallIncommingActivity - onCreate - State.StreamsRunning - speaker = false 2019-05-02 07:09:30:934 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_state_changed] 2019-05-02 07:09:30:934 [liblinphone] MESSAGE CallSession accepted 2019-05-02 07:09:30:934 [mediastreamer] MESSAGE MSAudioMixer [0x752036c180] is entering bypass mode. 2019-05-02 07:09:30:934 [linphone-android] WARNING [Manager] Routing audio to earpiece, disabling bluetooth audio route 2019-05-02 07:09:30:948 [ortp] MESSAGE First estimation 2019-05-02 07:09:30:948 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=0.00,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-05-02 07:09:30:948 [liblinphone] MESSAGE Thread processing load: audio=1.64101 video=0.00791748 text=0 2019-05-02 07:09:30:949 [mediastreamer] MESSAGE ice: Send binding request for Waiting pair 0x7520bf87e0: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [fe80::21f:c6ff:fec4:926a]:11110:host [a92f65d5639dcb94019a3d86] (flags:none) 2019-05-02 07:09:30:949 [mediastreamer] MESSAGE ice: Recv binding response: 10.75.22.32:7079 <-- 37.59.51.72:3478 [05bf431e599166ab6a933f87] 2019-05-02 07:09:30:949 [mediastreamer] WARNING ice: Received a binding response for an unknown transaction ID: 05bf431e599166ab6a933f87 2019-05-02 07:09:30:949 [liblinphone] MESSAGE Callbacks [0x7520bf6e90] unregistered on core [0x754549e800] 2019-05-02 07:09:30:950 [liblinphone] MESSAGE Callbacks [0x7520bf6670] unregistered on core [0x754549e800] 2019-05-02 07:09:30:950 [linphone-android] MESSAGE [Service] Activity paused:org.linphone.call.CallIncomingActivity@dd80f80 2019-05-02 07:09:30:950 [linphone-android] MESSAGE [Service] runningActivities=0 2019-05-02 07:09:30:977 [linphone-android] MESSAGE [Service] Activity created:org.linphone.call.CallActivity@4c89899 2019-05-02 07:09:31:023 [mediastreamer] ERROR Cannot open camera 0: Fail to connect to camera service 2019-05-02 07:09:31:028 [mediastreamer] ERROR Cannot open camera 1: Fail to connect to camera service 2019-05-02 07:09:31:031 [liblinphone] MESSAGE Notifying all friends that we are [on-the-phone] 2019-05-02 07:09:31:037 [linphone-android] MESSAGE Activity started:org.linphone.call.CallActivity@4c89899 2019-05-02 07:09:31:038 [linphone-android] MESSAGE [Service] Activity resumed:org.linphone.call.CallActivity@4c89899 2019-05-02 07:09:31:038 [linphone-android] MESSAGE [Service] runningActivities=1 2019-05-02 07:09:31:038 [liblinphone] MESSAGE Core callbacks [0x75209dd8b0] registered on core [0x754549e800] 2019-05-02 07:09:31:041 [linphone-android] WARNING Can't display ZRTP popup, no token ! 2019-05-02 07:09:31:044 [liblinphone] MESSAGE Core callbacks [0x75209dd950] registered on core [0x754549e800] 2019-05-02 07:09:31:057 [belle-sip] MESSAGE Dialog sending retransmission of 200Ok 2019-05-02 07:09:31:057 [belle-sip] MESSAGE channel [0x7520d40a00]: message sent to [TLS://sip.linphone.org:5223], size: [1956] bytes SIP/2.0 200 Ok Via: SIP/2.0/TLS sip6.linphone.org:5223;received=2001:41d0:700:789::2020;rport;branch=z9hG4bK.tpeaDmt3SayFt6jty2NU5eNS6B Via: SIP/2.0/TCP 10.75.22.8:5060;received=67.193.227.1;rport=35149;branch=z9hG4bKPj03c2af33-8b93-48cf-9c59-b410e5e55e26;alias From: "[redacted]" ;tag=8600d669-ea22-4ea6-a663-182d8b0c583c To: ;tag=nRl7QO6 Call-ID: 258fc975-5ce2-4158-88de-b7bf68308ca2 CSeq: 11856 INVITE User-Agent: LinphoneAndroid/4.1-debug (FRD-L04) LinphoneSDK/4.1-366-g1b22291 (master) (belle-sip/1.6.3) Supported: replaces, outbound, gruu Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Contact: ;+org.linphone.specs="groupchat,lime" Content-Type: application/sdp Content-Length: 956 Record-route: Record-route: v=0 o=my_account 3740 1048 IN IP6 2001:1234:5678:abcd:5453:af89:8529:61af s=Talk c=IN IP6 2001:1234:5678:abcd:5453:af89:8529:61af t=0 0 a=ice-pwd:d46cbb4f04a5c17feecd6f69 a=ice-ufrag:096d5d3c a=Ik:jT4cMZ8SBu8oQ4V8J7KlEoGWq0tHAe/LJSDffZ1mAeQ= m=audio 7078 RTP/AVP 0 8 110 117 107 c=IN IP4 67.193.227.1 a=rtpmap:110 speex/8000 a=fmtp:110 vbr=on a=rtpmap:117 speex/16000 a=fmtp:117 vbr=on a=rtpmap:107 opus/48000/2 a=fmtp:107 useinbandfec=1 a=candidate:1 1 UDP 2130706431 2001:1234:5678:abcd:5453:af89:8529:61af 7078 typ host a=candidate:1 2 UDP 2130706430 2001:1234:5678:abcd:5453:af89:8529:61af 7079 typ host a=candidate:2 1 UDP 2130706303 10.75.22.32 7078 typ host a=candidate:2 2 UDP 2130706302 10.75.22.32 7079 typ host a=candidate:3 1 UDP 1694498687 67.193.227.1 7078 typ srflx raddr 10.75.22.32 rport 7078 a=candidate:3 2 UDP 1694498686 67.193.227.1 7079 typ srflx raddr 10.75.22.32 rport 7079 m=video 0 RTP/AVP 0 a=inactive 2019-05-02 07:09:31:058 [mediastreamer] MESSAGE ice: Send binding request for Waiting pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host [6a2f90db3f980d7e765abc3a] (flags:none) 2019-05-02 07:09:31:110 [mediastreamer] MESSAGE ice: Send binding request for Waiting pair 0x7520bf80b0: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:123:ab:123::2]:11110:host [56e1d55fc8b98fbc7fca5e39] (flags:none) 2019-05-02 07:09:31:161 [mediastreamer] MESSAGE ice: Retransmit (1) binding request for pair 0x7520bf87e0: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [fe80::21f:c6ff:fec4:926a]:11110:host [a92f65d5639dcb94019a3d86] 2019-05-02 07:09:31:161 [mediastreamer] MESSAGE ice: Send binding request for Waiting pair 0x7520bf8060: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [fd31:aeb1:48df::2]:11110:host [111badf823696c9baf7ff102] (flags:none) 2019-05-02 07:09:31:202 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x2cfa] 2019-05-02 07:09:31:202 [belle-sip] MESSAGE channel [0x7520d40a00]: starting recv background task with id=[2cfa]. 2019-05-02 07:09:31:202 [belle-sip] MESSAGE channel [0x7520d40a00]: received [627] new bytes from [TLS://sip.linphone.org:5223]: ACK sip:my_account@[2001:1234:5678:abcd:5453:af89:8529:61af]:44542;pn-silent=1;transport=tls;fs-conn-id=89fa2e93437c9219 SIP/2.0 Via: SIP/2.0/TLS sip6.linphone.org:5223;rport;branch=z9hG4bK.r9385mDr245r4FvKU6D00pajSg Via: SIP/2.0/TCP 10.75.22.8:5060;rport=35149;branch=z9hG4bKPj8ec184c9-1c57-485d-ba02-74351e133e10;alias;received=67.193.227.1 From: "[redacted]" ;tag=8600d669-ea22-4ea6-a663-182d8b0c583c To: ;tag=nRl7QO6 Call-ID: 258fc975-5ce2-4158-88de-b7bf68308ca2 CSeq: 11856 ACK Max-Forwards: 69 User-Agent: Asterisk PBX 13.26.0 Content-Length: 0 2019-05-02 07:09:31:202 [mediastreamer] MESSAGE ice: Send binding request for Waiting pair 0x75209dc8c0: 10.75.22.32:7078:host --> 10.75.22.9:11110:host [b516c61cf328d31c954da585] (flags:none) 2019-05-02 07:09:31:202 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:1234:5678:abcd::2]:11110 [fc250000b825ff34a52c1e30] (flags:use-candidate) 2019-05-02 07:09:31:202 [mediastreamer] MESSAGE ice: we are receiving a STUN request on pair 0x7520bf8790, for which an outgoing STUN transaction is running. 2019-05-02 07:09:31:202 [mediastreamer] MESSAGE ice: transaction is canceled, a new binding request sent. 2019-05-02 07:09:31:202 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:202 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:1234:5678:abcd::2]:11110 [fc250000b825ff34a52c1e30] 2019-05-02 07:09:31:202 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:1234:5678:abcd::2]:11110 [fc2500008dee2f49a62c1e30] (flags:use-candidate) 2019-05-02 07:09:31:202 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:1234:5678:abcd::2]:11110 [fc2500008dee2f49a62c1e30] 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: Recv binding request: 10.75.22.32:7078 <-- 10.75.22.247:11110 [fc250000183bbf32a92c1e30] (flags:use-candidate) 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: Send binding response: 10.75.22.32:7078 --> 10.75.22.247:11110 [fc250000183bbf32a92c1e30] 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:1234:5678:abcd::2]:11110 [fc250000f3025a29a72c1e30] (flags:use-candidate) 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:1234:5678:abcd::2]:11110 [fc250000f3025a29a72c1e30] 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:1234:5678:abcd::2]:11110 [fc2500002199a87ba82c1e30] (flags:use-candidate) 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:1234:5678:abcd::2]:11110 [fc2500002199a87ba82c1e30] 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:1234:5678:abcd::2]:11110 [fc250000b825ff34a52c1e30] (flags:use-candidate) 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:1234:5678:abcd::2]:11110 [fc250000b825ff34a52c1e30] 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:1234:5678:abcd::2]:11110 [fc2500008dee2f49a62c1e30] (flags:use-candidate) 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:1234:5678:abcd::2]:11110 [fc2500008dee2f49a62c1e30] 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: Recv binding request: 10.75.22.32:7078 <-- 10.75.22.247:11110 [fc250000dad75c07aa2c1e30] (flags:use-candidate) 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:203 [mediastreamer] MESSAGE ice: Send binding response: 10.75.22.32:7078 --> 10.75.22.247:11110 [fc250000dad75c07aa2c1e30] 2019-05-02 07:09:31:204 [mediastreamer] MESSAGE ice: Recv binding request: 10.75.22.32:7078 <-- 10.75.22.247:11110 [fc2500000778b81aab2c1e30] (flags:use-candidate) 2019-05-02 07:09:31:204 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:204 [mediastreamer] MESSAGE ice: Send binding response: 10.75.22.32:7078 --> 10.75.22.247:11110 [fc2500000778b81aab2c1e30] 2019-05-02 07:09:31:204 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:1234:5678:abcd::2]:11110 [fc250000f3025a29a72c1e30] (flags:use-candidate) 2019-05-02 07:09:31:204 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:204 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:1234:5678:abcd::2]:11110 [fc250000f3025a29a72c1e30] 2019-05-02 07:09:31:204 [mediastreamer] MESSAGE ice: Recv binding request: 10.75.22.32:7078 <-- 10.75.22.247:11110 [fc2500003f53f633ac2c1e30] (flags:use-candidate) 2019-05-02 07:09:31:204 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:204 [mediastreamer] MESSAGE ice: Send binding response: 10.75.22.32:7078 --> 10.75.22.247:11110 [fc2500003f53f633ac2c1e30] 2019-05-02 07:09:31:204 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:41d0:700:789::2020]:27160 [fc2500003ea54c44b42c1e30] (flags:use-candidate) 2019-05-02 07:09:31:204 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:204 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:41d0:700:789::2020]:27160 [fc2500003ea54c44b42c1e30] 2019-05-02 07:09:31:204 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:1234:5678:abcd::2]:11110 [fc2500002199a87ba82c1e30] (flags:use-candidate) 2019-05-02 07:09:31:204 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:204 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:1234:5678:abcd::2]:11110 [fc2500002199a87ba82c1e30] 2019-05-02 07:09:31:204 [mediastreamer] MESSAGE ice: Recv binding request: 10.75.22.32:7078 <-- 10.75.22.247:11110 [fc250000183bbf32a92c1e30] (flags:use-candidate) 2019-05-02 07:09:31:204 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:204 [mediastreamer] MESSAGE ice: Send binding response: 10.75.22.32:7078 --> 10.75.22.247:11110 [fc250000183bbf32a92c1e30] 2019-05-02 07:09:31:205 [mediastreamer] MESSAGE ice: Recv binding request: 10.75.22.32:7078 <-- 10.75.22.247:11110 [fc250000dad75c07aa2c1e30] (flags:use-candidate) 2019-05-02 07:09:31:205 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:205 [mediastreamer] MESSAGE ice: Send binding response: 10.75.22.32:7078 --> 10.75.22.247:11110 [fc250000dad75c07aa2c1e30] 2019-05-02 07:09:31:205 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:1234:5678:abcd::2]:11110 [fc250000b825ff34a52c1e30] (flags:use-candidate) 2019-05-02 07:09:31:205 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:205 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:1234:5678:abcd::2]:11110 [fc250000b825ff34a52c1e30] 2019-05-02 07:09:31:205 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:1234:5678:abcd::2]:11110 [fc2500008dee2f49a62c1e30] (flags:use-candidate) 2019-05-02 07:09:31:205 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:205 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:1234:5678:abcd::2]:11110 [fc2500008dee2f49a62c1e30] 2019-05-02 07:09:31:220 [belle-sip] MESSAGE channel [0x7520d40a00]: received [2] new bytes from [TLS://sip.linphone.org:5223]: 2019-05-02 07:09:31:223 [belle-sip] MESSAGE channel [0x7520d40a00] [629] bytes parsed 2019-05-02 07:09:31:223 [belle-sip] MESSAGE Incoming INVITE has ACK, dialog is happy 2019-05-02 07:09:31:223 [belle-sip] MESSAGE channel [0x7520d40a00]: ending recv background task with id=[2cfa]. 2019-05-02 07:09:31:224 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x2cfa] 2019-05-02 07:09:31:224 [mediastreamer] MESSAGE ice: Recv binding request: 10.75.22.32:7078 <-- 10.75.22.247:11110 [fc2500000778b81aab2c1e30] (flags:use-candidate) 2019-05-02 07:09:31:224 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:224 [mediastreamer] MESSAGE ice: Send binding response: 10.75.22.32:7078 --> 10.75.22.247:11110 [fc2500000778b81aab2c1e30] 2019-05-02 07:09:31:225 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:41d0:700:789::2020]:27160 [fc250000a9837e73b12c1e30] (flags:use-candidate) 2019-05-02 07:09:31:225 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:225 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:41d0:700:789::2020]:27160 [fc250000a9837e73b12c1e30] 2019-05-02 07:09:31:225 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:1234:5678:abcd::2]:11110 [fc250000f3025a29a72c1e30] (flags:use-candidate) 2019-05-02 07:09:31:225 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:225 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:1234:5678:abcd::2]:11110 [fc250000f3025a29a72c1e30] 2019-05-02 07:09:31:225 [mediastreamer] MESSAGE ice: Recv binding request: 10.75.22.32:7078 <-- 10.75.22.247:11110 [fc2500003f53f633ac2c1e30] (flags:use-candidate) 2019-05-02 07:09:31:225 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:225 [mediastreamer] MESSAGE ice: Send binding response: 10.75.22.32:7078 --> 10.75.22.247:11110 [fc2500003f53f633ac2c1e30] 2019-05-02 07:09:31:225 [mediastreamer] MESSAGE ice: Recv binding request: 10.75.22.32:7078 <-- 10.75.22.247:11110 [fc250000183bbf32a92c1e30] (flags:use-candidate) 2019-05-02 07:09:31:225 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:225 [mediastreamer] MESSAGE ice: Send binding response: 10.75.22.32:7078 --> 10.75.22.247:11110 [fc250000183bbf32a92c1e30] 2019-05-02 07:09:31:225 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:1234:5678:abcd::2]:11110 [fc2500002199a87ba82c1e30] (flags:use-candidate) 2019-05-02 07:09:31:225 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:225 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:1234:5678:abcd::2]:11110 [fc2500002199a87ba82c1e30] 2019-05-02 07:09:31:225 [mediastreamer] MESSAGE ice: Recv binding request: 10.75.22.32:7078 <-- 10.75.22.247:11110 [fc250000dad75c07aa2c1e30] (flags:use-candidate) 2019-05-02 07:09:31:225 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:225 [mediastreamer] MESSAGE ice: Send binding response: 10.75.22.32:7078 --> 10.75.22.247:11110 [fc250000dad75c07aa2c1e30] 2019-05-02 07:09:31:226 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:41d0:700:789::2020]:27160 [fc25000088d4b634b22c1e30] (flags:use-candidate) 2019-05-02 07:09:31:226 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:226 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:41d0:700:789::2020]:27160 [fc25000088d4b634b22c1e30] 2019-05-02 07:09:31:240 [mediastreamer] MESSAGE ice: Recv binding request: 10.75.22.32:7078 <-- 10.75.22.247:11110 [fc2500000778b81aab2c1e30] (flags:use-candidate) 2019-05-02 07:09:31:240 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:240 [mediastreamer] MESSAGE ice: Send binding response: 10.75.22.32:7078 --> 10.75.22.247:11110 [fc2500000778b81aab2c1e30] 2019-05-02 07:09:31:261 [mediastreamer] MESSAGE ice: Send binding request for Waiting pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host [db86f70793957ff7c11a109c] (flags:none) 2019-05-02 07:09:31:261 [mediastreamer] MESSAGE ice: Recv binding request: 10.75.22.32:7078 <-- 10.75.22.247:11110 [fc2500003f53f633ac2c1e30] (flags:use-candidate) 2019-05-02 07:09:31:261 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:261 [mediastreamer] MESSAGE ice: Send binding response: 10.75.22.32:7078 --> 10.75.22.247:11110 [fc2500003f53f633ac2c1e30] 2019-05-02 07:09:31:261 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:41d0:700:789::2020]:27160 [fc25000002a19435b32c1e30] (flags:use-candidate) 2019-05-02 07:09:31:261 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:261 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:41d0:700:789::2020]:27160 [fc25000002a19435b32c1e30] 2019-05-02 07:09:31:261 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:41d0:700:789::2020]:27160 [fc2500003ea54c44b42c1e30] (flags:use-candidate) 2019-05-02 07:09:31:261 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:261 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:41d0:700:789::2020]:27160 [fc2500003ea54c44b42c1e30] 2019-05-02 07:09:31:301 [mediastreamer] MESSAGE ice: Send binding request for Waiting pair 0x7520bf88d0: 10.75.22.32:7078:host --> 10.75.22.247:11110:host [82c9076e991b7742fe6f4605] (flags:none) 2019-05-02 07:09:31:321 [mediastreamer] MESSAGE ice: Retransmit (1) binding request for pair 0x7520bf80b0: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:123:ab:123::2]:11110:host [56e1d55fc8b98fbc7fca5e39] 2019-05-02 07:09:31:342 [mediastreamer] MESSAGE ice: Send binding request for Waiting pair 0x7520bf8830: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:41d0:700:789::2020]:27160:relay [1c7b1f9e48f772c505325bf0] (flags:none) 2019-05-02 07:09:31:343 [mediastreamer] MESSAGE ice: Recv binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:1234:5678:abcd::2]:11110 [6a2f90db3f980d7e765abc3a] 2019-05-02 07:09:31:343 [mediastreamer] MESSAGE ice: Received a binding response for an cancelled transaction ID: 6a2f90db3f980d7e765abc3a 2019-05-02 07:09:31:343 [mediastreamer] MESSAGE ice: Added pair 0x7520bf8790 to the valid list: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:09:31:343 [mediastreamer] MESSAGE ice: Recv binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:1234:5678:abcd::2]:11110 [db86f70793957ff7c11a109c] 2019-05-02 07:09:31:344 [mediastreamer] MESSAGE ice: Pair already in the valid list: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:09:31:362 [mediastreamer] MESSAGE ice: Retransmit (1) binding request for pair 0x7520bf8060: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [fd31:aeb1:48df::2]:11110:host [111badf823696c9baf7ff102] 2019-05-02 07:09:31:363 [mediastreamer] MESSAGE ice: Recv binding response: 10.75.22.32:7078 <-- 10.75.22.247:11110 [82c9076e991b7742fe6f4605] 2019-05-02 07:09:31:363 [mediastreamer] MESSAGE ice: Added pair 0x7520bf88d0 to the valid list: 10.75.22.32:7078:host --> 10.75.22.247:11110:host 2019-05-02 07:09:31:364 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:41d0:700:789::2020]:27160 [fc250000a9837e73b12c1e30] (flags:use-candidate) 2019-05-02 07:09:31:364 [mediastreamer] MESSAGE ice: we are receiving a STUN request on pair 0x7520bf8830, for which an outgoing STUN transaction is running. 2019-05-02 07:09:31:364 [mediastreamer] MESSAGE ice: transaction is canceled, a new binding request sent. 2019-05-02 07:09:31:364 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:364 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:41d0:700:789::2020]:27160 [fc250000a9837e73b12c1e30] 2019-05-02 07:09:31:366 [mediastreamer] MESSAGE ice: Recv binding request: 10.75.22.32:7078 <-- 10.75.22.247:11110 [fc2500008e3a5d6eb52c1e30] (flags:use-candidate) 2019-05-02 07:09:31:366 [mediastreamer] MESSAGE ice: receiving a binding request with use-candidate flag on succeeded pair 2019-05-02 07:09:31:366 [mediastreamer] MESSAGE ice: Send binding response: 10.75.22.32:7078 --> 10.75.22.247:11110 [fc2500008e3a5d6eb52c1e30] 2019-05-02 07:09:31:382 [mediastreamer] MESSAGE ice: Send binding request for Frozen pair 0x7520bf8880: [2001:1234:5678:abcd:5453:af89:8529:61af]:7079:host --> [2001:41d0:700:789::2020]:27161:relay [ac104e3250c3694bca8517f1] (flags:none) 2019-05-02 07:09:31:494 [linphone-android] MESSAGE [Service] Activity stopped:org.linphone.call.CallIncomingActivity@dd80f80 2019-05-02 07:09:31:498 [linphone-android] MESSAGE [Service] Activity destroyed:org.linphone.call.CallIncomingActivity@dd80f80 2019-05-02 07:09:31:566 [mediastreamer] MESSAGE ice: Retransmit (2) binding request for pair 0x7520bf87e0: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [fe80::21f:c6ff:fec4:926a]:11110:host [a92f65d5639dcb94019a3d86] 2019-05-02 07:09:31:586 [mediastreamer] MESSAGE ice: Retransmit (1) binding request for pair 0x7520bf8880: [2001:1234:5678:abcd:5453:af89:8529:61af]:7079:host --> [2001:41d0:700:789::2020]:27161:relay [ac104e3250c3694bca8517f1] 2019-05-02 07:09:31:647 [mediastreamer] MESSAGE ice: Recv binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:41d0:700:789::2020]:27160 [1c7b1f9e48f772c505325bf0] 2019-05-02 07:09:31:647 [mediastreamer] MESSAGE ice: Received a binding response for an cancelled transaction ID: 1c7b1f9e48f772c505325bf0 2019-05-02 07:09:31:647 [mediastreamer] MESSAGE ice: Discovered peer reflexive candidate 54.37.202.229:1438 for componentID 1 2019-05-02 07:09:31:647 [mediastreamer] MESSAGE ice: Added pair 0x7520bf42d0 to the valid list: 54.37.202.229:1438:prflx --> [2001:41d0:700:789::2020]:27160:relay 2019-05-02 07:09:31:647 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:41d0:700:789::2020]:27160 [fc25000016dda77fb62c1e30] (flags:use-candidate) 2019-05-02 07:09:31:647 [mediastreamer] MESSAGE ice: Add new candidate pair [0x751e3aee60 - 0x752033cd80] in the check list 2019-05-02 07:09:31:647 [mediastreamer] MESSAGE ice: found the reflexive candidate corresponding to the candidate pair on which the binding request was received. 2019-05-02 07:09:31:647 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:648 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:41d0:700:789::2020]:27160 [fc25000016dda77fb62c1e30] 2019-05-02 07:09:31:728 [mediastreamer] MESSAGE ice: Retransmit (2) binding request for pair 0x7520bf80b0: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:123:ab:123::2]:11110:host [56e1d55fc8b98fbc7fca5e39] 2019-05-02 07:09:31:728 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:41d0:700:789::2020]:27160 [fc25000016dda77fb62c1e30] (flags:use-candidate) 2019-05-02 07:09:31:728 [mediastreamer] MESSAGE ice: Add new candidate pair [0x751e3aee60 - 0x752033cd80] in the check list 2019-05-02 07:09:31:728 [mediastreamer] MESSAGE ice: found the reflexive candidate corresponding to the candidate pair on which the binding request was received. 2019-05-02 07:09:31:728 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:728 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:41d0:700:789::2020]:27160 [fc25000016dda77fb62c1e30] 2019-05-02 07:09:31:768 [mediastreamer] MESSAGE ice: Retransmit (2) binding request for pair 0x7520bf8060: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [fd31:aeb1:48df::2]:11110:host [111badf823696c9baf7ff102] 2019-05-02 07:09:31:869 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=70.88,u=32.44], 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-05-02 07:09:31:869 [liblinphone] MESSAGE Thread processing load: audio=16.1898 video=0.00729494 text=0 2019-05-02 07:09:31:921 [mediastreamer] MESSAGE MSAudioMixer [0x752036ca00] is entering bypass mode. 2019-05-02 07:09:31:950 [mediastreamer] MESSAGE ice: Recv binding request: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 <-- [2001:41d0:700:789::2020]:27160 [fc25000016dda77fb62c1e30] (flags:use-candidate) 2019-05-02 07:09:31:950 [mediastreamer] MESSAGE ice: Add new candidate pair [0x751e3aee60 - 0x752033cd80] in the check list 2019-05-02 07:09:31:950 [mediastreamer] MESSAGE ice: found the reflexive candidate corresponding to the candidate pair on which the binding request was received. 2019-05-02 07:09:31:950 [mediastreamer] MESSAGE ice: receiving a binding request with nominated flag on non-succeeded pair 2019-05-02 07:09:31:950 [mediastreamer] MESSAGE ice: Send binding response: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078 --> [2001:41d0:700:789::2020]:27160 [fc25000016dda77fb62c1e30] 2019-05-02 07:09:31:990 [mediastreamer] MESSAGE ice: Retransmit (2) binding request for pair 0x7520bf8880: [2001:1234:5678:abcd:5453:af89:8529:61af]:7079:host --> [2001:41d0:700:789::2020]:27161:relay [ac104e3250c3694bca8517f1] 2019-05-02 07:09:32:378 [mediastreamer] MESSAGE ice: Retransmit (3) binding request for pair 0x7520bf87e0: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [fe80::21f:c6ff:fec4:926a]:11110:host [a92f65d5639dcb94019a3d86] 2019-05-02 07:09:32:538 [mediastreamer] MESSAGE ice: Retransmit (3) binding request for pair 0x7520bf80b0: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:123:ab:123::2]:11110:host [56e1d55fc8b98fbc7fca5e39] 2019-05-02 07:09:32:581 [mediastreamer] MESSAGE ice: Retransmit (3) binding request for pair 0x7520bf8060: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [fd31:aeb1:48df::2]:11110:host [111badf823696c9baf7ff102] 2019-05-02 07:09:32:956 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=68.11,u=100.19], 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-05-02 07:09:32:956 [liblinphone] MESSAGE Thread processing load: audio=5.17899 video=0.00837989 text=0 2019-05-02 07:09:32:957 [mediastreamer] MESSAGE ice: Retransmit (3) binding request for pair 0x7520bf8880: [2001:1234:5678:abcd:5453:af89:8529:61af]:7079:host --> [2001:41d0:700:789::2020]:27161:relay [ac104e3250c3694bca8517f1] 2019-05-02 07:09:33:311 [ortp] MESSAGE Sending RTCP SR compound message on session [0x751f6c1000]. 2019-05-02 07:09:33:316 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x7526193480], local statistics available: Local current jitter buffer size: 191.2ms 2019-05-02 07:09:33:316 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_stats_updated] 2019-05-02 07:09:33:370 [mediastreamer] MESSAGE [OpenSLES] sound/wall clock skew is average=0.303874 ms 2019-05-02 07:09:33:865 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=99.25,u=93.40], 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.79], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:09:33:865 [liblinphone] MESSAGE Thread processing load: audio=9.80176 video=0.0108843 text=0 2019-05-02 07:09:33:986 [mediastreamer] MESSAGE ice: Retransmit (4) binding request for pair 0x7520bf87e0: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [fe80::21f:c6ff:fec4:926a]:11110:host [a92f65d5639dcb94019a3d86] 2019-05-02 07:09:34:148 [mediastreamer] MESSAGE ice: Retransmit (4) binding request for pair 0x7520bf80b0: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:123:ab:123::2]:11110:host [56e1d55fc8b98fbc7fca5e39] 2019-05-02 07:09:34:187 [mediastreamer] MESSAGE ice: Retransmit (4) binding request for pair 0x7520bf8060: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [fd31:aeb1:48df::2]:11110:host [111badf823696c9baf7ff102] 2019-05-02 07:09:34:348 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:09:34:368 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf88d0: 10.75.22.32:7078:host --> 10.75.22.247:11110:host 2019-05-02 07:09:34:575 [mediastreamer] MESSAGE ice: Retransmit (4) binding request for pair 0x7520bf8880: [2001:1234:5678:abcd:5453:af89:8529:61af]:7079:host --> [2001:41d0:700:789::2020]:27161:relay [ac104e3250c3694bca8517f1] 2019-05-02 07:09:34:654 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf42d0: 54.37.202.229:1438:prflx --> [2001:41d0:700:789::2020]:27160:relay 2019-05-02 07:09:34:856 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=33.37,u=93.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=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:09:34:856 [liblinphone] MESSAGE Thread processing load: audio=5.47944 video=0.00731168 text=0 2019-05-02 07:09:34:921 [ortp] MESSAGE Sending RTCP SR compound message on session [0x751f6c1000]. 2019-05-02 07:09:34:937 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x7526193480], local statistics available: Local current jitter buffer size: 176.5ms 2019-05-02 07:09:34:937 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_stats_updated] 2019-05-02 07:09:35:866 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=90.32,u=88.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.79], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:09:35:866 [liblinphone] MESSAGE Thread processing load: audio=10.2811 video=0.0108017 text=0 2019-05-02 07:09:36:201 [ortp] MESSAGE jitter buffer stable: target-size: 258.125007 ms, effective-size: 176.543213 (min: 40 nom: 60, max: 500) 2019-05-02 07:09:36:202 [ortp] MESSAGE jitter buffer rls stats: count=259, clockrate=8000, offset=953.563 clock_ratio=0.998038, capped_offset=2953 capped_clock_ratio=0.998038, max_ts_deviation=2065.000000 prev_max_ts_deviation=1402.000000, deviation=735, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.010700 2019-05-02 07:09:36:862 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=39.65,u=89.75], 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-05-02 07:09:36:862 [liblinphone] MESSAGE Thread processing load: audio=5.48386 video=0.00704191 text=0 2019-05-02 07:09:37:154 [liblinphone] MESSAGE Destroying op [0x7526192580] of type [SalOpUnknown] 2019-05-02 07:09:37:154 [belle-sip] MESSAGE Transaction [0x7523a7f1c0] deleted 2019-05-02 07:09:37:204 [mediastreamer] MESSAGE ice: Retransmit (5) binding request for pair 0x7520bf87e0: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [fe80::21f:c6ff:fec4:926a]:11110:host [a92f65d5639dcb94019a3d86] 2019-05-02 07:09:37:362 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:09:37:363 [mediastreamer] MESSAGE ice: Retransmit (5) binding request for pair 0x7520bf80b0: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:123:ab:123::2]:11110:host [56e1d55fc8b98fbc7fca5e39] 2019-05-02 07:09:37:379 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf88d0: 10.75.22.32:7078:host --> 10.75.22.247:11110:host 2019-05-02 07:09:37:396 [mediastreamer] MESSAGE ice: Retransmit (5) binding request for pair 0x7520bf8060: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [fd31:aeb1:48df::2]:11110:host [111badf823696c9baf7ff102] 2019-05-02 07:09:37:670 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf42d0: 54.37.202.229:1438:prflx --> [2001:41d0:700:789::2020]:27160:relay 2019-05-02 07:09:37:792 [mediastreamer] MESSAGE ice: Retransmit (5) binding request for pair 0x7520bf8880: [2001:1234:5678:abcd:5453:af89:8529:61af]:7079:host --> [2001:41d0:700:789::2020]:27161:relay [ac104e3250c3694bca8517f1] 2019-05-02 07:09:37:873 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=57.20,u=89.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=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:09:37:874 [liblinphone] MESSAGE Thread processing load: audio=13.6203 video=0.0134118 text=0 2019-05-02 07:09:37:971 [ortp] MESSAGE Sending RTCP SR compound message on session [0x751f6c1000]. 2019-05-02 07:09:37:974 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x7526193480], local statistics available: Local current jitter buffer size: 248.0ms 2019-05-02 07:09:37:975 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_stats_updated] 2019-05-02 07:09:38:370 [mediastreamer] MESSAGE [OpenSLES] sound/wall clock skew is average=1.08816 ms 2019-05-02 07:09:38:865 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=95.68,u=91.88], 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.79], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:09:38:865 [liblinphone] MESSAGE Thread processing load: audio=16.1555 video=0.0129471 text=0 2019-05-02 07:09:39:858 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=62.54,u=88.02], 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-05-02 07:09:39:858 [liblinphone] MESSAGE Thread processing load: audio=12.7427 video=0.0126349 text=0 2019-05-02 07:09:40:382 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:09:40:383 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf88d0: 10.75.22.32:7078:host --> 10.75.22.247:11110:host 2019-05-02 07:09:40:685 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf42d0: 54.37.202.229:1438:prflx --> [2001:41d0:700:789::2020]:27160:relay 2019-05-02 07:09:40:693 [ortp] MESSAGE Sending RTCP SR compound message on session [0x751f6c1000]. 2019-05-02 07:09:40:705 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x7526193480], local statistics available: Local current jitter buffer size: 503.7ms 2019-05-02 07:09:40:706 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_stats_updated] 2019-05-02 07:09:40:867 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=106.62,u=87.95], 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-05-02 07:09:40:868 [liblinphone] MESSAGE Thread processing load: audio=17.4439 video=0.0116283 text=0 2019-05-02 07:09:41:251 [ortp] MESSAGE jitter buffer stable: target-size: 449.375004 ms, effective-size: 503.698639 (min: 40 nom: 60, max: 500) 2019-05-02 07:09:41:251 [ortp] MESSAGE jitter buffer rls stats: count=485, clockrate=8000, offset=858.082 clock_ratio=0.999392, capped_offset=2858 capped_clock_ratio=0.999391, max_ts_deviation=3595.000000 prev_max_ts_deviation=2279.000000, deviation=89, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.006928 2019-05-02 07:09:41:858 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=90.75,u=89.87], 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.79], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:09:41:858 [liblinphone] MESSAGE Thread processing load: audio=7.73767 video=0.00666549 text=0 2019-05-02 07:09:42:868 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=133.93,u=88.01], 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-05-02 07:09:42:869 [liblinphone] MESSAGE Thread processing load: audio=15.6032 video=0.0123278 text=0 2019-05-02 07:09:43:371 [mediastreamer] MESSAGE [OpenSLES] sound/wall clock skew is average=1.96996 ms 2019-05-02 07:09:43:396 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:09:43:397 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf88d0: 10.75.22.32:7078:host --> 10.75.22.247:11110:host 2019-05-02 07:09:43:411 [ortp] MESSAGE Sending RTCP SR compound message on session [0x751f6c1000]. 2019-05-02 07:09:43:416 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x7526193480], local statistics available: Local current jitter buffer size: 478.7ms 2019-05-02 07:09:43:417 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_stats_updated] 2019-05-02 07:09:43:620 [mediastreamer] MESSAGE ice: Retransmit (6) binding request for pair 0x7520bf87e0: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [fe80::21f:c6ff:fec4:926a]:11110:host [a92f65d5639dcb94019a3d86] 2019-05-02 07:09:43:700 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf42d0: 54.37.202.229:1438:prflx --> [2001:41d0:700:789::2020]:27160:relay 2019-05-02 07:09:43:781 [mediastreamer] MESSAGE ice: Retransmit (6) binding request for pair 0x7520bf80b0: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:123:ab:123::2]:11110:host [56e1d55fc8b98fbc7fca5e39] 2019-05-02 07:09:43:801 [mediastreamer] MESSAGE ice: Retransmit (6) binding request for pair 0x7520bf8060: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [fd31:aeb1:48df::2]:11110:host [111badf823696c9baf7ff102] 2019-05-02 07:09:43:862 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=58.14,u=87.84], 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-05-02 07:09:43:862 [liblinphone] MESSAGE Thread processing load: audio=14.7044 video=0.0120352 text=0 2019-05-02 07:09:44:207 [mediastreamer] MESSAGE ice: Retransmit (6) binding request for pair 0x7520bf8880: [2001:1234:5678:abcd:5453:af89:8529:61af]:7079:host --> [2001:41d0:700:789::2020]:27161:relay [ac104e3250c3694bca8517f1] 2019-05-02 07:09:44:853 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=55.87,u=95.25], 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.79], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:09:44:853 [liblinphone] MESSAGE Thread processing load: audio=6.20682 video=0.00975089 text=0 2019-05-02 07:09:45:864 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=58.27,u=88.04], 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-05-02 07:09:45:865 [liblinphone] MESSAGE Thread processing load: audio=15.536 video=0.0143372 text=0 2019-05-02 07:09:45:943 [mediastreamer] WARNING Flow controlled bufferizer of max 40 ms was filled with at least 50 ms in the last 5000 ms, asking to drop 10 ms 2019-05-02 07:09:45:946 [mediastreamer] WARNING Too much buffered audio signal, throwing out 10 ms 2019-05-02 07:09:46:302 [ortp] MESSAGE jitter buffer stable: target-size: 429.374993 ms, effective-size: 478.695648 (min: 40 nom: 60, max: 500) 2019-05-02 07:09:46:302 [ortp] MESSAGE jitter buffer rls stats: count=739, clockrate=8000, offset=779.19 clock_ratio=1.00219, capped_offset=2779 capped_clock_ratio=1.002193, max_ts_deviation=1632.000000 prev_max_ts_deviation=1547.000000, deviation=77, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.004949 2019-05-02 07:09:46:413 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:09:46:414 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf88d0: 10.75.22.32:7078:host --> 10.75.22.247:11110:host 2019-05-02 07:09:46:715 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf42d0: 54.37.202.229:1438:prflx --> [2001:41d0:700:789::2020]:27160:relay 2019-05-02 07:09:46:855 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=82.33,u=87.98], 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-05-02 07:09:46:856 [liblinphone] MESSAGE Thread processing load: audio=14.519 video=0.0127788 text=0 2019-05-02 07:09:47:867 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=71.85,u=89.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=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:09:47:867 [liblinphone] MESSAGE Thread processing load: audio=15.8709 video=0.0119397 text=0 2019-05-02 07:09:48:371 [mediastreamer] MESSAGE [OpenSLES] sound/wall clock skew is average=2.09804 ms 2019-05-02 07:09:48:855 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=88.49,u=87.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=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:09:48:855 [liblinphone] MESSAGE Thread processing load: audio=15.8883 video=0.0118919 text=0 2019-05-02 07:09:48:903 [ortp] MESSAGE Sending RTCP SR compound message on session [0x751f6c1000]. 2019-05-02 07:09:48:916 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x7526193480], local statistics available: Local current jitter buffer size: 437.8ms 2019-05-02 07:09:48:917 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_stats_updated] 2019-05-02 07:09:49:421 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:09:49:422 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf88d0: 10.75.22.32:7078:host --> 10.75.22.247:11110:host 2019-05-02 07:09:49:726 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf42d0: 54.37.202.229:1438:prflx --> [2001:41d0:700:789::2020]:27160:relay 2019-05-02 07:09:49:868 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=57.03,u=88.10], 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.79], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:09:49:868 [liblinphone] MESSAGE Thread processing load: audio=16.0365 video=0.0104078 text=0 2019-05-02 07:09:50:860 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=97.48,u=89.82], 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-05-02 07:09:50:860 [liblinphone] MESSAGE Thread processing load: audio=14.1999 video=0.0134495 text=0 2019-05-02 07:09:50:943 [mediastreamer] WARNING Flow controlled bufferizer of max 40 ms was filled with at least 80 ms in the last 5000 ms, asking to drop 40 ms 2019-05-02 07:09:50:960 [mediastreamer] WARNING Too much buffered audio signal, throwing out 40 ms 2019-05-02 07:09:51:372 [ortp] MESSAGE jitter buffer stable: target-size: 409.375012 ms, effective-size: 437.819550 (min: 40 nom: 60, max: 500) 2019-05-02 07:09:51:372 [ortp] MESSAGE jitter buffer rls stats: count=991, clockrate=8000, offset=774.142 clock_ratio=1.00221, capped_offset=2774 capped_clock_ratio=1.002211, max_ts_deviation=1796.000000 prev_max_ts_deviation=1632.000000, deviation=251, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.003825 2019-05-02 07:09:51:869 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=66.01,u=87.91], 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-05-02 07:09:51:870 [liblinphone] MESSAGE Thread processing load: audio=16.0513 video=0.0126249 text=0 2019-05-02 07:09:52:172 [belle-sip] MESSAGE channel [0x752606f600]: keep alive sent to [UDP://pbx.example.com:5060] 2019-05-02 07:09:52:434 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:09:52:436 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf88d0: 10.75.22.32:7078:host --> 10.75.22.247:11110:host 2019-05-02 07:09:52:737 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf42d0: 54.37.202.229:1438:prflx --> [2001:41d0:700:789::2020]:27160:relay 2019-05-02 07:09:52:860 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=125.02,u=88.09], 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-05-02 07:09:52:860 [liblinphone] MESSAGE Thread processing load: audio=14.689 video=0.0119658 text=0 2019-05-02 07:09:53:370 [mediastreamer] MESSAGE [OpenSLES] sound/wall clock skew is average=2.04565 ms 2019-05-02 07:09:53:873 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=29.03,u=89.75], 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-05-02 07:09:53:873 [liblinphone] MESSAGE Thread processing load: audio=13.8934 video=0.0145642 text=0 2019-05-02 07:09:53:951 [ortp] MESSAGE Sending RTCP SR compound message on session [0x751f6c1000]. 2019-05-02 07:09:53:953 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x7526193480], local statistics available: Local current jitter buffer size: 414.5ms 2019-05-02 07:09:53:954 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_stats_updated] 2019-05-02 07:09:54:861 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=102.68,u=88.05], 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.79], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:09:54:861 [liblinphone] MESSAGE Thread processing load: audio=16.118 video=0.0119098 text=0 2019-05-02 07:09:55:448 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:09:55:450 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf88d0: 10.75.22.32:7078:host --> 10.75.22.247:11110:host 2019-05-02 07:09:55:752 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf42d0: 54.37.202.229:1438:prflx --> [2001:41d0:700:789::2020]:27160:relay 2019-05-02 07:09:55:873 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=37.77,u=87.91], 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-05-02 07:09:55:873 [liblinphone] MESSAGE Thread processing load: audio=15.1946 video=0.0125124 text=0 2019-05-02 07:09:56:371 [ortp] MESSAGE jitter buffer stable: target-size: 409.375012 ms, effective-size: 414.496124 (min: 40 nom: 60, max: 500) 2019-05-02 07:09:56:371 [ortp] MESSAGE jitter buffer rls stats: count=1245, clockrate=8000, offset=908.074 clock_ratio=1, capped_offset=2908 capped_clock_ratio=1.000004, max_ts_deviation=2540.000000 prev_max_ts_deviation=1796.000000, deviation=774, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.003096 2019-05-02 07:09:56:438 [mediastreamer] MESSAGE ice: Retransmit (7) binding request for pair 0x7520bf87e0: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [fe80::21f:c6ff:fec4:926a]:11110:host [a92f65d5639dcb94019a3d86] 2019-05-02 07:09:56:600 [mediastreamer] MESSAGE ice: Retransmit (7) binding request for pair 0x7520bf80b0: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:123:ab:123::2]:11110:host [56e1d55fc8b98fbc7fca5e39] 2019-05-02 07:09:56:620 [mediastreamer] MESSAGE ice: Retransmit (7) binding request for pair 0x7520bf8060: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [fd31:aeb1:48df::2]:11110:host [111badf823696c9baf7ff102] 2019-05-02 07:09:56:864 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=40.65,u=91.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-05-02 07:09:56:864 [liblinphone] MESSAGE Thread processing load: audio=15.4407 video=0.0138523 text=0 2019-05-02 07:09:57:007 [mediastreamer] MESSAGE ice: Retransmit (7) binding request for pair 0x7520bf8880: [2001:1234:5678:abcd:5453:af89:8529:61af]:7079:host --> [2001:41d0:700:789::2020]:27161:relay [ac104e3250c3694bca8517f1] 2019-05-02 07:09:57:857 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=97.19,u=91.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-05-02 07:09:57:857 [liblinphone] MESSAGE Thread processing load: audio=14.4543 video=0.0118167 text=0 2019-05-02 07:09:58:370 [mediastreamer] MESSAGE [OpenSLES] sound/wall clock skew is average=2.31244 ms 2019-05-02 07:09:58:464 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:09:58:465 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf88d0: 10.75.22.32:7078:host --> 10.75.22.247:11110:host 2019-05-02 07:09:58:769 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf42d0: 54.37.202.229:1438:prflx --> [2001:41d0:700:789::2020]:27160:relay 2019-05-02 07:09:58:871 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=94.19,u=88.04], 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-05-02 07:09:58:871 [liblinphone] MESSAGE Thread processing load: audio=15.5545 video=0.0122989 text=0 2019-05-02 07:09:59:771 [ortp] MESSAGE Sending RTCP SR compound message on session [0x751f6c1000]. 2019-05-02 07:09:59:779 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x7526193480], local statistics available: Local current jitter buffer size: 398.6ms 2019-05-02 07:09:59:780 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_stats_updated] 2019-05-02 07:09:59:859 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=76.83,u=89.82], 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-05-02 07:09:59:859 [liblinphone] MESSAGE Thread processing load: audio=8.32657 video=0.00650465 text=0 2019-05-02 07:10:00:870 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=35.12,u=89.73], 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.79], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:10:00:870 [liblinphone] MESSAGE Thread processing load: audio=15.1814 video=0.01504 text=0 2019-05-02 07:10:01:461 [ortp] MESSAGE jitter buffer stable: target-size: 389.375001 ms, effective-size: 398.556702 (min: 40 nom: 60, max: 500) 2019-05-02 07:10:01:461 [ortp] MESSAGE jitter buffer rls stats: count=1493, clockrate=8000, offset=860.418 clock_ratio=1.00068, capped_offset=2860 capped_clock_ratio=1.000677, max_ts_deviation=2185.000000 prev_max_ts_deviation=1565.000000, deviation=224, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.002610 2019-05-02 07:10:01:475 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:10:01:476 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf88d0: 10.75.22.32:7078:host --> 10.75.22.247:11110:host 2019-05-02 07:10:01:779 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf42d0: 54.37.202.229:1438:prflx --> [2001:41d0:700:789::2020]:27160:relay 2019-05-02 07:10:01:860 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=79.15,u=86.25], 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-05-02 07:10:01:861 [liblinphone] MESSAGE Thread processing load: audio=13.7888 video=0.0109656 text=0 2019-05-02 07:10:02:548 [belle-sip] MESSAGE Changing [server] [INVITE] transaction [0x7520ad7840], from state [ACCEPTED] to [TERMINATED] 2019-05-02 07:10:02:548 [belle-sip] MESSAGE Server INVITE transaction [0x7520ad7840] terminated 2019-05-02 07:10:02:549 [belle-sip] MESSAGE transaction [0x7520ad7840]: ending transaction background task with id=[3172]. 2019-05-02 07:10:02:553 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x3172] 2019-05-02 07:10:02:873 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=88.83,u=89.84], 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-05-02 07:10:02:873 [liblinphone] MESSAGE Thread processing load: audio=14.857 video=0.0133274 text=0 2019-05-02 07:10:03:371 [mediastreamer] MESSAGE [OpenSLES] sound/wall clock skew is average=2.1923 ms 2019-05-02 07:10:03:866 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=48.24,u=87.91], 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-05-02 07:10:03:866 [liblinphone] MESSAGE Thread processing load: audio=16.6898 video=0.0118114 text=0 2019-05-02 07:10:04:478 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:10:04:480 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf88d0: 10.75.22.32:7078:host --> 10.75.22.247:11110:host 2019-05-02 07:10:04:781 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf42d0: 54.37.202.229:1438:prflx --> [2001:41d0:700:789::2020]:27160:relay 2019-05-02 07:10:04:862 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=99.19,u=88.01], 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-05-02 07:10:04:862 [liblinphone] MESSAGE Thread processing load: audio=5.77456 video=0.00954419 text=0 2019-05-02 07:10:05:842 [ortp] MESSAGE Sending RTCP SR compound message on session [0x751f6c1000]. 2019-05-02 07:10:05:856 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=31.02,u=89.88], 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-05-02 07:10:05:857 [liblinphone] MESSAGE Thread processing load: audio=15.8008 video=0.0150792 text=0 2019-05-02 07:10:05:857 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x7526193480], local statistics available: Local current jitter buffer size: 410.0ms 2019-05-02 07:10:05:858 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_stats_updated] 2019-05-02 07:10:06:663 [ortp] MESSAGE jitter buffer stable: target-size: 500.000000 ms, effective-size: 410.034607 (min: 40 nom: 60, max: 500) 2019-05-02 07:10:06:663 [ortp] MESSAGE jitter buffer rls stats: count=1736, clockrate=8000, offset=876.545 clock_ratio=1.00058, capped_offset=2876 capped_clock_ratio=1.000583, max_ts_deviation=4488.000000 prev_max_ts_deviation=2185.000000, deviation=2808, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.002259 2019-05-02 07:10:06:854 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=102.49,u=88.01], 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.79], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:10:06:854 [liblinphone] MESSAGE Thread processing load: audio=15.6031 video=0.0132398 text=0 2019-05-02 07:10:07:482 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:10:07:482 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf88d0: 10.75.22.32:7078:host --> 10.75.22.247:11110:host 2019-05-02 07:10:07:788 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf42d0: 54.37.202.229:1438:prflx --> [2001:41d0:700:789::2020]:27160:relay 2019-05-02 07:10:07:870 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=50.69,u=87.93], 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-05-02 07:10:07:870 [liblinphone] MESSAGE Thread processing load: audio=15.5912 video=0.013325 text=0 2019-05-02 07:10:08:371 [mediastreamer] MESSAGE [OpenSLES] sound/wall clock skew is average=2.02577 ms 2019-05-02 07:10:08:862 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=92.65,u=89.74], 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-05-02 07:10:08:863 [liblinphone] MESSAGE Thread processing load: audio=13.8506 video=0.0128888 text=0 2019-05-02 07:10:09:858 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=83.00,u=88.10], 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-05-02 07:10:09:858 [liblinphone] MESSAGE Thread processing load: audio=16.4364 video=0.0116859 text=0 2019-05-02 07:10:10:487 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:10:10:488 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf88d0: 10.75.22.32:7078:host --> 10.75.22.247:11110:host 2019-05-02 07:10:10:788 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf42d0: 54.37.202.229:1438:prflx --> [2001:41d0:700:789::2020]:27160:relay 2019-05-02 07:10:10:870 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=132.15,u=87.96], 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-05-02 07:10:10:870 [liblinphone] MESSAGE Thread processing load: audio=16.4812 video=0.0107871 text=0 2019-05-02 07:10:11:552 [ortp] MESSAGE Sending RTCP SR compound message on session [0x751f6c1000]. 2019-05-02 07:10:11:557 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x7526193480], local statistics available: Local current jitter buffer size: 446.8ms 2019-05-02 07:10:11:558 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_stats_updated] 2019-05-02 07:10:11:671 [ortp] MESSAGE jitter buffer stable: target-size: 500.000000 ms, effective-size: 446.804138 (min: 40 nom: 60, max: 500) 2019-05-02 07:10:11:672 [ortp] MESSAGE jitter buffer rls stats: count=1998, clockrate=8000, offset=949.299 clock_ratio=0.999804, capped_offset=2949 capped_clock_ratio=0.999804, max_ts_deviation=1054.000000 prev_max_ts_deviation=4488.000000, deviation=806, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.001971 2019-05-02 07:10:11:860 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=80.87,u=91.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-05-02 07:10:11:860 [liblinphone] MESSAGE Thread processing load: audio=6.4964 video=0.00484805 text=0 2019-05-02 07:10:12:855 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=87.27,u=86.24], 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.79], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:10:12:855 [liblinphone] MESSAGE Thread processing load: audio=15.0259 video=0.0141906 text=0 2019-05-02 07:10:13:370 [mediastreamer] MESSAGE [OpenSLES] sound/wall clock skew is average=2.13637 ms 2019-05-02 07:10:13:501 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:10:13:502 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf88d0: 10.75.22.32:7078:host --> 10.75.22.247:11110:host 2019-05-02 07:10:13:805 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf42d0: 54.37.202.229:1438:prflx --> [2001:41d0:700:789::2020]:27160:relay 2019-05-02 07:10:13:867 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=46.27,u=87.95], 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-05-02 07:10:13:867 [liblinphone] MESSAGE Thread processing load: audio=15.8102 video=0.0117206 text=0 2019-05-02 07:10:14:856 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=112.76,u=89.88], 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-05-02 07:10:14:857 [liblinphone] MESSAGE Thread processing load: audio=14.4074 video=0.012024 text=0 2019-05-02 07:10:15:867 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=87.51,u=87.97], 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-05-02 07:10:15:867 [liblinphone] MESSAGE Thread processing load: audio=16.1048 video=0.0115255 text=0 2019-05-02 07:10:16:516 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:10:16:516 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf88d0: 10.75.22.32:7078:host --> 10.75.22.247:11110:host 2019-05-02 07:10:16:691 [ortp] MESSAGE jitter buffer stable: target-size: 500.000000 ms, effective-size: 446.804138 (min: 40 nom: 60, max: 500) 2019-05-02 07:10:16:692 [ortp] MESSAGE jitter buffer rls stats: count=2257, clockrate=8000, offset=935.695 clock_ratio=0.999911, capped_offset=2935 capped_clock_ratio=0.999911, max_ts_deviation=2964.000000 prev_max_ts_deviation=1054.000000, deviation=619, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.001750 2019-05-02 07:10:16:819 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf42d0: 54.37.202.229:1438:prflx --> [2001:41d0:700:789::2020]:27160:relay 2019-05-02 07:10:16:860 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=73.15,u=87.97], 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-05-02 07:10:16:860 [liblinphone] MESSAGE Thread processing load: audio=9.25741 video=0.00903245 text=0 2019-05-02 07:10:17:131 [ortp] MESSAGE Sending RTCP SR compound message on session [0x751f6c1000]. 2019-05-02 07:10:17:142 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x7526193480], local statistics available: Local current jitter buffer size: 508.9ms 2019-05-02 07:10:17:142 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_stats_updated] 2019-05-02 07:10:17:873 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=35.08,u=89.91], 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.79], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:10:17:873 [liblinphone] MESSAGE Thread processing load: audio=15.5002 video=0.0125431 text=0 2019-05-02 07:10:18:371 [mediastreamer] MESSAGE [OpenSLES] sound/wall clock skew is average=2.15402 ms 2019-05-02 07:10:18:864 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=84.86,u=87.85], 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-05-02 07:10:18:864 [liblinphone] MESSAGE Thread processing load: audio=15.3352 video=0.012198 text=0 2019-05-02 07:10:19:529 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:10:19:530 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf88d0: 10.75.22.32:7078:host --> 10.75.22.247:11110:host 2019-05-02 07:10:19:833 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf42d0: 54.37.202.229:1438:prflx --> [2001:41d0:700:789::2020]:27160:relay 2019-05-02 07:10:19:874 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=95.53,u=88.03], 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-05-02 07:10:19:874 [liblinphone] MESSAGE Thread processing load: audio=15.8609 video=0.0139449 text=0 2019-05-02 07:10:20:868 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=134.34,u=89.91], 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-05-02 07:10:20:868 [liblinphone] MESSAGE Thread processing load: audio=17.0818 video=0.0104684 text=0 2019-05-02 07:10:21:723 [ortp] MESSAGE jitter buffer stable: target-size: 479.999989 ms, effective-size: 508.857147 (min: 40 nom: 60, max: 500) 2019-05-02 07:10:21:723 [ortp] MESSAGE jitter buffer rls stats: count=2503, clockrate=8000, offset=916.842 clock_ratio=1.00006, capped_offset=2916 capped_clock_ratio=1.000058, max_ts_deviation=1735.000000 prev_max_ts_deviation=1658.000000, deviation=300, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.001582 2019-05-02 07:10:21:859 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=96.02,u=87.96], 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-05-02 07:10:21:859 [liblinphone] MESSAGE Thread processing load: audio=16.0255 video=0.0131772 text=0 2019-05-02 07:10:22:181 [belle-sip] MESSAGE channel [0x752606f600]: keep alive sent to [UDP://pbx.example.com:5060] 2019-05-02 07:10:22:545 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf8790: [2001:1234:5678:abcd:5453:af89:8529:61af]:7078:host --> [2001:1234:5678:abcd::2]:11110:host 2019-05-02 07:10:22:547 [mediastreamer] MESSAGE ice: Send indication for pair 0x7520bf88d0: 10.75.22.32:7078:host --> 10.75.22.247:11110:host 2019-05-02 07:10:22:626 [mediastreamer] MESSAGE ice: There is no connectivity check left to be sent and no retransmissions pending, concluding checklist [0x752616eb00] 2019-05-02 07:10:22:627 [mediastreamer] MESSAGE ice: Failed ICE check list processing! 2019-05-02 07:10:22:627 [mediastreamer] MESSAGE Valid list: 2019-05-02 07:10:22:627 [mediastreamer] MESSAGE 1 [0x7520bf8790]: state=Succeeded use=0 nominated=1 priority=9151314442783293438 2019-05-02 07:10:22:627 [mediastreamer] MESSAGE Local: [0x751e3aee60]: type=host ip=2001:1234:5678:abcd:5453:af89:8529:61af port=7078 componentID=1 priority=2130706431 foundation=1 base=0x751e3aee60 2019-05-02 07:10:22:627 [mediastreamer] MESSAGE Remote: [0x752033c920]: type=host ip=2001:1234:5678:abcd::2 port=11110 componentID=1 priority=2130706431 foundation=Hf5a91683 base=0x752033c920 2019-05-02 07:10:22:627 [mediastreamer] MESSAGE 2 [0x7520bf88d0]: state=Succeeded use=0 nominated=1 priority=9151313893027479551 2019-05-02 07:10:22:628 [mediastreamer] MESSAGE Local: [0x751e3af0e0]: type=host ip=10.75.22.32 port=7078 componentID=1 priority=2130706303 foundation=2 base=0x751e3af0e0 2019-05-02 07:10:22:628 [mediastreamer] MESSAGE Remote: [0x752033ca60]: type=host ip=10.75.22.247 port=11110 componentID=1 priority=2130706431 foundation=Ha4b16f7 base=0x752033ca60 2019-05-02 07:10:22:628 [mediastreamer] MESSAGE 3 [0x7520bf42d0]: state=Frozen use=0 nominated=1 priority=72057593467502334 2019-05-02 07:10:22:628 [mediastreamer] MESSAGE Local: [0x75203d9620]: type=prflx ip=54.37.202.229 port=1438 componentID=1 priority=1862270847 foundation=4 base=0x751e3aee60 2019-05-02 07:10:22:628 [mediastreamer] MESSAGE Remote: [0x752033cd80]: * type=relay ip=2001:41d0:700:789::2020 port=27160 componentID=1 priority=16777215 foundation=2161c1713ffb139a base=0x0 2019-05-02 07:10:22:822 [ortp] MESSAGE Sending RTCP SR compound message on session [0x751f6c1000]. 2019-05-02 07:10:22:829 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x7526193480], local statistics available: Local current jitter buffer size: 494.9ms 2019-05-02 07:10:22:830 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_stats_updated] 2019-05-02 07:10:22:870 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=62.06,u=87.98], 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-05-02 07:10:22:870 [liblinphone] MESSAGE Thread processing load: audio=16.211 video=0.0131505 text=0 2019-05-02 07:10:23:371 [mediastreamer] MESSAGE [OpenSLES] sound/wall clock skew is average=2.27174 ms 2019-05-02 07:10:23:638 [liblinphone] MESSAGE CallSession [0x75135541b8] New ICE state: audio: [IceStateFailed] video: [IceStateInProgress] text: [IceStateNotActivated] 2019-05-02 07:10:23:861 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=73.13,u=89.12], 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.79], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:10:23:862 [liblinphone] MESSAGE Thread processing load: audio=14.2609 video=0.0117879 text=0 2019-05-02 07:10:24:853 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=98.95,u=88.09], 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-05-02 07:10:24:853 [liblinphone] MESSAGE Thread processing load: audio=5.8337 video=0.00914433 text=0 2019-05-02 07:10:25:864 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=75.17,u=88.03], 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-05-02 07:10:25:864 [liblinphone] MESSAGE Thread processing load: audio=16.3463 video=0.0121007 text=0 2019-05-02 07:10:26:856 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=62.12,u=87.95], 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-05-02 07:10:26:857 [liblinphone] MESSAGE Thread processing load: audio=14.8344 video=0.0139095 text=0 2019-05-02 07:10:27:162 [ortp] MESSAGE jitter buffer stable: target-size: 460.000008 ms, effective-size: 494.912903 (min: 40 nom: 60, max: 500) 2019-05-02 07:10:27:163 [ortp] MESSAGE jitter buffer rls stats: count=2760, clockrate=8000, offset=860.201 clock_ratio=1.00046, capped_offset=2860 capped_clock_ratio=1.000465, max_ts_deviation=2832.000000 prev_max_ts_deviation=993.000000, deviation=2832, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.001438 2019-05-02 07:10:27:682 [ortp] MESSAGE Sending RTCP SR compound message on session [0x751f6c1000]. 2019-05-02 07:10:27:687 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x7526193480], local statistics available: Local current jitter buffer size: 465.1ms 2019-05-02 07:10:27:688 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_stats_updated] 2019-05-02 07:10:27:871 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=75.17,u=87.96], 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-05-02 07:10:27:871 [liblinphone] MESSAGE Thread processing load: audio=16.0113 video=0.0106558 text=0 2019-05-02 07:10:28:371 [mediastreamer] MESSAGE [OpenSLES] sound/wall clock skew is average=2.31688 ms 2019-05-02 07:10:28:861 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=82.50,u=87.94], 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.79], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:10:28:861 [liblinphone] MESSAGE Thread processing load: audio=14.1241 video=0.0131409 text=0 2019-05-02 07:10:29:856 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=85.29,u=88.09], 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-05-02 07:10:29:856 [liblinphone] MESSAGE Thread processing load: audio=15.6622 video=0.0120323 text=0 2019-05-02 07:10:30:870 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=109.38,u=87.97], 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-05-02 07:10:30:870 [liblinphone] MESSAGE Thread processing load: audio=14.633 video=0.0111943 text=0 2019-05-02 07:10:30:972 [mediastreamer] WARNING Flow controlled bufferizer of max 40 ms was filled with at least 60 ms in the last 5000 ms, asking to drop 20 ms 2019-05-02 07:10:30:989 [mediastreamer] WARNING Too much buffered audio signal, throwing out 20 ms 2019-05-02 07:10:31:860 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=50.20,u=87.94], 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-05-02 07:10:31:860 [liblinphone] MESSAGE Thread processing load: audio=14.4776 video=0.0107233 text=0 2019-05-02 07:10:32:202 [ortp] MESSAGE jitter buffer stable: target-size: 460.000008 ms, effective-size: 465.123962 (min: 40 nom: 60, max: 500) 2019-05-02 07:10:32:203 [ortp] MESSAGE jitter buffer rls stats: count=3020, clockrate=8000, offset=870.193 clock_ratio=1.00039, capped_offset=2870 capped_clock_ratio=1.000392, max_ts_deviation=1543.000000 prev_max_ts_deviation=2832.000000, deviation=1543, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.001316 2019-05-02 07:10:32:705 [liblinphone] MESSAGE linphone_core_set_device_rotation : rotation=90 2019-05-02 07:10:32:871 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=71.71,u=88.04], 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-05-02 07:10:32:871 [liblinphone] MESSAGE Thread processing load: audio=15.9818 video=0.0126278 text=0 2019-05-02 07:10:33:371 [mediastreamer] MESSAGE [OpenSLES] sound/wall clock skew is average=1.82724 ms 2019-05-02 07:10:33:640 [liblinphone] MESSAGE Callbacks [0x75209dd950] unregistered on core [0x754549e800] 2019-05-02 07:10:33:640 [liblinphone] MESSAGE Callbacks [0x75209dd8b0] unregistered on core [0x754549e800] 2019-05-02 07:10:33:640 [linphone-android] MESSAGE [Service] Activity paused:org.linphone.call.CallActivity@4c89899 2019-05-02 07:10:33:640 [linphone-android] MESSAGE [Service] runningActivities=0 2019-05-02 07:10:33:643 [linphone-android] MESSAGE [Service] Activity stopped:org.linphone.call.CallActivity@4c89899 2019-05-02 07:10:33:644 [liblinphone] MESSAGE Notifying all friends that we are [open] 2019-05-02 07:10:33:649 [linphone-android] MESSAGE [Service] Activity destroyed:org.linphone.call.CallActivity@4c89899 2019-05-02 07:10:33:680 [linphone-android] MESSAGE [Service] Activity created:org.linphone.call.CallActivity@28e3ca5 2019-05-02 07:10:33:731 [ortp] MESSAGE Sending RTCP SR compound message on session [0x751f6c1000]. 2019-05-02 07:10:33:914 [mediastreamer] ERROR Cannot open camera 0: Fail to connect to camera service 2019-05-02 07:10:33:921 [mediastreamer] ERROR Cannot open camera 1: Fail to connect to camera service 2019-05-02 07:10:33:925 [liblinphone] MESSAGE Notifying all friends that we are [on-the-phone] 2019-05-02 07:10:33:929 [linphone-android] MESSAGE Activity started:org.linphone.call.CallActivity@28e3ca5 2019-05-02 07:10:33:943 [linphone-android] MESSAGE [Service] Activity resumed:org.linphone.call.CallActivity@28e3ca5 2019-05-02 07:10:33:943 [linphone-android] MESSAGE [Service] runningActivities=1 2019-05-02 07:10:33:944 [liblinphone] MESSAGE Core callbacks [0x751dda5110] registered on core [0x754549e800] 2019-05-02 07:10:33:947 [linphone-android] WARNING Can't display ZRTP popup, no token ! 2019-05-02 07:10:33:950 [liblinphone] MESSAGE Core callbacks [0x751dda51b0] registered on core [0x754549e800] 2019-05-02 07:10:34:024 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=59.92,u=89.65], 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-05-02 07:10:34:024 [liblinphone] MESSAGE Thread processing load: audio=2.27105 video=0.00420509 text=0 2019-05-02 07:10:34:024 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x7526193480], local statistics available: Local current jitter buffer size: 466.4ms 2019-05-02 07:10:34:024 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_stats_updated] 2019-05-02 07:10:34:858 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=97.23,u=87.98], 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.79], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:10:34:858 [liblinphone] MESSAGE Thread processing load: audio=6.25925 video=0.0093795 text=0 2019-05-02 07:10:35:907 [liblinphone] MESSAGE linphone_core_set_device_rotation : rotation=180 2019-05-02 07:10:35:908 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=30.06,u=86.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.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:10:35:908 [liblinphone] MESSAGE Thread processing load: audio=7.11579 video=0.00762017 text=0 2019-05-02 07:10:36:104 [liblinphone] MESSAGE linphone_core_set_device_rotation : rotation=0 2019-05-02 07:10:36:504 [liblinphone] MESSAGE Callbacks [0x751dda51b0] unregistered on core [0x754549e800] 2019-05-02 07:10:36:504 [liblinphone] MESSAGE Callbacks [0x751dda5110] unregistered on core [0x754549e800] 2019-05-02 07:10:36:505 [linphone-android] MESSAGE [Service] Activity paused:org.linphone.call.CallActivity@28e3ca5 2019-05-02 07:10:36:505 [linphone-android] MESSAGE [Service] runningActivities=0 2019-05-02 07:10:36:507 [linphone-android] MESSAGE [Service] Activity stopped:org.linphone.call.CallActivity@28e3ca5 2019-05-02 07:10:36:508 [liblinphone] MESSAGE Notifying all friends that we are [open] 2019-05-02 07:10:36:523 [linphone-android] MESSAGE [Service] Activity destroyed:org.linphone.call.CallActivity@28e3ca5 2019-05-02 07:10:36:551 [linphone-android] MESSAGE [Service] Activity created:org.linphone.call.CallActivity@2713c03 2019-05-02 07:10:36:760 [mediastreamer] ERROR Cannot open camera 0: Fail to connect to camera service 2019-05-02 07:10:36:765 [mediastreamer] ERROR Cannot open camera 1: Fail to connect to camera service 2019-05-02 07:10:36:770 [liblinphone] MESSAGE Notifying all friends that we are [on-the-phone] 2019-05-02 07:10:36:773 [linphone-android] MESSAGE Activity started:org.linphone.call.CallActivity@2713c03 2019-05-02 07:10:36:796 [linphone-android] MESSAGE [Service] Activity resumed:org.linphone.call.CallActivity@2713c03 2019-05-02 07:10:36:796 [linphone-android] MESSAGE [Service] runningActivities=1 2019-05-02 07:10:36:796 [liblinphone] MESSAGE Core callbacks [0x75454422e0] registered on core [0x754549e800] 2019-05-02 07:10:36:799 [linphone-android] WARNING Can't display ZRTP popup, no token ! 2019-05-02 07:10:36:804 [liblinphone] MESSAGE Core callbacks [0x75454424c0] registered on core [0x754549e800] 2019-05-02 07:10:36:878 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=62.40,u=88.10], 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-05-02 07:10:36:878 [liblinphone] MESSAGE Thread processing load: audio=2.47993 video=0.0045821 text=0 2019-05-02 07:10:37:331 [ortp] MESSAGE jitter buffer updated: target-size: 419.999987 ms, effective-size: 466.375824 (min: 40 nom: 60, max: 500) 2019-05-02 07:10:37:331 [ortp] MESSAGE jitter buffer rls stats: count=3264, clockrate=8000, offset=879.513 clock_ratio=1.00034, capped_offset=2879 capped_clock_ratio=1.000339, max_ts_deviation=2421.000000 prev_max_ts_deviation=1859.000000, deviation=2261, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.001218 2019-05-02 07:10:37:853 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=86.51,u=89.69], 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-05-02 07:10:37:854 [liblinphone] MESSAGE Thread processing load: audio=14.0185 video=0.0133764 text=0 2019-05-02 07:10:38:371 [mediastreamer] MESSAGE [OpenSLES] sound/wall clock skew is average=0.432172 ms 2019-05-02 07:10:38:591 [ortp] MESSAGE Sending RTCP SR compound message on session [0x751f6c1000]. 2019-05-02 07:10:38:602 [mediastreamer] MESSAGE MSAudio_stream_iterate[0x7526193480], local statistics available: Local current jitter buffer size: 418.8ms 2019-05-02 07:10:38:602 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_stats_updated] 2019-05-02 07:10:38:853 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=54.97,u=86.13], 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-05-02 07:10:38:854 [liblinphone] MESSAGE Thread processing load: audio=8.16157 video=0.0104982 text=0 2019-05-02 07:10:39:871 [liblinphone] MESSAGE Bandwidth usage for CallSession [0x75135541b8]: RTP audio=[d=131.90,u=88.13], 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.79], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-05-02 07:10:39:871 [liblinphone] MESSAGE Thread processing load: audio=10.5614 video=0.0156266 text=0 2019-05-02 07:10:40:634 [liblinphone] MESSAGE Terminate CallSession [0x75135541b8] which is currently in state [State::StreamsRunning] 2019-05-02 07:10:40:640 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x7520310640)] acquired [ref=0x30ca] 2019-05-02 07:10:40:640 [belle-sip] MESSAGE transaction [0x7520310640]: starting transaction background task with id=[30ca]. 2019-05-02 07:10:40:640 [belle-sip] MESSAGE Dialog [0x752616e300]: now updated by transaction [0x7520310640]. 2019-05-02 07:10:40:640 [belle-sip] MESSAGE Transaction [0x7520ad7840] deleted 2019-05-02 07:10:40:641 [belle-sip] MESSAGE Changing [client] [BYE] transaction [0x7520310640], from state [INIT] to [TRYING] 2019-05-02 07:10:40:642 [belle-sip] MESSAGE channel [0x7520d40a00]: message sent to [TLS://sip.linphone.org:5223], size: [596] bytes BYE sip:asterisk@67.193.227.1:35149;transport=tcp SIP/2.0 Via: SIP/2.0/TLS [2001:1234:5678:abcd:5453:af89:8529:61af]:44542;branch=z9hG4bK.iiFaHrzy~;rport From: ;tag=nRl7QO6 To: "[redacted]" ;tag=8600d669-ea22-4ea6-a663-182d8b0c583c CSeq: 111 BYE Call-ID: 258fc975-5ce2-4158-88de-b7bf68308ca2 Max-Forwards: 70 Route: Route: User-Agent: LinphoneAndroid/4.1-debug (FRD-L04) LinphoneSDK/4.1-366-g1b22291 (master) (belle-sip/1.6.3) Content-Length: 0 2019-05-02 07:10:40:689 [mediastreamer] MESSAGE ms_ticker_set_time_func: ticker's time method updated. 2019-05-02 07:10:40:692 [mediastreamer] MESSAGE Filter MSRtpRecv is not scheduled; nothing to do. 2019-05-02 07:10:40:692 [ortp] MESSAGE =========================================================== 2019-05-02 07:10:40:692 [ortp] MESSAGE AUDIO SESSION'S RTP STATISTICS 2019-05-02 07:10:40:692 [ortp] MESSAGE ----------------------------------------------------------- 2019-05-02 07:10:40:693 [ortp] MESSAGE sent 3485 packets 2019-05-02 07:10:40:693 [ortp] MESSAGE 0 duplicated packets 2019-05-02 07:10:40:693 [ortp] MESSAGE 599420 bytes 2019-05-02 07:10:40:693 [ortp] MESSAGE received 3446 packets 2019-05-02 07:10:40:693 [ortp] MESSAGE 0 duplicated packets 2019-05-02 07:10:40:693 [ortp] MESSAGE 592712 bytes 2019-05-02 07:10:40:693 [ortp] MESSAGE incoming delivered to the app 588240 bytes 2019-05-02 07:10:40:693 [ortp] MESSAGE incoming cumulative lost 35 packets 2019-05-02 07:10:40:693 [ortp] MESSAGE incoming received too late 3 packets 2019-05-02 07:10:40:693 [ortp] MESSAGE incoming bad formatted 0 packets 2019-05-02 07:10:40:693 [ortp] MESSAGE incoming discarded (queue overflow) 0 packets 2019-05-02 07:10:40:693 [ortp] MESSAGE sent rtcp 15 packets 2019-05-02 07:10:40:693 [ortp] MESSAGE received rtcp 0 packets 2019-05-02 07:10:40:693 [ortp] MESSAGE =========================================================== 2019-05-02 07:10:40:693 [mediastreamer] MESSAGE ms_filter_unlink: MSOpenSLESRecorder:0x753afd0100,0-->MSResample:0x752036c000,0 2019-05-02 07:10:40:693 [mediastreamer] MESSAGE ms_filter_unlink: MSResample:0x752036c000,0-->MSEqualizer:0x752036c800,0 2019-05-02 07:10:40:693 [mediastreamer] MESSAGE ms_filter_unlink: MSEqualizer:0x752036c800,0-->MSVolume:0x7520db5900,0 2019-05-02 07:10:40:693 [mediastreamer] MESSAGE ms_filter_unlink: MSVolume:0x7520db5900,0-->MSDtmfGen:0x7520344480,0 2019-05-02 07:10:40:693 [mediastreamer] MESSAGE ms_filter_unlink: MSDtmfGen:0x7520344480,0-->MSAudioMixer:0x752036c180,0 2019-05-02 07:10:40:693 [mediastreamer] MESSAGE ms_filter_unlink: MSAudioMixer:0x752036c180,0-->MSUlawEnc:0x7520344d80,0 2019-05-02 07:10:40:693 [mediastreamer] MESSAGE ms_filter_unlink: MSUlawEnc:0x7520344d80,0-->MSRtpSend:0x7520db5800,0 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSRtpRecv:0x7520329b80,0-->MSUlawDec:0x7520344e00,0 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSUlawDec:0x7520344e00,0-->MSGenericPLC:0x752036c900,0 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSGenericPLC:0x752036c900,0-->MSAudioFlowControl:0x752036c980,0 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSAudioFlowControl:0x752036c980,0-->MSDtmfGen:0x7520344280,0 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSDtmfGen:0x7520344280,0-->MSVolume:0x7520344e80,0 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSVolume:0x7520344e80,0-->MSTee:0x752036c300,0 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSTee:0x752036c300,0-->MSEqualizer:0x752036c880,0 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSEqualizer:0x752036c880,0-->MSAudioMixer:0x752036ca00,0 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSFilePlayer:0x752036ca80,0-->MSResample:0x752036cb00,0 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSResample:0x752036cb00,0-->MSAudioMixer:0x752036ca00,1 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSAudioMixer:0x752036ca00,0-->MSResample:0x752036c080,0 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSResample:0x752036c080,0-->MSOpenSLESPlayer:0x753aeecd80,0 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSAudioMixer:0x752036c280,1-->MSResample:0x752036c700,0 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSResample:0x752036c700,0-->MSOpusEnc:0x752036c780,0 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSOpusEnc:0x752036c780,0-->MSMKVRecorder:0x752036c400,1 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSItcSource:0x752036c480,0-->MSMKVRecorder:0x752036c400,0 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSAudioMixer:0x752036c180,1-->MSAudioMixer:0x752036c280,0 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSTee:0x752036c300,1-->MSAudioMixer:0x752036c280,1 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE ms_filter_unlink: MSAudioMixer:0x752036c280,0-->MSFileRec:0x752036c200,0 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE =========================================================== 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE FILTER USAGE STATISTICS 2019-05-02 07:10:40:694 [mediastreamer] MESSAGE Name Count Time/tick (ms) CPU Usage 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE ----------------------------------------------------------- 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSResample 7105 0.445034 39.5904 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSRtpSend 6973 0.425289 37.1311 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSRtpRecv 7085 0.150938 13.3897 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSAudioMixer 20919 0.00621659 1.62812 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSGenericPLC 6973 0.0164546 1.43662 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSVolume 6946 0.0133417 1.16033 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSUlawEnc 3426 0.0254648 1.09251 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSUlawDec 3420 0.0230758 0.988282 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSOpenSLESRecorder 6973 0.0076594 0.668726 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSDtmfGen 13946 0.00350972 0.612808 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSOpusEnc 99 0.488776 0.611901 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSItcSource 6973 0.00428257 0.373902 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSEqualizer 6946 0.00388928 0.33825 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSOpenSLESPlayer 3580 0.0066824 0.299577 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSAudioFlowControl 3520 0.00581083 0.256139 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSFilePlayer 6973 0.00261686 0.228473 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSTee 3520 0.00427643 0.188503 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSFileRec 99 0.00196877 0.00246471 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSMKVRecorder 49 0.00352088 0.0022039 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSOpusDec 0 0 0 2019-05-02 07:10:40:695 [mediastreamer] MESSAGE MSVoidSink 0 0 0 2019-05-02 07:10:40:696 [mediastreamer] MESSAGE MSItcSink 0 0 0 2019-05-02 07:10:40:696 [mediastreamer] MESSAGE MSWebRTCAECM 0 0 0 2019-05-02 07:10:40:696 [mediastreamer] MESSAGE =========================================================== 2019-05-02 07:10:40:696 [mediastreamer] MESSAGE MKVRecorder: destroyed 2019-05-02 07:10:40:697 [ortp] MESSAGE =========================================================== 2019-05-02 07:10:40:697 [ortp] MESSAGE VIDEO SESSION'S RTP STATISTICS 2019-05-02 07:10:40:697 [ortp] MESSAGE ----------------------------------------------------------- 2019-05-02 07:10:40:697 [ortp] MESSAGE sent 0 packets 2019-05-02 07:10:40:697 [ortp] MESSAGE 0 duplicated packets 2019-05-02 07:10:40:697 [ortp] MESSAGE 0 bytes 2019-05-02 07:10:40:697 [ortp] MESSAGE received 0 packets 2019-05-02 07:10:40:697 [ortp] MESSAGE 0 duplicated packets 2019-05-02 07:10:40:697 [ortp] MESSAGE 0 bytes 2019-05-02 07:10:40:697 [ortp] MESSAGE incoming delivered to the app 0 bytes 2019-05-02 07:10:40:697 [ortp] MESSAGE incoming cumulative lost 0 packets 2019-05-02 07:10:40:697 [ortp] MESSAGE incoming received too late 0 packets 2019-05-02 07:10:40:697 [ortp] MESSAGE incoming bad formatted 0 packets 2019-05-02 07:10:40:697 [ortp] MESSAGE incoming discarded (queue overflow) 0 packets 2019-05-02 07:10:40:697 [ortp] MESSAGE sent rtcp 0 packets 2019-05-02 07:10:40:697 [ortp] MESSAGE received rtcp 0 packets 2019-05-02 07:10:40:697 [ortp] MESSAGE =========================================================== 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE =========================================================== 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE FILTER USAGE STATISTICS 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE Name Count Time/tick (ms) CPU Usage 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE ----------------------------------------------------------- 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE MSResample 7105 0.445034 39.5904 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE MSRtpSend 6973 0.425289 37.1311 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE MSRtpRecv 7085 0.150938 13.3897 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE MSAudioMixer 20919 0.00621659 1.62812 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE MSGenericPLC 6973 0.0164546 1.43662 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE MSVolume 6946 0.0133417 1.16033 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE MSUlawEnc 3426 0.0254648 1.09251 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE MSUlawDec 3420 0.0230758 0.988282 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE MSOpenSLESRecorder 6973 0.0076594 0.668726 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE MSDtmfGen 13946 0.00350972 0.612808 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE MSOpusEnc 99 0.488776 0.611901 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE MSItcSource 6973 0.00428257 0.373902 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE MSEqualizer 6946 0.00388928 0.33825 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE MSOpenSLESPlayer 3580 0.0066824 0.299577 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE MSAudioFlowControl 3520 0.00581083 0.256139 2019-05-02 07:10:40:698 [mediastreamer] MESSAGE MSFilePlayer 6973 0.00261686 0.228473 2019-05-02 07:10:40:699 [mediastreamer] MESSAGE MSTee 3520 0.00427643 0.188503 2019-05-02 07:10:40:699 [mediastreamer] MESSAGE MSFileRec 99 0.00196877 0.00246471 2019-05-02 07:10:40:699 [mediastreamer] MESSAGE MSMKVRecorder 49 0.00352088 0.0022039 2019-05-02 07:10:40:699 [mediastreamer] MESSAGE MSOpusDec 0 0 0 2019-05-02 07:10:40:699 [mediastreamer] MESSAGE MSVoidSink 0 0 0 2019-05-02 07:10:40:699 [mediastreamer] MESSAGE MSItcSink 0 0 0 2019-05-02 07:10:40:699 [mediastreamer] MESSAGE MSWebRTCAECM 0 0 0 2019-05-02 07:10:40:699 [mediastreamer] MESSAGE =========================================================== 2019-05-02 07:10:40:699 [liblinphone] MESSAGE CallSession [0x75135541b8] moving from state State::StreamsRunning to State::End 2019-05-02 07:10:40:699 [liblinphone] MESSAGE Notifying soundcard that we don't need it anymore for calls 2019-05-02 07:10:40:700 [mediastreamer] MESSAGE Stopping ZRTP context on session [0x0] 2019-05-02 07:10:40:700 [mediastreamer] MESSAGE ZRTP context destroyed 2019-05-02 07:10:40:710 [mediastreamer] MESSAGE MSAudio MSTicker thread exiting 2019-05-02 07:10:40:711 [mediastreamer] MESSAGE Stopping ZRTP context on session [0x0] 2019-05-02 07:10:40:711 [mediastreamer] MESSAGE ZRTP context destroyed 2019-05-02 07:10:40:714 [mediastreamer] MESSAGE MSVideo MSTicker thread exiting 2019-05-02 07:10:40:726 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_log_updated] 2019-05-02 07:10:40:726 [liblinphone] MESSAGE Resetting the current call 2019-05-02 07:10:40:727 [linphone-android] MESSAGE Call is terminated, stopping mCountDownTimer in charge of stats refreshing. 2019-05-02 07:10:40:727 [linphone-android] MESSAGE Call is terminated, stopping mCountDownTimer in charge of stats refreshing. 2019-05-02 07:10:40:727 [linphone-android] MESSAGE Call is terminated, stopping mCountDownTimer in charge of stats refreshing. 2019-05-02 07:10:40:727 [linphone-android] MESSAGE [Manager] New call state [End] 2019-05-02 07:10:40:810 [linphone-android] WARNING [Manager] Routing audio to earpiece, disabling bluetooth audio route 2019-05-02 07:10:40:831 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_state_changed] 2019-05-02 07:10:40:835 [liblinphone] MESSAGE Publishing report with custom route sip:voip-metrics@sip.linphone.org;transport=tls 2019-05-02 07:10:40:838 [liblinphone] MESSAGE Skipping top route of initial route-set because same as request-uri 2019-05-02 07:10:40:841 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x7520ad8a40)] acquired [ref=0x3092] 2019-05-02 07:10:40:842 [belle-sip] MESSAGE transaction [0x7520ad8a40]: starting transaction background task with id=[3092]. 2019-05-02 07:10:40:842 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=my_account, realm=sip.linphone.org 2019-05-02 07:10:40:842 [belle-sip] MESSAGE Auth info found for [my_account] realm [sip.linphone.org] 2019-05-02 07:10:40:843 [belle-sip] MESSAGE Changing [client] [PUBLISH] transaction [0x7520ad8a40], from state [INIT] to [TRYING] 2019-05-02 07:10:40:845 [belle-sip] MESSAGE channel [0x7520d40a00]: message sent to [TLS://sip.linphone.org:5223], size: [2088] bytes PUBLISH sip:voip-metrics@sip.linphone.org;transport=tls SIP/2.0 Via: SIP/2.0/TLS [2001:1234:5678:abcd:5453:af89:8529:61af]:44542;branch=z9hG4bK.24k1IbNO2;rport From: "[redacted]" ;tag=iZCL-nB8r To: sip:voip-metrics@sip.linphone.org CSeq: 20 PUBLISH Call-ID: 9vCiCnggSC Max-Forwards: 70 Supported: replaces, outbound, gruu Event: vq-rtcpxr Content-Type: application/vq-rtcpxr Content-Length: 1227 User-Agent: LinphoneAndroid/4.1-debug (FRD-L04) LinphoneSDK/4.1-366-g1b22291 (master) (belle-sip/1.6.3) Proxy-Authorization: Digest realm="sip.linphone.org", nonce="FUp14AAAAACzmzz/AABvT7CaP1QAAAAA", algorithm=MD5, opaque="+GNywA==", username="my_account", uri="sip:voip-metrics@sip.linphone.org;transport=tls", response="494cbcb9717a7ffaa5acd2f3f5019421", cnonce="9Q64ZY62uBeuq6zH", nc=00000006, qop=auth VQSessionReport: CallTerm CallID: 258fc975-5ce2-4158-88de-b7bf68308ca2 LocalID: sip:my_account@sip.linphone.org RemoteID: "[redacted]" OrigID: "[redacted]" LocalGroup: 258fc975-5ce2-4158-88de-b7bf68308ca2;to-tag=nRl7QO6;from-tag=8600d669-ea22-4ea6-a663-182d8b0c583c-local-LinphoneAndroid/4.1-debug (FRD-L04) LinphoneSDK/4.1-366-g1b22291 (master) (belle-sip/1.6.3) RemoteGroup: 258fc975-5ce2-4158-88de-b7bf68308ca2;to-tag=nRl7QO6;from-tag=8600d669-ea22-4ea6-a663-182d8b0c583c-remote-Asterisk PBX 13.26.0 LocalAddr: IP=67.193.227.1 PORT=7078 SSRC=4122414864 RemoteAddr: IP=2001:41d0:700:789::2020 PORT=27160 SSRC=1714449255 LocalMetrics: Timestamps: START=2019-05-02T11:09:28Z STOP=2019-05-02T11:10:38Z SessionDesc: PT=0 PD=PCMU SR=8000 QualityEst: MOSLQ=4.8 MOSCQ=4.8 LinphoneExt: UA="LinphoneAndroid/4.1-debug (FRD-L04) LinphoneSDK/4.1-366-g1b22291 (master) (belle-sip/1.6.3)" RemoteMetrics: Timestamps: START=2019-05-02T11:09:28Z STOP=2019-05-02T11:10:38Z SessionDesc: PT=0 PD=PCMU SR=8000 LinphoneExt: UA="Asterisk PBX 13.26.0" DialogID: 258fc975-5ce2-4158-88de-b7bf68308ca2;to-tag=nRl7QO6;from-tag=8600d669-ea22-4ea6-a663-182d8b0c583c;4122414864 2019-05-02 07:10:40:845 [liblinphone] MESSAGE LinphoneEvent [0x75455fcb00] moving from [LinphonePublishNone] to publish state LinphonePublishProgress 2019-05-02 07:10:40:846 [liblinphone] MESSAGE QualityReporting[0x7500ff4980]: Send 'VQSessionReport: CallTerm' with status 0 2019-05-02 07:10:40:848 [liblinphone] MESSAGE Callbacks [0x75454424c0] unregistered on core [0x754549e800] 2019-05-02 07:10:40:849 [liblinphone] MESSAGE Callbacks [0x75454422e0] unregistered on core [0x754549e800] 2019-05-02 07:10:40:849 [linphone-android] MESSAGE [Service] Activity paused:org.linphone.call.CallActivity@2713c03 2019-05-02 07:10:40:849 [linphone-android] MESSAGE [Service] runningActivities=0 2019-05-02 07:10:40:890 [linphone-android] MESSAGE [Service] Activity created:org.linphone.call.CallActivity@83f86e2 2019-05-02 07:10:41:010 [mediastreamer] ERROR Cannot open camera 0: Fail to connect to camera service 2019-05-02 07:10:41:021 [mediastreamer] ERROR Cannot open camera 1: Fail to connect to camera service 2019-05-02 07:10:41:028 [liblinphone] WARNING linphone_core_mic_enabled(): No current call! 2019-05-02 07:10:41:037 [linphone-android] MESSAGE Activity started:org.linphone.call.CallActivity@83f86e2 2019-05-02 07:10:41:040 [linphone-android] MESSAGE [Service] Activity resumed:org.linphone.call.CallActivity@83f86e2 2019-05-02 07:10:41:040 [linphone-android] MESSAGE [Service] runningActivities=1 2019-05-02 07:10:41:041 [liblinphone] MESSAGE Core callbacks [0x7520bf8060] registered on core [0x754549e800] 2019-05-02 07:10:41:075 [liblinphone] MESSAGE Core callbacks [0x7520bf6710] registered on core [0x754549e800] 2019-05-02 07:10:41:118 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x2fd6] 2019-05-02 07:10:41:118 [belle-sip] MESSAGE channel [0x7520d40a00]: starting recv background task with id=[2fd6]. 2019-05-02 07:10:41:118 [belle-sip] MESSAGE channel [0x7520d40a00]: received [373] new bytes from [TLS://sip.linphone.org:5223]: SIP/2.0 200 OK Via: SIP/2.0/TLS [2001:1234:5678:abcd:5453:af89:8529:61af]:44542;rport=44542;branch=z9hG4bK.iiFaHrzy~ Call-ID: 258fc975-5ce2-4158-88de-b7bf68308ca2 From: ;tag=nRl7QO6 To: "[redacted]" ;tag=8600d669-ea22-4ea6-a663-182d8b0c583c CSeq: 111 BYE Server: Asterisk PBX 13.26.0 Content-Length: 0 2019-05-02 07:10:41:118 [belle-sip] MESSAGE channel [0x7520d40a00]: received [2] new bytes from [TLS://sip.linphone.org:5223]: 2019-05-02 07:10:41:119 [belle-sip] MESSAGE channel [0x7520d40a00] [375] bytes parsed 2019-05-02 07:10:41:119 [belle-sip] MESSAGE Found transaction matching response. 2019-05-02 07:10:41:120 [belle-sip] MESSAGE Changing [client] [BYE] transaction [0x7520310640], from state [TRYING] to [COMPLETED] 2019-05-02 07:10:41:120 [belle-sip] MESSAGE Dialog [0x752616e300]: now updated by transaction [0x7520310640]. 2019-05-02 07:10:41:120 [belle-sip] MESSAGE Dialog [0x752616e300] deleted (is_expired=0) 2019-05-02 07:10:41:120 [liblinphone] MESSAGE op [0x752617b100] : set_or_update_dialog() current=[0x752616e300] new=[0x752616e300] 2019-05-02 07:10:41:120 [liblinphone] MESSAGE Op [0x752617b100] receiving call response [200], dialog is [0x752616e300] in state [BELLE_SIP_DIALOG_TERMINATED] 2019-05-02 07:10:41:120 [belle-sip] MESSAGE Changing [client] [BYE] transaction [0x7520310640], from state [COMPLETED] to [TERMINATED] 2019-05-02 07:10:41:120 [belle-sip] MESSAGE Client BYE transaction [0x7520310640] terminated 2019-05-02 07:10:41:120 [belle-sip] MESSAGE transaction [0x7520310640]: ending transaction background task with id=[30ca]. 2019-05-02 07:10:41:121 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x30ca] 2019-05-02 07:10:41:121 [belle-sip] MESSAGE channel [0x7520d40a00]: ending recv background task with id=[2fd6]. 2019-05-02 07:10:41:122 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x2fd6] 2019-05-02 07:10:41:122 [liblinphone] MESSAGE Dialog [0x752616e300] terminated for op [0x752617b100] 2019-05-02 07:10:41:122 [liblinphone] MESSAGE CallSession [0x75135541b8] moving from state State::End to State::Released 2019-05-02 07:10:41:122 [linphone-android] MESSAGE [Platform Helper] releaseWifiLock() 2019-05-02 07:10:41:123 [linphone-android] MESSAGE [Platform Helper] releaseMcastLock() 2019-05-02 07:10:41:124 [linphone-android] MESSAGE [Platform Helper] releaseCpuLock() 2019-05-02 07:10:41:125 [linphone-android] MESSAGE [Manager] New call state [Released] 2019-05-02 07:10:41:127 [liblinphone] MESSAGE Linphone core [0x754549e800] notified [call_state_changed] 2019-05-02 07:10:41:127 [liblinphone] MESSAGE op [0x752617b100] : set_or_update_dialog() current=[0x752616e300] new=[0x0] 2019-05-02 07:10:41:127 [liblinphone] MESSAGE Destroying op [0x752617b100] of type [SalOpCall] 2019-05-02 07:10:41:127 [belle-sip] MESSAGE Transaction [0x7520310640] deleted 2019-05-02 07:10:41:128 [liblinphone] MESSAGE Callbacks [0x7520bf6710] unregistered on core [0x754549e800] 2019-05-02 07:10:41:128 [liblinphone] MESSAGE Callbacks [0x7520bf8060] unregistered on core [0x754549e800] 2019-05-02 07:10:41:128 [linphone-android] MESSAGE [Service] Activity paused:org.linphone.call.CallActivity@83f86e2 2019-05-02 07:10:41:128 [linphone-android] MESSAGE [Service] runningActivities=0 2019-05-02 07:10:41:159 [linphone-android] MESSAGE Activity started:org.linphone.LinphoneActivity@a85a962 2019-05-02 07:10:41:159 [linphone-android] MESSAGE [Permission] android.permission.SYSTEM_ALERT_WINDOW permission is denied 2019-05-02 07:10:41:160 [linphone-android] MESSAGE [Permission] android.permission.FOREGROUND_SERVICE permission is denied 2019-05-02 07:10:41:160 [linphone-android] MESSAGE [Permission] android.permission.READ_EXTERNAL_STORAGE permission is granted 2019-05-02 07:10:41:160 [linphone-android] MESSAGE [Permission] android.permission.READ_PHONE_STATE permission is granted 2019-05-02 07:10:41:160 [linphone-android] MESSAGE [Permission] android.permission.WRITE_CONTACTS permission is granted 2019-05-02 07:10:41:160 [linphone-android] MESSAGE [Permission] android.permission.READ_CONTACTS permission is granted 2019-05-02 07:10:41:160 [linphone-android] MESSAGE [Permission] Asking for android.permission.SYSTEM_ALERT_WINDOW permission 2019-05-02 07:10:41:160 [linphone-android] MESSAGE [Permission] Asking for android.permission.FOREGROUND_SERVICE permission 2019-05-02 07:10:41:184 [linphone-android] MESSAGE [Permission] android.permission.READ_CONTACTS permission is granted 2019-05-02 07:10:41:186 [linphone-android] MESSAGE [Intent Utils] Unknown action [null], skipping 2019-05-02 07:10:41:199 [linphone-android] MESSAGE [Service] Activity resumed:org.linphone.LinphoneActivity@a85a962 2019-05-02 07:10:41:199 [linphone-android] MESSAGE [Service] runningActivities=1 2019-05-02 07:10:41:200 [liblinphone] MESSAGE Core callbacks [0x7527ba7950] registered on core [0x754549e800] 2019-05-02 07:10:41:202 [liblinphone] MESSAGE Core callbacks [0x751dda57a0] registered on core [0x754549e800] 2019-05-02 07:10:41:202 [liblinphone] MESSAGE Core callbacks [0x751dda57f0] registered on core [0x754549e800] 2019-05-02 07:10:41:203 [liblinphone] MESSAGE Callbacks [0x751dda57a0] unregistered on core [0x754549e800] 2019-05-02 07:10:41:203 [liblinphone] MESSAGE Callbacks [0x751dda57f0] unregistered on core [0x754549e800] 2019-05-02 07:10:41:203 [liblinphone] MESSAGE Callbacks [0x7527ba7950] unregistered on core [0x754549e800] 2019-05-02 07:10:41:203 [linphone-android] MESSAGE [Service] Activity paused:org.linphone.LinphoneActivity@a85a962 2019-05-02 07:10:41:203 [linphone-android] MESSAGE [Service] runningActivities=0 2019-05-02 07:10:41:228 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=my_sip_account, realm=asterisk 2019-05-02 07:10:41:229 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=my_sip_account, realm=asterisk 2019-05-02 07:10:41:230 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=my_sip_account, realm=asterisk 2019-05-02 07:10:41:230 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=my_sip_account, realm=asterisk 2019-05-02 07:10:41:231 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=my_sip_account, realm=asterisk 2019-05-02 07:10:41:332 [linphone-android] MESSAGE [Permission] android.permission.SYSTEM_ALERT_WINDOW is denied 2019-05-02 07:10:41:332 [linphone-android] MESSAGE [Permission] android.permission.FOREGROUND_SERVICE is denied 2019-05-02 07:10:41:332 [linphone-android] MESSAGE [Service] Activity resumed:org.linphone.LinphoneActivity@a85a962 2019-05-02 07:10:41:332 [linphone-android] MESSAGE [Service] runningActivities=1 2019-05-02 07:10:41:360 [liblinphone] MESSAGE Core callbacks [0x751dda5ca0] registered on core [0x754549e800] 2019-05-02 07:10:41:362 [liblinphone] MESSAGE Core callbacks [0x751dda5cf0] registered on core [0x754549e800] 2019-05-02 07:10:41:362 [liblinphone] MESSAGE Core callbacks [0x751dda5d40] registered on core [0x754549e800] 2019-05-02 07:10:41:369 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=my_sip_account, realm=asterisk 2019-05-02 07:10:41:369 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=my_sip_account, realm=asterisk 2019-05-02 07:10:41:370 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=my_sip_account, realm=asterisk 2019-05-02 07:10:41:370 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=my_sip_account, realm=asterisk 2019-05-02 07:10:41:377 [liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=my_sip_account, realm=asterisk 2019-05-02 07:10:41:405 [linphone-android] MESSAGE [Service] Activity stopped:org.linphone.call.CallActivity@83f86e2 2019-05-02 07:10:41:406 [liblinphone] MESSAGE Notifying all friends that we are [open] 2019-05-02 07:10:41:409 [linphone-android] MESSAGE [Service] Activity destroyed:org.linphone.call.CallActivity@83f86e2 2019-05-02 07:10:41:418 [linphone-android] MESSAGE [Service] Activity stopped:org.linphone.call.CallActivity@2713c03 2019-05-02 07:10:41:419 [liblinphone] MESSAGE Notifying all friends that we are [open] 2019-05-02 07:10:41:427 [linphone-android] MESSAGE [Service] Activity destroyed:org.linphone.call.CallActivity@2713c03 2019-05-02 07:10:41:503 [belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x305a] 2019-05-02 07:10:41:504 [belle-sip] MESSAGE channel [0x7520d40a00]: starting recv background task with id=[305a]. 2019-05-02 07:10:41:504 [belle-sip] MESSAGE channel [0x7520d40a00]: received [357] new bytes from [TLS://sip.linphone.org:5223]: SIP/2.0 200 OK Via: SIP/2.0/TLS [2001:1234:5678:abcd:5453:af89:8529:61af]:44542;branch=z9hG4bK.24k1IbNO2;rport=44542 From: "[redacted]" ;tag=iZCL-nB8r To: ;tag=648rma76NcrSQ Call-ID: 9vCiCnggSC CSeq: 20 PUBLISH Server: Flexisip/1.0.13 (sofia-sip-nta/2.0) Content-Length: 0 2019-05-02 07:10:41:505 [belle-sip] MESSAGE channel [0x7520d40a00] [357] bytes parsed 2019-05-02 07:10:41:505 [belle-sip] MESSAGE Found transaction matching response. 2019-05-02 07:10:41:505 [belle-sip] MESSAGE Changing [client] [PUBLISH] transaction [0x7520ad8a40], from state [TRYING] to [COMPLETED] 2019-05-02 07:10:41:505 [liblinphone] MESSAGE LinphoneEvent [0x75455fcb00] moving from [LinphonePublishProgress] to publish state LinphonePublishOk 2019-05-02 07:10:41:505 [belle-sip] MESSAGE Changing [client] [PUBLISH] transaction [0x7520ad8a40], from state [COMPLETED] to [TERMINATED] 2019-05-02 07:10:41:505 [belle-sip] MESSAGE Client PUBLISH transaction [0x7520ad8a40] terminated 2019-05-02 07:10:41:505 [liblinphone] MESSAGE Unhandled transaction terminated [0x7520ad8a40] 2019-05-02 07:10:41:505 [belle-sip] MESSAGE transaction [0x7520ad8a40]: ending transaction background task with id=[3092]. 2019-05-02 07:10:41:507 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x3092] 2019-05-02 07:10:41:507 [belle-sip] MESSAGE channel [0x7520d40a00]: ending recv background task with id=[305a]. 2019-05-02 07:10:41:508 [belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x305a]