Phase 1) INVITE sip:address@hidden:1344;transport=TCP SIP/2.0 From: "dsantomaso";tag=a292a60-2601a8c0-ea99-55013-443d0-6d2baaf4-443d0 To: "steven";tag=N-F0yYl Call-ID: a29f898-2601a8c0-ea99-55013-443d0-bb193f6-443d0 CSeq: 2 INVITE Via: SIP/2.0/TCP 192.168.1.5:5060;branch=z9hG4bK-43894-107d05bd-7cec0085 Via: SIP/2.0/TCP 192.168.1.38:60057;branch=z9hG4bK-443da-10a90e82-5cf5732e Max-Forwards: 69 Supported: gruu,join,replaces,timer,100rel User-Agent: BCSBar 4.0.14.0 Allow-Events: hold,refer Contact: ;audio;video;text;application Session-Expires: 3600;refresher=uac Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,MESSAGE,INFO,SUBSCRIBE Content-Type: application/sdp Content-Length: 272 v=0 o=- 3716807545 3716807546 IN IP4 192.168.1.38 s=bcs c=IN IP4 192.168.1.38 t=0 0 m=audio 52220 RTP/AVP 8 96 c=IN IP4 192.168.1.38 b=TIAS:96000 a=rtcp:52221 IN IP4 192.168.1.38 a=inactive a=rtpmap:8 PCMA/8000 a=rtpmap:96 telephone-event/8000 a=fmtp:96 0-16 2017-10-12 14:32:35:964 MESSAGE channel [08CB2428] [808] bytes parsed 2017-10-12 14:32:35:964 MESSAGE channel [08CB2428] read [272] bytes of body from [server0f.ve.alceo.com:5060] 2017-10-12 14:32:35:965 MESSAGE Changing [server] [INVITE] transaction [0A6F8530], from state [INIT] to [PROCEEDING] 2017-10-12 14:32:35:965 MESSAGE channel [08CB2428]: message sent to [TCP://server0f.ve.alceo.com:5060], size: [414] bytes SIP/2.0 100 Trying Via: SIP/2.0/TCP 192.168.1.5:5060;branch=z9hG4bK-43894-107d05bd-7cec0085 Via: SIP/2.0/TCP 192.168.1.38:60057;branch=z9hG4bK-443da-10a90e82-5cf5732e From: "dsantomaso" ;tag=a292a60-2601a8c0-ea99-55013-443d0-6d2baaf4-443d0 To: "steven" ;tag=N-F0yYl Call-ID: a29f898-2601a8c0-ea99-55013-443d0-bb193f6-443d0 CSeq: 2 INVITE Content-Length: 0 2017-10-12 14:32:35:965 MESSAGE Dialog [0AAAB390]: now updated by transaction [0A6F8530]. 2017-10-12 14:32:35:966 MESSAGE Found payload PCMA/8000 fmtp= 2017-10-12 14:32:35:966 MESSAGE Found payload telephone-event/8000 fmtp=0-16 2017-10-12 14:32:35:966 MESSAGE audio stream index found: 0, updating main audio stream index 2017-10-12 14:32:35:966 MESSAGE Found media local-ip from signaling. 2017-10-12 14:32:35:966 MESSAGE Don't put video stream on local offer for call [089F8568] 2017-10-12 14:32:35:966 MESSAGE Don't put text stream on local offer for call [089F8568] 2017-10-12 14:32:35:966 MESSAGE Doing SDP offer/answer process of type incoming 2017-10-12 14:32:35:966 MESSAGE Found media local-ip from signaling. 2017-10-12 14:32:35:966 MESSAGE Don't put video stream on local offer for call [089F8568] 2017-10-12 14:32:35:966 MESSAGE Don't put text stream on local offer for call [089F8568] 2017-10-12 14:32:35:966 MESSAGE Accepting server transaction [0A6F8530] on op [0A8F8738] 2017-10-12 14:32:35:966 MESSAGE Doing SDP offer/answer process of type incoming 2017-10-12 14:32:35:966 MESSAGE channel [08CB2428]: message sent to [TCP://server0f.ve.alceo.com:5060], size: [889] bytes SIP/2.0 200 Ok Via: SIP/2.0/TCP 192.168.1.5:5060;branch=z9hG4bK-43894-107d05bd-7cec0085 Via: SIP/2.0/TCP 192.168.1.38:60057;branch=z9hG4bK-443da-10a90e82-5cf5732e From: "dsantomaso" ;tag=a292a60-2601a8c0-ea99-55013-443d0-6d2baaf4-443d0 To: "steven" ;tag=N-F0yYl Call-ID: a29f898-2601a8c0-ea99-55013-443d0-bb193f6-443d0 CSeq: 2 INVITE User-Agent: Linphone Desktop/4.1.1 (belle-sip/1.6.3) Supported: replaces, outbound Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Contact: ;+sip.instance="" Content-Type: application/sdp Content-Length: 151 v=0 o=steven 4085 3656 IN IP4 192.168.1.38 s=Talk c=IN IP4 0.0.0.0 t=0 0 m=audio 7078 RTP/AVP 8 96 a=rtpmap:96 telephone-event/8000 a=inactive 2017-10-12 14:32:35:966 MESSAGE Changing [server] [INVITE] transaction [0A6F8530], from state [PROCEEDING] to [ACCEPTED] 2017-10-12 14:32:35:966 MESSAGE Dialog [0AAAB390]: now updated by transaction [0A6F8530]. 2017-10-12 14:32:35:966 MESSAGE Other description has changed: CODEC_CHANGED 2017-10-12 14:32:35:967 MESSAGE Media descriptions are different, need to restart the streams. 2017-10-12 14:32:35:967 MESSAGE Writing echo canceler state, 0 bytes 2017-10-12 14:32:35:967 WARNING .linphone.ecstate has not been created because there is no data to write 2017-10-12 14:32:35:967 MESSAGE ms_ticker_set_time_func: ticker's time method updated. 2017-10-12 14:32:35:976 MESSAGE Filter MSRtpRecv is not scheduled; nothing to do. 2017-10-12 14:32:35:976 MESSAGE =========================================================== 2017-10-12 14:32:35:976 MESSAGE AUDIO SESSION'S RTP STATISTICS 2017-10-12 14:32:35:976 MESSAGE ----------------------------------------------------------- 2017-10-12 14:32:35:976 MESSAGE sent 366 packets 2017-10-12 14:32:35:976 MESSAGE 0 duplicated packets 2017-10-12 14:32:35:976 MESSAGE 62952 bytes 2017-10-12 14:32:35:976 MESSAGE received 365 packets 2017-10-12 14:32:35:976 MESSAGE 0 duplicated packets 2017-10-12 14:32:35:976 MESSAGE 62780 bytes 2017-10-12 14:32:35:976 MESSAGE incoming delivered to the app 62436 bytes 2017-10-12 14:32:35:976 MESSAGE incoming cumulative lost 0 packets 2017-10-12 14:32:35:976 MESSAGE incoming received too late 0 packets 2017-10-12 14:32:35:976 MESSAGE incoming bad formatted 0 packets 2017-10-12 14:32:35:976 MESSAGE incoming discarded (queue overflow) 0 packets 2017-10-12 14:32:35:976 MESSAGE sent rtcp 3 packets 2017-10-12 14:32:35:976 MESSAGE received rtcp 3 packets 2017-10-12 14:32:35:976 MESSAGE =========================================================== 2017-10-12 14:32:35:976 MESSAGE ms_filter_unlink: MSWASAPIRead:0A2A76C8,0-->MSResample:0A2A84C8,0 2017-10-12 14:32:35:976 MESSAGE ms_filter_unlink: MSResample:0A2A84C8,0-->MSEqualizer:0A236560,0 2017-10-12 14:32:35:976 MESSAGE ms_filter_unlink: MSEqualizer:0A236560,0-->MSWebRTCAEC:08A55670,1 2017-10-12 14:32:35:976 MESSAGE ms_filter_unlink: MSWebRTCAEC:08A55670,1-->MSVolume:0A2A7D48,0 2017-10-12 14:32:35:976 MESSAGE ms_filter_unlink: MSVolume:0A2A7D48,0-->MSAudioMixer:0A2A8648,0 2017-10-12 14:32:35:976 MESSAGE ms_filter_unlink: MSAudioMixer:0A2A8648,0-->MSAlawEnc:0A2A8008,0 2017-10-12 14:32:35:976 MESSAGE ms_filter_unlink: MSAlawEnc:0A2A8008,0-->MSRtpSend:08A55430,0 2017-10-12 14:32:35:976 MESSAGE ms_filter_unlink: MSRtpRecv:08C5BC18,0-->MSAlawDec:0A2A8208,0 2017-10-12 14:32:35:976 MESSAGE ms_filter_unlink: MSAlawDec:0A2A8208,0-->MSGenericPLC:0A2365E0,0 2017-10-12 14:32:35:976 MESSAGE ms_filter_unlink: MSGenericPLC:0A2365E0,0-->MSAudioFlowControl:0A2361E0,0 2017-10-12 14:32:35:976 MESSAGE ms_filter_unlink: MSAudioFlowControl:0A2361E0,0-->MSDtmfGen:0A2A7688,0 2017-10-12 14:32:35:977 MESSAGE ms_filter_unlink: MSDtmfGen:0A2A7688,0-->MSVolume:0A2A8308,0 2017-10-12 14:32:35:977 MESSAGE ms_filter_unlink: MSVolume:0A2A8308,0-->MSTee:0A2A6D88,0 2017-10-12 14:32:35:977 MESSAGE ms_filter_unlink: MSTee:0A2A6D88,0-->MSEqualizer:0A2365A0,0 2017-10-12 14:32:35:977 MESSAGE ms_filter_unlink: MSEqualizer:0A2365A0,0-->MSAudioMixer:0A235F60,0 2017-10-12 14:32:35:977 MESSAGE ms_filter_unlink: MSFilePlayer:08A54BF0,0-->MSResample:08A54630,0 2017-10-12 14:32:35:977 MESSAGE ms_filter_unlink: MSResample:08A54630,0-->MSAudioMixer:0A235F60,1 2017-10-12 14:32:35:977 MESSAGE ms_filter_unlink: MSAudioMixer:0A235F60,0-->MSWebRTCAEC:08A55670,0 2017-10-12 14:32:35:977 MESSAGE ms_filter_unlink: MSWebRTCAEC:08A55670,0-->MSResample:0A2A8608,0 2017-10-12 14:32:35:977 MESSAGE ms_filter_unlink: MSResample:0A2A8608,0-->MSWASAPIWrite:0A2A7AC8,0 2017-10-12 14:32:35:977 MESSAGE ms_filter_unlink: MSAudioMixer:0A2A6C48,1-->MSResample:0A2A7188,0 2017-10-12 14:32:35:977 MESSAGE ms_filter_unlink: MSResample:0A2A7188,0-->MSOpusEnc:0A237720,0 2017-10-12 14:32:35:977 MESSAGE ms_filter_unlink: MSOpusEnc:0A237720,0-->MSMKVRecorder:0A2A6FC8,1 2017-10-12 14:32:35:977 MESSAGE ms_filter_unlink: MSItcSource:0A2A7008,0-->MSMKVRecorder:0A2A6FC8,0 2017-10-12 14:32:35:977 MESSAGE ms_filter_unlink: MSAudioMixer:0A2A8648,1-->MSAudioMixer:0A2A6C48,0 2017-10-12 14:32:35:977 MESSAGE ms_filter_unlink: MSTee:0A2A6D88,1-->MSAudioMixer:0A2A6C48,1 2017-10-12 14:32:35:977 MESSAGE ms_filter_unlink: MSAudioMixer:0A2A6C48,0-->MSFileRec:0A2A7348,0 2017-10-12 14:32:35:977 MESSAGE =========================================================== 2017-10-12 14:32:35:977 MESSAGE FILTER USAGE STATISTICS 2017-10-12 14:32:35:977 MESSAGE Name Count Time/tick (ms) CPU Usage 2017-10-12 14:32:35:977 MESSAGE ----------------------------------------------------------- 2017-10-12 14:32:35:977 MESSAGE MSResample 1700 0.0352734 49.1803 2017-10-12 14:32:35:977 MESSAGE MSRtpSend 734 0.0707483 42.623 2017-10-12 14:32:35:977 MESSAGE MSOpusEnc 99 0.03 2.45902 2017-10-12 14:32:35:977 MESSAGE MSWASAPIWrite 869 0.00344828 2.45902 2017-10-12 14:32:35:977 MESSAGE MSWebRTCAEC 733 0.0027248 1.63934 2017-10-12 14:32:35:977 MESSAGE MSMKVRecorder 49 0.02 0.819672 2017-10-12 14:32:35:977 MESSAGE MSWASAPIRead 734 0.00136054 0.819672 2017-10-12 14:32:35:977 MESSAGE MSFilePlayer 734 0 0 2017-10-12 14:32:35:977 MESSAGE MSAudioFlowControl 363 0 0 2017-10-12 14:32:35:977 MESSAGE MSGenericPLC 734 0 0 2017-10-12 14:32:35:977 MESSAGE MSEqualizer 1095 0 0 2017-10-12 14:32:35:977 MESSAGE MSItcSource 734 0 0 2017-10-12 14:32:35:977 MESSAGE MSFileRec 99 0 0 2017-10-12 14:32:35:977 MESSAGE MSAudioMixer 2202 0 0 2017-10-12 14:32:35:977 MESSAGE MSVolume 1095 0 0 2017-10-12 14:32:35:977 MESSAGE MSAlawDec 363 0 0 2017-10-12 14:32:35:977 MESSAGE MSAlawEnc 732 0 0 2017-10-12 14:32:35:977 MESSAGE MSRtpRecv 734 0 0 2017-10-12 14:32:35:978 MESSAGE MSOpusDec 274 0 0 2017-10-12 14:32:35:978 MESSAGE MSDtmfGen 1008 0 0 2017-10-12 14:32:35:978 MESSAGE MSMKVPlayer 274 0 0 2017-10-12 14:32:35:978 MESSAGE MSItcSink 0 0 0 2017-10-12 14:32:35:978 MESSAGE MSTee 363 0 0 2017-10-12 14:32:35:978 MESSAGE =========================================================== 2017-10-12 14:32:35:979 MESSAGE MKVRecorder: destroyed 2017-10-12 14:32:35:979 MESSAGE =========================================================== 2017-10-12 14:32:35:979 MESSAGE FILTER USAGE STATISTICS 2017-10-12 14:32:35:979 MESSAGE Name Count Time/tick (ms) CPU Usage 2017-10-12 14:32:35:979 MESSAGE ----------------------------------------------------------- 2017-10-12 14:32:35:979 MESSAGE MSResample 1700 0.0352734 49.1803 2017-10-12 14:32:35:979 MESSAGE MSRtpSend 734 0.0707483 42.623 2017-10-12 14:32:35:979 MESSAGE MSOpusEnc 99 0.03 2.45902 2017-10-12 14:32:35:980 MESSAGE MSWASAPIWrite 869 0.00344828 2.45902 2017-10-12 14:32:35:980 MESSAGE MSWebRTCAEC 733 0.0027248 1.63934 2017-10-12 14:32:35:980 MESSAGE MSMKVRecorder 49 0.02 0.819672 2017-10-12 14:32:35:980 MESSAGE MSWASAPIRead 734 0.00136054 0.819672 2017-10-12 14:32:35:980 MESSAGE MSFilePlayer 734 0 0 2017-10-12 14:32:35:980 MESSAGE MSAudioFlowControl 363 0 0 2017-10-12 14:32:35:980 MESSAGE MSGenericPLC 734 0 0 2017-10-12 14:32:35:980 MESSAGE MSEqualizer 1095 0 0 2017-10-12 14:32:35:980 MESSAGE MSItcSource 734 0 0 2017-10-12 14:32:35:980 MESSAGE MSFileRec 99 0 0 2017-10-12 14:32:35:980 MESSAGE MSAudioMixer 2202 0 0 2017-10-12 14:32:35:980 MESSAGE MSVolume 1095 0 0 2017-10-12 14:32:35:980 MESSAGE MSAlawDec 363 0 0 2017-10-12 14:32:35:980 MESSAGE MSAlawEnc 732 0 0 2017-10-12 14:32:35:980 MESSAGE MSRtpRecv 734 0 0 2017-10-12 14:32:35:980 MESSAGE MSOpusDec 274 0 0 2017-10-12 14:32:35:980 MESSAGE MSDtmfGen 1008 0 0 2017-10-12 14:32:35:980 MESSAGE MSMKVPlayer 274 0 0 2017-10-12 14:32:35:980 MESSAGE MSItcSink 0 0 0 2017-10-12 14:32:35:980 MESSAGE MSTee 363 0 0 2017-10-12 14:32:35:980 MESSAGE =========================================================== 2017-10-12 14:32:35:980 MESSAGE Setting DSCP to 46 for MSAudio stream. 2017-10-12 14:32:35:980 MESSAGE check OS support for qwave.lib 2017-10-12 14:32:35:981 ERROR QOSAddSocketToFlow failed to add a flow with error 1168 2017-10-12 14:32:35:981 MESSAGE Equalizer location: hp 2017-10-12 14:32:35:982 MESSAGE cannot set noise gate mode to [0] because no volume send 2017-10-12 14:32:35:982 MESSAGE Setting DSCP to 0 for MSVideo stream. 2017-10-12 14:32:35:982 MESSAGE check OS support for qwave.lib 2017-10-12 14:32:35:982 ERROR QOSAddSocketToFlow failed to add a flow with error 87 2017-10-12 14:32:35:982 MESSAGE linphone_call_start_media_streams() call=[089F8568] local upload_bandwidth=[0] kbit/s; local download_bandwidth=[0] kbit/s 2017-10-12 14:32:35:982 MESSAGE No valid video stream defined. 2017-10-12 14:32:35:982 MESSAGE Call 089F8568: moving from state LinphoneCallStreamsRunning to LinphoneCallPausedByRemote 2017-10-12 14:32:35:984 MESSAGE Linphone core [04A12800] notified [call_state_changed] 2017-10-12 14:32:36:182 MESSAGE channel [08CB2428]: received [1326] new bytes from [TCP://server0f.ve.alceo.com:5060]: ACK sip:address@hidden:1344;transport=tcp SIP/2.0 From: "dsantomaso";tag=a292a60-2601a8c0-ea99-55013-443d0-6d2baaf4-443d0 To: "steven";tag=N-F0yYl Call-ID: a29f898-2601a8c0-ea99-55013-443d0-bb193f6-443d0 CSeq: 2 ACK Via: SIP/2.0/TCP 192.168.1.5:5060;branch=z9hG4bK-43895-107d068a-382edaad Via: SIP/2.0/TCP 192.168.1.38:60057;branch=z9hG4bK-443da-10a90f3f-4b5729cf Max-Forwards: 69 Contact: ;audio;video;text;application Content-Length: 0 Phase 2) REFER sip:address@hidden:1344;transport=TCP SIP/2.0 From: "dsantomaso";tag=a292a60-2601a8c0-ea99-55013-443d0-6d2baaf4-443d0 To: "steven";tag=N-F0yYl Call-ID: a29f898-2601a8c0-ea99-55013-443d0-bb193f6-443d0 CSeq: 3 REFER Via: SIP/2.0/TCP 192.168.1.5:5060;branch=z9hG4bK-43895-107d068c-3b92b760 Via: SIP/2.0/TCP 192.168.1.38:60057;branch=z9hG4bK-443da-10a90f3f-3a2cc20a Refer-To: "chuck" Referred-By: "dsantomaso" Max-Forwards: 69 Supported: gruu,join,replaces,timer,100rel User-Agent: BCSBar 4.0.14.0 Contact: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,MESSAGE Content-Length: 0 2017-10-12 14:32:36:186 MESSAGE channel [08CB2428] [567] bytes parsed 2017-10-12 14:32:36:192 MESSAGE channel [08CB2428] [759] bytes parsed 2017-10-12 14:32:36:192 MESSAGE Incoming INVITE has ACK, dialog is happy 2017-10-12 14:32:36:193 MESSAGE Changing [server] [REFER] transaction [0A33A3F0], from state [INIT] to [TRYING] 2017-10-12 14:32:36:193 MESSAGE Receiving REFER request on op [0A8F8738] 2017-10-12 14:32:36:194 MESSAGE Changing [server] [REFER] transaction [0A33A3F0], from state [TRYING] to [COMPLETED] 2017-10-12 14:32:36:194 MESSAGE channel [08CB2428]: message sent to [TCP://server0f.ve.alceo.com:5060], size: [500] bytes SIP/2.0 202 Accepted Via: SIP/2.0/TCP 192.168.1.5:5060;branch=z9hG4bK-43895-107d068c-3b92b760 Via: SIP/2.0/TCP 192.168.1.38:60057;branch=z9hG4bK-443da-10a90f3f-3a2cc20a From: "dsantomaso" ;tag=a292a60-2601a8c0-ea99-55013-443d0-6d2baaf4-443d0 To: "steven" ;tag=N-F0yYl Call-ID: a29f898-2601a8c0-ea99-55013-443d0-bb193f6-443d0 CSeq: 3 REFER User-Agent: Linphone Desktop/4.1.1 (belle-sip/1.6.3) Supported: replaces, outbound Content-Length: 0 Phase 3) 2017-10-12 14:32:36:194 MESSAGE Dialog [0AAAB390]: now updated by transaction [0A33A3F0]. 2017-10-12 14:32:36:194 MESSAGE Call 089F8568: moving from state LinphoneCallPausedByRemote to LinphoneCallRefered 2017-10-12 14:32:36:194 MESSAGE Linphone core [04A12800] notified [call_state_changed] 2017-10-12 14:32:36:194 MESSAGE Automatically pausing current call to accept transfer. 2017-10-12 14:32:36:194 ERROR No reason to pause this call, it is already paused or inactive. 2017-10-12 14:32:36:195 MESSAGE Starting new call to refered address sip:address@hidden 2017-10-12 14:32:36:195 MESSAGE Found media local-ip from signaling. 2017-10-12 14:32:36:195 MESSAGE New LinphoneCall [0A959E20] initialized (LinphoneCore version: 3.12.0) 2017-10-12 14:32:36:196 MESSAGE Linphone core [04A12800] notified [call_created] 2017-10-12 14:32:36:196 MESSAGE Call 0A959E20: moving from state LinphoneCallIdle to LinphoneCallOutgoingInit 2017-10-12 14:32:36:199 MESSAGE QT: "Add call:" CallModel(0xa344cf8) 2017-10-12 14:32:36:214 MESSAGE linphone_core_get_call_history_for_address(): completed in 0 ms 2017-10-12 14:32:36:240 MESSAGE Linphone core [04A12800] notified [call_state_changed] 2017-10-12 14:32:36:240 MESSAGE Call [0A959E20], stream type [audio], multicast role is [inactive] 2017-10-12 14:32:36:240 MESSAGE RtpSession bound to [0.0.0.0] ports [7080] [7081] 2017-10-12 14:32:36:240 MESSAGE rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2017-10-12 14:32:36:240 MESSAGE Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80 2017-10-12 14:32:36:240 MESSAGE Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32 2017-10-12 14:32:36:240 MESSAGE Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80 2017-10-12 14:32:36:240 MESSAGE Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32 2017-10-12 14:32:36:240 MESSAGE Creating ZRTP engine on rtp session [0ACC10F8] ssrc 0xe6f0284c 2017-10-12 14:32:36:241 MESSAGE Setting DSCP to 46 for MSAudio stream. 2017-10-12 14:32:36:241 MESSAGE check OS support for qwave.lib 2017-10-12 14:32:36:241 ERROR QOSAddSocketToFlow failed to add a flow with error 87 2017-10-12 14:32:36:241 MESSAGE Equalizer location: hp 2017-10-12 14:32:36:242 MESSAGE cannot set noise gate mode to [0] because no volume send 2017-10-12 14:32:36:242 MESSAGE Call [0A959E20], stream type [video], multicast role is [inactive] 2017-10-12 14:32:36:242 MESSAGE RtpSession bound to [0.0.0.0] ports [9080] [9081] 2017-10-12 14:32:36:242 MESSAGE rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2017-10-12 14:32:36:242 MESSAGE Initializing multistream ZRTP context on rtp session [0AD04078] ssrc 0xfccb9c61 2017-10-12 14:32:36:242 MESSAGE Setting DSCP to 0 for MSVideo stream. 2017-10-12 14:32:36:242 MESSAGE check OS support for qwave.lib 2017-10-12 14:32:36:243 ERROR QOSAddSocketToFlow failed to add a flow with error 87 2017-10-12 14:32:36:243 MESSAGE Call [0A959E20], stream type [text], multicast role is [inactive] 2017-10-12 14:32:36:243 MESSAGE RtpSession bound to [0.0.0.0] ports [11080] [11081] 2017-10-12 14:32:36:243 MESSAGE rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2017-10-12 14:32:36:243 MESSAGE Contact has been fixed using proxy 2017-10-12 14:32:36:243 MESSAGE Found media local-ip from signaling. 2017-10-12 14:32:36:243 MESSAGE Don't put audio stream on local offer for call [0A959E20] 2017-10-12 14:32:36:243 MESSAGE Don't put video stream on local offer for call [0A959E20] 2017-10-12 14:32:36:243 MESSAGE Don't put text stream on local offer for call [0A959E20] 2017-10-12 14:32:36:243 MESSAGE ms_filter_link: MSRtpRecv:0AECBDE8,0-->MSVoidSink:0AECC028,0 2017-10-12 14:32:36:243 MESSAGE [sip:address@hidden calling [sip:address@hidden on op [0A9ED848] 2017-10-12 14:32:36:243 MESSAGE win32 timer resolution set to 2 ms 2017-10-12 14:32:36:243 MESSAGE Changing [client] [INVITE] transaction [0AE7F420], from state [INIT] to [CALLING] 2017-10-12 14:32:36:244 MESSAGE channel [08CB2428]: message sent to [TCP://server0f.ve.alceo.com:5060], size: [869] bytes INVITE sip:address@hidden SIP/2.0 Via: SIP/2.0/TCP 192.168.1.38:1344;branch=z9hG4bK.c1K7XZgpg;rport From: ;tag=lx--NqViY To: sip:address@hidden CSeq: 20 INVITE Call-ID: ugxmjAxJfB Max-Forwards: 70 Route: Supported: replaces, outbound Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Content-Type: application/sdp Content-Length: 185 Referred-By: "dsantomaso" Contact: ;+sip.instance="" User-Agent: Linphone Desktop/4.1.1 (belle-sip/1.6.3) v=0 o=steven 1549 2953 IN IP4 192.168.1.38 s=Talk c=IN IP4 0.0.0.0 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics m=audio 0 RTP/AVP 0 a=inactive 2017-10-12 14:32:36:244 MESSAGE Call 0A959E20: moving from state LinphoneCallOutgoingInit to LinphoneCallOutgoingProgress 2017-10-12 14:32:36:244 MESSAGE Linphone core [04A12800] notified [call_state_changed] 2017-10-12 14:32:36:244 MESSAGE Dialog [0AAAB390]: now updated by transaction [0ADCDFD8]. 2017-10-12 14:32:36:244 MESSAGE Changing [client] [NOTIFY] transaction [0ADCDFD8], from state [INIT] to [TRYING] 2017-10-12 14:32:36:244 MESSAGE channel [08CB2428]: message sent to [TCP://server0f.ve.alceo.com:5060], size: [606] bytes NOTIFY sip:address@hidden SIP/2.0 Via: SIP/2.0/TCP 192.168.1.38:1344;branch=z9hG4bK.Vw9ydViqO;rport From: "steven" ;tag=N-F0yYl To: "dsantomaso" ;tag=a292a60-2601a8c0-ea99-55013-443d0-6d2baaf4-443d0 CSeq: 111 NOTIFY Call-ID: a29f898-2601a8c0-ea99-55013-443d0-bb193f6-443d0 Max-Forwards: 70 Subscription-State: active Event: refer Content-Type: message/sipfrag Content-Length: 20 User-Agent: Linphone Desktop/4.1.1 (belle-sip/1.6.3) Route: SIP/2.0 100 Trying 2017-10-12 14:32:36:319 MESSAGE channel [08CB2428]: received [548] new bytes from [TCP://server0f.ve.alceo.com:5060]: SIP/2.0 100 Trying From: ;tag=lx--NqViY To: Call-ID: ugxmjAxJfB CSeq: 20 INVITE Via: SIP/2.0/TCP 192.168.1.38:1344;rport;branch=z9hG4bK.c1K7XZgpg Content-Length: 0 SIP/2.0 100 Trying From: "steven";tag=N-F0yYl To: "dsantomaso";tag=a292a60-2601a8c0-ea99-55013-443d0-6d2baaf4-443d0 Call-ID: a29f898-2601a8c0-ea99-55013-443d0-bb193f6-443d0 CSeq: 111 NOTIFY Via: SIP/2.0/TCP 192.168.1.38:1344;rport;branch=z9hG4bK.Vw9ydViqO Content-Length: 0 2017-10-12 14:32:36:320 MESSAGE channel [08CB2428] [217] bytes parsed 2017-10-12 14:32:36:321 MESSAGE channel [08CB2428] [331] bytes parsed 2017-10-12 14:32:36:321 MESSAGE Found transaction matching response. 2017-10-12 14:32:36:321 MESSAGE Changing [client] [INVITE] transaction [0AE7F420], from state [CALLING] to [PROCEEDING] 2017-10-12 14:32:36:322 MESSAGE op [0A9ED848] : set_or_update_dialog() current=[00000000] new=[00000000] 2017-10-12 14:32:36:322 MESSAGE Op [0A9ED848] receiving call response [100], dialog is [00000000] in state [BELLE_SIP_DIALOG_NULL] 2017-10-12 14:32:36:322 MESSAGE Found transaction matching response. 2017-10-12 14:32:36:322 MESSAGE Changing [client] [NOTIFY] transaction [0ADCDFD8], from state [TRYING] to [PROCEEDING] 2017-10-12 14:32:36:322 MESSAGE Dialog [0AAAB390]: now updated by transaction [0ADCDFD8]. 2017-10-12 14:32:36:322 MESSAGE op [0A8F8738] : set_or_update_dialog() current=[0AAAB390] new=[0AAAB390] 2017-10-12 14:32:36:322 MESSAGE Op [0A8F8738] receiving call response [100], dialog is [0AAAB390] in state [BELLE_SIP_DIALOG_CONFIRMED] 2017-10-12 14:32:36:322 MESSAGE Changing [server] [REFER] transaction [0A33A3F0], from state [COMPLETED] to [TERMINATED] 2017-10-12 14:32:36:322 MESSAGE Server REFER transaction [0A33A3F0] terminated 2017-10-12 14:32:36:322 MESSAGE Transaction [0A33A3F0] deleted 2017-10-12 14:32:36:403 MESSAGE channel [08CB2428]: received [446] new bytes from [TCP://server0f.ve.alceo.com:5060]: SIP/2.0 200 OK From: "steven";tag=N-F0yYl To: "dsantomaso";tag=a292a60-2601a8c0-ea99-55013-443d0-6d2baaf4-443d0 Call-ID: a29f898-2601a8c0-ea99-55013-443d0-bb193f6-443d0 CSeq: 111 NOTIFY Via: SIP/2.0/TCP 192.168.1.38:1344;rport;branch=z9hG4bK.Vw9ydViqO Supported: gruu,join,replaces,timer,100rel Contact: Content-Length: 0 2017-10-12 14:32:36:405 MESSAGE channel [08CB2428] [446] bytes parsed 2017-10-12 14:32:36:405 MESSAGE Found transaction matching response. 2017-10-12 14:32:36:405 MESSAGE Changing [client] [NOTIFY] transaction [0ADCDFD8], from state [PROCEEDING] to [COMPLETED] 2017-10-12 14:32:36:405 MESSAGE Dialog [0AAAB390]: now updated by transaction [0ADCDFD8]. 2017-10-12 14:32:36:405 MESSAGE op [0A8F8738] : set_or_update_dialog() current=[0AAAB390] new=[0AAAB390] 2017-10-12 14:32:36:405 MESSAGE Op [0A8F8738] receiving call response [200], dialog is [0AAAB390] in state [BELLE_SIP_DIALOG_CONFIRMED] 2017-10-12 14:32:36:405 MESSAGE Changing [client] [NOTIFY] transaction [0ADCDFD8], from state [COMPLETED] to [TERMINATED] 2017-10-12 14:32:36:405 MESSAGE Client NOTIFY transaction [0ADCDFD8] terminated 2017-10-12 14:32:36:621 MESSAGE channel [08CB2428]: received [325] new bytes from [TCP://server0f.ve.alceo.com:5060]: SIP/2.0 488 Not acceptable here From: ;tag=lx--NqViY To: ;tag=LGqECcI Call-ID: ugxmjAxJfB CSeq: 20 INVITE Via: SIP/2.0/TCP 192.168.1.38:1344;rport;branch=z9hG4bK.c1K7XZgpg User-Agent: LinphoneAndroid/3.2.7 (belle-sip/1.6.1) Supported: replaces,outbound Content-Length: 0 2017-10-12 14:32:36:622 MESSAGE channel [08CB2428] [325] bytes parsed 2017-10-12 14:32:36:622 MESSAGE Found transaction matching response. 2017-10-12 14:32:36:622 MESSAGE Changing [client] [INVITE] transaction [0AE7F420], from state [PROCEEDING] to [COMPLETED] 2017-10-12 14:32:36:622 MESSAGE channel [08CB2428]: message sent to [TCP://server0f.ve.alceo.com:5060], size: [429] bytes ACK sip:address@hidden SIP/2.0 Via: SIP/2.0/TCP 192.168.1.38:1344;branch=z9hG4bK.c1K7XZgpg;rport Call-ID: ugxmjAxJfB From: ;tag=lx--NqViY To: ;tag=LGqECcI Contact: ;+sip.instance="" Route: Max-Forwards: 70 CSeq: 20 ACK Content-Length: 0