message: 2016-09-07 11:14:11:004 Call [048F8730] freed. message: 2016-09-07 11:14:13:364 Changing [client] [BYE] transaction [062EDFE8], from state [COMPLETED] to [TERMINATED] message: 2016-09-07 11:14:13:364 Client BYE transaction [062EDFE8] terminated message: 2016-09-07 11:14:16:824 channel [04944278]: keep alive sent to [UDP://vc.dfn.de:5060] message: 2016-09-07 11:14:22:750 New LinphoneCall [048F8730] initialized (LinphoneCore version: 3.10.2) message: 2016-09-07 11:14:22:750 Call 048F8730: moving from state LinphoneCallIdle to LinphoneCallOutgoingInit message: 2016-09-07 11:14:22:760 Call 048F8730 is locking sound resources. message: 2016-09-07 11:14:22:760 Linphone core [00631998] notifying [call_state_changed] message: 2016-09-07 11:14:22:760 Cannot determine multicast role for stream type [audio] on call [048F8730] message: 2016-09-07 11:14:22:760 check OS support for qwave.lib error: 2016-09-07 11:14:22:760 QOSAddSocketToFlow failed to add a flow with error 87 message: 2016-09-07 11:14:22:760 RtpSession bound to [0.0.0.0] ports [7078] [7079] message: 2016-09-07 11:14:22:760 rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION message: 2016-09-07 11:14:22:760 Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80 message: 2016-09-07 11:14:22:760 Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32 message: 2016-09-07 11:14:22:760 Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80 message: 2016-09-07 11:14:22:760 Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32 message: 2016-09-07 11:14:22:760 Creating ZRTP engine on rtp session [00658F40] ssrc 0xc60ae1f message: 2016-09-07 11:14:22:760 Setting DSCP to 46 for MSAudio stream. message: 2016-09-07 11:14:22:760 Equalizer location: hp message: 2016-09-07 11:14:22:770 cannot set noise gate mode to [0] because no volume send message: 2016-09-07 11:14:22:770 Cannot determine multicast role for stream type [video] on call [048F8730] message: 2016-09-07 11:14:22:770 check OS support for qwave.lib error: 2016-09-07 11:14:22:770 QOSAddSocketToFlow failed to add a flow with error 87 message: 2016-09-07 11:14:22:770 RtpSession bound to [0.0.0.0] ports [9078] [9079] message: 2016-09-07 11:14:22:770 rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION message: 2016-09-07 11:14:22:770 Initializing multistream ZRTP context on rtp session [0641C008] ssrc 0x2d19322c message: 2016-09-07 11:14:22:770 Setting DSCP to 0 for MSVideo stream. message: 2016-09-07 11:14:22:770 Cannot determine multicast role for stream type [text] on call [048F8730] message: 2016-09-07 11:14:22:770 check OS support for qwave.lib error: 2016-09-07 11:14:22:770 QOSAddSocketToFlow failed to add a flow with error 87 message: 2016-09-07 11:14:22:770 RtpSession bound to [0.0.0.0] ports [11078] [11079] message: 2016-09-07 11:14:22:770 rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION message: 2016-09-07 11:14:22:770 Contact has not been fixed stack will do message: 2016-09-07 11:14:22:770 Don't put text stream on local offer for call [048F8730] message: 2016-09-07 11:14:22:770 ms_filter_link: MSRtpRecv:048FFF60,0-->MSVoidSink:048FFFA0,0 message: 2016-09-07 11:14:22:770 win32 timer resolution set to 2 ms message: 2016-09-07 11:14:22:770 [sip:address@hidden:5060] calling [sip:address@hidden on op [048CB950] message: 2016-09-07 11:14:22:770 Changing [client] [INVITE] transaction [00612C48], from state [INIT] to [CALLING] message: 2016-09-07 11:14:22:770 channel [04944278]: message sent to [UDP://vc.dfn.de:5060], size: [1315] bytes INVITE sip:address@hidden SIP/2.0Via: SIP/2.0/UDP 10.35.34.97:5060;branch=z9hG4bK.JDUwiHgv1;rportFrom: ;tag=4gNXjKVb4To: sip:address@hidden: 20 INVITECall-ID: GeyDGySXXNMax-Forwards: 70Supported: replaces, outboundAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATEContent-Type: application/sdpContent-Length: 740Contact: ;+sip.instance=""User-Agent: Linphone/3.10.2 (belle-sip/1.5.0)v=0o=linphone 1968 2157 IN IP4 10.35.34.97s=Talkc=IN IP4 10.35.34.97t=0 0a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metricsm=audio 7078 RTP/AVP 9 96 97 98 0 8 101 99 100a=rtpmap:96 opus/48000/2a=fmtp:96 useinbandfec=1a=rtpmap:97 speex/16000a=fmtp:97 vbr=ona=rtpmap:98 speex/8000a=fmtp:98 vbr=ona=rtpmap:101 telephone-event/8000a=rtpmap:99 telephone-event/48000a=rtpmap:100 telephone-event/16000a=rtcp-fb:* trr-int 5m=video 9078 RTP/AVP 96 97a=rtpmap:96 H264/90000a=fmtp:96 profile-level-id=42801Fa=rtpmap:97 VP8/90000a=rtcp-fb:* trr-int 5a=rtcp-fb:96 nack plia=rtcp-fb:96 ccm fira=rtcp-fb:97 nack plia=rtcp-fb:97 nack slia=rtcp-fb:97 ack rpsia=rtcp-fb:97 ccm fir message: 2016-09-07 11:14:22:770 Call 048F8730: moving from state LinphoneCallOutgoingInit to LinphoneCallOutgoingProgress message: 2016-09-07 11:14:22:770 Call 048F8730 is locking sound resources. message: 2016-09-07 11:14:22:770 Linphone core [00631998] notifying [call_state_changed] message: 2016-09-07 11:14:22:802 channel [04944278]: received [306] new bytes from [UDP://vc.dfn.de:5060]: SIP/2.0 100 TryingVia: SIP/2.0/UDP 10.35.34.97:5060;branch=z9hG4bK.JDUwiHgv1;received=10.35.34.97;rport=5060;ingress-zone=DefaultZoneCall-ID: GeyDGySXXNCSeq: 20 INVITEFrom: ;tag=4gNXjKVb4To: Server: TANDBERG/4132 (X8.7.2)Content-Length: 0 message: 2016-09-07 11:14:22:802 channel [04944278] [306] bytes parsed message: 2016-09-07 11:14:22:802 Found transaction matching response. message: 2016-09-07 11:14:22:802 Changing [client] [INVITE] transaction [00612C48], from state [CALLING] to [PROCEEDING] message: 2016-09-07 11:14:22:802 op [048CB950] : set_or_update_dialog() current=[00000000] new=[00000000] message: 2016-09-07 11:14:22:802 Op [048CB950] receiving call response [100], dialog is [00000000] in state [BELLE_SIP_DIALOG_NULL] message: 2016-09-07 11:14:22:923 channel [04944278]: received [437] new bytes from [UDP://vc.dfn.de:5060]: SIP/2.0 180 RingingVia: SIP/2.0/UDP 10.35.34.97:5060;branch=z9hG4bK.JDUwiHgv1;received=10.35.34.97;rport=5060;ingress-zone=DefaultZoneCall-ID: GeyDGySXXNCSeq: 20 INVITEFrom: ;tag=4gNXjKVb4To: ;tag=49bf02ce9f0bd8e1Record-Route: Server: TANDBERG/4132 (X8.7.2)Content-Length: 0 message: 2016-09-07 11:14:22:925 channel [04944278] [437] bytes parsed message: 2016-09-07 11:14:22:925 Found transaction matching response. message: 2016-09-07 11:14:22:925 New client dialog [005F4528] , local tag [4gNXjKVb4], remote tag [49bf02ce9f0bd8e1] message: 2016-09-07 11:14:22:925 Dialog [005F4528]: now updated by transaction [00612C48]. message: 2016-09-07 11:14:22:925 op [048CB950] : set_or_update_dialog() current=[00000000] new=[005F4528] message: 2016-09-07 11:14:22:925 Op [048CB950] receiving call response [180], dialog is [005F4528] in state [BELLE_SIP_DIALOG_EARLY] message: 2016-09-07 11:14:22:925 Doing SDP offer/answer process of type outgoing message: 2016-09-07 11:14:22:925 ms_filter_unlink: MSRtpRecv:048FFF60,0-->MSVoidSink:048FFFA0,0 message: 2016-09-07 11:14:22:928 speex_lib_ctl init with neon ? 0 message: 2016-09-07 11:14:22:930 Configuring resampler input with rate=[8000], nchannels=[1] message: 2016-09-07 11:14:22:930 MSFilePlayer[048FFF60]: ./share/sounds/linphone/ringback.wav opened: rate=8000,channel=1 message: 2016-09-07 11:14:22:930 configuring resampler output to rate=[48000], nchannels=[2] message: 2016-09-07 11:14:22:930 ms_filter_link: MSFilePlayer:048FFF60,0-->MSDtmfGen:048FFFA0,0 message: 2016-09-07 11:14:22:931 ms_filter_link: MSDtmfGen:048FFFA0,0-->MSResample:048FFE60,0 message: 2016-09-07 11:14:22:931 ms_filter_link: MSResample:048FFE60,0-->MSWASAPIWrite:048FFD60,0 message: 2016-09-07 11:14:22:931 Initializing speex resampler in mode [voip] message: 2016-09-07 11:14:22:931 win32 timer resolution set to 2 ms message: 2016-09-07 11:14:22:951 MSWASAPI audio output interface buffer size: 12000 message: 2016-09-07 11:14:22:951 Remote ringing... message: 2016-09-07 11:14:22:951 Call 048F8730: moving from state LinphoneCallOutgoingProgress to LinphoneCallOutgoingRinging message: 2016-09-07 11:14:22:951 Call 048F8730 is locking sound resources. message: 2016-09-07 11:14:22:953 Linphone core [00631998] notifying [call_state_changed] message: 2016-09-07 11:14:23:258 channel [04944278]: received [884] new bytes from [UDP://vc.dfn.de:5060]: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.35.34.97:5060;branch=z9hG4bK.JDUwiHgv1;received=10.35.34.97;rport=5060;ingress-zone=DefaultZoneCall-ID: GeyDGySXXNCSeq: 20 INVITEContact: ;isfocusFrom: ;tag=4gNXjKVb4To: ;tag=49bf02ce9f0bd8e1Record-Route: Allow: INVITE,ACK,BYE,CANCEL,INFO,REFER,NOTIFYServer: TANDBERG/4132 (X8.7.2)Content-Type: application/sdpContent-Length: 328v=0o=tandberg 0 1 IN IP4 127.0.0.1s=-c=IN IP4 194.95.240.240t=0 0m=audio 51986 RTP/AVP 9 8 0 101a=rtpmap:9 G722/8000a=rtpmap:8 PCMA/8000a=rtpmap:0 PCMU/8000a=rtpmap:101 telephone-event/8000a=fmtp:101 0-15a=sendrecva=rtcp:51987m=video 51988 RTP/AVP 96a=rtpmap:96 H264/90000a=sendrecva=rtcp:51989 message: 2016-09-07 11:14:23:261 channel [04944278] [556] bytes parsed message: 2016-09-07 11:14:23:261 channel [04944278] read [328] bytes of body from [vc.dfn.de:5060] message: 2016-09-07 11:14:23:261 Found transaction matching response. message: 2016-09-07 11:14:23:261 Changing [client] [INVITE] transaction [00612C48], from state [PROCEEDING] to [ACCEPTED] message: 2016-09-07 11:14:23:261 Dialog [005F4528]: now updated by transaction [00612C48]. message: 2016-09-07 11:14:23:261 op [048CB950] : set_or_update_dialog() current=[005F4528] new=[005F4528] message: 2016-09-07 11:14:23:261 Op [048CB950] receiving call response [200], dialog is [005F4528] in state [BELLE_SIP_DIALOG_CONFIRMED] message: 2016-09-07 11:14:23:263 Found payload G722/8000 fmtp= message: 2016-09-07 11:14:23:263 Found payload PCMA/8000 fmtp= message: 2016-09-07 11:14:23:263 Found payload PCMU/8000 fmtp= message: 2016-09-07 11:14:23:263 Found payload telephone-event/8000 fmtp=0-15 message: 2016-09-07 11:14:23:264 Found payload H264/90000 fmtp= message: 2016-09-07 11:14:23:264 Doing SDP offer/answer process of type outgoing message: 2016-09-07 11:14:23:264 Processing for stream 0 message: 2016-09-07 11:14:23:264 Adding opus/48000 for compatibility, just in case. message: 2016-09-07 11:14:23:264 Adding speex/16000 for compatibility, just in case. message: 2016-09-07 11:14:23:264 Adding speex/8000 for compatibility, just in case. message: 2016-09-07 11:14:23:264 Adding telephone-event/48000 for compatibility, just in case. message: 2016-09-07 11:14:23:264 Adding telephone-event/16000 for compatibility, just in case. message: 2016-09-07 11:14:23:264 Processing for stream 1 message: 2016-09-07 11:14:23:264 Adding VP8/90000 for compatibility, just in case. message: 2016-09-07 11:14:23:264 Computing branch id z9hG4bK.ht~a3NEnp for message sent statelessly message: 2016-09-07 11:14:23:264 channel [04944278]: message sent to [UDP://vc.dfn.de:5060], size: [405] bytes ACK sip:address@hidden SIP/2.0Via: SIP/2.0/UDP 10.35.34.97:5060;rport;branch=z9hG4bK.ht~a3NEnpFrom: ;tag=4gNXjKVb4To: ;tag=49bf02ce9f0bd8e1CSeq: 20 ACKCall-ID: GeyDGySXXNMax-Forwards: 70Route: User-Agent: Linphone/3.10.2 (belle-sip/1.5.0) message: 2016-09-07 11:14:23:264 Call 048F8730: moving from state LinphoneCallOutgoingRinging to LinphoneCallConnected message: 2016-09-07 11:14:23:266 StatusIcon: blinking set to FALSE message: 2016-09-07 11:14:23:266 Call 048F8730 is locking sound resources. message: 2016-09-07 11:14:23:266 Linphone core [00631998] notifying [call_state_changed] message: 2016-09-07 11:14:23:268 ms_filter_unlink: MSFilePlayer:048FFF60,0-->MSDtmfGen:048FFFA0,0 message: 2016-09-07 11:14:23:268 ms_filter_unlink: MSDtmfGen:048FFFA0,0-->MSResample:048FFE60,0 message: 2016-09-07 11:14:23:268 ms_filter_unlink: MSResample:048FFE60,0-->MSWASAPIWrite:048FFD60,0 message: 2016-09-07 11:14:23:271 Ring MSTicker thread exiting message: 2016-09-07 11:14:23:271 linphone_call_start_media_streams() call=[048F8730] local upload_bandwidth=[0] kbit/s; local download_bandwidth=[0] kbit/s message: 2016-09-07 11:14:23:271 Audio bandwidth for this call is 80 message: 2016-09-07 11:14:23:271 RtpSession [00658F40] sending to rtp 194.95.240.240:51986 rtcp 194.95.240.240:51987 message: 2016-09-07 11:14:23:271 Stun packet sent for session [00658F40] message: 2016-09-07 11:14:23:271 Stun packet sent on rtcp for session [00658F40] message: 2016-09-07 11:14:23:273 check OS support for qwave.lib message: 2016-09-07 11:14:23:276 speex_lib_ctl init with neon ? 0 message: 2016-09-07 11:14:23:276 speex_lib_ctl init with neon ? 0 message: 2016-09-07 11:14:23:276 Setting echo canceller delay with value provided by soundcard: 0 ms message: 2016-09-07 11:14:23:276 MKVRecorder: initialisation message: 2016-09-07 11:14:23:278 speex_lib_ctl init with neon ? 0 message: 2016-09-07 11:14:23:278 Configuring av recorder with audio format type=audio;encoding=opus;rate=48000;channels=1;fmtp='' message: 2016-09-07 11:14:23:278 MKVRecorder: set pin #1 format. type=audio;encoding=opus;rate=48000;channels=1;fmtp='' message: 2016-09-07 11:14:23:278 target bitrate not set for stream [00621488] using payload's bitrate is 80000 message: 2016-09-07 11:14:23:278 Setting audio encoder network bitrate to [80000] on stream [00621488] message: 2016-09-07 11:14:23:278 Equalizer rate: 8000, selecting 128 steps for FFT message: 2016-09-07 11:14:23:278 Equalizer rate: 8000, selecting 128 steps for FFT message: 2016-09-07 11:14:23:278 Equalizer rate: 16000, selecting 256 steps for FFT message: 2016-09-07 11:14:23:278 Equalizer rate: 16000, selecting 256 steps for FFT error: 2016-09-07 11:14:23:278 Filter MSG722Enc does not implement the MS_FILTER_GET_NCHANNELS method message: 2016-09-07 11:14:23:278 configuring MSWASAPIRead:048FF5E0-->MSG722Enc:049003A0 from rate [48000] to rate [16000] and from channel [1] to channel [1] error: 2016-09-07 11:14:23:278 Filter MSG722Dec does not implement the MS_FILTER_GET_NCHANNELS method message: 2016-09-07 11:14:23:278 configuring MSG722Dec:04900320-->MSWASAPIWrite:049002E0 from rate [16000] to rate [48000] and from channel [1] to channel [2] message: 2016-09-07 11:14:23:278 ms_filter_link: MSWASAPIRead:048FF5E0,0-->MSResample:062A5F30,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSResample:062A5F30,0-->MSEqualizer:062A65F0,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSEqualizer:062A65F0,0-->MSSpeexEC:0065EEA0,1 message: 2016-09-07 11:14:23:278 ms_filter_link: MSSpeexEC:0065EEA0,1-->MSVolume:049003E0,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSVolume:049003E0,0-->MSAudioMixer:062A5770,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSAudioMixer:062A5770,0-->MSG722Enc:049003A0,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSG722Enc:049003A0,0-->MSRtpSend:0065EE20,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSRtpRecv:048FF720,0-->MSG722Dec:04900320,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSG722Dec:04900320,0-->MSDtmfGen:048FF6E0,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSDtmfGen:048FF6E0,0-->MSVolume:04900360,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSVolume:04900360,0-->MSTee:062A62F0,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSTee:062A62F0,0-->MSEqualizer:062A60B0,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSEqualizer:062A60B0,0-->MSAudioMixer:062A5B30,0 message: 2016-09-07 11:14:23:278 speex_lib_ctl init with neon ? 0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSFilePlayer:062A5EF0,0-->MSResample:062A62B0,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSResample:062A62B0,0-->MSAudioMixer:062A5B30,1 message: 2016-09-07 11:14:23:278 ms_filter_link: MSAudioMixer:062A5B30,0-->MSSpeexEC:0065EEA0,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSSpeexEC:0065EEA0,0-->MSResample:062A5E30,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSResample:062A5E30,0-->MSWASAPIWrite:049002E0,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSAudioMixer:062A57B0,1-->MSResample:062A6670,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSResample:062A6670,0-->MSOpusEnc:062A6030,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSOpusEnc:062A6030,0-->MSMKVRecorder:062A5BF0,1 message: 2016-09-07 11:14:23:278 ms_filter_link: MSItcSource:062A5DB0,0-->MSMKVRecorder:062A5BF0,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSAudioMixer:062A5770,1-->MSAudioMixer:062A57B0,0 message: 2016-09-07 11:14:23:278 ms_filter_link: MSTee:062A62F0,1-->MSAudioMixer:062A57B0,1 message: 2016-09-07 11:14:23:278 ms_filter_link: MSAudioMixer:062A57B0,0-->MSFileRec:062A6230,0 message: 2016-09-07 11:14:23:299 MSWASAPI audio input interface buffer size: 12000 message: 2016-09-07 11:14:23:299 Initializing speex resampler in mode [voip] message: 2016-09-07 11:14:23:299 Initializing speex echo canceler with framesize=128, filterlength=4000, delay_samples=0 error: 2016-09-07 11:14:23:299 Could not decode base64 message: 2016-09-07 11:14:23:300 Initializing speex resampler in mode [voip] message: 2016-09-07 11:14:23:300 MSOpusEnc: codec bitrate set to [30000] with ptime [20] message: 2016-09-07 11:14:23:300 Setting opus codec bitrate to [30000] from network bitrate [46000] with ptime [20] message: 2016-09-07 11:14:23:300 Initializing speex resampler in mode [voip] message: 2016-09-07 11:14:23:300 Initializing speex resampler in mode [voip] message: 2016-09-07 11:14:23:313 MSWASAPI audio output interface buffer size: 12000 message: 2016-09-07 11:14:23:313 Filter MSRtpRecv is already being scheduled; nothing to do. error: 2016-09-07 11:14:23:313 no such method on filter MSWASAPIWrite, fid=16394 method index=2 message: 2016-09-07 11:14:23:313 MSVolume set gain to [0,000000 db], [1,000000] linear message: 2016-09-07 11:14:23:313 audio_stream_link_video() connecting itc filters message: 2016-09-07 11:14:23:313 Setting video size 640x480 on stream [006330D8] message: 2016-09-07 11:14:23:313 linphone_call_start_video_stream lc rotation:0 message: 2016-09-07 11:14:23:314 RtpSession [0641C008] sending to rtp 194.95.240.240:51988 rtcp 194.95.240.240:51989 message: 2016-09-07 11:14:23:314 Using permissive algorithm message: 2016-09-07 11:14:23:314 check OS support for qwave.lib message: 2016-09-07 11:14:23:314 Stun packet sent for session [00658F40] message: 2016-09-07 11:14:23:314 Stun packet sent on rtcp for session [00658F40] message: 2016-09-07 11:14:23:314 Stun packet sent for session [0641C008] message: 2016-09-07 11:14:23:314 Stun packet sent on rtcp for session [0641C008] message: 2016-09-07 11:14:23:315 Using the 'custom' video preset non-tagged message: 2016-09-07 11:14:23:315 target bitrate not set for stream [006330D8] using payload's bitrate is 1500000 message: 2016-09-07 11:14:23:315 Limiting bitrate of video encoder to 1500000 bits/s for stream [006330D8] message: 2016-09-07 11:14:23:315 OpenH264 encoder: Video configuration set: bitrate=1500000bits/s, fps=30,000000, vsize=640x480 message: 2016-09-07 11:14:23:315 MSOpenH264Enc: disabling AVPF warning: 2016-09-07 11:14:23:324 Getting reference signal but no echo to synchronize on. warning: 2016-09-07 11:14:23:324 Not enough ref samples, using zeroes message: 2016-09-07 11:14:23:324 MSAudioMixer [062A5770] is entering bypass mode. message: 2016-09-07 11:14:23:334 Samples are back. message: 2016-09-07 11:14:23:343 Seeing format 640x480 YUY2 message: 2016-09-07 11:14:23:344 Seeing format 160x120 YUY2 message: 2016-09-07 11:14:23:344 Seeing format 176x144 YUY2 message: 2016-09-07 11:14:23:344 Seeing format 320x240 YUY2 message: 2016-09-07 11:14:23:345 Seeing format 352x288 YUY2 message: 2016-09-07 11:14:23:345 Seeing format 640x360 YUY2 message: 2016-09-07 11:14:23:345 Seeing format 640x400 YUY2 message: 2016-09-07 11:14:23:345 Seeing format 768x480 YUY2 message: 2016-09-07 11:14:23:346 Seeing format 800x456 YUY2 message: 2016-09-07 11:14:23:346 Seeing format 800x504 YUY2 message: 2016-09-07 11:14:23:346 Seeing format 800x600 YUY2 message: 2016-09-07 11:14:23:346 Seeing format 864x480 YUY2 message: 2016-09-07 11:14:23:348 Seeing format 960x720 YUY2 message: 2016-09-07 11:14:23:348 Seeing format 1280x720 YUY2 message: 2016-09-07 11:14:23:348 Seeing format 1280x800 YUY2 message: 2016-09-07 11:14:23:349 Seeing format 1600x904 YUY2 message: 2016-09-07 11:14:23:349 Seeing format 1600x1000 YUY2 message: 2016-09-07 11:14:23:349 Seeing format 1600x1200 YUY2 message: 2016-09-07 11:14:23:349 Seeing format 640x480 MJPG message: 2016-09-07 11:14:23:350 Seeing format 160x120 MJPG message: 2016-09-07 11:14:23:350 Seeing format 176x144 MJPG message: 2016-09-07 11:14:23:350 Seeing format 320x240 MJPG message: 2016-09-07 11:14:23:350 Seeing format 352x288 MJPG message: 2016-09-07 11:14:23:351 Seeing format 640x360 MJPG message: 2016-09-07 11:14:23:351 Seeing format 640x400 MJPG message: 2016-09-07 11:14:23:351 Seeing format 768x480 MJPG message: 2016-09-07 11:14:23:351 Seeing format 800x456 MJPG message: 2016-09-07 11:14:23:353 Seeing format 800x504 MJPG message: 2016-09-07 11:14:23:353 Seeing format 800x600 MJPG message: 2016-09-07 11:14:23:353 Seeing format 864x480 MJPG message: 2016-09-07 11:14:23:353 Seeing format 960x720 MJPG message: 2016-09-07 11:14:23:354 Seeing format 1280x720 MJPG warning: 2016-09-07 11:14:23:354 Not enough ref samples, using zeroes message: 2016-09-07 11:14:23:354 Seeing format 1280x800 MJPG message: 2016-09-07 11:14:23:354 Seeing format 1600x904 MJPG message: 2016-09-07 11:14:23:355 Seeing format 1600x1000 MJPG message: 2016-09-07 11:14:23:355 Seeing format 1600x1200 MJPG message: 2016-09-07 11:14:23:356 Seeing format 640x480 YUY2 message: 2016-09-07 11:14:23:356 Seeing format 160x120 YUY2 message: 2016-09-07 11:14:23:356 Seeing format 176x144 YUY2 message: 2016-09-07 11:14:23:356 Seeing format 320x240 YUY2 message: 2016-09-07 11:14:23:356 Seeing format 352x288 YUY2 message: 2016-09-07 11:14:23:356 Seeing format 640x360 YUY2 message: 2016-09-07 11:14:23:356 Seeing format 640x400 YUY2 message: 2016-09-07 11:14:23:356 Seeing format 768x480 YUY2 message: 2016-09-07 11:14:23:358 Seeing format 800x456 YUY2 message: 2016-09-07 11:14:23:358 Seeing format 800x504 YUY2 message: 2016-09-07 11:14:23:358 Seeing format 800x600 YUY2 message: 2016-09-07 11:14:23:358 Seeing format 864x480 YUY2 message: 2016-09-07 11:14:23:358 Seeing format 960x720 YUY2 message: 2016-09-07 11:14:23:358 Seeing format 1280x720 YUY2 message: 2016-09-07 11:14:23:358 Seeing format 1280x800 YUY2 message: 2016-09-07 11:14:23:358 Seeing format 1600x904 YUY2 message: 2016-09-07 11:14:23:359 Seeing format 1600x1000 YUY2 message: 2016-09-07 11:14:23:359 Seeing format 1600x1200 YUY2 message: 2016-09-07 11:14:23:359 Seeing format 640x480 MJPG message: 2016-09-07 11:14:23:359 Seeing format 160x120 MJPG message: 2016-09-07 11:14:23:359 Seeing format 176x144 MJPG message: 2016-09-07 11:14:23:359 Seeing format 320x240 MJPG message: 2016-09-07 11:14:23:359 Seeing format 352x288 MJPG message: 2016-09-07 11:14:23:360 Seeing format 640x360 MJPG message: 2016-09-07 11:14:23:360 Seeing format 640x400 MJPG message: 2016-09-07 11:14:23:360 Seeing format 768x480 MJPG message: 2016-09-07 11:14:23:360 Seeing format 800x456 MJPG message: 2016-09-07 11:14:23:360 Seeing format 800x504 MJPG message: 2016-09-07 11:14:23:360 Seeing format 800x600 MJPG message: 2016-09-07 11:14:23:360 Seeing format 864x480 MJPG message: 2016-09-07 11:14:23:360 Seeing format 960x720 MJPG message: 2016-09-07 11:14:23:361 Seeing format 1280x720 MJPG message: 2016-09-07 11:14:23:361 Seeing format 1280x800 MJPG message: 2016-09-07 11:14:23:361 Seeing format 1600x904 MJPG message: 2016-09-07 11:14:23:361 Seeing format 1600x1000 MJPG message: 2016-09-07 11:14:23:361 Seeing format 1600x1200 MJPG message: 2016-09-07 11:14:23:361 Best camera format is YUY2 640x480 message: 2016-09-07 11:14:23:364 Samples are back. message: 2016-09-07 11:14:23:433 Directshow graph is now ready to run. message: 2016-09-07 11:14:23:433 Output video size adjusted to match camera resolution (640x480) message: 2016-09-07 11:14:23:433 OpenH264 encoder: Video configuration set: bitrate=1500000bits/s, fps=30,000000, vsize=640x480 message: 2016-09-07 11:14:23:433 Setting sent vsize=640x480, fps=30,000000 message: 2016-09-07 11:14:23:433 OpenH264 encoder: Video configuration set: bitrate=1500000bits/s, fps=30,000000, vsize=640x480 message: 2016-09-07 11:14:23:433 ms_filter_link: MSDsCap:062A6470,0-->MSPixConv:062A5DF0,0 message: 2016-09-07 11:14:23:433 ms_filter_link: MSPixConv:062A5DF0,0-->MSTee:062A6630,0 message: 2016-09-07 11:14:23:433 ms_filter_link: MSTee:062A6630,0-->MSSizeConv:062A64F0,0 message: 2016-09-07 11:14:23:433 ms_filter_link: MSSizeConv:062A64F0,0-->MSOpenH264Enc:062A59F0,0 message: 2016-09-07 11:14:23:433 ms_filter_link: MSOpenH264Enc:062A59F0,0-->MSRtpSend:0065EFE0,0 message: 2016-09-07 11:14:23:433 ms_filter_link: MSTee:062A6630,2-->MSJpegWriter:062A61F0,0 error: 2016-09-07 11:14:23:433 no such method on filter MSOpenH264Dec, fid=16389 method index=7 error: 2016-09-07 11:14:23:434 no such method on filter MSOpenH264Dec, fid=16389 method index=8 message: 2016-09-07 11:14:23:434 ms_filter_link: MSRtpRecv:062A5AB0,0-->MSTee:0065EC60,0 message: 2016-09-07 11:14:23:434 ms_filter_link: MSTee:0065EC60,1-->MSItcSink:0065EF20,0 message: 2016-09-07 11:14:23:434 Video ITC source updated. message: 2016-09-07 11:14:23:434 Configuring av recorder with video format type=video;encoding=H264;vsize=0x0;fps=0.000000;fmtp='' message: 2016-09-07 11:14:23:434 MKVRecorder: set pin #0 format. type=video;encoding=H264;vsize=0x0;fps=0.000000;fmtp='' message: 2016-09-07 11:14:23:434 configure_itc(): format set to type=video;encoding=H264;vsize=0x0;fps=0.000000;fmtp='' message: 2016-09-07 11:14:23:434 ms_filter_link: MSTee:0065EC60,0-->MSOpenH264Dec:062A5CF0,0 message: 2016-09-07 11:14:23:434 ms_filter_link: MSOpenH264Dec:062A5CF0,0-->MSTee:062A63F0,0 message: 2016-09-07 11:14:23:434 ms_filter_link: MSTee:062A63F0,1-->MSJpegWriter:062A6070,0 message: 2016-09-07 11:14:23:434 ms_filter_link: MSTee:062A63F0,0-->MSDrawDibDisplay:062A64B0,0 message: 2016-09-07 11:14:23:434 ms_filter_link: MSTee:062A6630,1-->MSDrawDibDisplay:062A64B0,1 message: 2016-09-07 11:14:23:435 MSVideo MSTicker priority left to normal. message: 2016-09-07 11:14:23:464 Graph started message: 2016-09-07 11:14:23:471 Filter MSRtpRecv is already being scheduled; nothing to do. message: 2016-09-07 11:14:23:472 LinphoneCall[048F8730] : payload type 9 G722/8000 fmtp= added to frozen list. message: 2016-09-07 11:14:23:472 LinphoneCall[048F8730] : payload type 8 PCMA/8000 fmtp= added to frozen list. message: 2016-09-07 11:14:23:472 LinphoneCall[048F8730] : payload type 0 PCMU/8000 fmtp= added to frozen list. message: 2016-09-07 11:14:23:472 LinphoneCall[048F8730] : payload type 101 telephone-event/8000 fmtp= added to frozen list. message: 2016-09-07 11:14:23:472 LinphoneCall[048F8730] : payload type 96 opus/48000 fmtp=useinbandfec=1 added to frozen list. message: 2016-09-07 11:14:23:472 LinphoneCall[048F8730] : payload type 97 speex/16000 fmtp=vbr=on added to frozen list. message: 2016-09-07 11:14:23:472 LinphoneCall[048F8730] : payload type 98 speex/8000 fmtp=vbr=on added to frozen list. message: 2016-09-07 11:14:23:472 LinphoneCall[048F8730] : payload type 99 telephone-event/48000 fmtp= added to frozen list. message: 2016-09-07 11:14:23:472 LinphoneCall[048F8730] : payload type 100 telephone-event/16000 fmtp= added to frozen list. message: 2016-09-07 11:14:23:472 LinphoneCall[048F8730] : payload type 96 H264/90000 fmtp=profile-level-id=42801F added to frozen list. message: 2016-09-07 11:14:23:472 LinphoneCall[048F8730] : payload type 97 VP8/90000 fmtp= added to frozen list. message: 2016-09-07 11:14:23:472 audio stream index found: 0, updating main audio stream index message: 2016-09-07 11:14:23:472 video stream index found: 1, updating main video stream index message: 2016-09-07 11:14:23:472 Call 048F8730: moving from state LinphoneCallConnected to LinphoneCallStreamsRunning message: 2016-09-07 11:14:23:474 Stun packet sent for session [0641C008] message: 2016-09-07 11:14:23:474 Stun packet sent on rtcp for session [0641C008] message: 2016-09-07 11:14:23:486 Linphone core [00631998] notifying [call_state_changed] message: 2016-09-07 11:14:23:486 Garbage collecting unowned object of type belle_sip_hop_t message: 2016-09-07 11:14:23:486 Garbage collecting unowned object of type belle_sdp_session_description_t message: 2016-09-07 11:14:23:664 Video ITC source updated. message: 2016-09-07 11:14:23:664 Configuring av recorder with video format type=video;encoding=H264;vsize=176x144;fps=0.000000;fmtp='' message: 2016-09-07 11:14:23:664 MKVRecorder: set pin #0 format. type=video;encoding=H264;vsize=176x144;fps=0.000000;fmtp='' message: 2016-09-07 11:14:23:664 configure_itc(): format set to type=video;encoding=H264;vsize=176x144;fps=0.000000;fmtp='' message: 2016-09-07 11:14:23:666 Detected video resolution changed to 176x144 warning: 2016-09-07 11:14:23:681 Unhandled event 131072 message: 2016-09-07 11:14:23:681 First video frame decoded successfully message: 2016-09-07 11:14:23:801 Bandwidth usage for call [048F8730]: RTP audio=[d= 19,6,u= 71,5], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 1,6], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:23:801 Thread processing load: audio=7,095126 video=1,548187 text=0,000000 message: 2016-09-07 11:14:23:812 Stun packet sent on rtcp for session [00658F40] message: 2016-09-07 11:14:23:880 MSOpenH264Encoder: sending IDR message: 2016-09-07 11:14:23:951 channel [04944278]: received [778] new bytes from [UDP://vc.dfn.de:5060]: INFO sip:address@hidden;transport=udp SIP/2.0Via: SIP/2.0/UDP 194.95.240.240:5060;egress-zone=DefaultZone;branch=z9hG4bK27e45b698c01fa90da53f5add2a8a9f0507295;proxy-call-id=7e2d4653-aa03-460f-8142-b1279145d1a9;rportVia: SIP/2.0/TCP 127.0.0.1;branch=z9hG4bK3abf77db45de23156cc879a3374772f1507294Call-ID: GeyDGySXXNCSeq: 1 INFOFrom: ;tag=49bf02ce9f0bd8e1To: ;tag=4gNXjKVb4Max-Forwards: 69User-Agent: TANDBERG/4132 (X8.7.2)X-TAATag: 56d58eb2-4781-48aa-bae4-13d3e862d344Content-Type: application/media_control+xmlContent-Length: 168 message: 2016-09-07 11:14:23:958 channel [04944278] [610] bytes parsed message: 2016-09-07 11:14:23:958 channel [04944278] read [168] bytes of body from [vc.dfn.de:5060] message: 2016-09-07 11:14:23:958 Changing [server] [INFO] transaction [048FF118], from state [INIT] to [TRYING] message: 2016-09-07 11:14:23:958 Receiving VFU request on op [048CB950] message: 2016-09-07 11:14:23:958 Changing [server] [INFO] transaction [048FF118], from state [TRYING] to [COMPLETED] message: 2016-09-07 11:14:23:959 channel [04944278]: message sent to [UDP://vc.dfn.de:5060], size: [483] bytes SIP/2.0 200 OkVia: SIP/2.0/UDP 194.95.240.240:5060;egress-zone=DefaultZone;branch=z9hG4bK27e45b698c01fa90da53f5add2a8a9f0507295;proxy-call-id=7e2d4653-aa03-460f-8142-b1279145d1a9;rportVia: SIP/2.0/TCP 127.0.0.1;branch=z9hG4bK3abf77db45de23156cc879a3374772f1507294From: ;tag=49bf02ce9f0bd8e1To: ;tag=4gNXjKVb4Call-ID: GeyDGySXXNCSeq: 1 INFOUser-Agent: Linphone/3.10.2 (belle-sip/1.5.0)Supported: replaces, outbound message: 2016-09-07 11:14:23:959 Dialog [005F4528]: now updated by transaction [048FF118]. message: 2016-09-07 11:14:23:974 Stun packet sent on rtcp for session [0641C008] message: 2016-09-07 11:14:24:011 channel [04944278]: received [778] new bytes from [UDP://vc.dfn.de:5060]: INFO sip:address@hidden;transport=udp SIP/2.0Via: SIP/2.0/UDP 194.95.240.240:5060;egress-zone=DefaultZone;branch=z9hG4bK02702f3980804dcfad3c47329c6ee5e0507297;proxy-call-id=7e2d4653-aa03-460f-8142-b1279145d1a9;rportVia: SIP/2.0/TCP 127.0.0.1;branch=z9hG4bK870e7f7ea9c95aca24de24224d97bfea507296Call-ID: GeyDGySXXNCSeq: 2 INFOFrom: ;tag=49bf02ce9f0bd8e1To: ;tag=4gNXjKVb4Max-Forwards: 69User-Agent: TANDBERG/4132 (X8.7.2)X-TAATag: 56d58eb2-4781-48aa-bae4-13d3e862d344Content-Type: application/media_control+xmlContent-Length: 168 message: 2016-09-07 11:14:24:017 channel [04944278] [610] bytes parsed message: 2016-09-07 11:14:24:017 channel [04944278] read [168] bytes of body from [vc.dfn.de:5060] message: 2016-09-07 11:14:24:017 Changing [server] [INFO] transaction [048FF0C0], from state [INIT] to [TRYING] message: 2016-09-07 11:14:24:017 Receiving VFU request on op [048CB950] message: 2016-09-07 11:14:24:017 Changing [server] [INFO] transaction [048FF0C0], from state [TRYING] to [COMPLETED] message: 2016-09-07 11:14:24:018 channel [04944278]: message sent to [UDP://vc.dfn.de:5060], size: [483] bytes SIP/2.0 200 OkVia: SIP/2.0/UDP 194.95.240.240:5060;egress-zone=DefaultZone;branch=z9hG4bK02702f3980804dcfad3c47329c6ee5e0507297;proxy-call-id=7e2d4653-aa03-460f-8142-b1279145d1a9;rportVia: SIP/2.0/TCP 127.0.0.1;branch=z9hG4bK870e7f7ea9c95aca24de24224d97bfea507296From: ;tag=49bf02ce9f0bd8e1To: ;tag=4gNXjKVb4Call-ID: GeyDGySXXNCSeq: 2 INFOUser-Agent: Linphone/3.10.2 (belle-sip/1.5.0)Supported: replaces, outbound message: 2016-09-07 11:14:24:018 Dialog [005F4528]: now updated by transaction [048FF0C0]. message: 2016-09-07 11:14:24:018 MSVideo stream [006330D8]: receiving RTCP RR message: 2016-09-07 11:14:24:251 MSAudio stream [00621488]: receiving RTCP SR message: 2016-09-07 11:14:24:281 channel [04944278]: received [778] new bytes from [UDP://vc.dfn.de:5060]: INFO sip:address@hidden;transport=udp SIP/2.0Via: SIP/2.0/UDP 194.95.240.240:5060;egress-zone=DefaultZone;branch=z9hG4bKe847f872f8e966f419daba15d0c97272507299;proxy-call-id=7e2d4653-aa03-460f-8142-b1279145d1a9;rportVia: SIP/2.0/TCP 127.0.0.1;branch=z9hG4bKd4e37f504cdf77a0015a2156957e3f09507298Call-ID: GeyDGySXXNCSeq: 3 INFOFrom: ;tag=49bf02ce9f0bd8e1To: ;tag=4gNXjKVb4Max-Forwards: 69User-Agent: TANDBERG/4132 (X8.7.2)X-TAATag: 56d58eb2-4781-48aa-bae4-13d3e862d344Content-Type: application/media_control+xmlContent-Length: 168 message: 2016-09-07 11:14:24:287 channel [04944278] [610] bytes parsed message: 2016-09-07 11:14:24:287 channel [04944278] read [168] bytes of body from [vc.dfn.de:5060] message: 2016-09-07 11:14:24:287 Changing [server] [INFO] transaction [048FEF08], from state [INIT] to [TRYING] message: 2016-09-07 11:14:24:287 Receiving VFU request on op [048CB950] message: 2016-09-07 11:14:24:287 Changing [server] [INFO] transaction [048FEF08], from state [TRYING] to [COMPLETED] message: 2016-09-07 11:14:24:287 channel [04944278]: message sent to [UDP://vc.dfn.de:5060], size: [483] bytes SIP/2.0 200 OkVia: SIP/2.0/UDP 194.95.240.240:5060;egress-zone=DefaultZone;branch=z9hG4bKe847f872f8e966f419daba15d0c97272507299;proxy-call-id=7e2d4653-aa03-460f-8142-b1279145d1a9;rportVia: SIP/2.0/TCP 127.0.0.1;branch=z9hG4bKd4e37f504cdf77a0015a2156957e3f09507298From: ;tag=49bf02ce9f0bd8e1To: ;tag=4gNXjKVb4Call-ID: GeyDGySXXNCSeq: 3 INFOUser-Agent: Linphone/3.10.2 (belle-sip/1.5.0)Supported: replaces, outbound message: 2016-09-07 11:14:24:287 Dialog [005F4528]: now updated by transaction [048FEF08]. message: 2016-09-07 11:14:24:312 MSAudioMixer [062A5B30] is entering bypass mode. message: 2016-09-07 11:14:24:832 Bandwidth usage for call [048F8730]: RTP audio=[d= 78,4,u= 80,0], video=[d= 12,8,u=589,7], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,5,u= 0,4], video=[d= 1,3,u= 1,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:24:832 Thread processing load: audio=0,147937 video=6,712293 text=0,000000 message: 2016-09-07 11:14:24:906 MSOpenH264Encoder: sending IDR message: 2016-09-07 11:14:25:006 Sending RTCP SR compound message on session [0641C008]. message: 2016-09-07 11:14:25:011 MSVideo_stream_iterate[006330D8], local statistics available: Local current jitter buffer size: 25,4ms message: 2016-09-07 11:14:25:132 Sending RTCP SR compound message on session [00658F40]. message: 2016-09-07 11:14:25:138 MSAudio_stream_iterate[00621488], local statistics available: Local current jitter buffer size: 58,0ms warning: 2016-09-07 11:14:25:502 Measured and target fps significantly different (23,455536<->30,000000), updating encoder. message: 2016-09-07 11:14:25:502 OpenH264 encoder applyBitrate done message: 2016-09-07 11:14:25:821 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 17,7,u=1285,1], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 1,2], video=[d= 0,0,u= 1,3], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:25:821 Thread processing load: audio=2,473477 video=15,165160 text=0,000000 message: 2016-09-07 11:14:26:803 channel [04944278]: keep alive sent to [UDP://vc.dfn.de:5060] message: 2016-09-07 11:14:26:803 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 79,9], video=[d= 65,5,u=1541,4], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:26:803 Thread processing load: audio=5,020779 video=11,349777 text=0,000000 message: 2016-09-07 11:14:27:394 Sending RTCP SR compound message on session [0641C008]. message: 2016-09-07 11:14:27:403 MSVideo_stream_iterate[006330D8], local statistics available: Local current jitter buffer size: 12,8ms message: 2016-09-07 11:14:27:512 Sending RTCP SR compound message on session [00658F40]. message: 2016-09-07 11:14:27:523 MSAudio_stream_iterate[00621488], local statistics available: Local current jitter buffer size: 59,8ms message: 2016-09-07 11:14:27:823 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 74,1,u=1542,3], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 1,2], video=[d= 0,0,u= 1,3], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:27:823 Thread processing load: audio=4,748173 video=16,234959 text=0,000000 message: 2016-09-07 11:14:28:273 MSVideo stream [006330D8]: receiving RTCP SR message: 2016-09-07 11:14:28:273 MSSimpleQosAnalyzer: lost_percentage=0,000000, int_jitter=50,000000 ms, rt_prop=0,028046 sec message: 2016-09-07 11:14:28:273 MSSimpleQosAnalyzer: everything is fine. message: 2016-09-07 11:14:28:273 MSBitrateController: current state is Init message: 2016-09-07 11:14:28:273 MSQualityIndicator[00607D88][video], remote statistics available: Loss rate : 0,0% Inter-arrival jitter: 50,0ms RT propagation : 28,0ms message: 2016-09-07 11:14:28:738 OpenH264 decoder: FPS=12,810788 message: 2016-09-07 11:14:28:738 OpenH264 decoder: Frame size: 176x144 message: 2016-09-07 11:14:28:813 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 66,5,u=1476,5], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,7,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:28:813 Thread processing load: audio=4,975456 video=18,187258 text=0,000000 message: 2016-09-07 11:14:28:904 msdscap: fps=24,372463 message: 2016-09-07 11:14:29:263 MSAudio stream [00621488]: receiving RTCP SR message: 2016-09-07 11:14:29:803 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 69,2,u=1626,9], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,7,u= 0,0], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:29:803 Thread processing load: audio=5,412833 video=15,232794 text=0,000000 message: 2016-09-07 11:14:29:954 Sending RTCP SR compound message on session [0641C008]. message: 2016-09-07 11:14:29:983 MSVideo_stream_iterate[006330D8], local statistics available: Local current jitter buffer size: 22,6ms message: 2016-09-07 11:14:30:072 Sending RTCP SR compound message on session [00658F40]. message: 2016-09-07 11:14:30:073 MSAudio_stream_iterate[00621488], local statistics available: Local current jitter buffer size: 60,0ms message: 2016-09-07 11:14:30:823 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 70,4,u=1658,4], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 1,2], video=[d= 0,0,u= 1,2], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:30:823 Thread processing load: audio=4,752715 video=15,505949 text=0,000000 message: 2016-09-07 11:14:31:063 Changing [server] [INFO] transaction [048FE9E0], from state [COMPLETED] to [TERMINATED] message: 2016-09-07 11:14:31:063 Server INFO transaction [048FE9E0] terminated message: 2016-09-07 11:14:31:063 Destroying op [048CB2D0] of type [SalOpCall] message: 2016-09-07 11:14:31:063 Transaction [062EDFE8] deleted message: 2016-09-07 11:14:31:063 Transaction [048FE9E0] deleted message: 2016-09-07 11:14:31:813 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 65,5,u=1486,1], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:31:813 Thread processing load: audio=5,628730 video=17,648966 text=0,000000 message: 2016-09-07 11:14:32:694 Sending RTCP SR compound message on session [0641C008]. message: 2016-09-07 11:14:32:713 MSVideo_stream_iterate[006330D8], local statistics available: Local current jitter buffer size: 50,0ms message: 2016-09-07 11:14:32:805 Bandwidth usage for call [048F8730]: RTP audio=[d= 78,4,u= 80,0], video=[d= 69,0,u=1665,5], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:32:805 Thread processing load: audio=5,567387 video=14,373595 text=0,000000 message: 2016-09-07 11:14:32:812 Sending RTCP SR compound message on session [00658F40]. message: 2016-09-07 11:14:32:835 MSAudio_stream_iterate[00621488], local statistics available: Local current jitter buffer size: 59,7ms message: 2016-09-07 11:14:33:265 MSVideo stream [006330D8]: receiving RTCP SR message: 2016-09-07 11:14:33:265 MSSimpleQosAnalyzer: lost_percentage=0,000000, int_jitter=50,000000 ms, rt_prop=0,033173 sec message: 2016-09-07 11:14:33:265 MSSimpleQosAnalyzer: everything is fine. message: 2016-09-07 11:14:33:265 MSBitrateController: current state is Init message: 2016-09-07 11:14:33:265 MSQualityIndicator[00607D88][video], remote statistics available: Loss rate : 0,0% Inter-arrival jitter: 50,0ms RT propagation : 33,2ms warning: 2016-09-07 11:14:33:302 echo canceller: we are accumulating too much reference signal, need to throw out 128 samples message: 2016-09-07 11:14:33:764 OpenH264 decoder: FPS=12,992829 message: 2016-09-07 11:14:33:764 OpenH264 decoder: Frame size: 176x144 message: 2016-09-07 11:14:33:805 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,1], video=[d= 65,4,u=1626,8], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 1,2], video=[d= 0,7,u= 1,2], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:33:805 Thread processing load: audio=1,555627 video=10,180465 text=0,000000 message: 2016-09-07 11:14:33:934 msdscap: fps=26,934549 message: 2016-09-07 11:14:34:795 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 79,9], video=[d= 60,6,u=1734,5], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:34:795 Thread processing load: audio=5,129987 video=12,477583 text=0,000000 message: 2016-09-07 11:14:35:254 Sending RTCP SR compound message on session [0641C008]. message: 2016-09-07 11:14:35:275 MSAudio stream [00621488]: receiving RTCP SR message: 2016-09-07 11:14:35:275 MSQualityIndicator[00607DE8][audio], remote statistics available: Loss rate : 0,0% Inter-arrival jitter: 10,6ms RT propagation : 37,7ms message: 2016-09-07 11:14:35:275 MSVideo_stream_iterate[006330D8], local statistics available: Local current jitter buffer size: 39,6ms message: 2016-09-07 11:14:35:372 Sending RTCP SR compound message on session [00658F40]. message: 2016-09-07 11:14:35:395 MSAudio_stream_iterate[00621488], local statistics available: Local current jitter buffer size: 60,0ms message: 2016-09-07 11:14:35:815 Bandwidth usage for call [048F8730]: RTP audio=[d= 78,4,u= 80,0], video=[d= 42,8,u=1504,1], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,7,u= 1,2], video=[d= 0,0,u= 1,3], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:35:815 Thread processing load: audio=5,004547 video=15,317566 text=0,000000 message: 2016-09-07 11:14:35:864 sequence inconsistency detected (diff=1) message: 2016-09-07 11:14:35:924 OpenH264 decoder: [OpenH264] this = 0x062F8B60, Warning:referencing pictures lost due frame gaps exist, prev_frame_num: 131, curr_frame_num: 133 error: 2016-09-07 11:14:35:924 OpenH264 decoder: DecodeFrame2 failed: 0x22 warning: 2016-09-07 11:14:35:935 MS_VIDEO_DECODER_DECODING_ERRORS message: 2016-09-07 11:14:35:935 Request SIP INFO FIR on call [048F8730] message: 2016-09-07 11:14:35:935 Dialog [005F4528]: now updated by transaction [062EDFE8]. message: 2016-09-07 11:14:35:935 Changing [client] [INFO] transaction [062EDFE8], from state [INIT] to [TRYING] message: 2016-09-07 11:14:35:935 channel [04944278]: message sent to [UDP://vc.dfn.de:5060], size: [658] bytes INFO sip:address@hidden SIP/2.0Via: SIP/2.0/UDP 10.35.34.97:5060;branch=z9hG4bK.vNiIUOpBp;rportFrom: ;tag=4gNXjKVb4To: ;tag=49bf02ce9f0bd8e1CSeq: 21 INFOCall-ID: GeyDGySXXNMax-Forwards: 70Content-Type: application/media_control+xmlContent-Length: 185User-Agent: Linphone/3.10.2 (belle-sip/1.5.0)Route: message: 2016-09-07 11:14:35:965 channel [04944278]: received [412] new bytes from [UDP://vc.dfn.de:5060]: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.35.34.97:5060;branch=z9hG4bK.vNiIUOpBp;received=10.35.34.97;rport=5060;ingress-zone=DefaultZoneCall-ID: GeyDGySXXNCSeq: 21 INFOContact: ;isfocusFrom: ;tag=4gNXjKVb4To: ;tag=49bf02ce9f0bd8e1Allow: INVITE,ACK,BYE,CANCEL,INFO,REFER,NOTIFYServer: TANDBERG/4132 (X8.7.2)Content-Length: 0 message: 2016-09-07 11:14:35:967 channel [04944278] [412] bytes parsed message: 2016-09-07 11:14:35:967 Found transaction matching response. message: 2016-09-07 11:14:35:967 Changing [client] [INFO] transaction [062EDFE8], from state [TRYING] to [COMPLETED] message: 2016-09-07 11:14:35:967 Dialog [005F4528]: now updated by transaction [062EDFE8]. message: 2016-09-07 11:14:35:967 op [048CB950] : set_or_update_dialog() current=[005F4528] new=[005F4528] message: 2016-09-07 11:14:35:967 Op [048CB950] receiving call response [200], dialog is [005F4528] in state [BELLE_SIP_DIALOG_CONFIRMED] message: 2016-09-07 11:14:36:094 sequence inconsistency detected (diff=1) error: 2016-09-07 11:14:36:124 OpenH264 decoder: DecodeFrame2 failed: 0x20 message: 2016-09-07 11:14:36:810 MSOpenH264Encoder: sending IDR message: 2016-09-07 11:14:36:813 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 52,9,u=1726,2], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:36:813 Thread processing load: audio=4,976632 video=15,595734 text=0,000000 message: 2016-09-07 11:14:36:843 channel [04944278]: keep alive sent to [UDP://vc.dfn.de:5060] warning: 2016-09-07 11:14:37:594 Measured and target fps significantly different (29,325901<->23,455536), updating encoder. message: 2016-09-07 11:14:37:594 OpenH264 encoder applyBitrate done message: 2016-09-07 11:14:37:804 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 75,5,u=1893,3], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:37:804 Thread processing load: audio=5,546309 video=17,611584 text=0,000000 message: 2016-09-07 11:14:38:037 Sending RTCP SR compound message on session [0641C008]. message: 2016-09-07 11:14:38:045 MSVideo_stream_iterate[006330D8], local statistics available: Local current jitter buffer size: 11,7ms message: 2016-09-07 11:14:38:152 Sending RTCP SR compound message on session [00658F40]. message: 2016-09-07 11:14:38:165 MSAudio_stream_iterate[00621488], local statistics available: Local current jitter buffer size: 59,6ms warning: 2016-09-07 11:14:38:302 echo canceller: we are accumulating too much reference signal, need to throw out 1 samples message: 2016-09-07 11:14:38:799 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 72,8,u=1528,2], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 1,2], video=[d= 0,0,u= 1,2], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:38:799 Thread processing load: audio=5,028749 video=13,332341 text=0,000000 message: 2016-09-07 11:14:38:854 OpenH264 decoder: FPS=12,039270 message: 2016-09-07 11:14:38:854 OpenH264 decoder: Frame size: 176x144 message: 2016-09-07 11:14:38:974 msdscap: fps=29,326920 message: 2016-09-07 11:14:39:802 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 55,3,u=1506,8], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:39:802 Thread processing load: audio=5,028314 video=15,182298 text=0,000000 message: 2016-09-07 11:14:40:206 Sending RTCP SR compound message on session [0641C008]. message: 2016-09-07 11:14:40:225 MSVideo_stream_iterate[006330D8], local statistics available: Local current jitter buffer size: 77,5ms message: 2016-09-07 11:14:40:255 MSAudio stream [00621488]: receiving RTCP SR message: 2016-09-07 11:14:40:255 MSQualityIndicator[00607DE8][audio], remote statistics available: Loss rate : 0,0% Inter-arrival jitter: 9,5ms RT propagation : 35,2ms message: 2016-09-07 11:14:40:313 Sending RTCP SR compound message on session [00658F40]. message: 2016-09-07 11:14:40:319 MSAudio_stream_iterate[00621488], local statistics available: Local current jitter buffer size: 60,0ms message: 2016-09-07 11:14:40:799 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 45,0,u=1313,2], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,7,u= 1,2], video=[d= 0,0,u= 1,2], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:40:799 Thread processing load: audio=5,027431 video=12,482018 text=0,000000 message: 2016-09-07 11:14:40:979 Changing [client] [INFO] transaction [062EDFE8], from state [COMPLETED] to [TERMINATED] message: 2016-09-07 11:14:40:979 Client INFO transaction [062EDFE8] terminated message: 2016-09-07 11:14:41:799 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,1], video=[d= 25,3,u=841,0], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:41:799 Thread processing load: audio=3,743498 video=11,730052 text=0,000000 message: 2016-09-07 11:14:42:796 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 79,9], video=[d= 40,5,u=1362,0], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:42:796 Thread processing load: audio=5,090065 video=13,524101 text=0,000000 message: 2016-09-07 11:14:43:278 MSVideo stream [006330D8]: receiving RTCP SR message: 2016-09-07 11:14:43:278 MSSimpleQosAnalyzer: lost_percentage=0,000000, int_jitter=4,688889 ms, rt_prop=0,030670 sec message: 2016-09-07 11:14:43:278 MSSimpleQosAnalyzer: everything is fine. message: 2016-09-07 11:14:43:278 MSBitrateController: current state is Init message: 2016-09-07 11:14:43:278 MSQualityIndicator[00607D88][video], remote statistics available: Loss rate : 0,0% Inter-arrival jitter: 4,7ms RT propagation : 30,7ms message: 2016-09-07 11:14:43:819 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 57,2,u=1460,5], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,7,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:43:819 Thread processing load: audio=4,968345 video=17,818367 text=0,000000 message: 2016-09-07 11:14:43:880 OpenH264 decoder: FPS=12,264288 message: 2016-09-07 11:14:43:880 OpenH264 decoder: Frame size: 176x144 message: 2016-09-07 11:14:44:004 msdscap: fps=30,049257 message: 2016-09-07 11:14:44:809 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,1], video=[d= 63,6,u=1468,1], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:44:809 Thread processing load: audio=2,089836 video=12,376955 text=0,000000 message: 2016-09-07 11:14:45:802 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 44,5,u=1487,4], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:45:802 Thread processing load: audio=2,118686 video=9,512408 text=0,000000 message: 2016-09-07 11:14:46:237 Sending RTCP SR compound message on session [0641C008]. message: 2016-09-07 11:14:46:255 MSAudio stream [00621488]: receiving RTCP SR message: 2016-09-07 11:14:46:255 MSQualityIndicator[00607DE8][audio], remote statistics available: Loss rate : 1,7% Inter-arrival jitter: 15,1ms RT propagation : 39,2ms message: 2016-09-07 11:14:46:255 MSVideo_stream_iterate[006330D8], local statistics available: Local current jitter buffer size: 45,4ms message: 2016-09-07 11:14:46:352 Sending RTCP SR compound message on session [00658F40]. message: 2016-09-07 11:14:46:375 MSAudio_stream_iterate[00621488], local statistics available: Local current jitter buffer size: 60,0ms message: 2016-09-07 11:14:46:814 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 79,9], video=[d= 37,4,u=1368,3], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,7,u= 1,2], video=[d= 0,0,u= 1,2], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:46:814 Thread processing load: audio=4,799449 video=16,436783 text=0,000000 message: 2016-09-07 11:14:46:874 channel [04944278]: keep alive sent to [UDP://vc.dfn.de:5060] message: 2016-09-07 11:14:46:877 MSOpenH264Encoder: sending IDR message: 2016-09-07 11:14:47:809 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,1], video=[d= 70,5,u=1542,0], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:47:809 Thread processing load: audio=0,530577 video=11,727570 text=0,000000 message: 2016-09-07 11:14:48:292 MSVideo stream [006330D8]: receiving RTCP SR message: 2016-09-07 11:14:48:292 MSSimpleQosAnalyzer: lost_percentage=0,000000, int_jitter=11,355556 ms, rt_prop=0,032303 sec message: 2016-09-07 11:14:48:292 MSSimpleQosAnalyzer: everything is fine. message: 2016-09-07 11:14:48:292 MSBitrateController: current state is Init message: 2016-09-07 11:14:48:292 MSQualityIndicator[00607D88][video], remote statistics available: Loss rate : 0,0% Inter-arrival jitter: 11,4ms RT propagation : 32,3ms message: 2016-09-07 11:14:48:802 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 79,9], video=[d= 69,3,u=1455,5], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,7,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:48:802 Thread processing load: audio=5,025715 video=13,432358 text=0,000000 message: 2016-09-07 11:14:48:914 OpenH264 decoder: FPS=12,409177 message: 2016-09-07 11:14:48:914 OpenH264 decoder: Frame size: 176x144 message: 2016-09-07 11:14:49:034 msdscap: fps=30,653261 message: 2016-09-07 11:14:49:795 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 50,3,u=1474,2], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:49:795 Thread processing load: audio=5,053534 video=16,240074 text=0,000000 message: 2016-09-07 11:14:50:797 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 78,0,u=1362,0], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:50:797 Thread processing load: audio=5,122939 video=14,100482 text=0,000000 message: 2016-09-07 11:14:51:817 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 66,5,u=1492,2], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:51:817 Thread processing load: audio=5,036120 video=14,983601 text=0,000000 message: 2016-09-07 11:14:51:976 Sending RTCP SR compound message on session [0641C008]. message: 2016-09-07 11:14:51:997 MSVideo_stream_iterate[006330D8], local statistics available: Local current jitter buffer size: 31,5ms message: 2016-09-07 11:14:52:092 Sending RTCP SR compound message on session [00658F40]. message: 2016-09-07 11:14:52:117 MSAudio_stream_iterate[00621488], local statistics available: Local current jitter buffer size: 60,0ms message: 2016-09-07 11:14:52:267 MSAudio stream [00621488]: receiving RTCP SR message: 2016-09-07 11:14:52:267 MSQualityIndicator[00607DE8][audio], remote statistics available: Loss rate : 0,0% Inter-arrival jitter: 9,9ms RT propagation : 32,5ms message: 2016-09-07 11:14:52:817 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 53,9,u=1292,0], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,7,u= 1,2], video=[d= 0,0,u= 1,2], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:52:817 Thread processing load: audio=5,211637 video=15,996708 text=0,000000 message: 2016-09-07 11:14:53:297 MSVideo stream [006330D8]: receiving RTCP SR message: 2016-09-07 11:14:53:297 MSSimpleQosAnalyzer: lost_percentage=0,000000, int_jitter=2,888889 ms, rt_prop=0,039017 sec message: 2016-09-07 11:14:53:297 MSSimpleQosAnalyzer: everything is fine. message: 2016-09-07 11:14:53:297 MSBitrateController: current state is Init message: 2016-09-07 11:14:53:297 MSQualityIndicator[00607D88][video], remote statistics available: Loss rate : 0,0% Inter-arrival jitter: 2,9ms RT propagation : 39,0ms message: 2016-09-07 11:14:53:812 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 64,7,u=1376,8], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,7,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:53:812 Thread processing load: audio=5,550478 video=18,785625 text=0,000000 message: 2016-09-07 11:14:53:944 OpenH264 decoder: FPS=12,614829 message: 2016-09-07 11:14:53:944 OpenH264 decoder: Frame size: 176x144 message: 2016-09-07 11:14:54:074 msdscap: fps=25,289036 message: 2016-09-07 11:14:54:804 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 67,7,u=1247,9], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:54:804 Thread processing load: audio=5,091115 video=10,158081 text=0,000000 message: 2016-09-07 11:14:55:285 Changing [client] [INVITE] transaction [00612C48], from state [ACCEPTED] to [TERMINATED] message: 2016-09-07 11:14:55:285 Client INVITE transaction [00612C48] terminated message: 2016-09-07 11:14:55:285 Transaction [00612C48] deleted message: 2016-09-07 11:14:55:795 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,0], video=[d= 61,7,u=1203,1], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 0,0], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:55:795 Thread processing load: audio=5,029830 video=14,658903 text=0,000000 message: 2016-09-07 11:14:55:975 Changing [server] [INFO] transaction [048FF118], from state [COMPLETED] to [TERMINATED] message: 2016-09-07 11:14:55:975 Server INFO transaction [048FF118] terminated message: 2016-09-07 11:14:55:975 Transaction [048FF118] deleted message: 2016-09-07 11:14:56:035 Changing [server] [INFO] transaction [048FF0C0], from state [COMPLETED] to [TERMINATED] message: 2016-09-07 11:14:56:035 Server INFO transaction [048FF0C0] terminated message: 2016-09-07 11:14:56:035 Transaction [048FF0C0] deleted message: 2016-09-07 11:14:56:306 Changing [server] [INFO] transaction [048FEF08], from state [COMPLETED] to [TERMINATED] message: 2016-09-07 11:14:56:306 Server INFO transaction [048FEF08] terminated message: 2016-09-07 11:14:56:306 Transaction [048FEF08] deleted message: 2016-09-07 11:14:56:605 Sending RTCP SR compound message on session [0641C008]. message: 2016-09-07 11:14:56:607 MSVideo_stream_iterate[006330D8], local statistics available: Local current jitter buffer size: 41,0ms message: 2016-09-07 11:14:56:722 Sending RTCP SR compound message on session [00658F40]. message: 2016-09-07 11:14:56:726 MSAudio_stream_iterate[00621488], local statistics available: Local current jitter buffer size: 60,0ms message: 2016-09-07 11:14:56:816 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 80,1], video=[d= 36,5,u=1228,1], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,0,u= 1,2], video=[d= 0,0,u= 0,0], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:56:816 Thread processing load: audio=2,230433 video=7,598514 text=0,000000 message: 2016-09-07 11:14:56:876 channel [04944278]: keep alive sent to [UDP://vc.dfn.de:5060] message: 2016-09-07 11:14:57:259 MSAudio stream [00621488]: receiving RTCP SR message: 2016-09-07 11:14:57:259 MSQualityIndicator[00607DE8][audio], remote statistics available: Loss rate : 0,0% Inter-arrival jitter: 10,0ms RT propagation : 36,2ms message: 2016-09-07 11:14:57:807 Bandwidth usage for call [048F8730]: RTP audio=[d= 80,0,u= 79,9], video=[d= 47,4,u=1312,8], text=[d= 0,0,u= 0,0] kbits/sec RTCP audio=[d= 0,7,u= 0,0], video=[d= 0,0,u= 1,3], text=[d= 0,0,u= 0,0] kbits/sec message: 2016-09-07 11:14:57:807 Thread processing load: audio=4,842460 video=14,951583 text=0,000000 message: 2016-09-07 11:14:58:108 MSOpenH264Encoder: sending IDR message: 2016-09-07 11:14:58:287 MSVideo stream [006330D8]: receiving RTCP SR message: 2016-09-07 11:14:58:287 MSSimpleQosAnalyzer: lost_percentage=0,000000, int_jitter=1,044444 ms, rt_prop=0,031677 sec message: 2016-09-07 11:14:58:287 MSSimpleQosAnalyzer: everything is fine. message: 2016-09-07 11:14:58:287 MSBitrateController: current state is Init message: 2016-09-07 11:14:58:287 MSQualityIndicator[00607D88][video], remote statistics available: Loss rate : 0,0% Inter-arrival jitter: 1,0ms RT propagation : 31,7ms message: 2016-09-07 11:14:58:645 Terminate call [048F8730] which is currently in state LinphoneCallStreamsRunning message: 2016-09-07 11:14:58:645 Dialog [005F4528]: now updated by transaction [062B0650]. message: 2016-09-07 11:14:58:645 Transaction [062EDFE8] deleted message: 2016-09-07 11:14:58:645 Changing [client] [BYE] transaction [062B0650], from state [INIT] to [TRYING] message: 2016-09-07 11:14:58:645 channel [04944278]: message sent to [UDP://vc.dfn.de:5060], size: [405] bytes BYE sip:address@hidden SIP/2.0Via: SIP/2.0/UDP 10.35.34.97:5060;branch=z9hG4bK.jjlzUqAxg;rportFrom: ;tag=4gNXjKVb4To: ;tag=49bf02ce9f0bd8e1CSeq: 22 BYECall-ID: GeyDGySXXNMax-Forwards: 70Route: User-Agent: Linphone/3.10.2 (belle-sip/1.5.0) message: 2016-09-07 11:14:58:646 Writing echo canceler state, 43720 bytes message: 2016-09-07 11:14:58:651 Filter MSRtpRecv is not scheduled; nothing to do. message: 2016-09-07 11:14:58:651 =========================================================== message: 2016-09-07 11:14:58:651 AUDIO SESSION'S RTP STATISTICS message: 2016-09-07 11:14:58:651 ----------------------------------------------------------- message: 2016-09-07 11:14:58:651 sent 1766 packets message: 2016-09-07 11:14:58:651 0 duplicated packets message: 2016-09-07 11:14:58:651 303752 bytes message: 2016-09-07 11:14:58:651 received 1754 packets message: 2016-09-07 11:14:58:651 0 duplicated packets message: 2016-09-07 11:14:58:651 301688 bytes message: 2016-09-07 11:14:58:651 incoming delivered to the app 301000 bytes message: 2016-09-07 11:14:58:651 incoming cumulative lost 3 packets message: 2016-09-07 11:14:58:651 incoming received too late 0 packets message: 2016-09-07 11:14:58:651 incoming bad formatted 0 packets message: 2016-09-07 11:14:58:651 incoming discarded (queue overflow) 0 packets message: 2016-09-07 11:14:58:651 sent rtcp 10 packets message: 2016-09-07 11:14:58:651 received rtcp 7 packets message: 2016-09-07 11:14:58:651 =========================================================== message: 2016-09-07 11:14:58:651 ms_filter_unlink: MSWASAPIRead:048FF5E0,0-->MSResample:062A5F30,0 message: 2016-09-07 11:14:58:651 ms_filter_unlink: MSResample:062A5F30,0-->MSEqualizer:062A65F0,0 message: 2016-09-07 11:14:58:651 ms_filter_unlink: MSEqualizer:062A65F0,0-->MSSpeexEC:0065EEA0,1 message: 2016-09-07 11:14:58:651 ms_filter_unlink: MSSpeexEC:0065EEA0,1-->MSVolume:049003E0,0 message: 2016-09-07 11:14:58:651 ms_filter_unlink: MSVolume:049003E0,0-->MSAudioMixer:062A5770,0 message: 2016-09-07 11:14:58:651 ms_filter_unlink: MSAudioMixer:062A5770,0-->MSG722Enc:049003A0,0 message: 2016-09-07 11:14:58:651 ms_filter_unlink: MSG722Enc:049003A0,0-->MSRtpSend:0065EE20,0 message: 2016-09-07 11:14:58:651 ms_filter_unlink: MSRtpRecv:048FF720,0-->MSG722Dec:04900320,0 message: 2016-09-07 11:14:58:651 ms_filter_unlink: MSG722Dec:04900320,0-->MSDtmfGen:048FF6E0,0 message: 2016-09-07 11:14:58:651 ms_filter_unlink: MSDtmfGen:048FF6E0,0-->MSVolume:04900360,0 message: 2016-09-07 11:14:58:651 ms_filter_unlink: MSVolume:04900360,0-->MSTee:062A62F0,0 message: 2016-09-07 11:14:58:651 ms_filter_unlink: MSTee:062A62F0,0-->MSEqualizer:062A60B0,0 message: 2016-09-07 11:14:58:651 ms_filter_unlink: MSEqualizer:062A60B0,0-->MSAudioMixer:062A5B30,0 message: 2016-09-07 11:14:58:652 ms_filter_unlink: MSFilePlayer:062A5EF0,0-->MSResample:062A62B0,0 message: 2016-09-07 11:14:58:652 ms_filter_unlink: MSResample:062A62B0,0-->MSAudioMixer:062A5B30,1 message: 2016-09-07 11:14:58:652 ms_filter_unlink: MSAudioMixer:062A5B30,0-->MSSpeexEC:0065EEA0,0 message: 2016-09-07 11:14:58:652 ms_filter_unlink: MSSpeexEC:0065EEA0,0-->MSResample:062A5E30,0 message: 2016-09-07 11:14:58:652 ms_filter_unlink: MSResample:062A5E30,0-->MSWASAPIWrite:049002E0,0 message: 2016-09-07 11:14:58:652 ms_filter_unlink: MSAudioMixer:062A57B0,1-->MSResample:062A6670,0 message: 2016-09-07 11:14:58:652 ms_filter_unlink: MSResample:062A6670,0-->MSOpusEnc:062A6030,0 message: 2016-09-07 11:14:58:652 ms_filter_unlink: MSOpusEnc:062A6030,0-->MSMKVRecorder:062A5BF0,1 message: 2016-09-07 11:14:58:652 ms_filter_unlink: MSItcSource:062A5DB0,0-->MSMKVRecorder:062A5BF0,0 message: 2016-09-07 11:14:58:652 ms_filter_unlink: MSAudioMixer:062A5770,1-->MSAudioMixer:062A57B0,0 message: 2016-09-07 11:14:58:652 ms_filter_unlink: MSTee:062A62F0,1-->MSAudioMixer:062A57B0,1 message: 2016-09-07 11:14:58:652 ms_filter_unlink: MSAudioMixer:062A57B0,0-->MSFileRec:062A6230,0 message: 2016-09-07 11:14:58:652 =========================================================== message: 2016-09-07 11:14:58:652 FILTER USAGE STATISTICS message: 2016-09-07 11:14:58:652 Name Count Time/tick (ms) CPU Usage message: 2016-09-07 11:14:58:652 ----------------------------------------------------------- message: 2016-09-07 11:14:58:652 MSOpenH264Enc 953 2,64885 38,3169 message: 2016-09-07 11:14:58:652 MSDrawDibDisplay 1179 1,88898 33,7983 message: 2016-09-07 11:14:58:652 MSRtpSend 7052 0,122643 13,116 message: 2016-09-07 11:14:58:652 MSResample 7134 0,0894184 9,674 message: 2016-09-07 11:14:58:652 MSSpeexEC 3516 0,0372477 1,98635 message: 2016-09-07 11:14:58:652 MSOpenH264Dec 439 0,168182 1,12206 message: 2016-09-07 11:14:58:652 MSG722Enc 3502 0,0148444 0,788476 message: 2016-09-07 11:14:58:652 MSOpusEnc 99 0,44 0,667172 message: 2016-09-07 11:14:58:652 MSRtpRecv 7070 0,00197992 0,212282 message: 2016-09-07 11:14:58:652 MSAudioMixer 10602 0,000848816 0,136467 message: 2016-09-07 11:14:58:652 MSTee 3581 0,000837521 0,045489 message: 2016-09-07 11:14:58:652 MSJpegWriter 1385 0,001443 0,030326 message: 2016-09-07 11:14:58:652 MSMKVRecorder 484 0,00412371 0,030326 message: 2016-09-07 11:14:58:652 MSVolume 5259 0,000380228 0,030326 message: 2016-09-07 11:14:58:652 MSPixConv 953 0,00104822 0,015163 message: 2016-09-07 11:14:58:652 MSFileRec 99 0,01 0,015163 message: 2016-09-07 11:14:58:652 MSItcSink 439 0,00227273 0,015163 message: 2016-09-07 11:14:58:652 MSSizeConv 953 0 0 message: 2016-09-07 11:14:58:652 MSDsCap 3518 0 0 message: 2016-09-07 11:14:58:652 MSEqualizer 5259 0 0 message: 2016-09-07 11:14:58:652 MSItcSource 3534 0 0 message: 2016-09-07 11:14:58:652 MSG722Dec 3534 0 0 message: 2016-09-07 11:14:58:652 MSWASAPIRead 3534 0 0 message: 2016-09-07 11:14:58:652 MSWASAPIWrite 3533 0 0 message: 2016-09-07 11:14:58:652 MSDtmfGen 3565 0 0 message: 2016-09-07 11:14:58:652 MSFilePlayer 3565 0 0 message: 2016-09-07 11:14:58:652 MSVoidSink 0 0 0 message: 2016-09-07 11:14:58:652 =========================================================== message: 2016-09-07 11:14:58:662 MKVRecorder: destroyed message: 2016-09-07 11:14:59:305 Filter MSRtpRecv is not scheduled; nothing to do. message: 2016-09-07 11:14:59:305 =========================================================== message: 2016-09-07 11:14:59:305 VIDEO SESSION'S RTP STATISTICS message: 2016-09-07 11:14:59:305 ----------------------------------------------------------- message: 2016-09-07 11:14:59:305 sent 7616 packets message: 2016-09-07 11:14:59:305 0 duplicated packets message: 2016-09-07 11:14:59:305 6057293 bytes message: 2016-09-07 11:14:59:305 received 450 packets message: 2016-09-07 11:14:59:305 0 duplicated packets message: 2016-09-07 11:14:59:305 237387 bytes message: 2016-09-07 11:14:59:305 incoming delivered to the app 236702 bytes message: 2016-09-07 11:14:59:305 incoming cumulative lost 2 packets message: 2016-09-07 11:14:59:305 incoming received too late 0 packets message: 2016-09-07 11:14:59:305 incoming bad formatted 0 packets message: 2016-09-07 11:14:59:305 incoming discarded (queue overflow) 0 packets message: 2016-09-07 11:14:59:305 sent rtcp 10 packets message: 2016-09-07 11:14:59:305 received rtcp 7 packets message: 2016-09-07 11:14:59:305 =========================================================== message: 2016-09-07 11:14:59:305 ms_filter_unlink: MSDsCap:062A6470,0-->MSPixConv:062A5DF0,0 message: 2016-09-07 11:14:59:305 ms_filter_unlink: MSPixConv:062A5DF0,0-->MSTee:062A6630,0 message: 2016-09-07 11:14:59:305 ms_filter_unlink: MSTee:062A6630,0-->MSSizeConv:062A64F0,0 message: 2016-09-07 11:14:59:305 ms_filter_unlink: MSSizeConv:062A64F0,0-->MSOpenH264Enc:062A59F0,0 message: 2016-09-07 11:14:59:305 ms_filter_unlink: MSOpenH264Enc:062A59F0,0-->MSRtpSend:0065EFE0,0 message: 2016-09-07 11:14:59:305 ms_filter_unlink: MSTee:062A6630,2-->MSJpegWriter:062A61F0,0 message: 2016-09-07 11:14:59:305 ms_filter_unlink: MSRtpRecv:062A5AB0,0-->MSTee:0065EC60,0 message: 2016-09-07 11:14:59:305 ms_filter_unlink: MSTee:0065EC60,1-->MSItcSink:0065EF20,0 message: 2016-09-07 11:14:59:305 ms_filter_unlink: MSTee:0065EC60,0-->MSOpenH264Dec:062A5CF0,0 message: 2016-09-07 11:14:59:305 ms_filter_unlink: MSOpenH264Dec:062A5CF0,0-->MSTee:062A63F0,0 message: 2016-09-07 11:14:59:305 ms_filter_unlink: MSTee:062A63F0,1-->MSJpegWriter:062A6070,0 message: 2016-09-07 11:14:59:305 ms_filter_unlink: MSTee:062A63F0,0-->MSDrawDibDisplay:062A64B0,0 message: 2016-09-07 11:14:59:305 ms_filter_unlink: MSTee:062A6630,1-->MSDrawDibDisplay:062A64B0,1 message: 2016-09-07 11:14:59:306 DSCapture::Release message: 2016-09-07 11:14:59:306 =========================================================== message: 2016-09-07 11:14:59:306 FILTER USAGE STATISTICS message: 2016-09-07 11:14:59:306 Name Count Time/tick (ms) CPU Usage message: 2016-09-07 11:14:59:307 ----------------------------------------------------------- message: 2016-09-07 11:14:59:307 MSOpenH264Enc 953 2,64885 38,3169 message: 2016-09-07 11:14:59:307 MSDrawDibDisplay 1179 1,88898 33,7983 message: 2016-09-07 11:14:59:307 MSRtpSend 7053 0,122625 13,116 message: 2016-09-07 11:14:59:307 MSResample 7134 0,0894184 9,674 message: 2016-09-07 11:14:59:307 MSSpeexEC 3516 0,0372477 1,98635 message: 2016-09-07 11:14:59:307 MSOpenH264Dec 439 0,168182 1,12206 message: 2016-09-07 11:14:59:307 MSG722Enc 3502 0,0148444 0,788476 message: 2016-09-07 11:14:59:307 MSOpusEnc 99 0,44 0,667172 message: 2016-09-07 11:14:59:307 MSRtpRecv 7071 0,00197964 0,212282 message: 2016-09-07 11:14:59:307 MSAudioMixer 10602 0,000848816 0,136467 message: 2016-09-07 11:14:59:307 MSTee 3581 0,000837521 0,045489 message: 2016-09-07 11:14:59:307 MSJpegWriter 1385 0,001443 0,030326 message: 2016-09-07 11:14:59:307 MSMKVRecorder 484 0,00412371 0,030326 message: 2016-09-07 11:14:59:307 MSVolume 5259 0,000380228 0,030326 message: 2016-09-07 11:14:59:307 MSPixConv 953 0,00104822 0,015163 message: 2016-09-07 11:14:59:307 MSFileRec 99 0,01 0,015163 message: 2016-09-07 11:14:59:307 MSItcSink 439 0,00227273 0,015163 message: 2016-09-07 11:14:59:307 MSSizeConv 953 0 0 message: 2016-09-07 11:14:59:307 MSDsCap 3519 0 0 message: 2016-09-07 11:14:59:307 MSEqualizer 5259 0 0 message: 2016-09-07 11:14:59:307 MSItcSource 3534 0 0 message: 2016-09-07 11:14:59:307 MSG722Dec 3534 0 0 message: 2016-09-07 11:14:59:307 MSWASAPIRead 3534 0 0 message: 2016-09-07 11:14:59:307 MSWASAPIWrite 3533 0 0 message: 2016-09-07 11:14:59:307 MSDtmfGen 3565 0 0 message: 2016-09-07 11:14:59:307 MSFilePlayer 3565 0 0 message: 2016-09-07 11:14:59:307 MSVoidSink 0 0 0 message: 2016-09-07 11:14:59:307 =========================================================== message: 2016-09-07 11:14:59:307 Call 048F8730: moving from state LinphoneCallStreamsRunning to LinphoneCallEnd message: 2016-09-07 11:14:59:309 check OS support for qwave.lib message: 2016-09-07 11:14:59:309 Stopping ZRTP context on session [00000000] message: 2016-09-07 11:14:59:309 ZRTP context destroyed message: 2016-09-07 11:14:59:314 MSAudio MSTicker thread exiting message: 2016-09-07 11:14:59:314 check OS support for qwave.lib message: 2016-09-07 11:14:59:314 Stopping ZRTP context on session [00000000] message: 2016-09-07 11:14:59:324 ZRTP context destroyed message: 2016-09-07 11:14:59:324 MSVideo MSTicker thread exiting message: 2016-09-07 11:14:59:414 linphone_core_get_call_history(): completed in 10 ms message: 2016-09-07 11:14:59:434 Linphone core [00631998] notifying [call_log_updated] message: 2016-09-07 11:14:59:434 Resetting the current call message: 2016-09-07 11:14:59:434 StatusIcon: blinking set to FALSE message: 2016-09-07 11:14:59:434 Linphone core [00631998] notifying [call_state_changed] message: 2016-09-07 11:14:59:434 nict_on_timer_E: sending retransmission message: 2016-09-07 11:14:59:434 channel [04944278]: message sent to [UDP://vc.dfn.de:5060], size: [405] bytes BYE sip:address@hidden SIP/2.0Via: SIP/2.0/UDP 10.35.34.97:5060;branch=z9hG4bK.jjlzUqAxg;rportFrom: ;tag=4gNXjKVb4To: ;tag=49bf02ce9f0bd8e1CSeq: 22 BYECall-ID: GeyDGySXXNMax-Forwards: 70Route: User-Agent: Linphone/3.10.2 (belle-sip/1.5.0) message: 2016-09-07 11:14:59:434 channel [04944278]: received [411] new bytes from [UDP://vc.dfn.de:5060]: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.35.34.97:5060;branch=z9hG4bK.jjlzUqAxg;received=10.35.34.97;rport=5060;ingress-zone=DefaultZoneCall-ID: GeyDGySXXNCSeq: 22 BYEContact: ;isfocusFrom: ;tag=4gNXjKVb4To: ;tag=49bf02ce9f0bd8e1Allow: INVITE,ACK,BYE,CANCEL,INFO,REFER,NOTIFYServer: TANDBERG/4132 (X8.7.2)Content-Length: 0 message: 2016-09-07 11:14:59:435 channel [04944278] [411] bytes parsed message: 2016-09-07 11:14:59:435 Found transaction matching response. message: 2016-09-07 11:14:59:435 Changing [client] [BYE] transaction [062B0650], from state [TRYING] to [COMPLETED] message: 2016-09-07 11:14:59:435 Dialog [005F4528]: now updated by transaction [062B0650]. message: 2016-09-07 11:14:59:435 Dialog [005F4528] deleted (is_expired=0) message: 2016-09-07 11:14:59:435 op [048CB950] : set_or_update_dialog() current=[005F4528] new=[005F4528] message: 2016-09-07 11:14:59:435 Op [048CB950] receiving call response [200], dialog is [005F4528] in state [BELLE_SIP_DIALOG_TERMINATED] message: 2016-09-07 11:14:59:511 Dialog [005F4528] terminated for op [048CB950] message: 2016-09-07 11:14:59:511 channel [04944278]: received [411] new bytes from [UDP://vc.dfn.de:5060]: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.35.34.97:5060;branch=z9hG4bK.jjlzUqAxg;received=10.35.34.97;rport=5060;ingress-zone=DefaultZoneCall-ID: GeyDGySXXNCSeq: 22 BYEContact: ;isfocusFrom: ;tag=4gNXjKVb4To: ;tag=49bf02ce9f0bd8e1Allow: INVITE,ACK,BYE,CANCEL,INFO,REFER,NOTIFYServer: TANDBERG/4132 (X8.7.2)Content-Length: 0 message: 2016-09-07 11:14:59:511 channel [04944278] [411] bytes parsed message: 2016-09-07 11:14:59:511 Found transaction matching response. message: 2016-09-07 11:14:59:551 Call 048F8730: moving from state LinphoneCallEnd to LinphoneCallReleased message: 2016-09-07 11:14:59:551 Linphone core [00631998] notifying [call_state_changed] message: 2016-09-07 11:14:59:551 op [048CB950] : set_or_update_dialog() current=[005F4528] new=[00000000] message: 2016-09-07 11:15:02:005 Call [048F8730] freed. message: 2016-09-07 11:15:04:475 Changing [client] [BYE] transaction [062B0650], from state [COMPLETED] to [TERMINATED] message: 2016-09-07 11:15:04:475 Client BYE transaction [062B0650] terminated message: 2016-09-07 11:15:04:475 Destroying op [048CB950] of type [SalOpCall] message: 2016-09-07 11:15:04:475 Transaction [062B0650] deleted