search

BelledonneCommunications / linphone-android

Linphone.org mirror for linphone-android (https://gitlab.linphone.org/BC/public/linphone-android)
https://linphone.org
GNU General Public License v3.0
1.12k stars 689 forks source link

android don't receive video dispaly #790

Closed Roger1103 closed 5 years ago

Roger1103 commented 5 years ago

no such method on filter MSMediaCodecH264Decoder, fid=16389 method index=7

Viish commented 5 years ago

The "log" you attached is expected. If you want help please say which version of the app & SDK you are using and attach full logs from the app.

Roger1103 commented 5 years ago

Linphone Android4 1（debug） Linphone SDK 4.2-182-gleb2091（master）

The "log" you attached is expected. If you want help please say which version of the app & SDK you are using and attach full logs from the app.

Roger1103 commented 5 years ago

2019-10-12 09:56:30.282 2543-2543/org.linphone.debug W/Linphone: [Audio Manager] Routing audio to speaker 2019-10-12 09:56:30.300 2543-2543/org.linphone.debug W/MediaPlayer: Couldn't open content://settings/system/ringtone_cache: java.io.FileNotFoundException: No such file or directory 2019-10-12 09:56:51.410 2543-2543/org.linphone.debug W/Linphone: [Audio Manager] Routing audio to speaker 2019-10-12 09:56:51.436 2543-2543/org.linphone.debug W/Linphone: CallSession [0x8cba195c] - advanced adaptive rate control requested but avpf is not activated in this stream. Reverting to basic rate control instead 2019-10-12 09:56:51.456 2543-2543/org.linphone.debug W/libOpenSLES: Conversion from OpenSL ES positional channel mask 0x4 to Android mask 0 loses channels 2019-10-12 09:56:51.521 2543-2543/org.linphone.debug W/AudioRecord: AUDIO_INPUT_FLAG_FAST denied by server; frameCount 0 2019-10-12 09:56:51.545 2543-2648/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-12 09:56:51.547 2543-2543/org.linphone.debug W/Linphone: CallSession [0x8cba195c] - advanced adaptive rate control requested but avpf is not activated in this stream. Reverting to basic rate control instead 2019-10-12 09:56:51.548 2543-2543/org.linphone.debug W/Linphone: Fail to increase socket's recv buffer size with SO_RCVBUFFORCE: Operation not permitted. 2019-10-12 09:56:51.548 2543-2543/org.linphone.debug W/Linphone: Fail to increase socket's send buffer size with SO_SNDBUFFORCE: Operation not permitted. 2019-10-12 09:56:51.548 2543-2543/org.linphone.debug W/Linphone: Fail to increase socket's recv buffer size with SO_RCVBUFFORCE: Operation not permitted. 2019-10-12 09:56:51.555 2543-2648/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-12 09:56:51.564 2543-2648/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-12 09:56:51.574 2543-2648/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-12 09:56:51.584 2543-2648/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-12 09:56:51.590 2543-2543/org.linphone.debug E/Linphone: no such method on filter MSMediaCodecH264Decoder, fid=16389 method index=7 2019-10-12 09:56:51.591 2543-2543/org.linphone.debug E/Linphone: no such method on filter MSAndroidTextureDisplay, fid=16387 method index=1 2019-10-12 09:56:51.591 2543-2543/org.linphone.debug E/Linphone: no such method on filter MSAndroidTextureDisplay, fid=16387 method index=0 2019-10-12 09:56:51.594 2543-2648/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-12 09:56:51.604 2543-2648/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-12 09:56:51.614 2543-2648/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-12 09:56:51.624 2543-2648/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-12 09:56:51.635 2543-2648/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-12 09:56:51.644 2543-2648/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-12 09:56:51.654 2543-2648/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-12 09:56:51.664 2543-2648/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-12 09:56:51.674 2543-2648/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-12 09:56:51.674 2543-2648/org.linphone.debug W/Linphone: Not enough ref samples, using zeroes 2019-10-12 09:56:51.862 2543-2543/org.linphone.debug W/Linphone: Camera preview orientation: 0 2019-10-12 09:56:51.882 2543-2543/org.linphone.debug W/Linphone: [Audio Manager] already in MODE_IN_COMMUNICATION, skipping... 2019-10-12 09:56:51.915 2543-2543/org.linphone.debug W/MediaPlayer: mediaplayer went away with unhandled events 2019-10-12 09:56:52.247 2543-2543/org.linphone.debug W/Linphone: Camera preview orientation: 0 2019-10-12 09:56:52.298 2543-2543/org.linphone.debug W/Linphone: [Audio Manager] Routing audio to speaker 2019-10-12 09:56:52.312 2543-2543/org.linphone.debug W/Linphone: [Status Fragment] Can't display ZRTP popup, no token ! 2019-10-12 09:56:55.140 2543-2654/org.linphone.debug W/AMessage: failed to deliver message as target handler 13 is gone. 2019-10-12 09:56:55.445 2543-2664/org.linphone.debug E/Linphone: MediaCodecEncoder: I-frame requested to MediaCodec 2019-10-12 09:56:56.767 2543-2648/org.linphone.debug W/Linphone: Not enough ref samples, using zeroes 2019-10-12 09:56:57.476 2543-2664/org.linphone.debug E/Linphone: MediaCodecEncoder: I-frame requested to MediaCodec 2019-10-12 09:57:24.800 2543-2543/org.linphone.debug W/Linphone: .linphone.ecstate has not been created because there is no data to write 2019-10-12 09:57:25.043 2543-2543/org.linphone.debug W/Linphone: [Audio Manager] Routing audio to earpiece 2019-10-12 09:57:25.053 2543-2543/org.linphone.debug W/Linphone: linphone_core_mic_enabled(): No current call! 2019-10-12 09:57:25.071 2543-2543/org.linphone.debug W/Linphone: linphone_core_mic_enabled(): No current call!

Viish commented 5 years ago

Still not enough logs. Please send full logs.

Roger1103 commented 5 years ago

How to modify audio and video codec

Roger1103 commented 5 years ago

2019-10-15 13:39:40.151 5646-5646/org.linphone.debug W/Linphone: [Contacts Manager] Couldn't find friend... 2019-10-15 13:39:40.157 5646-5646/org.linphone.debug I/Linphone: ms_filter_unlink: MSFilePlayer:0x8c7653e8,0-->MSDtmfGen:0x8c765538,0 2019-10-15 13:39:40.157 5646-5646/org.linphone.debug I/Linphone: ms_filter_unlink: MSDtmfGen:0x8c765538,0-->MSResample:0x8c765928,0 2019-10-15 13:39:40.157 5646-5646/org.linphone.debug I/Linphone: ms_filter_unlink: MSResample:0x8c765928,0-->MSOpenSLESPlayer:0x8c765880,0 2019-10-15 13:39:40.161 5646-6391/org.linphone.debug I/Linphone: Ring MSTicker thread exiting 2019-10-15 13:39:40.161 5646-5646/org.linphone.debug I/Linphone: New MediaSession [0x83e4748c] initialized (LinphoneCore version: 3.12.0-3585-ga704c42d4) 2019-10-15 13:39:40.163 5646-5646/org.linphone.debug I/Linphone: Found media local-ip from signaling. 2019-10-15 13:39:40.163 5646-5646/org.linphone.debug I/Linphone: CallSession [0x83e4748c], stream type [audio], multicast role is [inactive] 2019-10-15 13:39:40.163 5646-5646/org.linphone.debug I/Linphone: RtpSession bound to [::0] ports [7078] [7079] 2019-10-15 13:39:40.164 5646-5646/org.linphone.debug I/Linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2019-10-15 13:39:40.164 5646-5646/org.linphone.debug I/Linphone: Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80 2019-10-15 13:39:40.164 5646-5646/org.linphone.debug I/Linphone: Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32 2019-10-15 13:39:40.164 5646-5646/org.linphone.debug I/Linphone: Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80 2019-10-15 13:39:40.164 5646-5646/org.linphone.debug I/Linphone: Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32 2019-10-15 13:39:40.164 5646-5646/org.linphone.debug I/Linphone: Creating ZRTP engine on rtp session [0x86e03e00] ssrc 0x86dbb5bd 2019-10-15 13:39:40.166 5646-5646/org.linphone.debug I/Linphone: Setting DSCP to 46 for MSAudio stream. 2019-10-15 13:39:40.166 5646-5646/org.linphone.debug I/Linphone: Equalizer location: hp 2019-10-15 13:39:40.167 5646-5646/org.linphone.debug I/Linphone: cannot set noise gate mode to [0] because no volume send 2019-10-15 13:39:40.168 5646-5646/org.linphone.debug I/Linphone: CallSession [0x83e4748c], stream type [video], multicast role is [inactive] 2019-10-15 13:39:40.168 5646-5646/org.linphone.debug I/Linphone: RtpSession bound to [::0] ports [9078] [9079] 2019-10-15 13:39:40.171 5646-5646/org.linphone.debug I/Linphone: This device (rockchip/rk3288/rk3288) says it has no built-in echo canceller. 2019-10-15 13:39:40.172 5646-5646/org.linphone.debug I/Linphone: No information available for [rockchip/rk3288/rk3288], 2019-10-15 13:39:40.172 5646-5646/org.linphone.debug I/Linphone: Sound device information for [rockchip/rk3288/rk3288] is: builtin=[no], delay=[250] ms 2019-10-15 13:39:40.172 5646-5646/org.linphone.debug I/Linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2019-10-15 13:39:40.172 5646-5646/org.linphone.debug I/Linphone: Initializing multistream ZRTP context on rtp session [0x86e04c00] ssrc 0xdd711b54 2019-10-15 13:39:40.172 5646-5646/org.linphone.debug I/Linphone: Setting DSCP to 0 for MSVideo stream. 2019-10-15 13:39:40.172 5646-5646/org.linphone.debug I/Linphone: CallSession [0x83e4748c], stream type [text], multicast role is [inactive] 2019-10-15 13:39:40.173 5646-5646/org.linphone.debug I/Linphone: RtpSession bound to [::0] ports [11078] [11079] 2019-10-15 13:39:40.173 5646-5646/org.linphone.debug I/Linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2019-10-15 13:39:40.173 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_created] 2019-10-15 13:39:40.173 5646-5646/org.linphone.debug I/Linphone: CallSession [0x83e4748c] moving from state State::Idle to State::OutgoingInit 2019-10-15 13:39:40.176 5646-5646/org.linphone.debug I/Linphone: [Manager] New call state [OutgoingInit] 2019-10-15 13:39:40.197 5646-5646/org.linphone.debug I/Linphone: [Notifications Manager] Call notification notifiable is Id: 15, local identity: null, myself: null, isGrouped: false, pending intent class org.linphone.call.CallOutgoingActivity 2019-10-15 13:39:40.198 5646-5646/org.linphone.debug W/Linphone: [Contacts Manager] Couldn't find friend... 2019-10-15 13:39:40.199 5646-5646/org.linphone.debug I/Linphone: [Notifications Manager] Notifying 15

--------- beginning of system

2019-10-15 13:39:40.210 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_state_changed] 2019-10-15 13:39:40.213 5646-5646/org.linphone.debug I/Linphone: Found media local-ip from signaling. 2019-10-15 13:39:40.213 5646-5646/org.linphone.debug I/Linphone: Don't put text stream on local offer for CallSession [0x83e4748c] 2019-10-15 13:39:40.215 5646-5646/org.linphone.debug I/Linphone: [LIME] /builds/BC/public/linphone-sdk/lime/src/lime_localStorage.cpp:255 Cannot find Lime User sip:0131@192.168.1.142 in DB while setting up identity key for ZRTP auxiliary secret 2019-10-15 13:39:40.216 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSRtpRecv:0x8c7666b8,0-->MSVoidSink:0x8c7665d8,0 2019-10-15 13:39:40.216 5646-5646/org.linphone.debug I/Linphone: Contact has been fixed using proxy 2019-10-15 13:39:40.217 5646-6403/org.linphone.debug I/Linphone: Priority used: 99 2019-10-15 13:39:40.217 5646-6403/org.linphone.debug I/Linphone: MSAudio MSTicker priority increased to maximum. 2019-10-15 13:39:40.218 5646-5646/org.linphone.debug I/Linphone: [sip:0131@192.168.1.181] calling [sip:0130@192.168.1.181] on op [0x8e0ef6c0] 2019-10-15 13:39:40.220 5646-5646/org.linphone.debug I/Linphone: Skipping top route of initial route-set because same as request-uri 2019-10-15 13:39:40.222 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x83b123b0)] acquired [ref=0x200aea] 2019-10-15 13:39:40.222 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 2099946 2019-10-15 13:39:40.223 5646-5646/org.linphone.debug I/Linphone: transaction [0x83b123b0]: starting transaction background task with id=[200aea]. 2019-10-15 13:39:40.223 5646-5646/org.linphone.debug I/Linphone: Changing [client] [INVITE] transaction [0x83b123b0], from state [INIT] to [CALLING] 2019-10-15 13:39:40.223 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: message sent to [UDP://192.168.1.181:5060], size: [1455] bytes 2019-10-15 13:39:40.223 5646-5646/org.linphone.debug I/Linphone: INVITE sip:0130@192.168.1.181 SIP/2.0 2019-10-15 13:39:40.223 5646-5646/org.linphone.debug I/Linphone: Via: SIP/2.0/UDP 192.168.1.142:57541;branch=z9hG4bK.A0zhK~fjz;rport 2019-10-15 13:39:40.223 5646-5646/org.linphone.debug I/Linphone: From: sip:0131@192.168.1.181;tag=orcivYktT 2019-10-15 13:39:40.223 5646-5646/org.linphone.debug I/Linphone: To: sip:0130@192.168.1.181 2019-10-15 13:39:40.223 5646-5646/org.linphone.debug I/Linphone: CSeq: 20 INVITE 2019-10-15 13:39:40.223 5646-5646/org.linphone.debug I/Linphone: Call-ID: 9LgXwhZBRu 2019-10-15 13:39:40.223 5646-5646/org.linphone.debug I/Linphone: Max-Forwards: 70 2019-10-15 13:39:40.223 5646-5646/org.linphone.debug I/Linphone: Supported: replaces, outbound, gruu 2019-10-15 13:39:40.223 5646-5646/org.linphone.debug I/Linphone: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE 2019-10-15 13:39:40.223 5646-5646/org.linphone.debug I/Linphone: Content-Type: application/sdp 2019-10-15 13:39:40.223 5646-5646/org.linphone.debug I/Linphone: Content-Length: 790 2019-10-15 13:39:40.223 5646-5646/org.linphone.debug I/Linphone: Contact: sip:0131@192.168.1.142:57541;transport=udp;expires=1799;+sip.instance="";+org.linphone.specs="lime" 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: User-Agent: LinphoneAndroid/4.1 (rk3288) LinphoneSDK/4.3.0-alpha.12+89393ba (master) 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: v=0 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: o=0131 2299 1165 IN IP4 192.168.1.142 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: s=Talk 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: c=IN IP4 192.168.1.142 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: t=0 0 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: m=audio 7078 RTP/AVP 96 97 98 0 8 101 99 100 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:96 opus/48000/2 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=fmtp:96 useinbandfec=1 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:97 speex/16000 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=fmtp:97 vbr=on 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:98 speex/8000 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=fmtp:98 vbr=on 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:101 telephone-event/48000 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:99 telephone-event/16000 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:100 telephone-event/8000 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb: trr-int 1000 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb: ccm tmmbr 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: m=video 9078 RTP/AVP 96 97 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:96 VP8/90000 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:97 H264/90000 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=fmtp:97 profile-level-id=42801F 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb: trr-int 1000 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb: ccm tmmbr 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb:96 nack pli 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb:96 nack sli 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb:96 ack rpsi 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb:96 ccm fir 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb:97 nack pli 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb:97 ccm fir 2019-10-15 13:39:40.224 5646-5646/org.linphone.debug I/Linphone: CallSession [0x83e4748c] moving from state State::OutgoingInit to State::OutgoingProgress 2019-10-15 13:39:40.225 5646-5646/org.linphone.debug I/Linphone: [Manager] New call state [OutgoingProgress] 2019-10-15 13:39:40.226 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_state_changed] 2019-10-15 13:39:40.232 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x100a7a] 2019-10-15 13:39:40.232 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 1051258 2019-10-15 13:39:40.232 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: starting recv background task with id=[100a7a]. 2019-10-15 13:39:40.232 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: received [441] new bytes from [UDP://192.168.1.181:5060]: 2019-10-15 13:39:40.233 5646-5646/org.linphone.debug I/Linphone: SIP/2.0 401 Unauthorized 2019-10-15 13:39:40.233 5646-5646/org.linphone.debug I/Linphone: Via: SIP/2.0/UDP 192.168.1.142:57541;rport=57541;received=192.168.1.142;branch=z9hG4bK.A0zhK~fjz 2019-10-15 13:39:40.233 5646-5646/org.linphone.debug I/Linphone: Call-ID: 9LgXwhZBRu 2019-10-15 13:39:40.233 5646-5646/org.linphone.debug I/Linphone: From: sip:0131@192.168.1.181;tag=orcivYktT 2019-10-15 13:39:40.233 5646-5646/org.linphone.debug I/Linphone: To: sip:0130@192.168.1.181;tag=z9hG4bK.A0zhK~fjz 2019-10-15 13:39:40.233 5646-5646/org.linphone.debug I/Linphone: CSeq: 20 INVITE 2019-10-15 13:39:40.233 5646-5646/org.linphone.debug I/Linphone: WWW-Authenticate: Digest realm="IPPBX",nonce="1571117855/d12f6ddcd42d1d5fe79a5597caaac930",opaque="66815f8e206cafee",algorithm=md5,qop="auth" 2019-10-15 13:39:40.233 5646-5646/org.linphone.debug I/Linphone: Server: IPPBX 2019-10-15 13:39:40.233 5646-5646/org.linphone.debug I/Linphone: Content-Length: 0 2019-10-15 13:39:40.237 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200] [441] bytes parsed 2019-10-15 13:39:40.237 5646-5646/org.linphone.debug I/Linphone: Found transaction matching response. 2019-10-15 13:39:40.237 5646-5646/org.linphone.debug I/Linphone: Changing [client] [INVITE] transaction [0x83b123b0], from state [CALLING] to [PROCEEDING] 2019-10-15 13:39:40.238 5646-5646/org.linphone.debug I/Linphone: Changing [client] [INVITE] transaction [0x83b123b0], from state [PROCEEDING] to [COMPLETED] 2019-10-15 13:39:40.238 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: message sent to [UDP://192.168.1.181:5060], size: [417] bytes ACK sip:0130@192.168.1.181 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.142:57541;branch=z9hG4bK.A0zhK~fjz;rport Call-ID: 9LgXwhZBRu From: sip:0131@192.168.1.181;tag=orcivYktT To: sip:0130@192.168.1.181;tag=z9hG4bK.A0zhK~fjz Contact: sip:0131@192.168.1.142:57541;transport=udp;expires=1799;+sip.instance="";+org.linphone.specs="lime" Max-Forwards: 70 CSeq: 20 ACK 2019-10-15 13:39:40.238 5646-5646/org.linphone.debug I/Linphone: linphone_core_find_auth_info(): returning auth info username=0131, realm=IPPBX 2019-10-15 13:39:40.238 5646-5646/org.linphone.debug I/Linphone: Auth info found for [0131] realm [IPPBX] 2019-10-15 13:39:40.241 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x83b39cf0)] acquired [ref=0xa5a] 2019-10-15 13:39:40.241 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 2650 2019-10-15 13:39:40.241 5646-5646/org.linphone.debug I/Linphone: transaction [0x83b39cf0]: starting transaction background task with id=[a5a]. 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: Changing [client] [INVITE] transaction [0x83b39cf0], from state [INIT] to [CALLING] 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: message sent to [UDP://192.168.1.181:5060], size: [1731] bytes 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: INVITE sip:0130@192.168.1.181 SIP/2.0 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: Via: SIP/2.0/UDP 192.168.1.142:57541;branch=z9hG4bK.PUGogITKS;rport 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: From: sip:0131@192.168.1.181;tag=orcivYktT 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: To: sip:0130@192.168.1.181 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: CSeq: 21 INVITE 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: Call-ID: 9LgXwhZBRu 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: Max-Forwards: 70 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: Supported: replaces, outbound, gruu 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: Content-Type: application/sdp 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: Content-Length: 790 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: Contact: sip:0131@192.168.1.142:57541;transport=udp;expires=1799;+sip.instance="";+org.linphone.specs="lime" 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: User-Agent: LinphoneAndroid/4.1 (rk3288) LinphoneSDK/4.3.0-alpha.12+89393ba (master) 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: Authorization: Digest realm="IPPBX", nonce="1571117855/d12f6ddcd42d1d5fe79a5597caaac930", algorithm=md5, opaque="66815f8e206cafee", username="0131", uri="sip:0130@192.168.1.181", response="bf77196d939d281e1ae35583e6b965fa", cnonce="j7lA0mvkv0goYtay", nc=00000001, qop=auth 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: v=0 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: o=0131 2299 1165 IN IP4 192.168.1.142 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: s=Talk 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: c=IN IP4 192.168.1.142 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: t=0 0 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: m=audio 7078 RTP/AVP 96 97 98 0 8 101 99 100 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:96 opus/48000/2 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: a=fmtp:96 useinbandfec=1 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:97 speex/16000 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: a=fmtp:97 vbr=on 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:98 speex/8000 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: a=fmtp:98 vbr=on 2019-10-15 13:39:40.242 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:101 telephone-event/48000 2019-10-15 13:39:40.243 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:99 telephone-event/16000 2019-10-15 13:39:40.243 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:100 telephone-event/8000 2019-10-15 13:39:40.243 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb: trr-int 1000 2019-10-15 13:39:40.243 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb: ccm tmmbr 2019-10-15 13:39:40.243 5646-5646/org.linphone.debug I/Linphone: m=video 9078 RTP/AVP 96 97 2019-10-15 13:39:40.243 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:96 VP8/90000 2019-10-15 13:39:40.243 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:97 H264/90000 2019-10-15 13:39:40.243 5646-5646/org.linphone.debug I/Linphone: a=fmtp:97 profile-level-id=42801F 2019-10-15 13:39:40.243 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb: trr-int 1000 2019-10-15 13:39:40.243 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb: ccm tmmbr 2019-10-15 13:39:40.243 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb:96 nack pli 2019-10-15 13:39:40.243 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb:96 nack sli 2019-10-15 13:39:40.243 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb:96 ack rpsi 2019-10-15 13:39:40.243 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb:96 ccm fir 2019-10-15 13:39:40.243 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb:97 nack pli 2019-10-15 13:39:40.243 5646-5646/org.linphone.debug I/Linphone: a=rtcp-fb:97 ccm fir 2019-10-15 13:39:40.243 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: ending recv background task with id=[100a7a]. 2019-10-15 13:39:40.245 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x100a7a] 2019-10-15 13:39:40.247 5646-5646/org.linphone.debug I/Linphone: Callbacks [0x863e1058] unregistered on core [0xa4a36100] 2019-10-15 13:39:40.247 5646-5646/org.linphone.debug I/Linphone: [Platform Helper] Preview window surface set to null 2019-10-15 13:39:40.247 5646-5646/org.linphone.debug I/Linphone: Callbacks [0x863e0658] unregistered on core [0xa4a36100] 2019-10-15 13:39:40.248 5646-5646/org.linphone.debug I/Linphone: Callbacks [0x86b55aa0] unregistered on core [0xa4a36100] 2019-10-15 13:39:40.248 5646-5646/org.linphone.debug I/Linphone: [Activity Monitor] Activity paused:org.linphone.activities.DialerActivity@49b5804 2019-10-15 13:39:40.256 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x100a4a] 2019-10-15 13:39:40.257 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 1051210 2019-10-15 13:39:40.257 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: starting recv background task with id=[100a4a]. 2019-10-15 13:39:40.257 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: received [269] new bytes from [UDP://192.168.1.181:5060]: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.142:57541;rport=57541;received=192.168.1.142;branch=z9hG4bK.PUGogITKS Call-ID: 9LgXwhZBRu From: sip:0131@192.168.1.181;tag=orcivYktT To: sip:0130@192.168.1.181 CSeq: 21 INVITE Server: IPPBX Content-Length: 0 2019-10-15 13:39:40.259 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200] [269] bytes parsed 2019-10-15 13:39:40.260 5646-5646/org.linphone.debug I/Linphone: Found transaction matching response. 2019-10-15 13:39:40.260 5646-5646/org.linphone.debug I/Linphone: Changing [client] [INVITE] transaction [0x83b39cf0], from state [CALLING] to [PROCEEDING] 2019-10-15 13:39:40.260 5646-5646/org.linphone.debug I/Linphone: op [0x8e0ef6c0] : set_or_update_dialog() current=[0x0] new=[0x0] 2019-10-15 13:39:40.260 5646-5646/org.linphone.debug I/Linphone: Op [0x8e0ef6c0] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL] 2019-10-15 13:39:40.260 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: ending recv background task with id=[100a4a]. 2019-10-15 13:39:40.261 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x100a4a] 2019-10-15 13:39:40.273 5646-5646/org.linphone.debug I/Linphone: [Activity Monitor] Activity created:org.linphone.call.CallOutgoingActivity@7640bd1 2019-10-15 13:39:40.302 5646-5646/org.linphone.debug I/Linphone: Activity started:org.linphone.call.CallOutgoingActivity@7640bd1 2019-10-15 13:39:40.303 5646-5646/org.linphone.debug I/Linphone: [Permission] Record audio permission is granted 2019-10-15 13:39:40.303 5646-5646/org.linphone.debug I/Linphone: [Permission] Camera permission is granted 2019-10-15 13:39:40.304 5646-5646/org.linphone.debug I/Linphone: [Permission] Read phone state permission is granted 2019-10-15 13:39:40.306 5646-5646/org.linphone.debug I/Linphone: [Activity Monitor] Activity resumed:org.linphone.call.CallOutgoingActivity@7640bd1 2019-10-15 13:39:40.306 5646-5646/org.linphone.debug I/Linphone: [Activity Monitor] runningActivities=1 2019-10-15 13:39:40.306 5646-5646/org.linphone.debug I/Linphone: [Activity Monitor] App has left background mode 2019-10-15 13:39:40.307 5646-5646/org.linphone.debug E/Linphone: Couldn't send chat room list subscription because there's no conference factory uri 2019-10-15 13:39:40.312 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x86e45e00)] acquired [ref=0xa2a] 2019-10-15 13:39:40.312 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 2602 2019-10-15 13:39:40.312 5646-5646/org.linphone.debug I/Linphone: transaction [0x86e45e00]: starting transaction background task with id=[a2a]. 2019-10-15 13:39:40.313 5646-5646/org.linphone.debug I/Linphone: belle_sip_client_transaction_send_request(): waiting channel to be ready 2019-10-15 13:39:40.313 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip send channel] acquired [ref=0xa02] 2019-10-15 13:39:40.314 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 2562 2019-10-15 13:39:40.314 5646-5646/org.linphone.debug I/Linphone: channel [0x8678bfc0]: starting send background task with id=[a02]. 2019-10-15 13:39:40.314 5646-5646/org.linphone.debug I/Linphone: channel [0x8678bfc0]: starting resolution of sip.linphone.org 2019-10-15 13:39:40.314 5646-5646/org.linphone.debug I/Linphone: channel 0x8678bfc0: state RES_IN_PROGRESS 2019-10-15 13:39:40.314 5646-5646/org.linphone.debug I/Linphone: transaction [0x86e45e00] channel state changed to [RES_IN_PROGRESS] 2019-10-15 13:39:40.314 5646-5646/org.linphone.debug I/Linphone: resconf using application supplied dns server list. 2019-10-15 13:39:40.314 5646-5646/org.linphone.debug I/Linphone: Resolver is using DNS server(s): 2019-10-15 13:39:40.314 5646-5646/org.linphone.debug I/Linphone: 0.0.0.0 2019-10-15 13:39:40.315 5646-5646/org.linphone.debug E/Linphone: Cannot reach [127.0.0.1:53] because [Connection refused] 2019-10-15 13:39:40.315 5646-5646/org.linphone.debug E/Linphone: Cannot reach [127.0.0.1:53] because [Connection refused] 2019-10-15 13:39:40.316 5646-5646/org.linphone.debug E/Linphone: Cannot reach [127.0.0.1:53] because [Connection refused] 2019-10-15 13:39:40.316 5646-5646/org.linphone.debug E/Linphone: Cannot reach [127.0.0.1:53] because [Connection refused] 2019-10-15 13:39:40.317 5646-5646/org.linphone.debug E/Linphone: Cannot reach [127.0.0.1:53] because [Connection refused] 2019-10-15 13:39:40.317 5646-5646/org.linphone.debug I/Linphone: No SRV result for [_sip._udp.sip.linphone.org], trying A/AAAA. 2019-10-15 13:39:40.317 5646-5646/org.linphone.debug I/Linphone: resconf using application supplied dns server list. 2019-10-15 13:39:40.317 5646-5646/org.linphone.debug I/Linphone: Resolver is using DNS server(s): 2019-10-15 13:39:40.317 5646-5646/org.linphone.debug I/Linphone: 0.0.0.0 2019-10-15 13:39:40.318 5646-5646/org.linphone.debug E/Linphone: Cannot reach [127.0.0.1:53] because [Connection refused] 2019-10-15 13:39:40.319 5646-5646/org.linphone.debug E/Linphone: Cannot reach [127.0.0.1:53] because [Connection refused] 2019-10-15 13:39:40.319 5646-5646/org.linphone.debug E/Linphone: Cannot reach [127.0.0.1:53] because [Connection refused] 2019-10-15 13:39:40.320 5646-5646/org.linphone.debug E/Linphone: Cannot reach [127.0.0.1:53] because [Connection refused] 2019-10-15 13:39:40.320 5646-5646/org.linphone.debug E/Linphone: Cannot reach [127.0.0.1:53] because [Connection refused] 2019-10-15 13:39:40.321 5646-5646/org.linphone.debug I/Linphone: resconf using application supplied dns server list. 2019-10-15 13:39:40.321 5646-5646/org.linphone.debug I/Linphone: Resolver is using DNS server(s): 2019-10-15 13:39:40.321 5646-5646/org.linphone.debug I/Linphone: 0.0.0.0 2019-10-15 13:39:40.321 5646-5646/org.linphone.debug E/Linphone: Cannot reach [127.0.0.1:53] because [Connection refused] 2019-10-15 13:39:40.322 5646-5646/org.linphone.debug E/Linphone: Cannot reach [127.0.0.1:53] because [Connection refused] 2019-10-15 13:39:40.322 5646-5646/org.linphone.debug E/Linphone: Cannot reach [127.0.0.1:53] because [Connection refused] 2019-10-15 13:39:40.323 5646-5646/org.linphone.debug E/Linphone: Cannot reach [127.0.0.1:53] because [Connection refused] 2019-10-15 13:39:40.323 5646-5646/org.linphone.debug E/Linphone: Cannot reach [127.0.0.1:53] because [Connection refused] 2019-10-15 13:39:40.323 5646-5646/org.linphone.debug E/Linphone: channel_res_done: DNS resolution failed for sip.linphone.org 2019-10-15 13:39:40.323 5646-5646/org.linphone.debug I/Linphone: channel 0x8678bfc0: state ERROR 2019-10-15 13:39:40.323 5646-5646/org.linphone.debug I/Linphone: LinphoneEvent [0x86e45da0] moving to subscription state LinphoneSubscriptionOutgoingProgress 2019-10-15 13:39:40.324 5646-5646/org.linphone.debug W/Linphone: core [0xa4a36100] Receiving unexpected state [LinphoneSubscriptionOutgoingProgress] for event [0x86e45da0], no associated friend list 2019-10-15 13:39:40.325 5646-5646/org.linphone.debug I/Linphone: [Generic Activity] Device orientation is 0 (raw value is 0) 2019-10-15 13:39:40.325 5646-5646/org.linphone.debug I/Linphone: Core callbacks [0x863e1058] registered on core [0xa4a36100] 2019-10-15 13:39:40.326 5646-5646/org.linphone.debug W/Linphone: [Contacts Manager] Couldn't find friend... 2019-10-15 13:39:40.326 5646-5646/org.linphone.debug I/Linphone: Core callbacks [0x86b55aa0] registered on core [0xa4a36100] 2019-10-15 13:39:40.327 5646-5646/org.linphone.debug W/Linphone: [Status Fragment] Can't display ZRTP popup, no token ! 2019-10-15 13:39:40.331 5646-5646/org.linphone.debug I/Linphone: channel [0x8678bfc0]: ending send background task with id=[a02]. 2019-10-15 13:39:40.332 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0xa02] 2019-10-15 13:39:40.332 5646-5646/org.linphone.debug I/Linphone: transaction [0x86e45e00] channel state changed to [ERROR] 2019-10-15 13:39:40.332 5646-5646/org.linphone.debug I/Linphone: Changing [client] [SUBSCRIBE] transaction [0x86e45e00], from state [INIT] to [TERMINATED] 2019-10-15 13:39:40.332 5646-5646/org.linphone.debug I/Linphone: Client SUBSCRIBE transaction [0x86e45e00] terminated 2019-10-15 13:39:40.332 5646-5646/org.linphone.debug I/Linphone: transaction [0x86e45e00]: ending transaction background task with id=[a2a]. 2019-10-15 13:39:40.333 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0xa2a] 2019-10-15 13:39:40.333 5646-5646/org.linphone.debug I/Linphone: Channel [0x8678bfc0] destroyed 2019-10-15 13:39:40.398 5646-5713/org.linphone.debug I/[Gralloc]: Got handle 4 for fd 89 2019-10-15 13:39:40.398 5646-5713/org.linphone.debug I/[Gralloc]: leave, w : 800, h : 1280, format : 0x1,internal_format : 0x1, usage : 0xb00. size=4096000,pixel_stride=800,byte_stride=3200 2019-10-15 13:39:40.398 5646-5713/org.linphone.debug I/[Gralloc]: leave: prime_fd=89,share_attr_fd=90 2019-10-15 13:39:40.412 5646-5713/org.linphone.debug I/[Gralloc]: Got handle 5 for fd 91 2019-10-15 13:39:40.412 5646-5713/org.linphone.debug I/[Gralloc]: leave, w : 800, h : 1280, format : 0x1,internal_format : 0x1, usage : 0xb00. size=4096000,pixel_stride=800,byte_stride=3200 2019-10-15 13:39:40.412 5646-5713/org.linphone.debug I/[Gralloc]: leave: prime_fd=91,share_attr_fd=92 2019-10-15 13:39:40.440 5646-5646/org.linphone.debug I/Linphone: [Activity Monitor] Activity stopped:org.linphone.activities.DialerActivity@49b5804 2019-10-15 13:39:40.568 5646-6403/org.linphone.debug I/Linphone: First estimation 2019-10-15 13:39:40.568 5646-6403/org.linphone.debug I/Linphone: payload type changed to 8(PCMA) ! 2019-10-15 13:39:40.580 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x100b2e] 2019-10-15 13:39:40.580 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 1051438 2019-10-15 13:39:40.580 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: starting recv background task with id=[100b2e]. 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: received [869] new bytes from [UDP://192.168.1.181:5060]: 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: SIP/2.0 183 Session Progress 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: Via: SIP/2.0/UDP 192.168.1.142:57541;rport=57541;received=192.168.1.142;branch=z9hG4bK.PUGogITKS 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: Call-ID: 9LgXwhZBRu 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: From: sip:0131@192.168.1.181;tag=orcivYktT 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: To: sip:0130@192.168.1.181;tag=aea6f729-573f-4c1e-9648-17b5a99ad2d1 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: CSeq: 21 INVITE 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: Server: IPPBX 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: Contact: 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, MESSAGE, REFER 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: Content-Type: application/sdp 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: Content-Length: 369 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: v=0 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: o=- 2299 1167 IN IP4 192.168.1.181 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: s=IPPBX 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: c=IN IP4 192.168.1.181 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: t=0 0 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: m=audio 10096 RTP/AVP 8 0 100 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:8 PCMA/8000 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:0 PCMU/8000 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:100 telephone-event/8000 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: a=fmtp:100 0-16 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: a=ptime:20 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: a=maxptime:150 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: a=sendrecv 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: a=rtcp-mux 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: m=video 10004 RTP/AVP 97 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:97 H264/90000 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: a=fmtp:97 profile-level-id=42801F 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: a=sendrecv 2019-10-15 13:39:40.581 5646-5646/org.linphone.debug I/Linphone: a=rtcp-mux 2019-10-15 13:39:40.586 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200] [500] bytes parsed 2019-10-15 13:39:40.586 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200] read [369] bytes of body from [192.168.1.181:5060] 2019-10-15 13:39:40.586 5646-5646/org.linphone.debug I/Linphone: Found transaction matching response. 2019-10-15 13:39:40.586 5646-5646/org.linphone.debug I/Linphone: New client dialog [0x8675d040] , local tag [orcivYktT], remote tag [aea6f729-573f-4c1e-9648-17b5a99ad2d1] 2019-10-15 13:39:40.586 5646-5646/org.linphone.debug I/Linphone: Dialog [0x8675d040]: now updated by transaction [0x83b39cf0]. 2019-10-15 13:39:40.587 5646-5646/org.linphone.debug I/Linphone: op [0x8e0ef6c0] : set_or_update_dialog() current=[0x0] new=[0x8675d040] 2019-10-15 13:39:40.587 5646-5646/org.linphone.debug I/Linphone: Op [0x8e0ef6c0] receiving call response [183], dialog is [0x8675d040] in state [BELLE_SIP_DIALOG_EARLY] 2019-10-15 13:39:40.589 5646-5646/org.linphone.debug I/Linphone: Found payload PCMA/8000 fmtp= 2019-10-15 13:39:40.589 5646-5646/org.linphone.debug I/Linphone: Found payload PCMU/8000 fmtp= 2019-10-15 13:39:40.589 5646-5646/org.linphone.debug I/Linphone: Found payload telephone-event/8000 fmtp=0-16 2019-10-15 13:39:40.589 5646-5646/org.linphone.debug I/Linphone: Found payload H264/90000 fmtp=profile-level-id=42801F 2019-10-15 13:39:40.590 5646-5646/org.linphone.debug I/Linphone: Doing SDP offer/answer process of type outgoing 2019-10-15 13:39:40.590 5646-5646/org.linphone.debug I/Linphone: Processing for stream 0 2019-10-15 13:39:40.590 5646-5646/org.linphone.debug I/Linphone: Adding opus/48000 for compatibility, just in case. 2019-10-15 13:39:40.590 5646-5646/org.linphone.debug I/Linphone: Adding speex/16000 for compatibility, just in case. 2019-10-15 13:39:40.590 5646-5646/org.linphone.debug I/Linphone: Adding speex/8000 for compatibility, just in case. 2019-10-15 13:39:40.590 5646-5646/org.linphone.debug I/Linphone: Adding telephone-event/48000 for compatibility, just in case. 2019-10-15 13:39:40.590 5646-5646/org.linphone.debug I/Linphone: Adding telephone-event/16000 for compatibility, just in case. 2019-10-15 13:39:40.590 5646-5646/org.linphone.debug I/Linphone: Processing for stream 1 2019-10-15 13:39:40.590 5646-5646/org.linphone.debug I/Linphone: Doing offer/answer processing with specific provider for codec [H264] 2019-10-15 13:39:40.590 5646-5646/org.linphone.debug I/Linphone: Adding VP8/90000 for compatibility, just in case. 2019-10-15 13:39:40.591 5646-5646/org.linphone.debug I/Linphone: CallSession [0x83e4748c] moving from state State::OutgoingProgress to State::OutgoingEarlyMedia 2019-10-15 13:39:40.591 5646-5646/org.linphone.debug I/Linphone: [Manager] New call state [OutgoingEarlyMedia] 2019-10-15 13:39:40.592 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_state_changed] 2019-10-15 13:39:40.593 5646-5646/org.linphone.debug I/Linphone: Doing early media... 2019-10-15 13:39:40.593 5646-5646/org.linphone.debug I/Linphone: startStreams() CallSession=[0x83e4748c] local upload_bandwidth=[0] kbit/s; local download_bandwidth=[0] kbit/s 2019-10-15 13:39:40.593 5646-5646/org.linphone.debug I/Linphone: Audio bandwidth for CallSession [0x83e4748c] is 80 2019-10-15 13:39:40.593 5646-5646/org.linphone.debug W/Linphone: CallSession [0x83e4748c] - advanced adaptive rate control requested but avpf is not activated in this stream. Reverting to basic rate control instead 2019-10-15 13:39:40.593 5646-5646/org.linphone.debug I/Linphone: RtpSession [0x86e03e00] sending to rtp [::ffff:192.168.1.181]:10096 rtcp [::ffff:192.168.1.181]:10097 2019-10-15 13:39:40.593 5646-5646/org.linphone.debug I/Linphone: Stun packet sent for session [0x86e03e00] 2019-10-15 13:39:40.593 5646-5646/org.linphone.debug I/Linphone: Stun packet sent on rtcp for session [0x86e03e00] 2019-10-15 13:39:40.594 5646-5646/org.linphone.debug I/Linphone: ms_filter_unlink: MSRtpRecv:0x8c7666b8,0-->MSVoidSink:0x8c7665d8,0 2019-10-15 13:39:40.594 5646-5646/org.linphone.debug I/Linphone: speex_lib_ctl init with neon ? 1 2019-10-15 13:39:40.594 5646-5646/org.linphone.debug I/Linphone: speex_lib_ctl init with neon ? 1 2019-10-15 13:39:40.594 5646-5646/org.linphone.debug I/Linphone: Setting echo canceller delay with value provided by soundcard: 250 ms 2019-10-15 13:39:40.594 5646-5646/org.linphone.debug I/Linphone: MKVRecorder: initialisation 2019-10-15 13:39:40.595 5646-5646/org.linphone.debug I/Linphone: speex_lib_ctl init with neon ? 1 2019-10-15 13:39:40.595 5646-5646/org.linphone.debug I/Linphone: Configuring av recorder with audio format type=audio;encoding=opus;rate=48000;channels=1;fmtp='' 2019-10-15 13:39:40.595 5646-5646/org.linphone.debug I/Linphone: MKVRecorder: set pin #1 format. type=audio;encoding=opus;rate=48000;channels=1;fmtp='' 2019-10-15 13:39:40.595 5646-5646/org.linphone.debug I/Linphone: target bitrate not set for stream [0x86694b80] using payload's bitrate is 80000 2019-10-15 13:39:40.595 5646-5646/org.linphone.debug I/Linphone: Setting audio encoder network bitrate to [80000] on stream [0x86694b80] 2019-10-15 13:39:40.595 5646-5646/org.linphone.debug I/Linphone: MSAlawEnc want ptime=20 2019-10-15 13:39:40.598 5646-5646/org.linphone.debug I/Linphone: This device (rockchip/rk3288/rk3288) says it has no built-in echo canceller. 2019-10-15 13:39:40.598 5646-5646/org.linphone.debug I/Linphone: No information available for [rockchip/rk3288/rk3288], 2019-10-15 13:39:40.599 5646-5646/org.linphone.debug I/Linphone: Sound device information for [rockchip/rk3288/rk3288] is: builtin=[no], delay=[250] ms 2019-10-15 13:39:40.602 5646-5646/org.linphone.debug I/Linphone: This device (rockchip/rk3288/rk3288) says it has no built-in echo canceller. 2019-10-15 13:39:40.602 5646-5646/org.linphone.debug I/Linphone: No information available for [rockchip/rk3288/rk3288], 2019-10-15 13:39:40.602 5646-5646/org.linphone.debug I/Linphone: Sound device information for [rockchip/rk3288/rk3288] is: builtin=[no], delay=[250] ms 2019-10-15 13:39:40.602 5646-5646/org.linphone.debug I/Linphone: MSVolume set gain to [0.000000 db], [1.000000] linear 2019-10-15 13:39:40.605 5646-5646/org.linphone.debug I/Linphone: This device (rockchip/rk3288/rk3288) says it has no built-in echo canceller. 2019-10-15 13:39:40.605 5646-5646/org.linphone.debug I/Linphone: No information available for [rockchip/rk3288/rk3288], 2019-10-15 13:39:40.605 5646-5646/org.linphone.debug I/Linphone: Sound device information for [rockchip/rk3288/rk3288] is: builtin=[no], delay=[250] ms 2019-10-15 13:39:40.605 5646-5646/org.linphone.debug I/Linphone: MSVolume set gain to [0.000000 db], [1.000000] linear 2019-10-15 13:39:40.605 5646-5646/org.linphone.debug I/Linphone: configuring MSOpenSLESRecorder:0x8c7666b8-->MSAlawEnc:0x8c767480 from rate [44100] to rate [8000] and from channel [1] to channel [1] 2019-10-15 13:39:40.605 5646-5646/org.linphone.debug I/Linphone: configuring MSAlawDec:0x8c7674f0-->MSOpenSLESPlayer:0x8c7665d8 from rate [8000] to rate [44100] and from channel [1] to channel [1] 2019-10-15 13:39:40.606 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSOpenSLESRecorder:0x8c7666b8,0-->MSResample:0x8c767640,0 2019-10-15 13:39:40.606 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSResample:0x8c767640,0-->MSEqualizer:0x8c7678e0,0 2019-10-15 13:39:40.606 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSEqualizer:0x8c7678e0,0-->MSWebRTCAECM:0x8c765458,1 2019-10-15 13:39:40.606 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSWebRTCAECM:0x8c765458,1-->MSVolume:0x8c767528,0 2019-10-15 13:39:40.606 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSVolume:0x8c767528,0-->MSAudioMixer:0x8c7676b0,0 2019-10-15 13:39:40.606 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSAudioMixer:0x8c7676b0,0-->MSAlawEnc:0x8c767480,0 2019-10-15 13:39:40.606 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSAlawEnc:0x8c767480,0-->MSRtpSend:0x8c765538,0 2019-10-15 13:39:40.606 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSRtpRecv:0x8c7673d8,0-->MSAlawDec:0x8c7674f0,0 2019-10-15 13:39:40.606 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSAlawDec:0x8c7674f0,0-->MSGenericPLC:0x8c767950,0 2019-10-15 13:39:40.606 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSGenericPLC:0x8c767950,0-->MSAudioFlowControl:0x8c767d40,0 2019-10-15 13:39:40.606 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSAudioFlowControl:0x8c767d40,0-->MSDtmfGen:0x8c767410,0 2019-10-15 13:39:40.606 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSDtmfGen:0x8c767410,0-->MSVolume:0x8c767560,0 2019-10-15 13:39:40.606 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSVolume:0x8c767560,0-->MSTee:0x8c767758,0 2019-10-15 13:39:40.606 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSTee:0x8c767758,0-->MSEqualizer:0x8c767918,0 2019-10-15 13:39:40.607 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSEqualizer:0x8c767918,0-->MSAudioMixer:0x8c767d78,0 2019-10-15 13:39:40.607 5646-5646/org.linphone.debug I/Linphone: speex_lib_ctl init with neon ? 1 2019-10-15 13:39:40.607 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSFilePlayer:0x8c767db0,0-->MSResample:0x8c767e20,0 2019-10-15 13:39:40.607 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSResample:0x8c767e20,0-->MSAudioMixer:0x8c767d78,1 2019-10-15 13:39:40.607 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSAudioMixer:0x8c767d78,0-->MSWebRTCAECM:0x8c765458,0 2019-10-15 13:39:40.607 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSWebRTCAECM:0x8c765458,0-->MSResample:0x8c767678,0 2019-10-15 13:39:40.607 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSResample:0x8c767678,0-->MSOpenSLESPlayer:0x8c7665d8,0 2019-10-15 13:39:40.607 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSAudioMixer:0x8c767720,1-->MSResample:0x8c767870,0 2019-10-15 13:39:40.607 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSResample:0x8c767870,0-->MSOpusEnc:0x8c7678a8,0 2019-10-15 13:39:40.607 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSOpusEnc:0x8c7678a8,0-->MSMKVRecorder:0x8c767790,1 2019-10-15 13:39:40.607 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSItcSource:0x8c767800,0-->MSMKVRecorder:0x8c767790,0 2019-10-15 13:39:40.607 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSAudioMixer:0x8c7676b0,1-->MSAudioMixer:0x8c767720,0 2019-10-15 13:39:40.607 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSTee:0x8c767758,1-->MSAudioMixer:0x8c767720,1 2019-10-15 13:39:40.608 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSAudioMixer:0x8c767720,0-->MSFileRec:0x8c7676e8,0 2019-10-15 13:39:40.608 5646-5646/org.linphone.debug W/libOpenSLES: Conversion from OpenSL ES positional channel mask 0x4 to Android mask 0 loses channels 2019-10-15 13:39:40.608 5646-5646/org.linphone.debug I/libOpenSLES: Emulating old channel mask behavior (ignoring positional mask 0x4, using default mask 0x10 based on channel count of 1) 2019-10-15 13:39:40.612 5646-5646/org.linphone.debug W/AudioRecord: AUDIO_INPUT_FLAG_FAST denied by server; frameCount 0 2019-10-15 13:39:40.621 5646-5646/org.linphone.debug I/Linphone: Initializing speex resampler in mode [voip] 2019-10-15 13:39:40.622 5646-5646/org.linphone.debug I/Linphone: Initializing WebRTC echo canceler with framesize=80, delay_ms=250, delay_samples=2000 2019-10-15 13:39:40.623 5646-5646/org.linphone.debug I/Linphone: Initializing speex resampler in mode [voip] 2019-10-15 13:39:40.623 5646-5646/org.linphone.debug I/Linphone: MSOpusEnc: codec bitrate set to [30000] with ptime [20] 2019-10-15 13:39:40.623 5646-5646/org.linphone.debug I/Linphone: Setting opus codec bitrate to [30000] from network bitrate [46000] with ptime [20] 2019-10-15 13:39:40.623 5646-5646/org.linphone.debug I/Linphone: Initializing speex resampler in mode [voip] 2019-10-15 13:39:40.623 5646-5646/org.linphone.debug I/Linphone: Initializing speex resampler in mode [voip] 2019-10-15 13:39:40.624 5646-5646/org.linphone.debug I/libOpenSLES: Emulating old channel mask behavior (ignoring positional mask 0x4, using default mask 0x1 based on channel count of 1) 2019-10-15 13:39:40.627 5646-5646/org.linphone.debug I/Linphone: Filter MSRtpRecv is already being scheduled; nothing to do. 2019-10-15 13:39:40.627 5646-5646/org.linphone.debug I/Linphone: MSVolume set gain to [0.000000] linear 2019-10-15 13:39:40.628 5646-6403/org.linphone.debug I/Linphone: MSAudioMixer [0x8c767d78] is entering bypass mode. 2019-10-15 13:39:40.628 5646-6403/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-15 13:39:40.628 5646-6403/org.linphone.debug I/Linphone: Stun packet sent for session [0x86e03e00] 2019-10-15 13:39:40.628 5646-6403/org.linphone.debug I/Linphone: Stun packet sent on rtcp for session [0x86e03e00] 2019-10-15 13:39:40.631 5646-5646/org.linphone.debug I/Linphone: This device (rockchip/rk3288/rk3288) says it has no built-in echo canceller. 2019-10-15 13:39:40.631 5646-5646/org.linphone.debug I/Linphone: No information available for [rockchip/rk3288/rk3288], 2019-10-15 13:39:40.631 5646-5646/org.linphone.debug I/Linphone: Sound device information for [rockchip/rk3288/rk3288] is: builtin=[no], delay=[250] ms 2019-10-15 13:39:40.631 5646-5646/org.linphone.debug I/Linphone: MSVolume set gain to [0.000000 db], [1.000000] linear 2019-10-15 13:39:40.631 5646-5646/org.linphone.debug I/Linphone: [LIME] Missing identity keys for mutual authentication, do not set auxiliary secret from identity keys 2019-10-15 13:39:40.631 5646-5646/org.linphone.debug I/Linphone: audio_stream_link_video() connecting itc filters 2019-10-15 13:39:40.631 5646-5646/org.linphone.debug I/Linphone: Setting video size 640x480 on stream [0x867fc000] 2019-10-15 13:39:40.631 5646-5646/org.linphone.debug W/Linphone: CallSession [0x83e4748c] - advanced adaptive rate control requested but avpf is not activated in this stream. Reverting to basic rate control instead 2019-10-15 13:39:40.631 5646-5646/org.linphone.debug I/Linphone: startVideoStream: device_rotation=0 2019-10-15 13:39:40.632 5646-5646/org.linphone.debug I/Linphone: RtpSession [0x86e04c00] sending to rtp [::ffff:192.168.1.181]:10004 rtcp [::ffff:192.168.1.181]:10005 2019-10-15 13:39:40.632 5646-5646/org.linphone.debug I/Linphone: Using permissive algorithm 2019-10-15 13:39:40.632 5646-5646/org.linphone.debug W/Linphone: Fail to increase socket's recv buffer size with SO_RCVBUFFORCE: Operation not permitted. 2019-10-15 13:39:40.632 5646-5646/org.linphone.debug W/Linphone: Fail to increase socket's send buffer size with SO_SNDBUFFORCE: Operation not permitted. 2019-10-15 13:39:40.632 5646-5646/org.linphone.debug W/Linphone: Fail to increase socket's recv buffer size with SO_RCVBUFFORCE: Operation not permitted. 2019-10-15 13:39:40.632 5646-5646/org.linphone.debug I/Linphone: Stun packet sent for session [0x86e04c00] 2019-10-15 13:39:40.632 5646-5646/org.linphone.debug I/Linphone: Stun packet sent on rtcp for session [0x86e04c00] 2019-10-15 13:39:40.634 5646-5646/org.linphone.debug I/MediaCodec: callingProcessName:org.linphone.debug 2019-10-15 13:39:40.635 5646-6409/org.linphone.debug I/OMXClient: MuxOMX ctor 2019-10-15 13:39:40.635 5646-6409/org.linphone.debug I/ACodec: onAllocateComponent:6752 mSoftCodecPref:0 componentName: 2019-10-15 13:39:40.636 5646-6409/org.linphone.debug I/ACodec: onAllocateComponent:6783 mSoftCodecPref:0 componentName:OMX.rk.video_encoder.avc 2019-10-15 13:39:40.638 5646-6403/org.linphone.debug I/Linphone: MSAudioMixer [0x8c767d78] is leaving bypass mode. 2019-10-15 13:39:40.638 5646-6403/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-15 13:39:40.638 5646-6403/org.linphone.debug I/Linphone: MSAudioMixer [0x8c7676b0] is entering bypass mode. 2019-10-15 13:39:40.639 5646-5646/org.linphone.debug I/Linphone: Codec video/avc successfully created. 2019-10-15 13:39:40.643 5646-5646/org.linphone.debug I/Linphone: This device (rockchip/rk3288/rk3288) says it has no built-in echo canceller. 2019-10-15 13:39:40.643 5646-5646/org.linphone.debug I/Linphone: No information available for [rockchip/rk3288/rk3288], 2019-10-15 13:39:40.643 5646-5646/org.linphone.debug I/Linphone: Sound device information for [rockchip/rk3288/rk3288] is: builtin=[no], delay=[250] ms 2019-10-15 13:39:40.643 5646-5646/org.linphone.debug I/Linphone: Using the default video configuration list 2019-10-15 13:39:40.643 5646-5646/org.linphone.debug I/Linphone: target and payload bitrates not set for stream [0x867fc000] using lowest configuration of preferred video size 640x480 2019-10-15 13:39:40.643 5646-5646/org.linphone.debug I/Linphone: Limiting bitrate of video encoder to 0 bits/s for stream [0x867fc000] 2019-10-15 13:39:40.643 5646-5646/org.linphone.debug I/Linphone: H26xEncoder: video configuration set (bitrate=128000b/s, fps=15.000000, vsize=352x288) 2019-10-15 13:39:40.644 5646-5646/org.linphone.debug I/Linphone: H26xEncoder: AVPF disabled 2019-10-15 13:39:40.644 5646-5646/org.linphone.debug E/Linphone: Cannot load /data/user/0/org.linphone.debug/files/share/images/nowebcamCIF.jpg 2019-10-15 13:39:40.644 5646-5646/org.linphone.debug I/Linphone: Output video size adjusted to match camera resolution (640x480) 2019-10-15 13:39:40.645 5646-5646/org.linphone.debug I/Linphone: Setting sent vsize=640x480, fps=15.000000 2019-10-15 13:39:40.645 5646-5646/org.linphone.debug I/Linphone: H26xEncoder: video configuration set (bitrate=400000b/s, fps=15.000000, vsize=640x480) 2019-10-15 13:39:40.645 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSStaticImage:0x8c767f70,0-->MSPixConv:0x8c7681a0,0 2019-10-15 13:39:40.645 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSPixConv:0x8c7681a0,0-->MSTee:0x8c7680c0,0 2019-10-15 13:39:40.645 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSTee:0x8c7680c0,0-->MSSizeConv:0x8c7681d8,0 2019-10-15 13:39:40.645 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSSizeConv:0x8c7681d8,0-->MSMediaCodecH264Encoder:0x8c767fa8,0 2019-10-15 13:39:40.645 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSMediaCodecH264Encoder:0x8c767fa8,0-->MSRtpSend:0x8c7659d0,0 2019-10-15 13:39:40.645 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSTee:0x8c7680c0,2-->MSJpegWriter:0x8c768168,0 2019-10-15 13:39:40.646 5646-5646/org.linphone.debug I/MediaCodec: callingProcessName:org.linphone.debug 2019-10-15 13:39:40.648 5646-6403/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-15 13:39:40.648 5646-6413/org.linphone.debug I/OMXClient: MuxOMX ctor 2019-10-15 13:39:40.648 5646-6413/org.linphone.debug I/ACodec: onAllocateComponent:6752 mSoftCodecPref:0 componentName: 2019-10-15 13:39:40.649 5646-6413/org.linphone.debug I/ACodec: onAllocateComponent:6783 mSoftCodecPref:0 componentName:OMX.rk.video_decoder.avc 2019-10-15 13:39:40.652 5646-5646/org.linphone.debug I/Linphone: Codec video/avc successfully created. 2019-10-15 13:39:40.652 5646-5646/org.linphone.debug I/Linphone: MediaCodecDecoder: starting decoder with following parameters: {max-height=1920, width=240, height=320, mime=video/avc, priority=0, max-width=1920, color-format=2135033992} 2019-10-15 13:39:40.654 5646-6413/org.linphone.debug I/ACodec: [OMX.rk.video_decoder.avc] using color format 0x15 in place of 0x7f420888 2019-10-15 13:39:40.656 5646-6413/org.linphone.debug I/ACodec: codec does not support config priority (err -1010) 2019-10-15 13:39:40.658 5646-6403/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-15 13:39:40.667 5646-6403/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-15 13:39:40.672 5646-5646/org.linphone.debug I/Linphone: MediaCodecDecoder: decoder successfully started. In-force parameters: {max-height=1920, width=240, height=320, mime=video/avc, priority=0, max-width=1920, color-format=2135033992} 2019-10-15 13:39:40.672 5646-5646/org.linphone.debug I/Linphone: MediaCodecH264Decoder: got device info: { 'rockchip', 'rk3288', 'rk3288' } 2019-10-15 13:39:40.672 5646-5646/org.linphone.debug E/Linphone: no such method on filter MSMediaCodecH264Decoder, fid=16389 method index=7 2019-10-15 13:39:40.672 5646-5646/org.linphone.debug I/Linphone: H26xDecoder: disabling AVPF mode 2019-10-15 13:39:40.672 5646-5646/org.linphone.debug I/Linphone: H26xDecoder: freeze on error enabled 2019-10-15 13:39:40.672 5646-5646/org.linphone.debug E/Linphone: no such method on filter MSAndroidTextureDisplay, fid=16387 method index=1 2019-10-15 13:39:40.672 5646-5646/org.linphone.debug E/Linphone: no such method on filter MSAndroidTextureDisplay, fid=16387 method index=0 2019-10-15 13:39:40.672 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSRtpRecv:0x8c768398,0-->MSTee:0x8c765a08,0 2019-10-15 13:39:40.672 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSTee:0x8c765a08,1-->MSItcSink:0x8c765b20,0 2019-10-15 13:39:40.673 5646-5646/org.linphone.debug I/Linphone: Video ITC source updated. 2019-10-15 13:39:40.673 5646-5646/org.linphone.debug I/Linphone: Configuring av recorder with video format type=video;encoding=H264;vsize=0x0;fps=0.000000;fmtp='' 2019-10-15 13:39:40.673 5646-5646/org.linphone.debug I/Linphone: MKVRecorder: set pin #0 format. type=video;encoding=H264;vsize=0x0;fps=0.000000;fmtp='' 2019-10-15 13:39:40.673 5646-5646/org.linphone.debug I/Linphone: configure_itc(): format set to type=video;encoding=H264;vsize=0x0;fps=0.000000;fmtp='' 2019-10-15 13:39:40.673 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSTee:0x8c765a08,0-->MSMediaCodecH264Decoder:0x8c768248,0 2019-10-15 13:39:40.673 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSMediaCodecH264Decoder:0x8c768248,0-->MSTee:0x8c768408,0 2019-10-15 13:39:40.673 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSTee:0x8c768408,1-->MSJpegWriter:0x8c7683d0,0 2019-10-15 13:39:40.673 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSTee:0x8c768408,0-->MSAndroidTextureDisplay:0x8c768360,0 2019-10-15 13:39:40.673 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSTee:0x8c7680c0,1-->MSAndroidTextureDisplay:0x8c768360,1 2019-10-15 13:39:40.674 5646-5646/org.linphone.debug I/Linphone: MediaCodecEncoder: configuring MediaCodec with the following parameters: {mime=video/avc, frame-rate=15, latency=1, color-format=2135033992, profile=1, height=480, width=640, bitrate=360000, priority=0, i-frame-interval=20, level=512, bitrate-mode=1} 2019-10-15 13:39:40.674 5646-6419/org.linphone.debug I/Linphone: MSVideo MSTicker priority left to normal. 2019-10-15 13:39:40.676 5646-6409/org.linphone.debug I/ACodec: [OMX.rk.video_encoder.avc] using color format 0x13 in place of 0x7f420888 2019-10-15 13:39:40.678 5646-6403/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-15 13:39:40.680 5646-6409/org.linphone.debug I/ACodec: setupAVCEncoderParameters with [profile: Baseline] [level: Level31] 2019-10-15 13:39:40.680 5646-6409/org.linphone.debug I/ACodec: [OMX.rk.video_encoder.avc] cannot encode color aspects. Ignoring. 2019-10-15 13:39:40.681 5646-6409/org.linphone.debug I/ACodec: [OMX.rk.video_encoder.avc] cannot encode HDR static metadata. Ignoring. 2019-10-15 13:39:40.681 5646-6409/org.linphone.debug I/ACodec: setupVideoEncoder succeeded 2019-10-15 13:39:40.681 5646-6409/org.linphone.debug I/ACodec: codec does not support config priority (err -1010) 2019-10-15 13:39:40.683 5646-5646/org.linphone.debug I/Linphone: MediaCodecEncoder: encoder successfully configured. In-force parameters: {height=480, width=640, bitrate=360000, mime=video/avc, max-bitrate=360000} 2019-10-15 13:39:40.688 5646-6403/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-15 13:39:40.688 5646-5646/org.linphone.debug I/Linphone: MediaCodecEncoder: encoder successfully started 2019-10-15 13:39:40.688 5646-5646/org.linphone.debug I/Linphone: Video ITC source updated. 2019-10-15 13:39:40.688 5646-5646/org.linphone.debug I/Linphone: Configuring av recorder with video format type=video;encoding=H264;vsize=0x0;fps=0.000000;fmtp='' 2019-10-15 13:39:40.688 5646-5646/org.linphone.debug I/Linphone: MKVRecorder: set pin #0 format. type=video;encoding=H264;vsize=0x0;fps=0.000000;fmtp='' 2019-10-15 13:39:40.688 5646-5646/org.linphone.debug I/Linphone: Filter MSRtpRecv is already being scheduled; nothing to do. 2019-10-15 13:39:40.689 5646-5646/org.linphone.debug I/Linphone: CallSession[0x83e4748c] : payload type 8 PCMA/8000 fmtp= added to frozen list 2019-10-15 13:39:40.689 5646-5646/org.linphone.debug I/Linphone: CallSession[0x83e4748c] : payload type 0 PCMU/8000 fmtp= added to frozen list 2019-10-15 13:39:40.689 5646-5646/org.linphone.debug I/Linphone: CallSession[0x83e4748c] : payload type 100 telephone-event/8000 fmtp= added to frozen list 2019-10-15 13:39:40.689 5646-5646/org.linphone.debug I/Linphone: CallSession[0x83e4748c] : payload type 96 opus/48000 fmtp=useinbandfec=1 added to frozen list 2019-10-15 13:39:40.689 5646-5646/org.linphone.debug I/Linphone: CallSession[0x83e4748c] : payload type 97 speex/16000 fmtp=vbr=on added to frozen list 2019-10-15 13:39:40.689 5646-5646/org.linphone.debug I/Linphone: CallSession[0x83e4748c] : payload type 98 speex/8000 fmtp=vbr=on added to frozen list 2019-10-15 13:39:40.689 5646-5646/org.linphone.debug I/Linphone: CallSession[0x83e4748c] : payload type 101 telephone-event/48000 fmtp= added to frozen list 2019-10-15 13:39:40.689 5646-5646/org.linphone.debug I/Linphone: CallSession[0x83e4748c] : payload type 99 telephone-event/16000 fmtp= added to frozen list 2019-10-15 13:39:40.689 5646-5646/org.linphone.debug I/Linphone: CallSession[0x83e4748c] : payload type 97 H264/90000 fmtp=profile-level-id=42801F added to frozen list 2019-10-15 13:39:40.689 5646-5646/org.linphone.debug I/Linphone: CallSession[0x83e4748c] : payload type 96 VP8/90000 fmtp= added to frozen list 2019-10-15 13:39:40.690 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: ending recv background task with id=[100b2e]. 2019-10-15 13:39:40.691 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x100b2e] 2019-10-15 13:39:40.691 5646-5646/org.linphone.debug I/Linphone: Garbage collecting unowned object of type belle_sdp_session_description_t 2019-10-15 13:39:40.695 5646-6419/org.linphone.debug E/Linphone: MediaCodecEncoder: no input buffer available. 2019-10-15 13:39:40.695 5646-6419/org.linphone.debug I/Linphone: Stun packet sent for session [0x86e04c00] 2019-10-15 13:39:40.695 5646-6419/org.linphone.debug I/Linphone: Stun packet sent on rtcp for session [0x86e04c00] 2019-10-15 13:39:40.696 5646-6419/org.linphone.debug I/Linphone: First estimation 2019-10-15 13:39:40.697 5646-6403/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-15 13:39:40.708 5646-6403/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-15 13:39:40.718 5646-6403/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-15 13:39:40.727 5646-6403/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-15 13:39:40.737 5646-6403/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-15 13:39:40.747 5646-6403/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-15 13:39:40.757 5646-6403/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-15 13:39:40.768 5646-6403/org.linphone.debug W/Linphone: Getting reference signal but no echo to synchronize on. 2019-10-15 13:39:40.768 5646-6403/org.linphone.debug W/Linphone: Not enough ref samples, using zeroes 2019-10-15 13:39:40.779 5646-5646/org.linphone.debug I/Linphone: First estimation 2019-10-15 13:39:40.779 5646-5646/org.linphone.debug I/Linphone: First estimation 2019-10-15 13:39:40.779 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=54.18,u=0.00], video=[d=5.04,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-10-15 13:39:40.779 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=5.65094 video=1.02884 text=0 2019-10-15 13:39:40.815 5646-6403/org.linphone.debug I/Linphone: Samples are back. 2019-10-15 13:39:41.124 5646-6403/org.linphone.debug I/Linphone: Stun packet sent on rtcp for session [0x86e03e00] 2019-10-15 13:39:41.195 5646-6419/org.linphone.debug I/Linphone: Stun packet sent on rtcp for session [0x86e04c00] 2019-10-15 13:39:41.358 5646-5713/org.linphone.debug I/[Gralloc]: Got handle 2 for fd 81 2019-10-15 13:39:41.358 5646-5713/org.linphone.debug I/[Gralloc]: leave, w : 800, h : 1280, format : 0x1,internal_format : 0x1, usage : 0xb00. size=4096000,pixel_stride=800,byte_stride=3200 2019-10-15 13:39:41.358 5646-5713/org.linphone.debug I/[Gralloc]: leave: prime_fd=81,share_attr_fd=87 2019-10-15 13:39:41.624 5646-6403/org.linphone.debug I/Linphone: MSAudioMixer [0x8c767d78] is entering bypass mode. 2019-10-15 13:39:41.625 5646-6403/org.linphone.debug I/Linphone: Stun packet sent on rtcp for session [0x86e03e00] 2019-10-15 13:39:41.694 5646-6419/org.linphone.debug I/Linphone: Stun packet sent on rtcp for session [0x86e04c00] 2019-10-15 13:39:41.709 5646-6419/org.linphone.debug I/Linphone: MediaCodecEncoder: output format has changed. 2019-10-15 13:39:41.711 5646-6419/org.linphone.debug I/Linphone: MediaCodecEncoder: new output format: {csd-1=java.nio.HeapByteBuffer[pos=0 lim=8 cap=8], mime=video/avc, width=640, height=480, bitrate=360000, csd-0=java.nio.HeapByteBuffer[pos=0 lim=27 cap=27], max-bitrate=360000} 2019-10-15 13:39:41.770 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=77.17,u=55.35], video=[d=0.03,u=0.74,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.86], video=[d=0.00,u=1.11], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:39:41.770 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=13.9875 video=6.2975 text=0 2019-10-15 13:39:42.125 5646-6403/org.linphone.debug I/Linphone: Stun packet sent on rtcp for session [0x86e03e00] 2019-10-15 13:39:42.194 5646-6419/org.linphone.debug I/Linphone: Stun packet sent on rtcp for session [0x86e04c00] 2019-10-15 13:39:42.244 5646-6403/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e03e00]. 2019-10-15 13:39:42.257 5646-5646/org.linphone.debug I/Linphone: MSAudio_stream_iterate[0x86694b80], local statistics available: Local current jitter buffer size: 58.1ms 2019-10-15 13:39:42.257 5646-5646/org.linphone.debug I/Linphone: MSAudio stream [0x86694b80]: receiving RTCP RR 2019-10-15 13:39:42.258 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:42.258 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:42.381 5646-5713/org.linphone.debug I/[Gralloc]: Got handle 3 for fd 93 2019-10-15 13:39:42.381 5646-5713/org.linphone.debug I/[Gralloc]: leave, w : 800, h : 1280, format : 0x1,internal_format : 0x1, usage : 0xb00. size=4096000,pixel_stride=800,byte_stride=3200 2019-10-15 13:39:42.381 5646-5713/org.linphone.debug I/[Gralloc]: leave: prime_fd=93,share_attr_fd=94 2019-10-15 13:39:42.674 5646-6419/org.linphone.debug I/Linphone: Stun packet sent for session [0x86e04c00] 2019-10-15 13:39:42.694 5646-6419/org.linphone.debug I/Linphone: Stun packet sent on rtcp for session [0x86e04c00] 2019-10-15 13:39:42.702 5646-5646/org.linphone.debug W/Linphone: Measured and target fps significantly different (1.000000<->15.000000), updating encoder. 2019-10-15 13:39:42.702 5646-5646/org.linphone.debug W/Linphone: H26xEncoderFilter: ignoring video size change because the encoder is started 2019-10-15 13:39:42.703 5646-5646/org.linphone.debug I/Linphone: AMediaCodec_setParams() frame-rate key transfered to Bundle with value 1 2019-10-15 13:39:42.704 5646-5646/org.linphone.debug I/Linphone: AMediaCodec_setParams() video-bitrate key transfered to Bundle with value 360000 2019-10-15 13:39:42.705 5646-5646/org.linphone.debug I/Linphone: H26xEncoder: video configuration set (bitrate=400000b/s, fps=15.000000, vsize=640x480) 2019-10-15 13:39:42.783 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=72.29,u=79.96], video=[d=2.58,u=0.38,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.18,u=0.77], video=[d=0.00,u=0.77], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:39:42.783 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=11.3581 video=4.50807 text=0 2019-10-15 13:39:43.194 5646-6419/org.linphone.debug I/Linphone: Stun packet sent on rtcp for session [0x86e04c00] 2019-10-15 13:39:43.388 5646-5646/org.linphone.debug I/Linphone: Changing [client] [INVITE] transaction [0x83b35610], from state [COMPLETED] to [TERMINATED] 2019-10-15 13:39:43.388 5646-5646/org.linphone.debug I/Linphone: Client INVITE transaction [0x83b35610] terminated 2019-10-15 13:39:43.388 5646-5646/org.linphone.debug I/Linphone: transaction [0x83b35610]: ending transaction background task with id=[200a92]. 2019-10-15 13:39:43.390 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x200a92] 2019-10-15 13:39:43.694 5646-6419/org.linphone.debug I/Linphone: Stun packet sent on rtcp for session [0x86e04c00] 2019-10-15 13:39:43.725 5646-6419/org.linphone.debug I/Linphone: H26xEncoder: requesting I-frame to the encoder. 2019-10-15 13:39:43.726 5646-6419/org.linphone.debug I/Linphone: AMediaCodec_setParams() request-sync key transfered to Bundle with value 0 2019-10-15 13:39:43.728 5646-6419/org.linphone.debug E/Linphone: MediaCodecEncoder: I-frame requested to MediaCodec 2019-10-15 13:39:43.776 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=75.12,u=80.04], video=[d=0.02,u=10.89,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.50], video=[d=0.00,u=0.77], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:39:43.777 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=15.9833 video=8.72729 text=0 2019-10-15 13:39:43.918 5646-5646/org.linphone.debug I/Linphone: Changing [client] [INVITE] transaction [0x83c37510], from state [ACCEPTED] to [TERMINATED] 2019-10-15 13:39:43.919 5646-5646/org.linphone.debug I/Linphone: Client INVITE transaction [0x83c37510] terminated 2019-10-15 13:39:43.920 5646-5646/org.linphone.debug I/Linphone: Destroying op [0x8e0ee700] of type [SalOpCall] 2019-10-15 13:39:43.921 5646-5646/org.linphone.debug I/Linphone: Transaction [0x83b35610] deleted 2019-10-15 13:39:43.922 5646-5646/org.linphone.debug I/Linphone: Transaction [0x868a34a0] deleted 2019-10-15 13:39:43.923 5646-5646/org.linphone.debug I/Linphone: transaction [0x83c37510]: ending transaction background task with id=[a06]. 2019-10-15 13:39:43.927 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0xa06] 2019-10-15 13:39:43.929 5646-5646/org.linphone.debug I/Linphone: Transaction [0x83c37510] deleted 2019-10-15 13:39:44.020 5646-5646/org.linphone.debug I/Linphone: MSVideo stream [0x867fc000]: receiving RTCP RR 2019-10-15 13:39:44.022 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:44.064 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x200aa2] 2019-10-15 13:39:44.064 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 2099874 2019-10-15 13:39:44.065 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: starting recv background task with id=[200aa2]. 2019-10-15 13:39:44.066 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: received [903] new bytes from [UDP://192.168.1.181:5060]: 2019-10-15 13:39:44.066 5646-5646/org.linphone.debug I/Linphone: SIP/2.0 200 OK 2019-10-15 13:39:44.066 5646-5646/org.linphone.debug I/Linphone: Via: SIP/2.0/UDP 192.168.1.142:57541;rport=57541;received=192.168.1.142;branch=z9hG4bK.PUGogITKS 2019-10-15 13:39:44.066 5646-5646/org.linphone.debug I/Linphone: Call-ID: 9LgXwhZBRu 2019-10-15 13:39:44.066 5646-5646/org.linphone.debug I/Linphone: From: sip:0131@192.168.1.181;tag=orcivYktT 2019-10-15 13:39:44.066 5646-5646/org.linphone.debug I/Linphone: To: sip:0130@192.168.1.181;tag=aea6f729-573f-4c1e-9648-17b5a99ad2d1 2019-10-15 13:39:44.066 5646-5646/org.linphone.debug I/Linphone: CSeq: 21 INVITE 2019-10-15 13:39:44.066 5646-5646/org.linphone.debug I/Linphone: Server: IPPBX 2019-10-15 13:39:44.067 5646-5646/org.linphone.debug I/Linphone: Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, MESSAGE, REFER 2019-10-15 13:39:44.067 5646-5646/org.linphone.debug I/Linphone: Contact: 2019-10-15 13:39:44.067 5646-5646/org.linphone.debug I/Linphone: Supported: 100rel, timer, replaces, norefersub 2019-10-15 13:39:44.067 5646-5646/org.linphone.debug I/Linphone: Content-Type: application/sdp 2019-10-15 13:39:44.067 5646-5646/org.linphone.debug I/Linphone: Content-Length: 369 2019-10-15 13:39:44.067 5646-5646/org.linphone.debug I/Linphone: v=0 2019-10-15 13:39:44.067 5646-5646/org.linphone.debug I/Linphone: o=- 2299 1167 IN IP4 192.168.1.181 2019-10-15 13:39:44.067 5646-5646/org.linphone.debug I/Linphone: s=IPPBX 2019-10-15 13:39:44.067 5646-5646/org.linphone.debug I/Linphone: c=IN IP4 192.168.1.181 2019-10-15 13:39:44.067 5646-5646/org.linphone.debug I/Linphone: t=0 0 2019-10-15 13:39:44.067 5646-5646/org.linphone.debug I/Linphone: m=audio 10096 RTP/AVP 8 0 100 2019-10-15 13:39:44.068 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:8 PCMA/8000 2019-10-15 13:39:44.068 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:0 PCMU/8000 2019-10-15 13:39:44.068 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:100 telephone-event/8000 2019-10-15 13:39:44.068 5646-5646/org.linphone.debug I/Linphone: a=fmtp:100 0-16 2019-10-15 13:39:44.068 5646-5646/org.linphone.debug I/Linphone: a=ptime:20 2019-10-15 13:39:44.068 5646-5646/org.linphone.debug I/Linphone: a=maxptime:150 2019-10-15 13:39:44.068 5646-5646/org.linphone.debug I/Linphone: a=sendrecv 2019-10-15 13:39:44.068 5646-5646/org.linphone.debug I/Linphone: a=rtcp-mux 2019-10-15 13:39:44.068 5646-5646/org.linphone.debug I/Linphone: m=video 10004 RTP/AVP 97 2019-10-15 13:39:44.068 5646-5646/org.linphone.debug I/Linphone: a=rtpmap:97 H264/90000 2019-10-15 13:39:44.068 5646-5646/org.linphone.debug I/Linphone: a=fmtp:97 profile-level-id=42801F 2019-10-15 13:39:44.068 5646-5646/org.linphone.debug I/Linphone: a=sendrecv 2019-10-15 13:39:44.068 5646-5646/org.linphone.debug I/Linphone: a=rtcp-mux 2019-10-15 13:39:44.088 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200] [534] bytes parsed 2019-10-15 13:39:44.088 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200] read [369] bytes of body from [192.168.1.181:5060] 2019-10-15 13:39:44.088 5646-5646/org.linphone.debug I/Linphone: Found transaction matching response. 2019-10-15 13:39:44.088 5646-5646/org.linphone.debug I/Linphone: Changing [client] [INVITE] transaction [0x83b39cf0], from state [PROCEEDING] to [ACCEPTED] 2019-10-15 13:39:44.088 5646-5646/org.linphone.debug I/Linphone: Dialog [0x8675d040]: now updated by transaction [0x83b39cf0]. 2019-10-15 13:39:44.089 5646-5646/org.linphone.debug I/Linphone: op [0x8e0ef6c0] : set_or_update_dialog() current=[0x8675d040] new=[0x8675d040] 2019-10-15 13:39:44.089 5646-5646/org.linphone.debug I/Linphone: Op [0x8e0ef6c0] receiving call response [200], dialog is [0x8675d040] in state [BELLE_SIP_DIALOG_CONFIRMED] 2019-10-15 13:39:44.092 5646-5646/org.linphone.debug I/Linphone: Found payload PCMA/8000 fmtp= 2019-10-15 13:39:44.092 5646-5646/org.linphone.debug I/Linphone: Found payload PCMU/8000 fmtp= 2019-10-15 13:39:44.092 5646-5646/org.linphone.debug I/Linphone: Found payload telephone-event/8000 fmtp=0-16 2019-10-15 13:39:44.092 5646-5646/org.linphone.debug I/Linphone: Found payload H264/90000 fmtp=profile-level-id=42801F 2019-10-15 13:39:44.092 5646-5646/org.linphone.debug I/Linphone: Doing SDP offer/answer process of type outgoing 2019-10-15 13:39:44.093 5646-5646/org.linphone.debug I/Linphone: Processing for stream 0 2019-10-15 13:39:44.093 5646-5646/org.linphone.debug I/Linphone: Adding opus/48000 for compatibility, just in case. 2019-10-15 13:39:44.093 5646-5646/org.linphone.debug I/Linphone: Adding speex/16000 for compatibility, just in case. 2019-10-15 13:39:44.093 5646-5646/org.linphone.debug I/Linphone: Adding speex/8000 for compatibility, just in case. 2019-10-15 13:39:44.093 5646-5646/org.linphone.debug I/Linphone: Adding telephone-event/48000 for compatibility, just in case. 2019-10-15 13:39:44.093 5646-5646/org.linphone.debug I/Linphone: Adding telephone-event/16000 for compatibility, just in case. 2019-10-15 13:39:44.093 5646-5646/org.linphone.debug I/Linphone: Processing for stream 1 2019-10-15 13:39:44.093 5646-5646/org.linphone.debug I/Linphone: Doing offer/answer processing with specific provider for codec [H264] 2019-10-15 13:39:44.093 5646-5646/org.linphone.debug I/Linphone: Adding VP8/90000 for compatibility, just in case. 2019-10-15 13:39:44.093 5646-5646/org.linphone.debug I/Linphone: CallSession [0x83e4748c] moving from state State::OutgoingEarlyMedia to State::Connected 2019-10-15 13:39:44.094 5646-5646/org.linphone.debug I/Linphone: [Manager] New call state [Connected] 2019-10-15 13:39:44.094 5646-5646/org.linphone.debug W/Linphone: [Audio Manager] Routing audio to speaker 2019-10-15 13:39:44.095 5646-5646/org.linphone.debug I/Linphone: [Audio Manager] Registering headset receiver 2019-10-15 13:39:44.098 5646-5646/org.linphone.debug I/Linphone: [Notifications Manager] Call notification notifiable is Id: 15, local identity: null, myself: null, isGrouped: false, pending intent class org.linphone.call.CallActivity 2019-10-15 13:39:44.099 5646-5646/org.linphone.debug W/Linphone: [Contacts Manager] Couldn't find friend... 2019-10-15 13:39:44.101 5646-5646/org.linphone.debug I/Linphone: [Notifications Manager] Notifying 15 2019-10-15 13:39:44.114 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_state_changed] 2019-10-15 13:39:44.115 5646-5646/org.linphone.debug I/Linphone: Early media finished, unmuting audio input... 2019-10-15 13:39:44.119 5646-5646/org.linphone.debug I/Linphone: This device (rockchip/rk3288/rk3288) says it has no built-in echo canceller. 2019-10-15 13:39:44.119 5646-5646/org.linphone.debug I/Linphone: No information available for [rockchip/rk3288/rk3288], 2019-10-15 13:39:44.119 5646-5646/org.linphone.debug I/Linphone: Sound device information for [rockchip/rk3288/rk3288] is: builtin=[no], delay=[250] ms 2019-10-15 13:39:44.119 5646-5646/org.linphone.debug I/Linphone: MSVolume set gain to [0.000000 db], [1.000000] linear 2019-10-15 13:39:44.120 5646-5646/org.linphone.debug I/Linphone: No need to restart the audio stream, SDP is unchanged 2019-10-15 13:39:44.120 5646-5646/org.linphone.debug I/Linphone: Early media finished, unmuting video input... 2019-10-15 13:39:44.120 5646-5646/org.linphone.debug I/Linphone: Switching video cam from [Static picture] to [Android video name] on CallSession [0x83e4748c] 2019-10-15 13:39:44.142 5646-5646/org.linphone.debug I/MediaCodec: callingProcessName:org.linphone.debug 2019-10-15 13:39:44.143 5646-6409/org.linphone.debug I/OMXClient: MuxOMX ctor 2019-10-15 13:39:44.143 5646-6409/org.linphone.debug I/ACodec: onAllocateComponent:6752 mSoftCodecPref:0 componentName: 2019-10-15 13:39:44.144 5646-6409/org.linphone.debug I/ACodec: onAllocateComponent:6783 mSoftCodecPref:0 componentName:OMX.rk.video_encoder.avc 2019-10-15 13:39:44.148 5646-5646/org.linphone.debug I/Linphone: ms_filter_unlink: MSStaticImage:0x8c767f70,0-->MSPixConv:0x8c7681a0,0 2019-10-15 13:39:44.148 5646-6418/org.linphone.debug I/Linphone: [TextureView Display] Destroying context 2019-10-15 13:39:44.148 5646-6418/org.linphone.debug I/Linphone: [TextureView Display] Context destroyed 2019-10-15 13:39:44.148 5646-5646/org.linphone.debug I/Linphone: ms_filter_unlink: MSPixConv:0x8c7681a0,0-->MSTee:0x8c7680c0,0 2019-10-15 13:39:44.148 5646-5646/org.linphone.debug I/Linphone: ms_filter_unlink: MSTee:0x8c7680c0,0-->MSSizeConv:0x8c7681d8,0 2019-10-15 13:39:44.149 5646-5646/org.linphone.debug I/Linphone: ms_filter_unlink: MSSizeConv:0x8c7681d8,0-->MSMediaCodecH264Encoder:0x8c767fa8,0 2019-10-15 13:39:44.149 5646-5646/org.linphone.debug I/Linphone: Instanciating Android VIDEO capture MS filter 2019-10-15 13:39:44.149 5646-5646/org.linphone.debug I/Linphone: Creating AndroidReaderContext for Android VIDEO capture filter 2019-10-15 13:39:44.149 5646-5646/org.linphone.debug I/Linphone: Init of Android VIDEO capture filter (0x8c06d900) 2019-10-15 13:39:44.149 5646-5646/org.linphone.debug I/Linphone: getHelperClassGlobalRef (env: 0xa5923230) 2019-10-15 13:39:44.149 5646-5646/org.linphone.debug I/Linphone: Android SDK version found is 25 2019-10-15 13:39:44.149 5646-5646/org.linphone.debug I/Linphone: Using the default video configuration list 2019-10-15 13:39:44.149 5646-5646/org.linphone.debug I/Linphone: target and payload bitrates not set for stream [0x867fc000] using lowest configuration of preferred video size 640x480 2019-10-15 13:39:44.149 5646-5646/org.linphone.debug I/Linphone: Limiting bitrate of video encoder to 0 bits/s for stream [0x867fc000] 2019-10-15 13:39:44.150 5646-5646/org.linphone.debug I/Linphone: H26xEncoder: video configuration set (bitrate=400000b/s, fps=1.000000, vsize=640x480) 2019-10-15 13:39:44.150 5646-5646/org.linphone.debug I/Linphone: video_set_device_rotation : 0 2019-10-15 13:39:44.150 5646-5646/org.linphone.debug I/Linphone: mediastreamer8 supported resolutions : 2019-10-15 13:39:44.150 5646-5646/org.linphone.debug I/Linphone: mediastreamer 1920x1080 2019-10-15 13:39:44.150 5646-5646/org.linphone.debug I/Linphone: mediastreamer 1280x720 2019-10-15 13:39:44.150 5646-5646/org.linphone.debug I/Linphone: mediastreamer 800x600 2019-10-15 13:39:44.150 5646-5646/org.linphone.debug I/Linphone: mediastreamer 640x480 2019-10-15 13:39:44.150 5646-5646/org.linphone.debug I/Linphone: mediastreamer 640x360 2019-10-15 13:39:44.150 5646-5646/org.linphone.debug I/Linphone: mediastreamer 320x240 2019-10-15 13:39:44.151 5646-5646/org.linphone.debug I/Linphone: mediastreamer 240x180 2019-10-15 13:39:44.151 5646-5646/org.linphone.debug I/Linphone: mediastreamer 1920x1080 2019-10-15 13:39:44.151 5646-5646/org.linphone.debug I/Linphone: mediastreamerresolution selection done (640, 480, 0) 2019-10-15 13:39:44.151 5646-5646/org.linphone.debug I/Linphone: Camera selected resolution is: 640x480 (requested: 640x480) with downscaling?0 2019-10-15 13:39:44.151 5646-5646/org.linphone.debug I/Linphone: Camera video preview size changed on videostream [0x867fc000] 2019-10-15 13:39:44.151 5646-5646/org.linphone.debug I/Linphone: Output video size adjusted to match camera resolution (640x480) 2019-10-15 13:39:44.151 5646-5646/org.linphone.debug I/Linphone: Setting sent vsize=640x480, fps=15.000000 2019-10-15 13:39:44.152 5646-5646/org.linphone.debug I/Linphone: H26xEncoder: video configuration set (bitrate=400000b/s, fps=15.000000, vsize=640x480) 2019-10-15 13:39:44.152 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSAndroidVideoCapture:0x8c7681d8,0-->MSPixConv:0x8c7681a0,0 2019-10-15 13:39:44.152 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSPixConv:0x8c7681a0,0-->MSTee:0x8c7680c0,0 2019-10-15 13:39:44.152 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSTee:0x8c7680c0,0-->MSSizeConv:0x8c767f70,0 2019-10-15 13:39:44.152 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSSizeConv:0x8c767f70,0-->MSMediaCodecH264Encoder:0x8c767fa8,0 2019-10-15 13:39:44.152 5646-5646/org.linphone.debug I/Linphone: Preprocessing of Android VIDEO capture filter 2019-10-15 13:39:44.152 5646-5646/org.linphone.debug I/Linphone: Starting Android camera '1' (rotation:0) 2019-10-15 13:39:44.152 5646-5646/org.linphone.debug I/Linphone: startRecording(1, 640, 480, 30, 0, 2349259008) 2019-10-15 13:39:44.467 5646-5646/org.linphone.debug W/Linphone: Camera preview orientation: 0 2019-10-15 13:39:44.468 5646-5646/org.linphone.debug I/Linphone: Preprocessing of Android VIDEO capture filter done 2019-10-15 13:39:44.469 5646-5646/org.linphone.debug I/Linphone: MediaCodecEncoder: configuring MediaCodec with the following parameters: {mime=video/avc, frame-rate=15, latency=1, color-format=2135033992, profile=1, height=480, width=640, bitrate=360000, priority=0, i-frame-interval=20, level=512, bitrate-mode=1} 2019-10-15 13:39:44.473 5646-6409/org.linphone.debug I/ACodec: [OMX.rk.video_encoder.avc] using color format 0x13 in place of 0x7f420888 2019-10-15 13:39:44.484 5646-6409/org.linphone.debug I/ACodec: setupAVCEncoderParameters with [profile: Baseline] [level: Level31] 2019-10-15 13:39:44.486 5646-6409/org.linphone.debug I/ACodec: [OMX.rk.video_encoder.avc] cannot encode color aspects. Ignoring. 2019-10-15 13:39:44.487 5646-6409/org.linphone.debug I/ACodec: [OMX.rk.video_encoder.avc] cannot encode HDR static metadata. Ignoring. 2019-10-15 13:39:44.487 5646-6409/org.linphone.debug I/ACodec: setupVideoEncoder succeeded 2019-10-15 13:39:44.489 5646-6409/org.linphone.debug I/ACodec: codec does not support config priority (err -1010) 2019-10-15 13:39:44.498 5646-5646/org.linphone.debug I/Linphone: MediaCodecEncoder: encoder successfully configured. In-force parameters: {height=480, width=640, bitrate=360000, mime=video/avc, max-bitrate=360000} 2019-10-15 13:39:44.516 5646-5646/org.linphone.debug I/Linphone: MediaCodecEncoder: encoder successfully started 2019-10-15 13:39:44.516 5646-5646/org.linphone.debug I/Linphone: Video ITC source updated. 2019-10-15 13:39:44.517 5646-5646/org.linphone.debug I/Linphone: Configuring av recorder with video format type=video;encoding=H264;vsize=0x0;fps=0.000000;fmtp='' 2019-10-15 13:39:44.517 5646-5646/org.linphone.debug I/Linphone: MKVRecorder: set pin #0 format. type=video;encoding=H264;vsize=0x0;fps=0.000000;fmtp='' 2019-10-15 13:39:44.518 5646-5646/org.linphone.debug I/Linphone: No need to restart the video stream, SDP is unchanged 2019-10-15 13:39:44.518 5646-5646/org.linphone.debug I/Linphone: audio stream index found: 0, updating main audio stream index 2019-10-15 13:39:44.518 5646-5646/org.linphone.debug I/Linphone: video stream index found: 1, updating main video stream index 2019-10-15 13:39:44.518 5646-5646/org.linphone.debug I/Linphone: CallSession [0x83e4748c] moving from state State::Connected to State::StreamsRunning 2019-10-15 13:39:44.519 5646-5646/org.linphone.debug I/Linphone: [Manager] New call state [StreamsRunning] 2019-10-15 13:39:44.521 5646-5646/org.linphone.debug W/Linphone: [Audio Manager] already in MODE_IN_COMMUNICATION, skipping... 2019-10-15 13:39:44.530 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_state_changed] 2019-10-15 13:39:44.530 5646-5646/org.linphone.debug I/Linphone: Computing branch id z9hG4bK.WaPX9sGZp for message sent statelessly 2019-10-15 13:39:44.531 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: message sent to [UDP://192.168.1.181:5060], size: [639] bytes 2019-10-15 13:39:44.531 5646-5646/org.linphone.debug I/Linphone: ACK sip:192.168.1.181:5060 SIP/2.0 2019-10-15 13:39:44.531 5646-5646/org.linphone.debug I/Linphone: Via: SIP/2.0/UDP 192.168.1.142:57541;rport;branch=z9hG4bK.WaPX9sGZp 2019-10-15 13:39:44.531 5646-5646/org.linphone.debug I/Linphone: From: sip:0131@192.168.1.181;tag=orcivYktT 2019-10-15 13:39:44.531 5646-5646/org.linphone.debug I/Linphone: To: sip:0130@192.168.1.181;tag=aea6f729-573f-4c1e-9648-17b5a99ad2d1 2019-10-15 13:39:44.531 5646-5646/org.linphone.debug I/Linphone: CSeq: 21 ACK 2019-10-15 13:39:44.531 5646-5646/org.linphone.debug I/Linphone: Call-ID: 9LgXwhZBRu 2019-10-15 13:39:44.531 5646-5646/org.linphone.debug I/Linphone: Max-Forwards: 70 2019-10-15 13:39:44.531 5646-5646/org.linphone.debug I/Linphone: Authorization: Digest realm="IPPBX", nonce="1571117855/d12f6ddcd42d1d5fe79a5597caaac930", algorithm=md5, opaque="66815f8e206cafee", username="0131", uri="sip:0130@192.168.1.181", response="bf77196d939d281e1ae35583e6b965fa", cnonce="j7lA0mvkv0goYtay", nc=00000001, qop=auth 2019-10-15 13:39:44.531 5646-5646/org.linphone.debug I/Linphone: User-Agent: LinphoneAndroid/4.1 (rk3288) LinphoneSDK/4.3.0-alpha.12+89393ba (master) 2019-10-15 13:39:44.531 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: ending recv background task with id=[200aa2]. 2019-10-15 13:39:44.532 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x200aa2] 2019-10-15 13:39:44.532 5646-5646/org.linphone.debug I/Linphone: Garbage collecting unowned object of type belle_sip_hop_t 2019-10-15 13:39:44.532 5646-5646/org.linphone.debug I/Linphone: Garbage collecting unowned object of type belle_sdp_session_description_t 2019-10-15 13:39:44.533 5646-5646/org.linphone.debug I/Linphone: Camera video preview size changed: 640x480 2019-10-15 13:39:44.533 5646-5646/org.linphone.debug I/Linphone: Resizing camera video preview to: 640x480 2019-10-15 13:39:44.534 5646-5646/org.linphone.debug I/Linphone: Callbacks [0x86b55aa0] unregistered on core [0xa4a36100] 2019-10-15 13:39:44.534 5646-5646/org.linphone.debug I/Linphone: Callbacks [0x863e1058] unregistered on core [0xa4a36100] 2019-10-15 13:39:44.534 5646-5646/org.linphone.debug I/Linphone: [Activity Monitor] Activity paused:org.linphone.call.CallOutgoingActivity@7640bd1 2019-10-15 13:39:44.534 5646-5646/org.linphone.debug I/Linphone: [Activity Monitor] runningActivities=0 2019-10-15 13:39:44.553 5646-5646/org.linphone.debug I/Linphone: [Activity Monitor] Activity created:org.linphone.call.CallActivity@618441e 2019-10-15 13:39:44.651 5646-5646/org.linphone.debug I/Linphone: Activity started:org.linphone.call.CallActivity@618441e 2019-10-15 13:39:44.652 5646-5646/org.linphone.debug I/Linphone: Core callbacks [0x863e3948] registered on core [0xa4a36100] 2019-10-15 13:39:44.654 5646-5646/org.linphone.debug I/Linphone: [Activity Monitor] Activity resumed:org.linphone.call.CallActivity@618441e 2019-10-15 13:39:44.654 5646-5646/org.linphone.debug I/Linphone: [Activity Monitor] runningActivities=1 2019-10-15 13:39:44.655 5646-5646/org.linphone.debug I/Linphone: [Generic Activity] Device orientation is 0 (raw value is 0) 2019-10-15 13:39:44.655 5646-5646/org.linphone.debug I/Linphone: Postprocessing of Android VIDEO capture filter 2019-10-15 13:39:44.679 5646-5646/org.linphone.debug I/MediaCodec: callingProcessName:org.linphone.debug 2019-10-15 13:39:44.680 5646-6409/org.linphone.debug I/OMXClient: MuxOMX ctor 2019-10-15 13:39:44.680 5646-6409/org.linphone.debug I/ACodec: onAllocateComponent:6752 mSoftCodecPref:0 componentName: 2019-10-15 13:39:44.681 5646-6409/org.linphone.debug I/ACodec: onAllocateComponent:6783 mSoftCodecPref:0 componentName:OMX.rk.video_encoder.avc 2019-10-15 13:39:44.683 5646-6418/org.linphone.debug I/Linphone: [TextureView Display] Destroying context 2019-10-15 13:39:44.683 5646-5646/org.linphone.debug I/Linphone: ms_filter_unlink: MSAndroidVideoCapture:0x8c7681d8,0-->MSPixConv:0x8c7681a0,0 2019-10-15 13:39:44.683 5646-5646/org.linphone.debug I/Linphone: ms_filter_unlink: MSPixConv:0x8c7681a0,0-->MSTee:0x8c7680c0,0 2019-10-15 13:39:44.683 5646-6418/org.linphone.debug I/Linphone: [TextureView Display] Context destroyed 2019-10-15 13:39:44.683 5646-5646/org.linphone.debug I/Linphone: ms_filter_unlink: MSTee:0x8c7680c0,0-->MSSizeConv:0x8c767f70,0 2019-10-15 13:39:44.683 5646-5646/org.linphone.debug I/Linphone: ms_filter_unlink: MSSizeConv:0x8c767f70,0-->MSMediaCodecH264Encoder:0x8c767fa8,0 2019-10-15 13:39:44.684 5646-5646/org.linphone.debug I/Linphone: Using the default video configuration list 2019-10-15 13:39:44.684 5646-5646/org.linphone.debug I/Linphone: video_set_device_rotation : 0 2019-10-15 13:39:44.684 5646-5646/org.linphone.debug I/Linphone: mediastreamer8 supported resolutions : 2019-10-15 13:39:44.684 5646-5646/org.linphone.debug I/Linphone: mediastreamer 1920x1080 2019-10-15 13:39:44.684 5646-5646/org.linphone.debug I/Linphone: mediastreamer 1280x720 2019-10-15 13:39:44.684 5646-5646/org.linphone.debug I/Linphone: mediastreamer 800x600 2019-10-15 13:39:44.684 5646-5646/org.linphone.debug I/Linphone: mediastreamer 640x480 2019-10-15 13:39:44.684 5646-5646/org.linphone.debug I/Linphone: mediastreamer 640x360 2019-10-15 13:39:44.685 5646-5646/org.linphone.debug I/Linphone: mediastreamer 320x240 2019-10-15 13:39:44.685 5646-5646/org.linphone.debug I/Linphone: mediastreamer 240x180 2019-10-15 13:39:44.685 5646-5646/org.linphone.debug I/Linphone: mediastreamer 1920x1080 2019-10-15 13:39:44.685 5646-5646/org.linphone.debug I/Linphone: mediastreamerresolution selection done (640, 480, 0) 2019-10-15 13:39:44.685 5646-5646/org.linphone.debug I/Linphone: Camera selected resolution is: 640x480 (requested: 640x480) with downscaling?0 2019-10-15 13:39:44.685 5646-5646/org.linphone.debug I/Linphone: Camera video preview size changed on videostream [0x867fc000] 2019-10-15 13:39:44.685 5646-5646/org.linphone.debug I/Linphone: Camera video preview size changed on videostream [0x867fc000] 2019-10-15 13:39:44.685 5646-5646/org.linphone.debug I/Linphone: Output video size adjusted to match camera resolution (640x480) 2019-10-15 13:39:44.685 5646-5646/org.linphone.debug I/Linphone: Setting sent vsize=640x480, fps=15.000000 2019-10-15 13:39:44.686 5646-5646/org.linphone.debug I/Linphone: H26xEncoder: video configuration set (bitrate=400000b/s, fps=15.000000, vsize=640x480) 2019-10-15 13:39:44.686 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSAndroidVideoCapture:0x8c7681d8,0-->MSPixConv:0x8c767f70,0 2019-10-15 13:39:44.686 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSPixConv:0x8c767f70,0-->MSTee:0x8c7680c0,0 2019-10-15 13:39:44.686 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSTee:0x8c7680c0,0-->MSSizeConv:0x8c7681a0,0 2019-10-15 13:39:44.686 5646-5646/org.linphone.debug I/Linphone: ms_filter_link: MSSizeConv:0x8c7681a0,0-->MSMediaCodecH264Encoder:0x8c767fa8,0 2019-10-15 13:39:44.686 5646-5646/org.linphone.debug I/Linphone: Preprocessing of Android VIDEO capture filter 2019-10-15 13:39:44.686 5646-5646/org.linphone.debug I/Linphone: Starting Android camera '1' (rotation:0) 2019-10-15 13:39:44.686 5646-5646/org.linphone.debug I/Linphone: startRecording(1, 640, 480, 30, 0, 2349259008) 2019-10-15 13:39:44.786 5646-5646/org.linphone.debug W/Linphone: Camera preview orientation: 0 2019-10-15 13:39:44.786 5646-5646/org.linphone.debug I/Linphone: Preprocessing of Android VIDEO capture filter done 2019-10-15 13:39:44.787 5646-5646/org.linphone.debug I/Linphone: MediaCodecEncoder: configuring MediaCodec with the following parameters: {mime=video/avc, frame-rate=15, latency=1, color-format=2135033992, profile=1, height=480, width=640, bitrate=360000, priority=0, i-frame-interval=20, level=512, bitrate-mode=1} 2019-10-15 13:39:44.788 5646-6409/org.linphone.debug I/ACodec: [OMX.rk.video_encoder.avc] using color format 0x13 in place of 0x7f420888 2019-10-15 13:39:44.791 5646-6409/org.linphone.debug I/ACodec: setupAVCEncoderParameters with [profile: Baseline] [level: Level31] 2019-10-15 13:39:44.792 5646-6409/org.linphone.debug I/ACodec: [OMX.rk.video_encoder.avc] cannot encode color aspects. Ignoring. 2019-10-15 13:39:44.792 5646-6409/org.linphone.debug I/ACodec: [OMX.rk.video_encoder.avc] cannot encode HDR static metadata. Ignoring. 2019-10-15 13:39:44.792 5646-6409/org.linphone.debug I/ACodec: setupVideoEncoder succeeded 2019-10-15 13:39:44.792 5646-6409/org.linphone.debug I/ACodec: codec does not support config priority (err -1010) 2019-10-15 13:39:44.794 5646-5646/org.linphone.debug I/Linphone: MediaCodecEncoder: encoder successfully configured. In-force parameters: {height=480, width=640, bitrate=360000, mime=video/avc, max-bitrate=360000} 2019-10-15 13:39:44.805 5646-5646/org.linphone.debug I/Linphone: MediaCodecEncoder: encoder successfully started 2019-10-15 13:39:44.805 5646-5646/org.linphone.debug I/Linphone: Video ITC source updated. 2019-10-15 13:39:44.805 5646-5646/org.linphone.debug I/Linphone: Configuring av recorder with video format type=video;encoding=H264;vsize=0x0;fps=0.000000;fmtp='' 2019-10-15 13:39:44.805 5646-5646/org.linphone.debug I/Linphone: MKVRecorder: set pin #0 format. type=video;encoding=H264;vsize=0x0;fps=0.000000;fmtp='' 2019-10-15 13:39:44.807 5646-5646/org.linphone.debug I/Linphone: [Permission] android.permission.RECORD_AUDIO permission is granted 2019-10-15 13:39:44.812 5646-5646/org.linphone.debug I/Linphone: This device (rockchip/rk3288/rk3288) says it has no built-in echo canceller. 2019-10-15 13:39:44.812 5646-5646/org.linphone.debug I/Linphone: No information available for [rockchip/rk3288/rk3288], 2019-10-15 13:39:44.812 5646-5646/org.linphone.debug I/Linphone: Sound device information for [rockchip/rk3288/rk3288] is: builtin=[no], delay=[250] ms 2019-10-15 13:39:44.812 5646-5646/org.linphone.debug I/Linphone: MSVolume set gain to [0.000000 db], [1.000000] linear 2019-10-15 13:39:44.815 5646-5646/org.linphone.debug W/Linphone: [Audio Manager] Routing audio to speaker 2019-10-15 13:39:44.817 5646-5646/org.linphone.debug W/Linphone: [Contacts Manager] Couldn't find friend... 2019-10-15 13:39:44.818 5646-5646/org.linphone.debug I/Linphone: [Service] Destroying video overlay 2019-10-15 13:39:44.820 5646-5646/org.linphone.debug I/Linphone: Core callbacks [0x863e2c50] registered on core [0xa4a36100] 2019-10-15 13:39:44.820 5646-5646/org.linphone.debug I/Linphone: Core callbacks [0x863e39e8] registered on core [0xa4a36100] 2019-10-15 13:39:44.821 5646-5646/org.linphone.debug W/Linphone: [Status Fragment] Can't display ZRTP popup, no token ! 2019-10-15 13:39:44.826 5646-5646/org.linphone.debug I/Linphone: Camera video preview size changed: 640x480 2019-10-15 13:39:44.826 5646-5646/org.linphone.debug I/Linphone: Resizing camera video preview to: 640x480 2019-10-15 13:39:44.826 5646-5646/org.linphone.debug I/Linphone: [Platform Helper] Found CaptureTextureView, setting video capture size to 640x480 2019-10-15 13:39:44.826 5646-5646/org.linphone.debug I/Linphone: [CaptureTextureView] Changing preview texture ratio to match 640x480 2019-10-15 13:39:44.827 5646-5646/org.linphone.debug I/Linphone: Camera video preview size changed: 640x480 2019-10-15 13:39:44.827 5646-5646/org.linphone.debug I/Linphone: Resizing camera video preview to: 640x480 2019-10-15 13:39:44.827 5646-5646/org.linphone.debug I/Linphone: [Platform Helper] Found CaptureTextureView, setting video capture size to 640x480 2019-10-15 13:39:44.827 5646-5646/org.linphone.debug I/Linphone: [CaptureTextureView] Changing preview texture ratio to match 640x480 2019-10-15 13:39:44.828 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=72.47,u=83.18], video=[d=0.00,u=10.89,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.04,u=0.77], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:39:44.828 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=5.76441 video=0.250989 text=0 2019-10-15 13:39:44.893 5646-5646/org.linphone.debug W/Linphone: [Contacts Manager] Couldn't find friend... 2019-10-15 13:39:44.927 5646-5646/org.linphone.debug I/Linphone: [Platform Helper] Rendering window surface is available 2019-10-15 13:39:44.927 5646-5646/org.linphone.debug I/Linphone: [Platform Helper] Found CaptureTextureView, rotating... 2019-10-15 13:39:44.928 5646-5646/org.linphone.debug I/Linphone: [TextureView Display] New window jobject ptr is 0x9a6, current one is 0x0 2019-10-15 13:39:44.928 5646-6418/org.linphone.debug I/Linphone: [TextureView Display] Initializing context 2019-10-15 13:39:44.929 5646-6418/org.linphone.debug I/Linphone: [TextureView Display] Creating Surface from SurfaceTexture 0x9a6 2019-10-15 13:39:44.929 5646-5646/org.linphone.debug I/Linphone: [Platform Helper] Preview window surface is available 2019-10-15 13:39:44.929 5646-6418/org.linphone.debug I/Linphone: [TextureView Display] Surface created: 0x100b5e 2019-10-15 13:39:44.929 5646-5646/org.linphone.debug I/Linphone: Preview capture window set for the 1st time (win: 0x100b62 rotation:0) 2019-10-15 13:39:44.929 5646-6418/org.linphone.debug I/Linphone: [TextureView Display] Chosen format is 2 2019-10-15 13:39:44.929 5646-6418/org.linphone.debug I/Linphone: [TextureView Display] Surface size is 800x1208 2019-10-15 13:39:44.931 5646-5646/org.linphone.debug I/Linphone: Camera [android.hardware.Camera@800f6c1] preview started 2019-10-15 13:39:44.931 5646-6418/org.linphone.debug I/Linphone: ogl_display_new : 0x84112620 2019-10-15 13:39:44.931 5646-6418/org.linphone.debug I/Linphone: init opengles_display (800 x 1208, gl initialized:0) 2019-10-15 13:39:44.931 5646-5646/org.linphone.debug I/Linphone: Camera video preview size changed on videostream [0x867fc000] 2019-10-15 13:39:44.931 5646-6418/org.linphone.debug I/Linphone: resize opengles_display (800 x 1208, gl initialized:0) 2019-10-15 13:39:44.932 5646-5646/org.linphone.debug I/Linphone: Camera video preview size changed on videostream [0x867fc000] 2019-10-15 13:39:44.932 5646-6418/org.linphone.debug I/Linphone: OpenGL program info: [NO INFORMATION] 2019-10-15 13:39:44.933 5646-6418/org.linphone.debug I/Linphone: [TextureView Display] Context initialized 2019-10-15 13:39:44.943 5646-5713/org.linphone.debug I/[Gralloc]: Got handle 6 for fd 99 2019-10-15 13:39:44.943 5646-5713/org.linphone.debug I/[Gralloc]: leave, w : 800, h : 1280, format : 0x1,internal_format : 0x1, usage : 0xb00. size=4096000,pixel_stride=800,byte_stride=3200 2019-10-15 13:39:44.943 5646-5713/org.linphone.debug I/[Gralloc]: leave: prime_fd=99,share_attr_fd=100 2019-10-15 13:39:44.957 5646-5713/org.linphone.debug I/[Gralloc]: Got handle 7 for fd 101 2019-10-15 13:39:44.957 5646-5713/org.linphone.debug I/[Gralloc]: leave, w : 800, h : 1280, format : 0x1,internal_format : 0x1, usage : 0xb00. size=4096000,pixel_stride=800,byte_stride=3200 2019-10-15 13:39:44.957 5646-5713/org.linphone.debug I/[Gralloc]: leave: prime_fd=101,share_attr_fd=102 2019-10-15 13:39:44.965 5646-5646/org.linphone.debug I/Linphone: Camera video preview size changed: 640x480 2019-10-15 13:39:44.965 5646-5646/org.linphone.debug I/Linphone: Resizing camera video preview to: 640x480 2019-10-15 13:39:44.965 5646-5646/org.linphone.debug I/Linphone: [Platform Helper] Found CaptureTextureView, setting video capture size to 640x480 2019-10-15 13:39:44.965 5646-5646/org.linphone.debug I/Linphone: [CaptureTextureView] Changing preview texture ratio to match 640x480 2019-10-15 13:39:44.966 5646-5646/org.linphone.debug I/Linphone: Camera video preview size changed: 640x480 2019-10-15 13:39:44.966 5646-5646/org.linphone.debug I/Linphone: Resizing camera video preview to: 640x480 2019-10-15 13:39:44.966 5646-5646/org.linphone.debug I/Linphone: [Platform Helper] Found CaptureTextureView, setting video capture size to 640x480 2019-10-15 13:39:44.966 5646-5646/org.linphone.debug I/Linphone: [CaptureTextureView] Changing preview texture ratio to match 640x480 2019-10-15 13:39:45.000 5646-5713/org.linphone.debug I/[Gralloc]: Got handle 8 for fd 103 2019-10-15 13:39:45.000 5646-5713/org.linphone.debug I/[Gralloc]: leave, w : 800, h : 1280, format : 0x1,internal_format : 0x1, usage : 0xb00. size=4096000,pixel_stride=800,byte_stride=3200 2019-10-15 13:39:45.000 5646-5713/org.linphone.debug I/[Gralloc]: leave: prime_fd=103,share_attr_fd=104 2019-10-15 13:39:45.035 5646-5646/org.linphone.debug I/Linphone: [Activity Monitor] Activity stopped:org.linphone.call.CallOutgoingActivity@7640bd1 2019-10-15 13:39:45.035 5646-5646/org.linphone.debug I/Linphone: [Activity Monitor] Activity destroyed:org.linphone.call.CallOutgoingActivity@7640bd1 2019-10-15 13:39:45.085 5646-6419/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e04c00]. 2019-10-15 13:39:45.091 5646-5646/org.linphone.debug I/Linphone: MSVideo_stream_iterate[0x867fc000], local statistics available: Local current jitter buffer size: 0.0ms 2019-10-15 13:39:45.092 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:45.155 5646-6403/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e03e00]. 2019-10-15 13:39:45.174 5646-5646/org.linphone.debug I/Linphone: MSAudio_stream_iterate[0x86694b80], local statistics available: Local current jitter buffer size: 59.9ms 2019-10-15 13:39:45.176 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:45.215 5646-6403/org.linphone.debug I/Linphone: [OpenSLES] sound/wall clock skew is average=-2.38051 ms 2019-10-15 13:39:45.480 5646-5676/org.linphone.debug I/[Gralloc]: for nv12, w : 640, h : 480, pixel_stride : 640, byte_stride : 640, size : 614400; internalHeight : 480. 2019-10-15 13:39:45.480 5646-5676/org.linphone.debug I/[Gralloc]: Got handle 2 for fd 86 2019-10-15 13:39:45.480 5646-5676/org.linphone.debug I/[Gralloc]: leave, w : 640, h : 480, format : 0x15,internal_format : 0x15, usage : 0x333. size=614400,pixel_stride=640,byte_stride=640 2019-10-15 13:39:45.480 5646-5676/org.linphone.debug I/[Gralloc]: leave: prime_fd=86,share_attr_fd=87 2019-10-15 13:39:45.491 5646-5676/org.linphone.debug I/[Gralloc]: for nv12, w : 640, h : 480, pixel_stride : 640, byte_stride : 640, size : 614400; internalHeight : 480. 2019-10-15 13:39:45.491 5646-5676/org.linphone.debug I/[Gralloc]: Got handle 3 for fd 88 2019-10-15 13:39:45.491 5646-5676/org.linphone.debug I/[Gralloc]: leave, w : 640, h : 480, format : 0x15,internal_format : 0x15, usage : 0x333. size=614400,pixel_stride=640,byte_stride=640 2019-10-15 13:39:45.491 5646-5676/org.linphone.debug I/[Gralloc]: leave: prime_fd=88,share_attr_fd=89 2019-10-15 13:39:45.498 5646-5676/org.linphone.debug I/[Gralloc]: for nv12, w : 640, h : 480, pixel_stride : 640, byte_stride : 640, size : 614400; internalHeight : 480. 2019-10-15 13:39:45.498 5646-5676/org.linphone.debug I/[Gralloc]: Got handle 4 for fd 90 2019-10-15 13:39:45.498 5646-5676/org.linphone.debug I/[Gralloc]: leave, w : 640, h : 480, format : 0x15,internal_format : 0x15, usage : 0x333. size=614400,pixel_stride=640,byte_stride=640 2019-10-15 13:39:45.499 5646-5676/org.linphone.debug I/[Gralloc]: leave: prime_fd=90,share_attr_fd=91 2019-10-15 13:39:45.506 5646-5659/org.linphone.debug I/[Gralloc]: for nv12, w : 640, h : 480, pixel_stride : 640, byte_stride : 640, size : 614400; internalHeight : 480. 2019-10-15 13:39:45.506 5646-5659/org.linphone.debug I/[Gralloc]: Got handle 5 for fd 92 2019-10-15 13:39:45.506 5646-5659/org.linphone.debug I/[Gralloc]: leave, w : 640, h : 480, format : 0x15,internal_format : 0x15, usage : 0x333. size=614400,pixel_stride=640,byte_stride=640 2019-10-15 13:39:45.506 5646-5659/org.linphone.debug I/[Gralloc]: leave: prime_fd=92,share_attr_fd=93 2019-10-15 13:39:45.565 5646-6403/org.linphone.debug I/Linphone: jitter buffer stable: target-size: 59.999999 ms, effective-size: 59.862068 (min: 40 nom: 60, max: 500) 2019-10-15 13:39:45.565 5646-6403/org.linphone.debug I/Linphone: jitter buffer rls stats: count=248, clockrate=8000, offset=0.382139 clock_ratio=0.9999, capped_offset=160 capped_clock_ratio=0.999900, max_ts_deviation=480.000000 prev_max_ts_deviation=480.000000, deviation=5, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.011874 2019-10-15 13:39:45.580 5646-5646/org.linphone.debug I/Linphone: MSAudio stream [0x86694b80]: receiving RTCP SR 2019-10-15 13:39:45.580 5646-5646/org.linphone.debug W/Linphone: ortp_loss_rate_estimator_process 0x8c7653e8: Suspected discontinuity in sequence numbering from 286 to 238. 2019-10-15 13:39:45.581 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:45.674 5646-6419/org.linphone.debug I/Linphone: video processing delay = { min=0ms, mean=0.0ms, max=0ms } (RtpSession=0x86e04c00) 2019-10-15 13:39:45.781 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=75.16,u=76.63], video=[d=0.00,u=0.22,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=1.17,u=1.50], video=[d=0.00,u=0.85], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:39:45.782 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=13.3181 video=1.89947 text=0 2019-10-15 13:39:46.007 5646-6419/org.linphone.debug I/Linphone: MediaCodecEncoder: output format has changed. 2019-10-15 13:39:46.009 5646-6419/org.linphone.debug I/Linphone: MediaCodecEncoder: new output format: {csd-1=java.nio.HeapByteBuffer[pos=0 lim=8 cap=8], mime=video/avc, width=640, height=480, bitrate=360000, csd-0=java.nio.HeapByteBuffer[pos=0 lim=27 cap=27], max-bitrate=360000} 2019-10-15 13:39:46.027 5646-5713/org.linphone.debug I/[Gralloc]: Got handle 9 for fd 94 2019-10-15 13:39:46.027 5646-5713/org.linphone.debug I/[Gralloc]: leave, w : 800, h : 1280, format : 0x1,internal_format : 0x1, usage : 0xb00. size=4096000,pixel_stride=800,byte_stride=3200 2019-10-15 13:39:46.027 5646-5713/org.linphone.debug I/[Gralloc]: leave: prime_fd=94,share_attr_fd=97 2019-10-15 13:39:46.206 5646-6403/org.linphone.debug W/Linphone: Not enough ref samples, using zeroes 2019-10-15 13:39:46.256 5646-6403/org.linphone.debug I/Linphone: Samples are back. 2019-10-15 13:39:46.774 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=78.03,u=84.96], video=[d=0.00,u=682.22,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.01,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:39:46.774 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=9.39969 video=7.70755 text=0 2019-10-15 13:39:47.140 5646-6409/org.linphone.debug W/AMessage: failed to deliver message as target handler 43 is gone. 2019-10-15 13:39:47.263 5646-5646/org.linphone.debug I/Linphone: MSAudio stream [0x86694b80]: receiving RTCP RR 2019-10-15 13:39:47.264 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:47.404 5646-6419/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e04c00]. 2019-10-15 13:39:47.419 5646-5646/org.linphone.debug I/Linphone: MSVideo_stream_iterate[0x867fc000], local statistics available: Local current jitter buffer size: 0.0ms 2019-10-15 13:39:47.421 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:47.677 5646-6409/org.linphone.debug W/AMessage: failed to deliver message as target handler 49 is gone. 2019-10-15 13:39:47.741 5646-5646/org.linphone.debug I/Linphone: MSVideo stream [0x867fc000]: receiving RTCP RR 2019-10-15 13:39:47.741 5646-5646/org.linphone.debug W/Linphone: ortp_loss_rate_estimator_process 0x8c765998: Suspected discontinuity in sequence numbering from 263 to 139. 2019-10-15 13:39:47.742 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:47.783 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=74.53,u=78.08], video=[d=0.00,u=716.61,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.18,u=0.00], video=[d=2.08,u=1.31], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:39:47.783 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=14.5168 video=7.38874 text=0 2019-10-15 13:39:47.994 5646-6403/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e03e00]. 2019-10-15 13:39:48.004 5646-6419/org.linphone.debug I/Linphone: H26xEncoder: requesting I-frame to the encoder. 2019-10-15 13:39:48.005 5646-6419/org.linphone.debug I/Linphone: AMediaCodec_setParams() request-sync key transfered to Bundle with value 0 2019-10-15 13:39:48.005 5646-6419/org.linphone.debug E/Linphone: MediaCodecEncoder: I-frame requested to MediaCodec 2019-10-15 13:39:48.018 5646-5646/org.linphone.debug I/Linphone: MSAudio_stream_iterate[0x86694b80], local statistics available: Local current jitter buffer size: 44.8ms 2019-10-15 13:39:48.018 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:48.769 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=80.03,u=80.08], video=[d=0.00,u=757.18,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.51], video=[d=0.01,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:39:48.769 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=11.5657 video=16.2968 text=0 2019-10-15 13:39:49.024 5646-5646/org.linphone.debug I/Linphone: MSVideo stream [0x867fc000]: receiving RTCP RR 2019-10-15 13:39:49.025 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x86742d20][video], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 0.0ms RT propagation : 10.9ms 2019-10-15 13:39:49.025 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:49.779 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=76.13,u=79.88], video=[d=0.00,u=631.25,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.05,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:39:49.779 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=12.5927 video=7.9937 text=0 2019-10-15 13:39:50.004 5646-6419/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e04c00]. 2019-10-15 13:39:50.015 5646-6419/org.linphone.debug I/Linphone: H26xEncoder: requesting I-frame to the encoder. 2019-10-15 13:39:50.015 5646-6419/org.linphone.debug I/Linphone: AMediaCodec_setParams() request-sync key transfered to Bundle with value 0 2019-10-15 13:39:50.016 5646-6419/org.linphone.debug E/Linphone: MediaCodecEncoder: I-frame requested to MediaCodec 2019-10-15 13:39:50.020 5646-5646/org.linphone.debug I/Linphone: MSVideo_stream_iterate[0x867fc000], local statistics available: Local current jitter buffer size: 0.0ms 2019-10-15 13:39:50.021 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:50.214 5646-6403/org.linphone.debug I/Linphone: [OpenSLES] sound/wall clock skew is average=-5.82872 ms 2019-10-15 13:39:50.565 5646-5646/org.linphone.debug I/Linphone: MSAudio stream [0x86694b80]: receiving RTCP SR 2019-10-15 13:39:50.565 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x8c2c78e0][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 20.0ms RT propagation : 7.1ms 2019-10-15 13:39:50.566 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:50.575 5646-6403/org.linphone.debug I/Linphone: jitter buffer stable: target-size: 39.999999 ms, effective-size: 44.788731 (min: 40 nom: 60, max: 500) 2019-10-15 13:39:50.575 5646-6403/org.linphone.debug I/Linphone: jitter buffer rls stats: count=498, clockrate=8000, offset=6.56955 clock_ratio=0.999501, capped_offset=166 capped_clock_ratio=0.999501, max_ts_deviation=76.000000 prev_max_ts_deviation=5.000000, deviation=46, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.007291 2019-10-15 13:39:50.675 5646-6419/org.linphone.debug I/Linphone: video processing delay = { min=0ms, mean=0.0ms, max=0ms } (RtpSession=0x86e04c00) 2019-10-15 13:39:50.766 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=73.59,u=80.07], video=[d=0.00,u=818.55,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=1.27,u=0.00], video=[d=0.00,u=1.31], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:39:50.766 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=10.212 video=9.1238 text=0 2019-10-15 13:39:50.804 5646-6403/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e03e00]. 2019-10-15 13:39:50.806 5646-5646/org.linphone.debug I/Linphone: MSAudio_stream_iterate[0x86694b80], local statistics available: Local current jitter buffer size: 40.0ms 2019-10-15 13:39:50.807 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:51.024 5646-6419/org.linphone.debug I/Linphone: Captured mean fps=14.915890, expected=15.000000 2019-10-15 13:39:51.791 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=79.12,u=80.03], video=[d=0.00,u=640.58,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.01,u=1.50], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:39:51.792 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=10.2145 video=6.44504 text=0 2019-10-15 13:39:51.935 5646-6419/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e04c00]. 2019-10-15 13:39:51.981 5646-5646/org.linphone.debug I/Linphone: MSVideo_stream_iterate[0x867fc000], local statistics available: Local current jitter buffer size: 0.0ms 2019-10-15 13:39:51.982 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:52.731 5646-5646/org.linphone.debug I/Linphone: MSVideo stream [0x867fc000]: receiving RTCP RR 2019-10-15 13:39:52.731 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=0.000000 ms, rt_prop=0.005524 sec 2019-10-15 13:39:52.732 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: everything is fine. 2019-10-15 13:39:52.732 5646-5646/org.linphone.debug I/Linphone: MSBitrateController: current state is Init 2019-10-15 13:39:52.732 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x86742d20][video], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 0.0ms RT propagation : 5.5ms 2019-10-15 13:39:52.733 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:52.772 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=78.84,u=79.97], video=[d=0.00,u=612.75,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=2.21,u=1.31], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:39:52.773 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=9.17717 video=9.82443 text=0 2019-10-15 13:39:53.485 5646-6403/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e03e00]. 2019-10-15 13:39:53.520 5646-5646/org.linphone.debug I/Linphone: MSAudio_stream_iterate[0x86694b80], local statistics available: Local current jitter buffer size: 40.0ms 2019-10-15 13:39:53.521 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:53.784 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=74.41,u=79.88], video=[d=0.00,u=549.78,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.01,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:39:53.785 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=14.3331 video=14.4664 text=0 2019-10-15 13:39:54.773 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=70.88,u=80.10], video=[d=0.00,u=544.56,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.51], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:39:54.774 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=10.3767 video=10.0201 text=0 2019-10-15 13:39:54.995 5646-6419/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e04c00]. 2019-10-15 13:39:54.998 5646-5646/org.linphone.debug I/Linphone: MSVideo_stream_iterate[0x867fc000], local statistics available: Local current jitter buffer size: 0.0ms 2019-10-15 13:39:54.999 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:55.214 5646-6403/org.linphone.debug I/Linphone: [OpenSLES] sound/wall clock skew is average=-5.53485 ms 2019-10-15 13:39:55.567 5646-5646/org.linphone.debug I/Linphone: MSAudio stream [0x86694b80]: receiving RTCP SR 2019-10-15 13:39:55.567 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x8c2c78e0][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 19.9ms RT propagation : 9.0ms 2019-10-15 13:39:55.568 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:55.575 5646-6403/org.linphone.debug I/Linphone: jitter buffer stable: target-size: 39.999999 ms, effective-size: 40.000000 (min: 40 nom: 60, max: 500) 2019-10-15 13:39:55.576 5646-6403/org.linphone.debug I/Linphone: jitter buffer rls stats: count=748, clockrate=8000, offset=7.34344 clock_ratio=0.99948, capped_offset=167 capped_clock_ratio=0.999480, max_ts_deviation=56.000000 prev_max_ts_deviation=55.000000, deviation=24, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.005057 2019-10-15 13:39:55.679 5646-6419/org.linphone.debug I/Linphone: video processing delay = { min=0ms, mean=0.0ms, max=0ms } (RtpSession=0x86e04c00) 2019-10-15 13:39:55.767 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=73.15,u=79.94], video=[d=0.00,u=558.32,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=1.26,u=0.00], video=[d=0.00,u=1.31], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:39:55.768 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=9.65155 video=8.19452 text=0 2019-10-15 13:39:56.084 5646-6419/org.linphone.debug I/Linphone: Captured mean fps=15.310983, expected=15.000000 2019-10-15 13:39:56.779 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=76.12,u=79.99], video=[d=0.00,u=486.32,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.01,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:39:56.780 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=12.6818 video=8.66729 text=0 2019-10-15 13:39:57.729 5646-5646/org.linphone.debug I/Linphone: MSVideo stream [0x867fc000]: receiving RTCP RR 2019-10-15 13:39:57.729 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=0.000000 ms, rt_prop=0.004379 sec 2019-10-15 13:39:57.729 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: everything is fine. 2019-10-15 13:39:57.729 5646-5646/org.linphone.debug I/Linphone: MSBitrateController: current state is Init 2019-10-15 13:39:57.730 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x86742d20][video], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 0.0ms RT propagation : 4.4ms 2019-10-15 13:39:57.730 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:57.767 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=73.35,u=80.03], video=[d=0.00,u=561.33,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=2.28,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:39:57.767 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=10.6657 video=8.08959 text=0 2019-10-15 13:39:58.095 5646-6403/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e03e00]. 2019-10-15 13:39:58.109 5646-5646/org.linphone.debug I/Linphone: MSAudio_stream_iterate[0x86694b80], local statistics available: Local current jitter buffer size: 40.0ms 2019-10-15 13:39:58.109 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:39:58.774 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=70.61,u=79.88], video=[d=0.00,u=690.98,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.50], video=[d=0.01,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:39:58.774 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=9.30257 video=9.22708 text=0 2019-10-15 13:39:59.341 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: keep alive sent to [UDP://192.168.1.181:5060] 2019-10-15 13:39:59.765 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=73.87,u=80.08], video=[d=0.00,u=663.67,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-10-15 13:39:59.766 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=10.1655 video=10.8169 text=0 2019-10-15 13:40:00.214 5646-6403/org.linphone.debug I/Linphone: [OpenSLES] sound/wall clock skew is average=-6.23199 ms 2019-10-15 13:40:00.574 5646-5646/org.linphone.debug I/Linphone: MSAudio stream [0x86694b80]: receiving RTCP SR 2019-10-15 13:40:00.575 5646-6403/org.linphone.debug I/Linphone: jitter buffer stable: target-size: 39.999999 ms, effective-size: 40.000000 (min: 40 nom: 60, max: 500) 2019-10-15 13:40:00.575 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x8c2c78e0][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 20.0ms RT propagation : 7.8ms 2019-10-15 13:40:00.575 5646-6403/org.linphone.debug I/Linphone: jitter buffer rls stats: count=998, clockrate=8000, offset=8.48497 clock_ratio=0.999455, capped_offset=168 capped_clock_ratio=0.999454, max_ts_deviation=79.000000 prev_max_ts_deviation=78.000000, deviation=0, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.003853 2019-10-15 13:40:00.575 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:00.674 5646-6419/org.linphone.debug I/Linphone: video processing delay = { min=0ms, mean=0.0ms, max=0ms } (RtpSession=0x86e04c00) 2019-10-15 13:40:00.765 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=73.88,u=79.83], video=[d=0.00,u=608.49,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=1.28,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:00.766 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=10.5747 video=7.02831 text=0 2019-10-15 13:40:00.854 5646-6419/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e04c00]. 2019-10-15 13:40:00.866 5646-5646/org.linphone.debug I/Linphone: MSVideo_stream_iterate[0x867fc000], local statistics available: Local current jitter buffer size: 0.0ms 2019-10-15 13:40:00.866 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:01.164 5646-6419/org.linphone.debug I/Linphone: Captured mean fps=14.437598, expected=15.000000 2019-10-15 13:40:01.771 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=71.77,u=81.72], video=[d=0.00,u=512.47,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.01,u=0.00], video=[d=0.00,u=1.31], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:01.772 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=9.27248 video=8.43647 text=0 2019-10-15 13:40:02.545 5646-6403/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e03e00]. 2019-10-15 13:40:02.559 5646-5646/org.linphone.debug I/Linphone: MSAudio_stream_iterate[0x86694b80], local statistics available: Local current jitter buffer size: 40.2ms 2019-10-15 13:40:02.560 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:02.731 5646-5646/org.linphone.debug I/Linphone: MSVideo stream [0x867fc000]: receiving RTCP RR 2019-10-15 13:40:02.731 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=0.000000 ms, rt_prop=0.005325 sec 2019-10-15 13:40:02.732 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: everything is fine. 2019-10-15 13:40:02.732 5646-5646/org.linphone.debug I/Linphone: MSBitrateController: current state is Init 2019-10-15 13:40:02.732 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x86742d20][video], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 0.0ms RT propagation : 5.3ms 2019-10-15 13:40:02.732 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:02.782 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=75.40,u=78.44], video=[d=0.00,u=563.16,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=2.11,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:02.782 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=12.2582 video=9.43524 text=0 2019-10-15 13:40:03.772 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=71.60,u=80.13], video=[d=0.00,u=570.36,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.51], video=[d=0.01,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:03.772 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=9.70999 video=5.712 text=0 2019-10-15 13:40:04.774 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=70.93,u=79.72], video=[d=0.00,u=531.27,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-10-15 13:40:04.774 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=10.0635 video=8.81752 text=0 2019-10-15 13:40:05.014 5646-6419/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e04c00]. 2019-10-15 13:40:05.025 5646-5646/org.linphone.debug I/Linphone: MSVideo_stream_iterate[0x867fc000], local statistics available: Local current jitter buffer size: 0.0ms 2019-10-15 13:40:05.026 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:05.214 5646-6403/org.linphone.debug I/Linphone: [OpenSLES] sound/wall clock skew is average=-5.634 ms 2019-10-15 13:40:05.572 5646-5646/org.linphone.debug I/Linphone: MSAudio stream [0x86694b80]: receiving RTCP SR 2019-10-15 13:40:05.572 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x8c2c78e0][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 19.9ms RT propagation : 6.5ms 2019-10-15 13:40:05.573 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:05.575 5646-6403/org.linphone.debug I/Linphone: jitter buffer stable: target-size: 39.999999 ms, effective-size: 40.180180 (min: 40 nom: 60, max: 500) 2019-10-15 13:40:05.575 5646-6403/org.linphone.debug I/Linphone: jitter buffer rls stats: count=1248, clockrate=8000, offset=5.08783 clock_ratio=0.999511, capped_offset=165 capped_clock_ratio=0.999511, max_ts_deviation=92.000000 prev_max_ts_deviation=89.000000, deviation=13, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.003108 2019-10-15 13:40:05.674 5646-6419/org.linphone.debug I/Linphone: video processing delay = { min=0ms, mean=0.0ms, max=0ms } (RtpSession=0x86e04c00) 2019-10-15 13:40:05.773 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=71.25,u=80.10], video=[d=0.00,u=473.91,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=1.23,u=0.00], video=[d=0.00,u=1.32], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:05.773 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=10.986 video=10.1215 text=0 2019-10-15 13:40:06.175 5646-6419/org.linphone.debug I/Linphone: Captured mean fps=14.297730, expected=15.000000 2019-10-15 13:40:06.770 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=72.39,u=80.07], video=[d=0.00,u=517.50,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.01,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:06.770 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=10.1319 video=6.07458 text=0 2019-10-15 13:40:07.742 5646-5646/org.linphone.debug I/Linphone: MSVideo stream [0x867fc000]: receiving RTCP RR 2019-10-15 13:40:07.742 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=0.000000 ms, rt_prop=0.006180 sec 2019-10-15 13:40:07.742 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: everything is fine. 2019-10-15 13:40:07.742 5646-5646/org.linphone.debug I/Linphone: MSBitrateController: current state is Init 2019-10-15 13:40:07.743 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x86742d20][video], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 0.0ms RT propagation : 6.2ms 2019-10-15 13:40:07.743 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:07.775 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=70.42,u=79.93], video=[d=0.00,u=520.59,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=2.18,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:07.775 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=11.0481 video=8.97009 text=0 2019-10-15 13:40:08.415 5646-6403/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e03e00]. 2019-10-15 13:40:08.419 5646-5646/org.linphone.debug I/Linphone: MSAudio_stream_iterate[0x86694b80], local statistics available: Local current jitter buffer size: 40.3ms 2019-10-15 13:40:08.420 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:08.767 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=73.49,u=79.97], video=[d=0.00,u=537.39,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.01,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:08.767 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=9.58047 video=5.35311 text=0 2019-10-15 13:40:09.772 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=71.53,u=80.00], video=[d=0.00,u=505.18,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.50], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:09.773 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=9.30252 video=5.84829 text=0 2019-10-15 13:40:10.214 5646-6403/org.linphone.debug I/Linphone: [OpenSLES] sound/wall clock skew is average=-6.05445 ms 2019-10-15 13:40:10.499 5646-6419/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e04c00]. 2019-10-15 13:40:10.509 5646-5646/org.linphone.debug I/Linphone: MSVideo_stream_iterate[0x867fc000], local statistics available: Local current jitter buffer size: 0.0ms 2019-10-15 13:40:10.510 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:10.575 5646-6403/org.linphone.debug I/Linphone: jitter buffer stable: target-size: 39.999999 ms, effective-size: 40.272110 (min: 40 nom: 60, max: 500) 2019-10-15 13:40:10.575 5646-6403/org.linphone.debug I/Linphone: jitter buffer rls stats: count=1498, clockrate=8000, offset=-0.935724 clock_ratio=0.999594, capped_offset=160 capped_clock_ratio=0.999594, max_ts_deviation=99.000000 prev_max_ts_deviation=97.000000, deviation=18, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.002604 2019-10-15 13:40:10.576 5646-5646/org.linphone.debug I/Linphone: MSAudio stream [0x86694b80]: receiving RTCP SR 2019-10-15 13:40:10.576 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x8c2c78e0][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 19.9ms RT propagation : 6.7ms 2019-10-15 13:40:10.577 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:10.674 5646-6419/org.linphone.debug I/Linphone: video processing delay = { min=0ms, mean=0.0ms, max=0ms } (RtpSession=0x86e04c00) 2019-10-15 13:40:10.780 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=76.00,u=80.04], video=[d=0.00,u=719.32,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=1.19,u=0.00], video=[d=0.00,u=1.31], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:10.781 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=12.2533 video=5.03933 text=0 2019-10-15 13:40:11.234 5646-6419/org.linphone.debug I/Linphone: Captured mean fps=14.585308, expected=15.000000 2019-10-15 13:40:11.770 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=72.33,u=79.92], video=[d=0.00,u=498.04,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.01,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:11.770 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=11.3401 video=10.889 text=0 2019-10-15 13:40:12.254 5646-5646/org.linphone.debug I/Linphone: Changing [client] [INVITE] transaction [0x83b123b0], from state [COMPLETED] to [TERMINATED] 2019-10-15 13:40:12.254 5646-5646/org.linphone.debug I/Linphone: Client INVITE transaction [0x83b123b0] terminated 2019-10-15 13:40:12.255 5646-5646/org.linphone.debug I/Linphone: transaction [0x83b123b0]: ending transaction background task with id=[200aea]. 2019-10-15 13:40:12.257 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x200aea] 2019-10-15 13:40:12.742 5646-5646/org.linphone.debug I/Linphone: MSVideo stream [0x867fc000]: receiving RTCP RR 2019-10-15 13:40:12.742 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=0.000000 ms, rt_prop=0.005585 sec 2019-10-15 13:40:12.742 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: everything is fine. 2019-10-15 13:40:12.743 5646-5646/org.linphone.debug I/Linphone: MSBitrateController: current state is Init 2019-10-15 13:40:12.743 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x86742d20][video], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 0.0ms RT propagation : 5.6ms 2019-10-15 13:40:12.744 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:12.791 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=72.16,u=79.88], video=[d=0.00,u=511.54,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=2.02,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:12.791 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=14.6873 video=8.64046 text=0 2019-10-15 13:40:13.780 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=76.08,u=80.17], video=[d=0.00,u=550.20,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.01,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:13.781 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=11.9978 video=9.35219 text=0 2019-10-15 13:40:14.138 5646-6403/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e03e00]. 2019-10-15 13:40:14.154 5646-5646/org.linphone.debug I/Linphone: MSAudio_stream_iterate[0x86694b80], local statistics available: Local current jitter buffer size: 40.3ms 2019-10-15 13:40:14.155 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:14.780 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=76.11,u=79.99], video=[d=0.00,u=455.54,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.50], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:14.781 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=13.3851 video=9.0022 text=0 2019-10-15 13:40:14.945 5646-6419/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e04c00]. 2019-10-15 13:40:14.986 5646-5646/org.linphone.debug I/Linphone: MSVideo_stream_iterate[0x867fc000], local statistics available: Local current jitter buffer size: 0.0ms 2019-10-15 13:40:14.987 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:15.215 5646-6403/org.linphone.debug I/Linphone: [OpenSLES] sound/wall clock skew is average=-5.4465 ms 2019-10-15 13:40:15.574 5646-5646/org.linphone.debug I/Linphone: MSAudio stream [0x86694b80]: receiving RTCP SR 2019-10-15 13:40:15.574 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x8c2c78e0][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 20.0ms RT propagation : 6.6ms 2019-10-15 13:40:15.575 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:15.575 5646-6403/org.linphone.debug I/Linphone: jitter buffer stable: target-size: 39.999999 ms, effective-size: 40.279720 (min: 40 nom: 60, max: 500) 2019-10-15 13:40:15.576 5646-6403/org.linphone.debug I/Linphone: jitter buffer rls stats: count=1748, clockrate=8000, offset=-7.15256 clock_ratio=0.999667, capped_offset=153 capped_clock_ratio=0.999667, max_ts_deviation=100.000000 prev_max_ts_deviation=99.000000, deviation=21, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.002240 2019-10-15 13:40:15.675 5646-6419/org.linphone.debug I/Linphone: video processing delay = { min=0ms, mean=0.0ms, max=0ms } (RtpSession=0x86e04c00) 2019-10-15 13:40:15.775 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=78.11,u=79.94], video=[d=0.00,u=488.97,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=1.22,u=0.00], video=[d=0.00,u=1.31], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:15.776 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=9.28186 video=7.90542 text=0 2019-10-15 13:40:16.094 5646-5646/org.linphone.debug I/Linphone: Changing [client] [INVITE] transaction [0x83b39cf0], from state [ACCEPTED] to [TERMINATED] 2019-10-15 13:40:16.094 5646-5646/org.linphone.debug I/Linphone: Client INVITE transaction [0x83b39cf0] terminated 2019-10-15 13:40:16.094 5646-5646/org.linphone.debug I/Linphone: transaction [0x83b39cf0]: ending transaction background task with id=[a5a]. 2019-10-15 13:40:16.097 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0xa5a] 2019-10-15 13:40:16.274 5646-6419/org.linphone.debug I/Linphone: Captured mean fps=15.754525, expected=15.000000 2019-10-15 13:40:16.783 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=75.20,u=80.08], video=[d=0.00,u=535.56,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.01,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:16.783 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=14.2299 video=10.5815 text=0 2019-10-15 13:40:17.738 5646-5646/org.linphone.debug I/Linphone: MSVideo stream [0x867fc000]: receiving RTCP RR 2019-10-15 13:40:17.739 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=0.000000 ms, rt_prop=0.004593 sec 2019-10-15 13:40:17.739 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: everything is fine. 2019-10-15 13:40:17.739 5646-5646/org.linphone.debug I/Linphone: MSBitrateController: current state is Init 2019-10-15 13:40:17.739 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x86742d20][video], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 0.0ms RT propagation : 4.6ms 2019-10-15 13:40:17.740 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:17.776 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=77.96,u=79.95], video=[d=0.00,u=570.14,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=2.18,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:17.776 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=8.70416 video=9.09875 text=0 2019-10-15 13:40:18.779 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=76.95,u=80.00], video=[d=0.00,u=568.60,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.01,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:18.779 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=11.8096 video=4.56951 text=0 2019-10-15 13:40:19.784 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=74.84,u=79.79], video=[d=0.00,u=535.58,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-10-15 13:40:19.784 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=11.8686 video=8.92187 text=0 2019-10-15 13:40:20.018 5646-6403/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e03e00]. 2019-10-15 13:40:20.020 5646-5646/org.linphone.debug I/Linphone: MSAudio_stream_iterate[0x86694b80], local statistics available: Local current jitter buffer size: 40.1ms 2019-10-15 13:40:20.021 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:20.214 5646-6403/org.linphone.debug I/Linphone: [OpenSLES] sound/wall clock skew is average=-5.51769 ms 2019-10-15 13:40:20.572 5646-5646/org.linphone.debug I/Linphone: MSAudio stream [0x86694b80]: receiving RTCP SR 2019-10-15 13:40:20.572 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x8c2c78e0][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 19.9ms RT propagation : 5.8ms 2019-10-15 13:40:20.574 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:20.575 5646-6403/org.linphone.debug I/Linphone: jitter buffer stable: target-size: 39.999999 ms, effective-size: 40.068027 (min: 40 nom: 60, max: 500) 2019-10-15 13:40:20.576 5646-6403/org.linphone.debug I/Linphone: jitter buffer rls stats: count=1998, clockrate=8000, offset=-12.7579 clock_ratio=0.999723, capped_offset=148 capped_clock_ratio=0.999723, max_ts_deviation=102.000000 prev_max_ts_deviation=22.000000, deviation=21, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.001965 2019-10-15 13:40:20.679 5646-6419/org.linphone.debug I/Linphone: video processing delay = { min=0ms, mean=0.0ms, max=0ms } (RtpSession=0x86e04c00) 2019-10-15 13:40:20.774 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=71.17,u=80.03], video=[d=0.00,u=521.01,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=1.23,u=1.50], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:20.774 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=9.48508 video=9.90458 text=0 2019-10-15 13:40:20.905 5646-6419/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e04c00]. 2019-10-15 13:40:20.915 5646-5646/org.linphone.debug I/Linphone: MSVideo_stream_iterate[0x867fc000], local statistics available: Local current jitter buffer size: 0.0ms 2019-10-15 13:40:20.916 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:21.374 5646-6419/org.linphone.debug I/Linphone: Captured mean fps=13.982913, expected=15.000000 2019-10-15 13:40:21.782 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=75.50,u=80.11], video=[d=0.00,u=508.15,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.01,u=0.00], video=[d=0.00,u=1.31], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:21.783 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=11.538 video=7.38982 text=0 2019-10-15 13:40:22.730 5646-5646/org.linphone.debug I/Linphone: MSVideo stream [0x867fc000]: receiving RTCP RR 2019-10-15 13:40:22.731 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=0.000000 ms, rt_prop=0.004837 sec 2019-10-15 13:40:22.731 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: everything is fine. 2019-10-15 13:40:22.731 5646-5646/org.linphone.debug I/Linphone: MSBitrateController: current state is Init 2019-10-15 13:40:22.731 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x86742d20][video], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 0.0ms RT propagation : 4.8ms 2019-10-15 13:40:22.732 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:22.786 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=74.18,u=79.89], video=[d=0.00,u=507.21,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=2.07,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:22.786 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=11.074 video=6.41521 text=0 2019-10-15 13:40:23.781 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=76.03,u=80.17], video=[d=0.00,u=640.26,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.01,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:23.781 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=11.9423 video=7.67823 text=0 2019-10-15 13:40:24.774 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=71.36,u=79.85], video=[d=0.00,u=548.83,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-10-15 13:40:24.774 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=13.1907 video=11.2566 text=0 2019-10-15 13:40:25.175 5646-6403/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e03e00]. 2019-10-15 13:40:25.184 5646-5646/org.linphone.debug I/Linphone: MSAudio_stream_iterate[0x86694b80], local statistics available: Local current jitter buffer size: 40.0ms 2019-10-15 13:40:25.185 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:25.214 5646-6403/org.linphone.debug I/Linphone: [OpenSLES] sound/wall clock skew is average=-5.32798 ms 2019-10-15 13:40:25.325 5646-6419/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e04c00]. 2019-10-15 13:40:25.337 5646-5646/org.linphone.debug I/Linphone: MSVideo_stream_iterate[0x867fc000], local statistics available: Local current jitter buffer size: 0.0ms 2019-10-15 13:40:25.338 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:25.575 5646-6403/org.linphone.debug I/Linphone: jitter buffer stable: target-size: 39.999999 ms, effective-size: 40.000000 (min: 40 nom: 60, max: 500) 2019-10-15 13:40:25.575 5646-6403/org.linphone.debug I/Linphone: jitter buffer rls stats: count=2248, clockrate=8000, offset=-17.7257 clock_ratio=0.999768, capped_offset=143 capped_clock_ratio=0.999768, max_ts_deviation=22.000000 prev_max_ts_deviation=21.000000, deviation=21, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.001751 2019-10-15 13:40:25.587 5646-5646/org.linphone.debug I/Linphone: MSAudio stream [0x86694b80]: receiving RTCP SR 2019-10-15 13:40:25.587 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x8c2c78e0][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 20.0ms RT propagation : 5.8ms 2019-10-15 13:40:25.588 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:25.674 5646-6419/org.linphone.debug I/Linphone: video processing delay = { min=0ms, mean=0.0ms, max=0ms } (RtpSession=0x86e04c00) 2019-10-15 13:40:25.789 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=72.98,u=80.08], video=[d=0.00,u=514.33,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=1.14,u=1.51], video=[d=0.00,u=1.31], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:25.790 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=12.229 video=9.29685 text=0 2019-10-15 13:40:26.405 5646-6419/org.linphone.debug I/Linphone: Captured mean fps=15.543431, expected=15.000000 2019-10-15 13:40:26.778 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=77.51,u=79.85], video=[d=0.00,u=476.86,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.01,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:26.778 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=12.6351 video=6.06235 text=0 2019-10-15 13:40:27.726 5646-5646/org.linphone.debug I/Linphone: MSVideo stream [0x867fc000]: receiving RTCP RR 2019-10-15 13:40:27.726 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=0.000000 ms, rt_prop=0.004517 sec 2019-10-15 13:40:27.726 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: everything is fine. 2019-10-15 13:40:27.726 5646-5646/org.linphone.debug I/Linphone: MSBitrateController: current state is Init 2019-10-15 13:40:27.726 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x86742d20][video], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 0.0ms RT propagation : 4.5ms 2019-10-15 13:40:27.727 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:27.766 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=74.38,u=80.11], video=[d=0.00,u=512.34,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=2.31,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:27.766 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=10.8477 video=11.2207 text=0 2019-10-15 13:40:28.790 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=72.72,u=80.05], video=[d=0.00,u=493.68,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.01,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:28.790 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=11.6367 video=5.6871 text=0 2019-10-15 13:40:29.360 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: keep alive sent to [UDP://192.168.1.181:5060] 2019-10-15 13:40:29.768 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=73.51,u=80.00], video=[d=0.00,u=533.49,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-10-15 13:40:29.768 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=9.61176 video=9.72521 text=0 2019-10-15 13:40:30.215 5646-6403/org.linphone.debug I/Linphone: [OpenSLES] sound/wall clock skew is average=-4.77694 ms 2019-10-15 13:40:30.548 5646-6419/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e04c00]. 2019-10-15 13:40:30.557 5646-5646/org.linphone.debug I/Linphone: MSVideo_stream_iterate[0x867fc000], local statistics available: Local current jitter buffer size: 0.0ms 2019-10-15 13:40:30.557 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:30.564 5646-6403/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e03e00]. 2019-10-15 13:40:30.575 5646-6403/org.linphone.debug I/Linphone: jitter buffer stable: target-size: 39.999999 ms, effective-size: 40.148701 (min: 40 nom: 60, max: 500) 2019-10-15 13:40:30.575 5646-6403/org.linphone.debug I/Linphone: jitter buffer rls stats: count=2498, clockrate=8000, offset=-22.2337 clock_ratio=0.999804, capped_offset=138 capped_clock_ratio=0.999804, max_ts_deviation=101.000000 prev_max_ts_deviation=22.000000, deviation=21, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.001578 2019-10-15 13:40:30.585 5646-5646/org.linphone.debug I/Linphone: MSAudio stream [0x86694b80]: receiving RTCP SR 2019-10-15 13:40:30.585 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x8c2c78e0][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 19.9ms RT propagation : 6.2ms 2019-10-15 13:40:30.585 5646-5646/org.linphone.debug I/Linphone: MSAudio_stream_iterate[0x86694b80], local statistics available: Local current jitter buffer size: 40.1ms 2019-10-15 13:40:30.586 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:30.586 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:30.674 5646-6419/org.linphone.debug I/Linphone: video processing delay = { min=0ms, mean=0.0ms, max=0ms } (RtpSession=0x86e04c00) 2019-10-15 13:40:30.778 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=77.43,u=79.92], video=[d=0.00,u=727.67,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=1.21,u=0.00], video=[d=0.00,u=1.31], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:30.778 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=10.9743 video=7.5177 text=0 2019-10-15 13:40:31.292 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0xb4a] 2019-10-15 13:40:31.292 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_acquire(): cast long of wakelock 2890 2019-10-15 13:40:31.292 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: starting recv background task with id=[b4a]. 2019-10-15 13:40:31.293 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: received [443] new bytes from [UDP://192.168.1.181:5060]: 2019-10-15 13:40:31.293 5646-5646/org.linphone.debug I/Linphone: OPTIONS sip:0131@192.168.1.142:57541;transport=udp SIP/2.0 2019-10-15 13:40:31.293 5646-5646/org.linphone.debug I/Linphone: Via: SIP/2.0/UDP 192.168.1.181:5060;rport;branch=z9hG4bKPj408cec36-032d-4866-bd11-0da8ddeca701 2019-10-15 13:40:31.293 5646-5646/org.linphone.debug I/Linphone: From: sip:0131@192.168.1.181;tag=5692fdfa-9763-471e-8860-c0623d20c725 2019-10-15 13:40:31.293 5646-5646/org.linphone.debug I/Linphone: To: sip:0131@192.168.1.142 2019-10-15 13:40:31.293 5646-5646/org.linphone.debug I/Linphone: Contact: sip:0131@192.168.1.181:5060 2019-10-15 13:40:31.293 5646-5646/org.linphone.debug I/Linphone: Call-ID: f0555c79-c34c-4aa1-aa44-6b681122d90f 2019-10-15 13:40:31.293 5646-5646/org.linphone.debug I/Linphone: CSeq: 28399 OPTIONS 2019-10-15 13:40:31.293 5646-5646/org.linphone.debug I/Linphone: Supported: path 2019-10-15 13:40:31.293 5646-5646/org.linphone.debug I/Linphone: Max-Forwards: 70 2019-10-15 13:40:31.293 5646-5646/org.linphone.debug I/Linphone: User-Agent: IPPBX 2019-10-15 13:40:31.293 5646-5646/org.linphone.debug I/Linphone: Content-Length: 0 2019-10-15 13:40:31.312 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200] [443] bytes parsed 2019-10-15 13:40:31.313 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: message sent to [UDP://192.168.1.181:5060], size: [295] bytes SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.1.181:5060;rport;branch=z9hG4bKPj408cec36-032d-4866-bd11-0da8ddeca701 From: sip:0131@192.168.1.181;tag=5692fdfa-9763-471e-8860-c0623d20c725 To: sip:0131@192.168.1.142;tag=d0eGB Call-ID: f0555c79-c34c-4aa1-aa44-6b681122d90f CSeq: 28399 OPTIONS 2019-10-15 13:40:31.314 5646-5646/org.linphone.debug I/Linphone: channel [0x8c325200]: ending recv background task with id=[b4a]. 2019-10-15 13:40:31.316 5646-5646/org.linphone.debug I/Linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0xb4a] 2019-10-15 13:40:31.434 5646-6419/org.linphone.debug I/Linphone: Captured mean fps=14.257344, expected=15.000000 2019-10-15 13:40:31.769 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=73.19,u=80.12], video=[d=0.00,u=535.31,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.01,u=1.51], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:31.769 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=10.5111 video=6.3506 text=0 2019-10-15 13:40:32.739 5646-5646/org.linphone.debug I/Linphone: MSVideo stream [0x867fc000]: receiving RTCP RR 2019-10-15 13:40:32.739 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=0.000000 ms, rt_prop=0.003510 sec 2019-10-15 13:40:32.740 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: everything is fine. 2019-10-15 13:40:32.740 5646-5646/org.linphone.debug I/Linphone: MSBitrateController: current state is Init 2019-10-15 13:40:32.740 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x86742d20][video], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 0.0ms RT propagation : 3.5ms 2019-10-15 13:40:32.741 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:32.782 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=75.92,u=79.96], video=[d=0.00,u=622.13,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=2.13,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:32.782 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=13.5761 video=7.54747 text=0 2019-10-15 13:40:33.314 5646-6419/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e04c00]. 2019-10-15 13:40:33.325 5646-5646/org.linphone.debug I/Linphone: MSVideo_stream_iterate[0x867fc000], local statistics available: Local current jitter buffer size: 0.0ms 2019-10-15 13:40:33.326 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:33.774 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=71.39,u=79.95], video=[d=0.00,u=564.70,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.01,u=1.30], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:33.774 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=12.2221 video=9.52419 text=0 2019-10-15 13:40:34.767 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=74.08,u=79.84], video=[d=0.00,u=537.49,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-10-15 13:40:34.767 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=9.14047 video=7.42518 text=0 2019-10-15 13:40:35.214 5646-6403/org.linphone.debug I/Linphone: [OpenSLES] sound/wall clock skew is average=-4.88573 ms 2019-10-15 13:40:35.566 5646-5646/org.linphone.debug I/Linphone: MSAudio stream [0x86694b80]: receiving RTCP SR 2019-10-15 13:40:35.566 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x8c2c78e0][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 19.9ms RT propagation : 5.4ms 2019-10-15 13:40:35.567 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:35.575 5646-6403/org.linphone.debug I/Linphone: jitter buffer stable: target-size: 39.999999 ms, effective-size: 40.148701 (min: 40 nom: 60, max: 500) 2019-10-15 13:40:35.576 5646-6403/org.linphone.debug I/Linphone: jitter buffer rls stats: count=2748, clockrate=8000, offset=-26.1062 clock_ratio=0.999832, capped_offset=134 capped_clock_ratio=0.999831, max_ts_deviation=21.000000 prev_max_ts_deviation=101.000000, deviation=21, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.001437 2019-10-15 13:40:35.675 5646-6419/org.linphone.debug I/Linphone: video processing delay = { min=0ms, mean=0.0ms, max=0ms } (RtpSession=0x86e04c00) 2019-10-15 13:40:35.784 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=75.19,u=80.00], video=[d=0.00,u=549.94,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=1.17,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:35.784 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=12.1076 video=9.20134 text=0 2019-10-15 13:40:36.494 5646-6419/org.linphone.debug I/Linphone: Captured mean fps=14.647026, expected=15.000000 2019-10-15 13:40:36.564 5646-6403/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e03e00]. 2019-10-15 13:40:36.568 5646-5646/org.linphone.debug I/Linphone: MSAudio_stream_iterate[0x86694b80], local statistics available: Local current jitter buffer size: 40.0ms 2019-10-15 13:40:36.568 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:36.772 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=72.05,u=80.17], video=[d=0.00,u=565.40,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.01,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:36.773 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=11.7501 video=9.30613 text=0 2019-10-15 13:40:37.729 5646-5646/org.linphone.debug I/Linphone: MSVideo stream [0x867fc000]: receiving RTCP RR 2019-10-15 13:40:37.730 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=0.000000 ms, rt_prop=0.002930 sec 2019-10-15 13:40:37.730 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: everything is fine. 2019-10-15 13:40:37.730 5646-5646/org.linphone.debug I/Linphone: MSBitrateController: current state is Init 2019-10-15 13:40:37.730 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x86742d20][video], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 0.0ms RT propagation : 2.9ms 2019-10-15 13:40:37.731 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:37.797 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=77.94,u=79.85], video=[d=0.00,u=540.40,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.50], video=[d=1.97,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:37.797 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=10.4318 video=6.09185 text=0 2019-10-15 13:40:38.315 5646-6419/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e04c00]. 2019-10-15 13:40:38.333 5646-5646/org.linphone.debug I/Linphone: MSVideo_stream_iterate[0x867fc000], local statistics available: Local current jitter buffer size: 0.0ms 2019-10-15 13:40:38.334 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:38.773 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=71.74,u=80.13], video=[d=0.00,u=620.12,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.01,u=1.31], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:38.773 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=10.0057 video=9.01647 text=0 2019-10-15 13:40:39.765 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=74.77,u=79.97], video=[d=0.00,u=953.17,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-10-15 13:40:39.765 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=10.0596 video=13.6702 text=0 2019-10-15 13:40:40.214 5646-6403/org.linphone.debug I/Linphone: [OpenSLES] sound/wall clock skew is average=-4.73241 ms 2019-10-15 13:40:40.574 5646-5646/org.linphone.debug I/Linphone: MSAudio stream [0x86694b80]: receiving RTCP SR 2019-10-15 13:40:40.574 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x8c2c78e0][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 19.9ms RT propagation : 5.9ms 2019-10-15 13:40:40.575 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:40.575 5646-6403/org.linphone.debug I/Linphone: jitter buffer stable: target-size: 39.999999 ms, effective-size: 40.000000 (min: 40 nom: 60, max: 500) 2019-10-15 13:40:40.576 5646-6403/org.linphone.debug I/Linphone: jitter buffer rls stats: count=2998, clockrate=8000, offset=-29.5841 clock_ratio=0.999854, capped_offset=131 capped_clock_ratio=0.999854, max_ts_deviation=101.000000 prev_max_ts_deviation=21.000000, deviation=19, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.001318 2019-10-15 13:40:40.674 5646-6419/org.linphone.debug I/Linphone: video processing delay = { min=0ms, mean=0.0ms, max=0ms } (RtpSession=0x86e04c00) 2019-10-15 13:40:40.766 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=74.30,u=79.96], video=[d=0.00,u=768.20,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=1.29,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:40.767 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=8.99036 video=6.1988 text=0 2019-10-15 13:40:41.554 5646-6419/org.linphone.debug I/Linphone: Captured mean fps=14.826181, expected=15.000000 2019-10-15 13:40:41.579 5646-6403/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e03e00]. 2019-10-15 13:40:41.587 5646-5646/org.linphone.debug I/Linphone: MSAudio_stream_iterate[0x86694b80], local statistics available: Local current jitter buffer size: 40.1ms 2019-10-15 13:40:41.588 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:41.765 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=74.73,u=80.01], video=[d=0.00,u=598.25,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.01,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:41.766 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=9.86184 video=8.89056 text=0 2019-10-15 13:40:42.144 5646-6419/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e04c00]. 2019-10-15 13:40:42.159 5646-5646/org.linphone.debug I/Linphone: MSVideo_stream_iterate[0x867fc000], local statistics available: Local current jitter buffer size: 0.0ms 2019-10-15 13:40:42.160 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:42.728 5646-5646/org.linphone.debug I/Linphone: MSVideo stream [0x867fc000]: receiving RTCP RR 2019-10-15 13:40:42.729 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=0.000000 ms, rt_prop=0.002747 sec 2019-10-15 13:40:42.729 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: everything is fine. 2019-10-15 13:40:42.729 5646-5646/org.linphone.debug I/Linphone: MSBitrateController: current state is Init 2019-10-15 13:40:42.729 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x86742d20][video], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 0.0ms RT propagation : 2.7ms 2019-10-15 13:40:42.730 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:42.778 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=77.74,u=80.02], video=[d=0.00,u=540.33,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.50], video=[d=2.18,u=1.31], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:42.778 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=9.53211 video=9.47931 text=0 2019-10-15 13:40:43.770 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=72.90,u=79.94], video=[d=0.00,u=666.89,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.01,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:43.771 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=10.51 video=4.28299 text=0 2019-10-15 13:40:44.781 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=76.35,u=80.03], video=[d=0.00,u=611.84,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-10-15 13:40:44.782 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=11.5286 video=9.77212 text=0 2019-10-15 13:40:45.214 5646-6403/org.linphone.debug I/Linphone: [OpenSLES] sound/wall clock skew is average=-4.53571 ms 2019-10-15 13:40:45.575 5646-6403/org.linphone.debug I/Linphone: jitter buffer stable: target-size: 39.999999 ms, effective-size: 40.079681 (min: 40 nom: 60, max: 500) 2019-10-15 13:40:45.576 5646-6403/org.linphone.debug I/Linphone: jitter buffer rls stats: count=3248, clockrate=8000, offset=-32.6689 clock_ratio=0.999873, capped_offset=128 capped_clock_ratio=0.999873, max_ts_deviation=99.000000 prev_max_ts_deviation=20.000000, deviation=19, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.001218 2019-10-15 13:40:45.580 5646-5646/org.linphone.debug I/Linphone: MSAudio stream [0x86694b80]: receiving RTCP SR 2019-10-15 13:40:45.580 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x8c2c78e0][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 20.0ms RT propagation : 5.9ms 2019-10-15 13:40:45.581 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:45.675 5646-6419/org.linphone.debug I/Linphone: video processing delay = { min=0ms, mean=0.0ms, max=0ms } (RtpSession=0x86e04c00) 2019-10-15 13:40:45.770 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=72.93,u=79.97], video=[d=0.00,u=551.85,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=1.26,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:45.771 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=11.0921 video=8.96735 text=0 2019-10-15 13:40:46.564 5646-6419/org.linphone.debug I/Linphone: Captured mean fps=14.677595, expected=15.000000 2019-10-15 13:40:46.770 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=73.10,u=79.76], video=[d=0.00,u=578.69,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.01,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:46.770 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=11.3807 video=10.2832 text=0 2019-10-15 13:40:47.664 5646-6403/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e03e00]. 2019-10-15 13:40:47.681 5646-5646/org.linphone.debug I/Linphone: MSAudio_stream_iterate[0x86694b80], local statistics available: Local current jitter buffer size: 40.1ms 2019-10-15 13:40:47.682 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:47.726 5646-5646/org.linphone.debug I/Linphone: MSVideo stream [0x867fc000]: receiving RTCP RR 2019-10-15 13:40:47.726 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=0.000000 ms, rt_prop=0.002396 sec 2019-10-15 13:40:47.726 5646-5646/org.linphone.debug I/Linphone: MSSimpleQosAnalyzer: everything is fine. 2019-10-15 13:40:47.726 5646-5646/org.linphone.debug I/Linphone: MSBitrateController: current state is Init 2019-10-15 13:40:47.726 5646-5646/org.linphone.debug I/Linphone: MSQualityIndicator[0x86742d20][video], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 0.0ms RT propagation : 2.4ms 2019-10-15 13:40:47.727 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:47.782 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=76.16,u=80.25], video=[d=0.00,u=624.66,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=2.14,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:47.782 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=12.5445 video=8.66764 text=0 2019-10-15 13:40:47.954 5646-6419/org.linphone.debug I/Linphone: Sending RTCP SR compound message on session [0x86e04c00]. 2019-10-15 13:40:47.958 5646-5646/org.linphone.debug I/Linphone: MSVideo_stream_iterate[0x867fc000], local statistics available: Local current jitter buffer size: 0.0ms 2019-10-15 13:40:47.959 5646-5646/org.linphone.debug I/Linphone: Linphone core [0xa4a36100] notified [call_stats_updated] 2019-10-15 13:40:48.768 5646-5646/org.linphone.debug I/Linphone: Bandwidth usage for CallSession [0x83e4748c]: RTP audio=[d=73.91,u=80.00], video=[d=0.00,u=546.33,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.50], video=[d=0.01,u=1.31], text=[d=0.00,u=0.00] kbits/sec 2019-10-15 13:40:48.768 5646-5646/org.linphone.debug I/Linphone: Thread processing load: audio=10.4407 video=6.13116 text=0