Hi All, I am having some problem with video when using pjproject-2.0-rc. This problem is not happening with pjproject-2.0-beta. When I make an outgoing call from pjsua.exe to a Polycom VVX1500 phone the video tx and rx is working fine but there is a second window on the PC that is closing and opening repeatedly. The log shows that the sdl video stream is stopping and starting repeatedly after PJMEDIA_EVENT_FMT_CHANGED event is triggered repeatedly. Can anyone explain what might be happening? See attached log files for both versions of pj-project. Regards, Omar -------------- next part -------------- An HTML attachment was scrubbed... URL: <http://lists.pjsip.org/pipermail/pjsip_lists.pjsip.org/attachments/20120429/c3853a80/attachment-0001.html> -------------- next part -------------- 10:39:20.565 sip_endpoint.c .Module "mod-pjsua-log" registered 10:39:20.565 sip_endpoint.c .Module "mod-tsx-layer" registered 10:39:20.566 sip_endpoint.c .Module "mod-stateful-util" registered 10:39:20.566 sip_endpoint.c .Module "mod-ua" registered 10:39:20.566 sip_endpoint.c .Module "mod-100rel" registered 10:39:20.566 sip_endpoint.c .Module "mod-pjsua" registered 10:39:20.566 sip_endpoint.c .Module "mod-invite" registered 10:39:20.577 wmme_dev.c ..WMME found 6 devices: 10:39:20.577 wmme_dev.c .. dev_id 0: Wave mapper (in=2, out=2) 10:39:20.577 wmme_dev.c .. dev_id 1: Microphone (2- Microsoft LifeCa (in=2, out=0) 10:39:20.577 wmme_dev.c .. dev_id 2: Realtek Digital Input (Realtek (in=2, out=0) 10:39:20.577 wmme_dev.c .. dev_id 3: Speakers (Realtek High Definiti (in=0, out=2) 10:39:20.577 wmme_dev.c .. dev_id 4: Realtek Digital Output (Realtek (in=0, out=2) 10:39:20.577 wmme_dev.c .. dev_id 5: Realtek Digital Output(Optical) (in=0, out=2) 10:39:20.577 wmme_dev.c ..WMME initialized 10:39:20.578 pjlib ..select() I/O Queue created (027F4924) 10:39:20.578 conference.c ..Creating conference bridge with 254 ports 10:39:20.578 Master/sound ..Using delay buffer with WSOLA. 10:39:20.582 pjsua_vid.c ..Initializing video subsystem.. 10:39:20.584 ffmpeg_codecs. ...The H263-1998 decoder is using base codec (H263) 10:39:20.584 ffmpeg_codecs. ...The XVID codec is using base codec (MP4V) 10:39:21.800 dshow_dev.c ...DShow has 1 devices: 10:39:21.801 dshow_dev.c ... dev_id 0: Microsoft LifeCam NX-3000 (capture) 10:39:21.801 colorbar_dev.c ...Colorbar video src initialized with 1 device(s): 10:39:21.801 colorbar_dev.c ... 0: Colorbar generator 10:39:21.803 sdl_dev.c ...SDL 1.3 initialized 10:39:21.803 sip_endpoint.c .Module "mod-evsub" registered 10:39:21.803 sip_endpoint.c .Module "mod-presence" registered 10:39:21.803 evsub.c .Event pkg "presence" registered by mod-presence 10:39:21.803 sip_endpoint.c .Module "mod-mwi" registered 10:39:21.804 evsub.c .Event pkg "message-summary" registered by mod-mwi 10:39:21.804 sip_endpoint.c .Module "mod-refer" registered 10:39:21.804 evsub.c .Event pkg "refer" registered by mod-refer 10:39:21.804 sip_endpoint.c .Module "mod-pjsua-pres" registered 10:39:21.804 sip_endpoint.c .Module "mod-pjsua-im" registered 10:39:21.804 sip_endpoint.c .Module "mod-pjsua-options" registered 10:39:21.804 pjsua_core.c .1 SIP worker threads created 10:39:21.804 pjsua_core.c .pjsua version 2.0.0-beta for win32-6.1/i386/msvc-15.0 initialized 10:39:21.804 pjsua_core.c .PJSUA state changed: CREATED --> INIT 10:39:21.804 sip_endpoint.c Module "mod-default-handler" registered 10:39:21.807 pjsua_core.c SIP UDP socket reachable at 10.0.0.193:5060 10:39:21.807 udp02845F60 SIP UDP transport started, published address is 10.0.0.193:5060 10:39:21.807 pjsua_acc.c Adding account: id=<sip:10.0.0.193:5060> 10:39:21.807 pjsua_acc.c .Account <sip:10.0.0.193:5060> added with id 0 10:39:21.807 pjsua_acc.c Modifying accunt 0 10:39:21.807 pjsua_acc.c Acc 0: setting online status to 1.. 10:39:21.810 tcplis:5060 SIP TCP listener ready for incoming connections at 10.0.0.193:5060 10:39:21.810 pjsua_acc.c Adding account: id=<sip:10.0.0.193:5060;transport=TCP> 10:39:21.810 pjsua_acc.c .Account <sip:10.0.0.193:5060;transport=TCP> added with id 1 10:39:21.815 pjsua_acc.c Modifying accunt 1 10:39:21.819 pjsua_acc.c Acc 1: setting online status to 1.. 10:39:21.821 pjsua_core.c PJSUA state changed: INIT --> STARTING 10:39:21.822 pjsua_media.c ..NAT type detection failed: Invalid STUN server or server not configured (PJNATH_ESTUNINSERVER) 10:39:21.824 sip_endpoint.c .Module "mod-unsolicited-mwi" registered 10:39:21.827 pjsua_core.c .PJSUA state changed: STARTING --> RUNNING 10:39:23.463 pjsua_app.c Video will be enabled in next offer/answer 10:39:28.935 pjsua_acc.c Modifying accunt 1 10:39:31.702 pjsua_acc.c Modifying accunt 1 10:39:34.606 pjsua_call.c Making call with acc #1 to sip:10.0.0.208 10:39:34.608 pjsua_media.c .Set sound device: capture=-1, playback=-2 10:39:34.612 pjsua_app.c ..Turning sound device ON 10:39:34.617 pjsua_media.c ..Opening sound device PCM at 16000/1/20ms 10:39:34.695 wmme_dev.c ... WaveAPI Sound player "Wave mapper" initialized (format=PCM, clock_rate=16000, channel_count=1, samples_per_frame=320 (20ms)) 10:39:34.704 wmme_dev.c ... WaveAPI Sound recorder "Wave mapper" initialized (format=PCM, clock_rate=16000, channel_count=1, samples_per_frame=320 (20ms)) 10:39:34.706 ec02851818 ...Creating AEC 10:39:34.707 ec02851818 ...Using delay buffer with WSOLA. 10:39:34.707 ec02851818 ...AEC created, clock_rate=16000, channel=1, samples per frame=320, tail length=200 ms, latency=100 ms 10:39:34.710 wmme_dev.c ...WMME playback stream started 10:39:34.713 wmme_dev.c ...WMME capture stream started 10:39:34.719 dlg02887454 .UAC dialog created 10:39:34.719 dlg02887454 ..Session count inc to 1 by mod-pjsua 10:39:34.719 pjsua_media.c .Call 0: initializing media.. 10:39:34.724 pjsua_media.c ..RTP socket reachable at 10.0.0.193:40000 10:39:34.726 pjsua_media.c ..RTCP socket reachable at 10.0.0.193:40001 10:39:34.731 pjsua_media.c ..RTP socket reachable at 10.0.0.193:40002 10:39:34.734 pjsua_media.c ..RTCP socket reachable at 10.0.0.193:40003 10:39:34.736 pjsua_media.c ..Media index 0 selected for audio call 0 10:39:34.738 dlg02887454 ..Session count dec to 1 by mod-pjsua 10:39:34.738 dlg02887454 .Module mod-invite added as dialog usage, data=0288F2F4 10:39:34.738 dlg02887454 ..Session count inc to 3 by mod-invite 10:39:34.738 dlg02887454 .Module mod-100rel added as dialog usage, data=02894B04 10:39:34.738 dlg02887454 .100rel module attached 10:39:34.738 inv02887454 .UAC invite session created for dialog dlg02887454 10:39:34.738 endpoint .Request msg INVITE/cseq=5995 (tdta02894C00) created. 10:39:34.738 inv02887454 ..Sending Request msg INVITE/cseq=5995 (tdta02894C00) 10:39:34.738 dlg02887454 ...Sending Request msg INVITE/cseq=5995 (tdta02894C00) 10:39:34.738 tsx02896C64 ....Transaction created for Request msg INVITE/cseq=5994 (tdta02894C00) 10:39:34.738 tsx02896C64 ...Sending Request msg INVITE/cseq=5994 (tdta02894C00) in state Null 10:39:34.738 sip_resolve.c ....Target '10.0.0.208:0' type=Unspecified resolved to '10.0.0.208:5060' type=UDP (UDP transport) 10:39:34.738 pjsua_core.c ....TX 1214 bytes Request msg INVITE/cseq=5994 (tdta02894C00) to UDP 10.0.0.208:5060: INVITE sip:10.0.0.208 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPjc3d6e53015f743e792854f29b735f392 Max-Forwards: 70 From: <sip:10.0.0.193>;tag=8e32cb9d7de54f638cbcd9609911dc73 To: sip:10.0.0.208 Contact: <sip:10.0.0.193:5060;ob> Call-ID: 602cc3bd2a1740c0975e6827b7a24abe CSeq: 5994 INVITE Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Supported: replaces, 100rel, timer, norefersub Session-Expires: 1800 Min-SE: 90 User-Agent: PJSUA v2.0.0-beta win32-6.1/i386/msvc-15.0 Content-Type: application/sdp Content-Length: 611 v=0 o=- 3544684774 3544684774 IN IP4 10.0.0.193 s=pjmedia c=IN IP4 10.0.0.193 t=0 0 a=X-nat:0 m=audio 40000 RTP/AVP 98 97 99 104 3 0 8 9 96 c=IN IP4 10.0.0.193 a=rtcp:40001 IN IP4 10.0.0.193 a=sendrecv a=rtpmap:98 speex/16000 a=rtpmap:97 speex/8000 a=rtpmap:99 speex/32000 a=rtpmap:104 iLBC/8000 a=fmtp:104 mode=30 a=rtpmap:3 GSM/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:9 G722/8000 a=rtpmap:96 telephone-event/8000 a=fmtp:96 0-15 m=video 40002 RTP/AVP 96 c=IN IP4 10.0.0.193 a=rtcp:40003 IN IP4 10.0.0.193 a=sendrecv a=rtpmap:96 H263-1998/90000 a=fmtp:96 CIF=1;QCIF=1 --end msg-- 10:39:34.740 tsx02896C64 ....State changed from Null to Calling, event=TX_MSG 10:39:34.740 dlg02887454 .....Transaction tsx02896C64 state changed to Calling 10:39:34.740 pjsua_app.c .......Call 0 state changed to CALLING 10:39:34.746 ec02851818 !Prefetching.. 10:39:34.756 ec02851818 Prefetching.. 10:39:34.767 sip_endpoint.c !Processing incoming message: Response msg 100/INVITE/cseq=5994 (rdata0284AF24) 10:39:34.767 pjsua_core.c .RX 409 bytes Response msg 100/INVITE/cseq=5994 (rdata0284AF24) from UDP 10.0.0.208:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPjc3d6e53015f743e792854f29b735f392 From: <sip:10.0.0.193>;tag=8e32cb9d7de54f638cbcd9609911dc73 To: "4099" <sip:10.0.0.208>;tag=79B1B565-F7B916FC CSeq: 5994 INVITE Call-ID: 602cc3bd2a1740c0975e6827b7a24abe Contact: <sip:4099 at 10.0.0.208:5060> User-Agent: PolycomVVX-VVX_1500-UA/3.2.2.0481 Accept-Language: en Content-Length: 0 --end msg-- 10:39:34.776 ec02851818 !Prefetching.. 10:39:34.786 ec02851818 Prefetching.. 10:39:34.806 ec02851818 Prefetching.. 10:39:34.816 ec02851818 Prefetching.. 10:39:34.835 tsx02896C64 !.Incoming Response msg 100/INVITE/cseq=5994 (rdata0284AF24) in state Calling 10:39:34.835 tsx02896C64 ..State changed from Calling to Proceeding, event=RX_MSG 10:39:34.835 dlg02887454 ...Received Response msg 100/INVITE/cseq=5994 (rdata0284AF24) 10:39:34.835 dlg02887454 ...Transaction tsx02896C64 state changed to Proceeding 10:39:34.836 ec02851818 !Prefetching.. 10:39:34.846 ec02851818 Prefetching.. 10:39:34.850 ec02851818 Latency bufferring complete 10:39:34.927 sip_endpoint.c !Processing incoming message: Response msg 180/INVITE/cseq=5994 (rdata0284AF24) 10:39:34.927 pjsua_core.c .RX 475 bytes Response msg 180/INVITE/cseq=5994 (rdata0284AF24) from UDP 10.0.0.208:5060: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPjc3d6e53015f743e792854f29b735f392 From: <sip:10.0.0.193>;tag=8e32cb9d7de54f638cbcd9609911dc73 To: "4099" <sip:10.0.0.208>;tag=79B1B565-F7B916FC CSeq: 5994 INVITE Call-ID: 602cc3bd2a1740c0975e6827b7a24abe Contact: <sip:4099 at 10.0.0.208:5060> User-Agent: PolycomVVX-VVX_1500-UA/3.2.2.0481 Allow-Events: talk,hold,conference Accept-Language: en Require: 100rel RSeq: 8193 Content-Length: 0 --end msg-- 10:39:34.929 tsx02896C64 .Incoming Response msg 180/INVITE/cseq=5994 (rdata0284AF24) in state Proceeding 10:39:34.929 tsx02896C64 ..State changed from Proceeding to Proceeding, event=RX_MSG 10:39:34.929 dlg02887454 ...Received Response msg 180/INVITE/cseq=5994 (rdata0284AF24) 10:39:34.929 dlg02887454 ....Route-set updated 10:39:34.929 dlg02887454 ...Transaction tsx02896C64 state changed to Proceeding 10:39:34.929 pjsua_media.c .....Conf connect: 1 --> 0 10:39:34.937 conference.c ......Port 1 (ringback) transmitting to port 0 (Wave mapper) 10:39:34.938 pjsua_app.c .....Call 0 state changed to EARLY (180 Ringing) 10:39:34.939 endpoint ....Request msg PRACK/cseq=5996 (tdta02899820) created. 10:39:34.939 dlg02887454 .....Sending Request msg PRACK/cseq=5996 (tdta02899820) 10:39:34.939 tsx0289A8B4 ......Transaction created for Request msg PRACK/cseq=5995 (tdta02899820) 10:39:34.940 tsx0289A8B4 .....Sending Request msg PRACK/cseq=5995 (tdta02899820) in state Null 10:39:34.940 sip_resolve.c ......Target '10.0.0.208:5060' type=Unspecified resolved to '10.0.0.208:5060' type=UDP (UDP transport) 10:39:34.940 pjsua_core.c ......TX 357 bytes Request msg PRACK/cseq=5995 (tdta02899820) to UDP 10.0.0.208:5060: PRACK sip:4099 at 10.0.0.208:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPjbca110c459c843638432fc6542fb02a3 Max-Forwards: 70 From: <sip:10.0.0.193>;tag=8e32cb9d7de54f638cbcd9609911dc73 To: sip:10.0.0.208;tag=79B1B565-F7B916FC Call-ID: 602cc3bd2a1740c0975e6827b7a24abe CSeq: 5995 PRACK RAck: 8193 5994 INVITE Content-Length: 0 --end msg-- 10:39:34.941 tsx0289A8B4 ......State changed from Null to Calling, event=TX_MSG 10:39:34.941 dlg02887454 .......Transaction tsx0289A8B4 state changed to Calling 10:39:34.958 sip_endpoint.c Processing incoming message: Response msg 200/PRACK/cseq=5995 (rdata0284AF24) 10:39:34.958 pjsua_core.c .RX 404 bytes Response msg 200/PRACK/cseq=5995 (rdata0284AF24) from UDP 10.0.0.208:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPjbca110c459c843638432fc6542fb02a3 From: <sip:10.0.0.193>;tag=8e32cb9d7de54f638cbcd9609911dc73 To: "4099" <sip:10.0.0.208>;tag=79B1B565-F7B916FC CSeq: 5995 PRACK Call-ID: 602cc3bd2a1740c0975e6827b7a24abe Contact: <sip:4099 at 10.0.0.208:5060> User-Agent: PolycomVVX-VVX_1500-UA/3.2.2.0481 Accept-Language: en Content-Length: 0 --end msg-- 10:39:34.960 tsx0289A8B4 .Incoming Response msg 200/PRACK/cseq=5995 (rdata0284AF24) in state Calling 10:39:34.960 tsx0289A8B4 ..State changed from Calling to Completed, event=RX_MSG 10:39:34.960 dlg02887454 ...Received Response msg 200/PRACK/cseq=5995 (rdata0284AF24) 10:39:34.960 dlg02887454 ...Transaction tsx0289A8B4 state changed to Completed 10:39:36.676 sip_endpoint.c Processing incoming message: Response msg 200/INVITE/cseq=5994 (rdata0284AF24) 10:39:36.676 pjsua_core.c .RX 857 bytes Response msg 200/INVITE/cseq=5994 (rdata0284AF24) from UDP 10.0.0.208:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPjc3d6e53015f743e792854f29b735f392 From: <sip:10.0.0.193>;tag=8e32cb9d7de54f638cbcd9609911dc73 To: "4099" <sip:10.0.0.208>;tag=79B1B565-F7B916FC CSeq: 5994 INVITE Call-ID: 602cc3bd2a1740c0975e6827b7a24abe Contact: <sip:4099 at 10.0.0.208:5060> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER Supported: 100rel,replaces User-Agent: PolycomVVX-VVX_1500-UA/3.2.2.0481 Accept-Language: en Content-Type: application/sdp Content-Length: 293 v=0 o=- 1168888114 1168888114 IN IP4 10.0.0.208 s=Polycom IP Phone c=IN IP4 10.0.0.208 b=AS:448 t=0 0 m=audio 2242 RTP/AVP 0 96 a=sendrecv a=rtpmap:0 PCMU/8000 a=rtpmap:96 telephone-event/8000 m=video 2244 RTP/AVP 96 a=sendrecv a=rtpmap:96 H263-1998/90000 a=fmtp:96 CIF=1;QCIF=1 --end msg-- 10:39:36.680 tsx02896C64 .Incoming Response msg 200/INVITE/cseq=5994 (rdata0284AF24) in state Proceeding 10:39:36.680 tsx02896C64 ..State changed from Proceeding to Terminated, event=RX_MSG 10:39:36.680 dlg02887454 ...Received Response msg 200/INVITE/cseq=5994 (rdata0284AF24) 10:39:36.680 dlg02887454 ....Route-set updated 10:39:36.680 dlg02887454 ....Route-set frozen 10:39:36.680 dlg02887454 ...Transaction tsx02896C64 state changed to Terminated 10:39:36.680 pjsua_app.c .....Call 0 state changed to CONNECTING 10:39:36.680 inv02887454 ....Got SDP answer in Response msg 200/INVITE/cseq=5994 (rdata0284AF24) 10:39:36.680 inv02887454 ....SDP negotiation done, status=0 10:39:36.680 pjsua_call.c .....Call 0: remote NAT type is 0 (Unknown) 10:39:36.680 pjsua_media.c .....Call 0: updating media.. 10:39:36.681 pjsua_media.c .......Media session call00:0 is destroyed 10:39:36.681 pjsua_media.c .......Media session call00:1 is destroyed 10:39:36.681 pjsua_media.c ......Audio channel update.. 10:39:36.681 strm0289DDC4 .......VAD temporarily disabled 10:39:36.681 rtp.c .......pjmedia_rtp_session_init: ses=0289F8B0, default_pt=0, ssrc=0x61bd309e 10:39:36.681 rtp.c .......pjmedia_rtp_session_init: ses=0289FF60, default_pt=0, ssrc=0x61bd309e 10:39:36.681 stream.c .......Stream strm0289DDC4 created 10:39:36.681 strm0289DDC4 .......Encoder stream started 10:39:36.682 strm0289DDC4 .......Decoder stream started 10:39:36.682 resample.c .......resample created: high qualiy, large filter, in/out rate=8000/16000 10:39:36.682 resample.c .......resample created: high qualiy, large filter, in/out rate=16000/8000 10:39:36.682 pjsua_media.c .......Audio updated, stream #0: PCMU (sendrecv) 10:39:36.682 pjsua_vid.c ......Video channel update.. 10:39:36.685 rtp.c .......pjmedia_rtp_session_init: ses=028A2034, default_pt=96, ssrc=0x414c2afe 10:39:36.685 vstdec028A1B3C .......Decoding channel created 352x288 I420<-H263-1998 30000/1001(~29)fps 10:39:36.685 rtp.c .......pjmedia_rtp_session_init: ses=028A20E8, default_pt=96, ssrc=0x414c2afe 10:39:36.685 vstenc028A1B3C .......Encoding channel created 352x288 I420->H263-1998 30000/1001(~29)fps 10:39:36.686 vid_stream.c .......Video stream vstrm028A1B3C created 10:39:36.686 vstenc028A1B3C .......Encoder stream started 10:39:36.686 vstdec028A1B3C .......Decoder stream started 10:39:36.687 pjsua_vid.c .......Setting up RX.. 10:39:36.687 pjsua_vid.c ........Creating video window: type=stream, cap_id=-1, rend_id=2 10:39:36.687 vid_port.c .........Opening device SDL renderer [SDL] for render: format=I420, size=352x288 @30000:1001 fps 10:39:36.690 strm0289DDC4 !Start talksprut.. 10:39:36.753 vid_port.c !.........Device SDL renderer [SDL] opened: format=I420, size=352x288 @30000:1001 fps 10:39:36.753 pjsua_vid.c .........stream window id 0 created for cap_dev=-1 rend_dev=2 10:39:36.754 pjsua_vid.c .........Window 0 created 10:39:36.754 sdl_dev.c ........Starting sdl video stream 10:39:36.754 pjsua_vid.c .......Setting up TX.. 10:39:36.754 pjsua_vid.c ........Creating video window: type=preview, cap_id=0, rend_id=2 10:39:36.754 vid_port.c .........Opening device Microsoft LifeCam NX-3000 [dshow] for capture: format=RGB24, size=352x288 @30000:1001 fps 10:39:36.761 dshow_dev.c .........Error creating filter graph: There was no match for the specified key in the index. (hr=0x80070491) 10:39:36.762 dshow_dev.c .........Trying to open dshow dev with default fps 10:39:36.781 vid_port.c .........Device Microsoft LifeCam NX-3000 [dshow] opened: format=RGB24, size=352x288 @10000000:333333 fps 10:39:36.781 vid_port.c .........Opening device SDL renderer [SDL] for render: format=I420, size=352x288 @30000:1001 fps 10:39:36.795 vid_port.c .........Device SDL renderer [SDL] opened: format=I420, size=352x288 @30000:1001 fps 10:39:36.795 pjsua_vid.c .........preview window id 1 created for cap_dev=0 rend_dev=2 10:39:36.795 pjsua_vid.c .........Window 1 created 10:39:36.799 dshow_dev.c ........Starting dshow video stream 10:39:36.799 pjsua_vid.c .......Video updated, stream #1: H263-1998 (sendrecv) 10:39:36.800 pjsua_app.c .....Call 0 media 0 [type=audio], status is Active 10:39:36.800 pjsua_media.c .....Conf disconnect: 1 -x- 0 10:39:36.800 conference.c ......Port 1 (ringback) stop transmitting to port 0 (Wave mapper) 10:39:36.800 pjsua_media.c .....Conf connect: 3 --> 0 10:39:36.800 conference.c ......Port 3 (sip:10.0.0.208) transmitting to port 0 (Wave mapper) 10:39:36.800 pjsua_media.c .....Conf connect: 0 --> 3 10:39:36.800 conference.c ......Port 0 (Wave mapper) transmitting to port 3 (sip:10.0.0.208) 10:39:36.800 pjsua_app.c .....Call 0 media 1 [type=video], status is Active 10:39:36.801 inv02887454 ....Received Response msg 200/INVITE/cseq=5994 (rdata0284AF24), sending ACK 10:39:36.801 endpoint ....Request msg ACK/cseq=5994 (tdta062BB818) created. 10:39:36.801 dlg02887454 .....Sending Request msg ACK/cseq=5994 (tdta062BB818) 10:39:36.801 sip_resolve.c .....Target '10.0.0.208:5060' type=Unspecified resolved to '10.0.0.208:5060' type=UDP (UDP transport) 10:39:36.801 pjsua_core.c .....TX 329 bytes Request msg ACK/cseq=5994 (tdta062BB818) to UDP 10.0.0.208:5060: ACK sip:4099 at 10.0.0.208:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPjc01c7483bb0d498aaf97455fbd81b365 Max-Forwards: 70 From: <sip:10.0.0.193>;tag=8e32cb9d7de54f638cbcd9609911dc73 To: sip:10.0.0.208;tag=79B1B565-F7B916FC Call-ID: 602cc3bd2a1740c0975e6827b7a24abe CSeq: 5994 ACK Content-Length: 0 --end msg-- 10:39:36.802 pjsua_app.c .....Call 0 state changed to CONFIRMED 10:39:36.802 tsx02896C64 Timeout timer event 10:39:36.802 tsx02896C64 .State changed from Terminated to Destroyed, event=TIMER 10:39:36.802 tsx02896C64 ..Transaction destroyed! 10:39:36.810 strm0289DDC4 !Jitter buffer is bufferring (prefetch=0), plc invoked 10:39:36.893 strm0289DDC4 !RTP status: badpt=0, badssrc=0, dup=0, outorder=0, probation=-1, restart=0 10:39:36.910 strm0289DDC4 !Jitter buffer starts returning normal frames (after 5 empty/lost) 10:39:36.914 dshow_dev.c !Capture thread started 10:39:37.065 vstdec028A1B3C !RTP status: badpt=0, badssrc=0, dup=0, outorder=-1, probation=-1, restart=0 10:39:37.066 vstdec028A1B3C RTP status: badpt=0, badssrc=0, dup=0, outorder=0, probation=-1, restart=0 10:39:37.158 vstdec028A1B3C codec decode() error: Bad or corrupted bitstream (PJMEDIA_CODEC_EBADBITSTREAM) [err:220087] 10:39:37.188 pjsua_media.c !Sending video keyframe request via SIP INFO 10:39:37.188 pjsua_call.c Call 0 sending INFO request.. 10:39:37.188 endpoint .Request msg INFO/cseq=5997 (tdta062BE7B8) created. 10:39:37.188 dlg02887454 ..Sending Request msg INFO/cseq=5997 (tdta062BE7B8) 10:39:37.188 tsx062BF84C ...Transaction created for Request msg INFO/cseq=5996 (tdta062BE7B8) 10:39:37.188 tsx062BF84C ..Sending Request msg INFO/cseq=5996 (tdta062BE7B8) in state Null 10:39:37.188 sip_resolve.c ...Target '10.0.0.208:5060' type=Unspecified resolved to '10.0.0.208:5060' type=UDP (UDP transport) 10:39:37.188 pjsua_core.c ...TX 581 bytes Request msg INFO/cseq=5996 (tdta062BE7B8) to UDP 10.0.0.208:5060: INFO sip:4099 at 10.0.0.208:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPjcfb1c42543384dda939472fcd34777b6 Max-Forwards: 70 From: <sip:10.0.0.193>;tag=8e32cb9d7de54f638cbcd9609911dc73 To: sip:10.0.0.208;tag=79B1B565-F7B916FC Call-ID: 602cc3bd2a1740c0975e6827b7a24abe CSeq: 5996 INFO User-Agent: PJSUA v2.0.0-beta win32-6.1/i386/msvc-15.0 Content-Type: application/media_control+xml Content-Length: 146 <?xml version="1.0" encoding="utf-8" ?><media_control><vc_primitive><to_encoder><picture_fast_update/></to_encoder></vc_primitive></media_control> --end msg-- 10:39:37.189 tsx062BF84C ...State changed from Null to Calling, event=TX_MSG 10:39:37.189 dlg02887454 ....Transaction tsx062BF84C state changed to Calling 10:39:37.189 pjsua_app.c Event IFRM 10:39:37.237 ffmpeg_codecs. !Reallocating decoding buffer 152064 --> 622080 10:39:37.237 vstdec028A1B3C codec decode() error: Size is too short (PJ_ETOOSMALL) [err:70019] 10:39:37.248 sip_endpoint.c !Processing incoming message: Response msg 200/INFO/cseq=5996 (rdata0284AF24) 10:39:37.248 pjsua_core.c .RX 403 bytes Response msg 200/INFO/cseq=5996 (rdata0284AF24) from UDP 10.0.0.208:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPjcfb1c42543384dda939472fcd34777b6 From: <sip:10.0.0.193>;tag=8e32cb9d7de54f638cbcd9609911dc73 To: "4099" <sip:10.0.0.208>;tag=79B1B565-F7B916FC CSeq: 5996 INFO Call-ID: 602cc3bd2a1740c0975e6827b7a24abe Contact: <sip:4099 at 10.0.0.208:5060> User-Agent: PolycomVVX-VVX_1500-UA/3.2.2.0481 Accept-Language: en Content-Length: 0 --end msg-- 10:39:37.249 tsx062BF84C .Incoming Response msg 200/INFO/cseq=5996 (rdata0284AF24) in state Calling 10:39:37.249 tsx062BF84C ..State changed from Calling to Completed, event=RX_MSG 10:39:37.249 dlg02887454 ...Received Response msg 200/INFO/cseq=5996 (rdata0284AF24) 10:39:37.249 dlg02887454 ...Transaction tsx062BF84C state changed to Completed 10:39:37.254 vstdec028A1B3C ! Decoding format changed: 720x576 I420<- 30000/1001(~29)fps 10:39:37.254 sdl_dev.c Stopping sdl video stream 10:39:37.301 sdl_dev.c Starting sdl video stream 10:39:37.302 pjsua_app.c !Event FMCH 10:39:37.303 pjsua_app.c Event IFRM 10:39:37.311 strm0289DDC4 !VAD re-enabled 10:39:37.311 strm0289DDC4 Starting silence 10:39:37.317 vstdec028A1B3C !codec decode() error: Size is too short (PJ_ETOOSMALL) [err:70019] 10:39:37.368 vstdec028A1B3C ! Decoding format changed: 352x288 I420<- 30000/1001(~29)fps 10:39:37.368 sdl_dev.c Stopping sdl video stream 10:39:37.409 sdl_dev.c Starting sdl video stream 10:39:37.409 pjsua_app.c !Event FMCH 10:39:37.428 pjsua_app.c Event IFRM 10:39:39.751 strm0289DDC4 !Start talksprut.. 10:39:39.771 strm0289DDC4 Starting silence 10:39:39.961 tsx0289A8B4 !Timeout timer event 10:39:39.961 tsx0289A8B4 .State changed from Completed to Terminated, event=TIMER 10:39:39.961 dlg02887454 ..Transaction tsx0289A8B4 state changed to Terminated 10:39:39.961 tsx0289A8B4 Timeout timer event 10:39:39.961 tsx0289A8B4 .State changed from Terminated to Destroyed, event=TIMER 10:39:39.961 tdta02899820 ..Destroying txdata Request msg PRACK/cseq=5995 (tdta02899820) 10:39:39.961 tsx0289A8B4 ..Transaction destroyed! 10:39:41.291 silencedet.c !Re-adjust threshold (in silence)to 8 10:39:42.250 tsx062BF84C !Timeout timer event 10:39:42.250 tsx062BF84C .State changed from Completed to Terminated, event=TIMER 10:39:42.250 dlg02887454 ..Transaction tsx062BF84C state changed to Terminated 10:39:42.250 tsx062BF84C Timeout timer event 10:39:42.250 tsx062BF84C .State changed from Terminated to Destroyed, event=TIMER 10:39:42.250 tdta062BE7B8 ..Destroying txdata Request msg INFO/cseq=5996 (tdta062BE7B8) 10:39:42.250 tsx062BF84C ..Transaction destroyed! 10:39:44.751 strm0289DDC4 !Start talksprut.. 10:39:44.771 strm0289DDC4 Starting silence 10:39:45.291 silencedet.c Re-adjust threshold (in silence)to 8 10:39:49.291 silencedet.c Re-adjust threshold (in silence)to 8 10:39:49.751 strm0289DDC4 Start talksprut.. 10:39:49.771 strm0289DDC4 Starting silence 10:39:49.831 silencedet.c Starting talk burst (level=15 threshold=8) 10:39:49.831 strm0289DDC4 Start talksprut.. 10:39:50.651 silencedet.c Starting silence (level=3 threshold=8) 10:39:50.651 strm0289DDC4 Starting silence 10:39:51.051 silencedet.c Starting talk burst (level=10 threshold=8) 10:39:51.051 strm0289DDC4 Start talksprut.. 10:39:51.438 pjsua_call.c !Call 0 hanging up: code=0.. 10:39:51.438 endpoint ..Request msg BYE/cseq=5998 (tdta062BE7B8) created. 10:39:51.438 inv02887454 ..Sending Request msg BYE/cseq=5998 (tdta062BE7B8) 10:39:51.438 dlg02887454 ...Sending Request msg BYE/cseq=5998 (tdta062BE7B8) 10:39:51.438 tsx062C222C ....Transaction created for Request msg BYE/cseq=5997 (tdta062BE7B8) 10:39:51.438 tsx062C222C ...Sending Request msg BYE/cseq=5997 (tdta062BE7B8) in state Null 10:39:51.438 sip_resolve.c ....Target '10.0.0.208:5060' type=Unspecified resolved to '10.0.0.208:5060' type=UDP (UDP transport) 10:39:51.438 pjsua_core.c ....TX 385 bytes Request msg BYE/cseq=5997 (tdta062BE7B8) to UDP 10.0.0.208:5060: BYE sip:4099 at 10.0.0.208:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPjc330c3e92fbb41bcb5a8390e682d081e Max-Forwards: 70 From: <sip:10.0.0.193>;tag=8e32cb9d7de54f638cbcd9609911dc73 To: sip:10.0.0.208;tag=79B1B565-F7B916FC Call-ID: 602cc3bd2a1740c0975e6827b7a24abe CSeq: 5997 BYE User-Agent: PJSUA v2.0.0-beta win32-6.1/i386/msvc-15.0 Content-Length: 0 --end msg-- 10:39:51.440 tsx062C222C ....State changed from Null to Calling, event=TX_MSG 10:39:51.440 dlg02887454 .....Transaction tsx062C222C state changed to Calling 10:39:51.458 sip_endpoint.c !Processing incoming message: Response msg 200/BYE/cseq=5997 (rdata0284AF24) 10:39:51.458 pjsua_core.c .RX 402 bytes Response msg 200/BYE/cseq=5997 (rdata0284AF24) from UDP 10.0.0.208:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPjc330c3e92fbb41bcb5a8390e682d081e From: <sip:10.0.0.193>;tag=8e32cb9d7de54f638cbcd9609911dc73 To: "4099" <sip:10.0.0.208>;tag=79B1B565-F7B916FC CSeq: 5997 BYE Call-ID: 602cc3bd2a1740c0975e6827b7a24abe Contact: <sip:4099 at 10.0.0.208:5060> User-Agent: PolycomVVX-VVX_1500-UA/3.2.2.0481 Accept-Language: en Content-Length: 0 --end msg-- 10:39:51.459 tsx062C222C .Incoming Response msg 200/BYE/cseq=5997 (rdata0284AF24) in state Calling 10:39:51.459 tsx062C222C ..State changed from Calling to Completed, event=RX_MSG 10:39:51.459 dlg02887454 ...Received Response msg 200/BYE/cseq=5997 (rdata0284AF24) 10:39:51.459 dlg02887454 ...Transaction tsx062C222C state changed to Completed 10:39:51.459 pjsua_app.c .....Call 0 is DISCONNECTED [reason=200 (Normal call clearing)] 10:39:51.459 pjsua_app.c .....Call 0 disconnected, dumping media stats.. 10:39:51.459 pjsua_app.c ..... [DISCONNCTD] To: sip:10.0.0.208;tag=79B1B565-F7B916FC Call time: 00h:00m:14s, 1st res in 210 ms, conn in 2082ms #0 audio PCMU @8kHz, sendrecv, peer=10.0.0.208:2242 SRTP status: Not active Crypto-suite: (null) RX pt=0, last update:00h:00m:04.688s ago total 729pkt 116.6KB (145.8KB +IP hdr) @avg=63.1Kbps/78.9Kbps pkt loss=0 (0.0%), discrd=0 (0.0%), dup=0 (0.0%), reord=0 (0.0%) (msec) min avg max last dev loss period: 0.000 0.000 0.000 0.000 0.000 jitter : 0.000 0.741 2.125 0.875 0.359 TX pt=0, ptime=20, last update:00h:00m:04.815s ago total 96pkt 14.8KB (18.7KB +IP hdr) @avg=8.0Kbps/10.1Kbps pkt loss=0 (0.0%), dup=0 (0.0%), reorder=0 (0.0%) (msec) min avg max last dev loss period: 0.000 0.000 0.000 0.000 0.000 jitter : 0.000 0.000 0.000 0.000 0.000 RTT msec : 4.699 18.394 32.089 4.699 13.695 #1 video H263-1998, sendrecv, peer=10.0.0.208:2244 SRTP status: Not active Crypto-suite: (null) RX pt=96, size=352x288, fps=29.97, last update:00h:00m:03.998s ago total 771pkt 761.8KB (792.6KB +IP hdr) @avg=412.5Kbps/429.2Kbps pkt loss=0 (0.0%), discrd=1 (0.1%), dup=0 (0.0%), reord=0 (0.0%) (msec) min avg max last dev loss period: 0.000 0.000 0.000 0.000 0.000 jitter : 0.688 4.452 7.333 4.333 1.195 TX pt=96, size=352x288, fps=29.97, last update:00h:00m:04.733s ago total 220pkt 253.4KB (262.2KB +IP hdr) @avg=137.2Kbps/142.0Kbps pkt loss=0 (0.0%), dup=0 (0.0%), reorder=0 (0.0%) (msec) min avg max last dev loss period: 0.000 0.000 0.000 0.000 0.000 jitter : 0.355 0.361 0.366 0.366 0.005 RTT msec : 17.608 18.448 19.287 19.287 0.839 10:39:51.467 pjsua_media.c .....Call 0: deinitializing media.. 10:39:51.467 strm0289DDC4 .......JB summary: size=2/eff=2 prefetch=0 level=2 delay (min/max/avg/dev)=20/20/20/0 ms burst (min/max/avg/dev)=2/2/2/0 frames lost=0 discard=0 empty=5 10:39:51.467 pjsua_media.c .......Media session call00:0 is destroyed 10:39:51.488 pjsua_vid.c .......Stopping video stream.. 10:39:52.628 dshow_dev.c ........Stopping dshow video stream 10:39:52.629 pjsua_vid.c ........Window 1: destroying.. 10:39:53.629 dshow_dev.c .........Stopping dshow video stream 10:39:53.629 vid_port.c .........Closing Microsoft LifeCam NX-3000.. 10:39:54.630 dshow_dev.c .........Stopping dshow video stream 10:39:54.633 sdl_dev.c .........Stopping sdl video stream 10:39:54.633 vid_port.c .........Closing SDL renderer.. 10:39:54.633 sdl_dev.c .........Stopping sdl video stream 10:39:54.693 clock !Thread stack max usage=6077 by c:\users\omar\documents\pjproject-2.0-beta\pjproject-2.0-beta\pjlib\src\pj\os_core_win32.c:1405 10:39:54.693 sdl_dev.c !........Stopping sdl video stream 10:39:54.693 pjsua_vid.c ........Window 0: destroying.. 10:39:54.693 sdl_dev.c .........Stopping sdl video stream 10:39:54.693 vid_port.c .........Closing SDL renderer.. 10:39:54.693 sdl_dev.c .........Stopping sdl video stream 10:39:54.745 vstdec028A1B3C ........JB summary: size=2/eff=2 prefetch=0 level=0 delay (min/max/avg/dev)=0/0/0/0 ms burst (min/max/avg/dev)=0/0/0/0 frames lost=0 discard=0 empty=0 10:39:54.745 pjsua_media.c .......Media session call00:1 is destroyed 10:39:54.745 tdta062BB818 ....Destroying txdata Request msg ACK/cseq=5994 (tdta062BB818) 10:39:54.746 tdta02894C00 ....Destroying txdata Request msg INVITE/cseq=5994 (tdta02894C00) 10:39:54.746 dlg02887454 .....Session count dec to 1 by mod-invite 10:39:54.746 pjsua_media.c Closing sound device after idle for 1 seconds 10:39:54.746 pjsua_app.c .Turning sound device OFF 10:39:54.746 pjsua_media.c .Closing Wave mapper sound playback device and Wave mapper sound capture device 10:39:54.746 wmme_dev.c .Stopped WMME playback stream 10:39:54.747 wmme_dev.c .Stopped WMME capture stream 10:39:54.748 wmme_dev.c .Stopped WMME playback stream 10:39:54.748 wmme_dev.c .Stopped WMME capture stream 10:39:54.748 wmme_dev.c !WMME: thread stopping.. 10:39:54.748 wmme Thread stack max usage=6305 by c:\users\omar\documents\pjproject-2.0-beta\pjproject-2.0-beta\pjlib\src\pj\string.c:175 10:39:56.460 tsx062C222C !Timeout timer event 10:39:56.460 tsx062C222C .State changed from Completed to Terminated, event=TIMER 10:39:56.460 dlg02887454 ..Transaction tsx062C222C state changed to Terminated 10:39:56.460 dlg02887454 ...Dialog destroyed 10:39:56.460 tsx062C222C Timeout timer event 10:39:56.460 tsx062C222C .State changed from Terminated to Destroyed, event=TIMER 10:39:56.460 tdta062BE7B8 ..Destroying txdata Request msg BYE/cseq=5997 (tdta062BE7B8) 10:39:56.460 tsx062C222C ..Transaction destroyed! 10:39:59.006 main.c !Ctrl-C detected, quitting.. 10:39:59.007 pjsua_core.c PJSUA state changed: RUNNING --> CLOSING 10:39:59.010 pjsua !Thread stack max usage=13029 by c:\users\omar\documents\pjproject-2.0-beta\pjproject-2.0-beta\pjlib\src\pj\os_core_win32.c:1405 10:39:59.010 pjsua_core.c !Shutting down, flags=0... 10:39:59.010 pjsua_call.c .Hangup all calls.. 10:39:59.010 pjsua_pres.c .Shutting down presence.. 10:39:59.010 pjsua_media.c .Shutting down media.. 10:39:59.010 pjsua_vid.c ..Destroying video subsystem.. 10:39:59.011 pjsua_vid.c ...Window 0: destroying.. 10:39:59.011 pjsua_vid.c ...Window 1: destroying.. 10:39:59.012 pjsua_vid.c ...Window 2: destroying.. 10:39:59.013 pjsua_vid.c ...Window 3: destroying.. 10:39:59.013 pjsua_vid.c ...Window 4: destroying.. 10:39:59.013 pjsua_vid.c ...Window 5: destroying.. 10:39:59.013 pjsua_vid.c ...Window 6: destroying.. 10:39:59.013 pjsua_vid.c ...Window 7: destroying.. 10:39:59.013 pjsua_vid.c ...Window 8: destroying.. 10:39:59.013 pjsua_vid.c ...Window 9: destroying.. 10:39:59.014 pjsua_vid.c ...Window 10: destroying.. 10:39:59.014 pjsua_vid.c ...Window 11: destroying.. 10:39:59.014 pjsua_vid.c ...Window 12: destroying.. 10:39:59.014 pjsua_vid.c ...Window 13: destroying.. 10:39:59.014 pjsua_vid.c ...Window 14: destroying.. 10:39:59.014 pjsua_vid.c ...Window 15: destroying.. 10:39:59.015 sdl_thread !Thread stack max usage=509 by c:\users\omar\documents\pjproject-2.0-beta\pjproject-2.0-beta\pjlib\src\pj\os_core_win32.c:1119 10:39:59.015 job_th !Thread stack max usage=505 by c:\users\omar\documents\pjproject-2.0-beta\pjproject-2.0-beta\pjlib\src\pj\os_core_win32.c:924 10:39:59.015 ev_thread !Thread stack max usage=7621 by c:\users\omar\documents\pjproject-2.0-beta\pjproject-2.0-beta\pjlib\src\pj\os_core_win32.c:1405 10:39:59.245 media !Thread stack max usage=7465 by c:\users\omar\documents\pjproject-2.0-beta\pjproject-2.0-beta\pjlib\src\pj\string.c:175 10:40:00.246 pjsua_core.c !.Destroying... 10:40:00.246 sip_endpoint.c .Destroying endpoing instance.. 10:40:00.246 sip_transactio .Stopping transaction layer module 10:40:00.246 sip_endpoint.c .Module "mod-default-handler" unregistered 10:40:00.246 sip_endpoint.c .Module "mod-unsolicited-mwi" unregistered 10:40:00.246 sip_endpoint.c .Module "mod-pjsua-options" unregistered 10:40:00.246 sip_endpoint.c .Module "mod-pjsua-im" unregistered 10:40:00.247 sip_endpoint.c .Module "mod-pjsua-pres" unregistered 10:40:00.247 sip_endpoint.c .Module "mod-pjsua" unregistered 10:40:00.247 sip_endpoint.c .Module "mod-stateful-util" unregistered 10:40:00.247 sip_endpoint.c .Module "mod-refer" unregistered 10:40:00.247 sip_endpoint.c .Module "mod-mwi" unregistered 10:40:00.247 sip_endpoint.c .Module "mod-presence" unregistered 10:40:00.248 sip_endpoint.c .Module "mod-evsub" unregistered 10:40:00.248 sip_endpoint.c .Module "mod-invite" unregistered 10:40:00.248 sip_endpoint.c .Module "mod-100rel" unregistered 10:40:00.248 sip_endpoint.c .Module "mod-ua" unregistered 10:40:00.248 sip_transactio .Transaction layer module destroyed 10:40:00.248 sip_endpoint.c .Module "mod-tsx-layer" unregistered 10:40:00.249 sip_endpoint.c .Module "mod-msg-print" unregistered 10:40:00.249 sip_endpoint.c .Module "mod-pjsua-log" unregistered 10:40:00.249 sip_transport. .Destroying transport manager 10:40:00.250 tcplis:5060 .SIP TCP listener destroyed 10:40:00.250 sip_endpoint.c .Endpoint 025145A4 destroyed 10:40:00.250 pjsua_core.c .PJSUA state changed: CLOSING --> NULL 10:40:00.250 pjsua_core.c .PJSUA destroyed... -------------- next part -------------- 10:35:21.033 sip_endpoint.c .Module "mod-pjsua-log" registered 10:35:21.039 sip_endpoint.c .Module "mod-tsx-layer" registered 10:35:21.040 sip_endpoint.c .Module "mod-stateful-util" registered 10:35:21.042 sip_endpoint.c .Module "mod-ua" registered 10:35:21.044 sip_endpoint.c .Module "mod-100rel" registered 10:35:21.046 sip_endpoint.c .Module "mod-pjsua" registered 10:35:21.048 sip_endpoint.c .Module "mod-invite" registered 10:35:21.062 wmme_dev.c ..WMME found 6 devices: 10:35:21.063 wmme_dev.c .. dev_id 0: Wave mapper (in=2, out=2) 10:35:21.066 wmme_dev.c .. dev_id 1: Microphone (2- Microsoft LifeCa (in=2, out=0) 10:35:21.072 wmme_dev.c .. dev_id 2: Realtek Digital Input (Realtek (in=2, out=0) 10:35:21.075 wmme_dev.c .. dev_id 3: Speakers (Realtek High Definiti (in=0, out=2) 10:35:21.079 wmme_dev.c .. dev_id 4: Realtek Digital Output (Realtek (in=0, out=2) 10:35:21.082 wmme_dev.c .. dev_id 5: Realtek Digital Output(Optical) (in=0, out=2) 10:35:21.085 wmme_dev.c ..WMME initialized 10:35:21.093 pjlib ..select() I/O Queue created (02794924) 10:35:21.095 conference.c ..Creating conference bridge with 254 ports 10:35:21.095 Master/sound ..Using delay buffer with WSOLA. 10:35:21.097 pjsua_vid.c ..Initializing video subsystem.. 10:35:21.099 ffmpeg_vid_cod ...The H263-1998 decoder is using base codec (H263) 10:35:22.317 dshow_dev.c ...DShow has 1 devices: 10:35:22.318 dshow_dev.c ... dev_id 0: Microsoft LifeCam NX-3000 (capture) 10:35:22.321 colorbar_dev.c ...Colorbar video src initialized with 1 device(s): 10:35:22.323 colorbar_dev.c ... 0: Colorbar generator 10:35:22.330 sdl_dev.c ...SDL 1.3 initialized 10:35:22.333 sip_endpoint.c .Module "mod-evsub" registered 10:35:22.333 sip_endpoint.c .Module "mod-presence" registered 10:35:22.339 evsub.c .Event pkg "presence" registered by mod-presence 10:35:22.339 sip_endpoint.c .Module "mod-mwi" registered 10:35:22.341 evsub.c .Event pkg "message-summary" registered by mod-mwi 10:35:22.341 sip_endpoint.c .Module "mod-refer" registered 10:35:22.343 evsub.c .Event pkg "refer" registered by mod-refer 10:35:22.343 sip_endpoint.c .Module "mod-pjsua-pres" registered 10:35:22.345 sip_endpoint.c .Module "mod-pjsua-im" registered 10:35:22.346 sip_endpoint.c .Module "mod-pjsua-options" registered 10:35:22.348 pjsua_core.c .1 SIP worker threads created 10:35:22.350 pjsua_core.c .pjsua version 2.0-rc for win32-6.1/i386/msvc-15.0 initialized 10:35:22.362 pjsua_core.c .PJSUA state changed: CREATED --> INIT 10:35:22.366 sip_endpoint.c Module "mod-default-handler" registered 10:35:22.372 pjsua_core.c SIP UDP socket reachable at 10.0.0.193:5060 10:35:22.376 udp027E7B70 SIP UDP transport started, published address is 10.0.0.193:5060 10:35:22.378 pjsua_acc.c Adding account: id=<sip:10.0.0.193:5060> 10:35:22.381 pjsua_acc.c .Account <sip:10.0.0.193:5060> added with id 0 10:35:22.383 pjsua_acc.c Modifying accunt 0 10:35:22.389 pjsua_acc.c Acc 0: setting online status to 1.. 10:35:22.395 tcplis:5060 SIP TCP listener ready for incoming connections at 10.0.0.193:5060 10:35:22.396 pjsua_acc.c Adding account: id=<sip:10.0.0.193:5060;transport=TCP> 10:35:22.399 pjsua_acc.c .Account <sip:10.0.0.193:5060;transport=TCP> added with id 1 10:35:22.402 pjsua_acc.c Modifying accunt 1 10:35:22.409 pjsua_acc.c Acc 1: setting online status to 1.. 10:35:22.411 pjsua_core.c PJSUA state changed: INIT --> STARTING 10:35:22.413 pjsua_media.c ..NAT type detection failed: Invalid STUN server or server not configured (PJNATH_ESTUNINSERVER) 10:35:22.415 sip_endpoint.c .Module "mod-unsolicited-mwi" registered 10:35:22.418 pjsua_core.c .PJSUA state changed: STARTING --> RUNNING 10:35:24.727 pjsua_app.c Video will be enabled in next offer/answer 10:35:27.134 pjsua_acc.c Modifying accunt 1 10:35:29.608 pjsua_acc.c Modifying accunt 1 10:35:34.495 pjsua_call.c Making call with acc #1 to sip:10.0.0.208 10:35:34.497 pjsua_aud.c .Set sound device: capture=-1, playback=-2 10:35:34.500 pjsua_app.c ..Turning sound device ON 10:35:34.506 pjsua_aud.c ..Opening sound device PCM at 16000/1/20ms 10:35:34.574 wmme_dev.c ... WaveAPI Sound player "Wave mapper" initialized (format=PCM, clock_rate=16000, channel_count=1, samples_per_frame=320 (20ms)) 10:35:34.582 wmme_dev.c ... WaveAPI Sound recorder "Wave mapper" initialized (format=PCM, clock_rate=16000, channel_count=1, samples_per_frame=320 (20ms)) 10:35:34.583 ec027FB010 ...Creating AEC 10:35:34.584 ec027FB010 ...Using delay buffer with WSOLA. 10:35:34.584 ec027FB010 ...AEC created, clock_rate=16000, channel=1, samples per frame=320, tail length=200 ms, latency=100 ms 10:35:34.587 wmme_dev.c ...WMME playback stream started 10:35:34.594 wmme_dev.c ...WMME capture stream started 10:35:34.596 dlg02830C4C .UAC dialog created 10:35:34.596 dlg02830C4C ..Session count inc to 2 by mod-pjsua 10:35:34.596 pjsua_media.c .Call 0: initializing media.. 10:35:34.600 pjsua_media.c ..RTP socket reachable at 10.0.0.193:40000 10:35:34.601 pjsua_media.c ..RTCP socket reachable at 10.0.0.193:40001 10:35:34.609 pjsua_media.c ..RTP socket reachable at 10.0.0.193:40002 10:35:34.611 pjsua_media.c ..RTCP socket reachable at 10.0.0.193:40003 10:35:34.613 pjsua_media.c ..Media index 0 selected for audio call 0 10:35:34.615 dlg02830C4C ..Session count dec to 2 by mod-pjsua 10:35:34.615 dlg02830C4C .Module mod-invite added as dialog usage, data=02838AEC 10:35:34.615 dlg02830C4C ..Session count inc to 4 by mod-invite 10:35:34.615 dlg02830C4C .Module mod-100rel added as dialog usage, data=0283E30C 10:35:34.615 dlg02830C4C .100rel module attached 10:35:34.615 inv02830C4C .UAC invite session created for dialog dlg02830C4C 10:35:34.615 endpoint .Request msg INVITE/cseq=22859 (tdta0283E3F8) created. 10:35:34.615 inv02830C4C ..Sending Request msg INVITE/cseq=22859 (tdta0283E3F8) 10:35:34.615 dlg02830C4C ...Sending Request msg INVITE/cseq=22859 (tdta0283E3F8) 10:35:34.615 tsx0284045C ....Transaction created for Request msg INVITE/cseq=22858 (tdta0283E3F8) 10:35:34.615 tsx0284045C ...Sending Request msg INVITE/cseq=22858 (tdta0283E3F8) in state Null 10:35:34.615 sip_resolve.c ....Target '10.0.0.208:0' type=Unspecified resolved to '10.0.0.208:5060' type=UDP (UDP transport) 10:35:34.615 pjsua_core.c ....TX 1250 bytes Request msg INVITE/cseq=22858 (tdta0283E3F8) to UDP 10.0.0.208:5060: INVITE sip:10.0.0.208 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPj341859d57c3c4039a81cf2838a57711e Max-Forwards: 70 From: <sip:10.0.0.193>;tag=00ef5f34d5a84440830eb9cb6e7e68cd To: sip:10.0.0.208 Contact: <sip:10.0.0.193:5060;ob> Call-ID: 8ab18011b5b94a8387c24df92c29da8d CSeq: 22858 INVITE Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Supported: replaces, 100rel, timer, norefersub Session-Expires: 1800 Min-SE: 90 User-Agent: PJSUA v2.0-rc win32-6.1/i386/msvc-15.0 Content-Type: application/sdp Content-Length: 650 v=0 o=- 3544684534 3544684534 IN IP4 10.0.0.193 s=pjmedia c=IN IP4 10.0.0.193 b=AS:352 t=0 0 a=X-nat:0 m=audio 40000 RTP/AVP 98 97 99 104 3 0 8 9 96 c=IN IP4 10.0.0.193 b=TIAS:64000 a=rtcp:40001 IN IP4 10.0.0.193 a=sendrecv a=rtpmap:98 speex/16000 a=rtpmap:97 speex/8000 a=rtpmap:99 speex/32000 a=rtpmap:104 iLBC/8000 a=fmtp:104 mode=30 a=rtpmap:3 GSM/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:9 G722/8000 a=rtpmap:96 telephone-event/8000 a=fmtp:96 0-15 m=video 40002 RTP/AVP 96 c=IN IP4 10.0.0.193 b=TIAS:256000 a=rtcp:40003 IN IP4 10.0.0.193 a=sendrecv a=rtpmap:96 H263-1998/90000 a=fmtp:96 CIF=1;QCIF=1 --end msg-- 10:35:34.617 ec027FB010 !Prefetching.. 10:35:34.617 tsx0284045C !....State changed from Null to Calling, event=TX_MSG 10:35:34.617 dlg02830C4C .....Transaction tsx0284045C state changed to Calling 10:35:34.617 pjsua_app.c .......Call 0 state changed to CALLING 10:35:34.627 ec027FB010 !Prefetching.. 10:35:34.647 sip_endpoint.c !Processing incoming message: Response msg 100/INVITE/cseq=22858 (rdata027ECB34) 10:35:34.647 ec027FB010 !Prefetching.. 10:35:34.647 pjsua_core.c !.RX 410 bytes Response msg 100/INVITE/cseq=22858 (rdata027ECB34) from UDP 10.0.0.208:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPj341859d57c3c4039a81cf2838a57711e From: <sip:10.0.0.193>;tag=00ef5f34d5a84440830eb9cb6e7e68cd To: "4099" <sip:10.0.0.208>;tag=FEC39C52-DD23E451 CSeq: 22858 INVITE Call-ID: 8ab18011b5b94a8387c24df92c29da8d Contact: <sip:4099 at 10.0.0.208:5060> User-Agent: PolycomVVX-VVX_1500-UA/3.2.2.0481 Accept-Language: en Content-Length: 0 --end msg-- 10:35:34.655 tsx0284045C .Incoming Response msg 100/INVITE/cseq=22858 (rdata027ECB34) in state Calling 10:35:34.655 tsx0284045C ..State changed from Calling to Proceeding, event=RX_MSG 10:35:34.655 dlg02830C4C ...Received Response msg 100/INVITE/cseq=22858 (rdata027ECB34) 10:35:34.655 dlg02830C4C ...Transaction tsx0284045C state changed to Proceeding 10:35:34.657 ec027FB010 !Prefetching.. 10:35:34.677 ec027FB010 Prefetching.. 10:35:34.687 ec027FB010 Prefetching.. 10:35:34.707 ec027FB010 Prefetching.. 10:35:34.717 ec027FB010 Prefetching.. 10:35:34.724 ec027FB010 Latency bufferring complete 10:35:34.808 sip_endpoint.c !Processing incoming message: Response msg 180/INVITE/cseq=22858 (rdata027ECB34) 10:35:34.808 pjsua_core.c .RX 476 bytes Response msg 180/INVITE/cseq=22858 (rdata027ECB34) from UDP 10.0.0.208:5060: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPj341859d57c3c4039a81cf2838a57711e From: <sip:10.0.0.193>;tag=00ef5f34d5a84440830eb9cb6e7e68cd To: "4099" <sip:10.0.0.208>;tag=FEC39C52-DD23E451 CSeq: 22858 INVITE Call-ID: 8ab18011b5b94a8387c24df92c29da8d Contact: <sip:4099 at 10.0.0.208:5060> User-Agent: PolycomVVX-VVX_1500-UA/3.2.2.0481 Allow-Events: talk,hold,conference Accept-Language: en Require: 100rel RSeq: 8193 Content-Length: 0 --end msg-- 10:35:34.810 tsx0284045C .Incoming Response msg 180/INVITE/cseq=22858 (rdata027ECB34) in state Proceeding 10:35:34.810 tsx0284045C ..State changed from Proceeding to Proceeding, event=RX_MSG 10:35:34.810 dlg02830C4C ...Received Response msg 180/INVITE/cseq=22858 (rdata027ECB34) 10:35:34.810 dlg02830C4C ....Route-set updated 10:35:34.810 dlg02830C4C ...Transaction tsx0284045C state changed to Proceeding 10:35:34.810 pjsua_aud.c .....Conf connect: 1 --> 0 10:35:34.810 conference.c ......Port 1 (ringback) transmitting to port 0 (Wave mapper) 10:35:34.810 pjsua_app.c .....Call 0 state changed to EARLY (180 Ringing) 10:35:34.810 endpoint ....Request msg PRACK/cseq=22860 (tdta02843018) created. 10:35:34.810 dlg02830C4C .....Sending Request msg PRACK/cseq=22860 (tdta02843018) 10:35:34.810 tsx028440AC ......Transaction created for Request msg PRACK/cseq=22859 (tdta02843018) 10:35:34.810 tsx028440AC .....Sending Request msg PRACK/cseq=22859 (tdta02843018) in state Null 10:35:34.810 sip_resolve.c ......Target '10.0.0.208:5060' type=Unspecified resolved to '10.0.0.208:5060' type=UDP (UDP transport) 10:35:34.810 pjsua_core.c ......TX 359 bytes Request msg PRACK/cseq=22859 (tdta02843018) to UDP 10.0.0.208:5060: PRACK sip:4099 at 10.0.0.208:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPj4ba0d99305a74a45a6066b3b37011667 Max-Forwards: 70 From: <sip:10.0.0.193>;tag=00ef5f34d5a84440830eb9cb6e7e68cd To: sip:10.0.0.208;tag=FEC39C52-DD23E451 Call-ID: 8ab18011b5b94a8387c24df92c29da8d CSeq: 22859 PRACK RAck: 8193 22858 INVITE Content-Length: 0 --end msg-- 10:35:34.811 tsx028440AC ......State changed from Null to Calling, event=TX_MSG 10:35:34.811 dlg02830C4C .......Transaction tsx028440AC state changed to Calling 10:35:34.828 sip_endpoint.c Processing incoming message: Response msg 200/PRACK/cseq=22859 (rdata027ECB34) 10:35:34.829 pjsua_core.c .RX 405 bytes Response msg 200/PRACK/cseq=22859 (rdata027ECB34) from UDP 10.0.0.208:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPj4ba0d99305a74a45a6066b3b37011667 From: <sip:10.0.0.193>;tag=00ef5f34d5a84440830eb9cb6e7e68cd To: "4099" <sip:10.0.0.208>;tag=FEC39C52-DD23E451 CSeq: 22859 PRACK Call-ID: 8ab18011b5b94a8387c24df92c29da8d Contact: <sip:4099 at 10.0.0.208:5060> User-Agent: PolycomVVX-VVX_1500-UA/3.2.2.0481 Accept-Language: en Content-Length: 0 --end msg-- 10:35:34.829 tsx028440AC .Incoming Response msg 200/PRACK/cseq=22859 (rdata027ECB34) in state Calling 10:35:34.830 tsx028440AC ..State changed from Calling to Completed, event=RX_MSG 10:35:34.830 dlg02830C4C ...Received Response msg 200/PRACK/cseq=22859 (rdata027ECB34) 10:35:34.830 dlg02830C4C ...Transaction tsx028440AC state changed to Completed 10:35:36.143 sip_endpoint.c Processing incoming message: Response msg 200/INVITE/cseq=22858 (rdata027ECB34) 10:35:36.143 pjsua_core.c .RX 858 bytes Response msg 200/INVITE/cseq=22858 (rdata027ECB34) from UDP 10.0.0.208:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPj341859d57c3c4039a81cf2838a57711e From: <sip:10.0.0.193>;tag=00ef5f34d5a84440830eb9cb6e7e68cd To: "4099" <sip:10.0.0.208>;tag=FEC39C52-DD23E451 CSeq: 22858 INVITE Call-ID: 8ab18011b5b94a8387c24df92c29da8d Contact: <sip:4099 at 10.0.0.208:5060> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER Supported: 100rel,replaces User-Agent: PolycomVVX-VVX_1500-UA/3.2.2.0481 Accept-Language: en Content-Type: application/sdp Content-Length: 293 v=0 o=- 1168887873 1168887873 IN IP4 10.0.0.208 s=Polycom IP Phone c=IN IP4 10.0.0.208 b=AS:352 t=0 0 m=audio 2238 RTP/AVP 0 96 a=sendrecv a=rtpmap:0 PCMU/8000 a=rtpmap:96 telephone-event/8000 m=video 2240 RTP/AVP 96 a=sendrecv a=rtpmap:96 H263-1998/90000 a=fmtp:96 CIF=1;QCIF=1 --end msg-- 10:35:36.146 tsx0284045C .Incoming Response msg 200/INVITE/cseq=22858 (rdata027ECB34) in state Proceeding 10:35:36.146 tsx0284045C ..State changed from Proceeding to Terminated, event=RX_MSG 10:35:36.146 dlg02830C4C ...Received Response msg 200/INVITE/cseq=22858 (rdata027ECB34) 10:35:36.146 dlg02830C4C ....Route-set updated 10:35:36.146 dlg02830C4C ....Route-set frozen 10:35:36.146 dlg02830C4C ...Transaction tsx0284045C state changed to Terminated 10:35:36.146 pjsua_app.c .....Call 0 state changed to CONNECTING 10:35:36.147 inv02830C4C ....Got SDP answer in Response msg 200/INVITE/cseq=22858 (rdata027ECB34) 10:35:36.147 inv02830C4C ....SDP negotiation done, status=0 10:35:36.147 pjsua_call.c .....Call 0: remote NAT type is 0 (Unknown) 10:35:36.147 pjsua_media.c .....Call 0: updating media.. 10:35:36.147 pjsua_aud.c ......Audio channel update.. 10:35:36.147 strm028475BC .......VAD temporarily disabled 10:35:36.147 rtp.c .......pjmedia_rtp_session_init: ses=028490A8, default_pt=0, ssrc=0xe7e169d 10:35:36.147 rtp.c .......pjmedia_rtp_session_init: ses=02849758, default_pt=0, ssrc=0xe7e169d 10:35:36.147 stream.c .......Stream strm028475BC created 10:35:36.147 strm028475BC .......Encoder stream started 10:35:36.148 strm028475BC .......Decoder stream started 10:35:36.148 resample.c .......resample created: high qualiy, large filter, in/out rate=8000/16000 10:35:36.148 resample.c .......resample created: high qualiy, large filter, in/out rate=16000/8000 10:35:36.148 pjsua_media.c ......Audio updated, stream #0: PCMU (sendrecv) 10:35:36.148 pjsua_vid.c ......Video channel update.. 10:35:36.151 rtp.c .......pjmedia_rtp_session_init: ses=0284B8C4, default_pt=96, ssrc=0x57c86660 10:35:36.151 vstdec0284B334 .......Decoding channel created 352x288 I420<-H263-1998 45000/1001(~44)fps 10:35:36.151 rtp.c .......pjmedia_rtp_session_init: ses=0284B978, default_pt=96, ssrc=0x57c86660 10:35:36.151 vstenc0284B334 .......Encoding channel created 352x288 I420->H263-1998 30000/2002(~14)fps 10:35:36.152 vid_stream.c .......Video stream vstrm0284B334 created 10:35:36.152 vstenc0284B334 .......Encoder stream started 10:35:36.152 vstdec0284B334 .......Decoder stream started 10:35:36.152 pjsua_vid.c .......Setting up RX.. 10:35:36.152 pjsua_vid.c ........Creating video window: type=stream, cap_id=-1, rend_id=2 10:35:36.152 vid_port.c .........Opening device SDL renderer [SDL] for render: format=I420, size=352x288 @45000:1001 fps 10:35:36.164 strm028475BC !Start talksprut.. 10:35:36.223 vid_port.c !.........Device SDL renderer [SDL] opened: format=I420, size=352x288 @45000:1001 fps 10:35:36.223 pjsua_vid.c .........stream window id 0 created for cap_dev=-1 rend_dev=2 10:35:36.223 pjsua_vid.c .........Window 0 created 10:35:36.223 sdl_dev.c ........Starting sdl video stream 10:35:36.223 pjsua_vid.c .......Setting up TX.. 10:35:36.223 pjsua_vid.c ........Creating video window: type=preview, cap_id=0, rend_id=2 10:35:36.224 vid_port.c .........Opening device Microsoft LifeCam NX-3000 [dshow] for capture: format=RGB3, size=352x288 @30000:2002 fps 10:35:36.231 dshow_dev.c .........Error creating filter graph: There was no match for the specified key in the index. (hr=0x80070491) 10:35:36.232 dshow_dev.c .........Trying to open dshow dev with default fps 10:35:36.253 vid_port.c .........Device Microsoft LifeCam NX-3000 [dshow] opened: format=RGB3, size=352x288 @10000000:333333 fps 10:35:36.253 vid_port.c .........Opening device SDL renderer [SDL] for render: format=I420, size=352x288 @30000:2002 fps 10:35:36.267 vid_port.c .........Device SDL renderer [SDL] opened: format=I420, size=352x288 @30000:2002 fps 10:35:36.267 pjsua_vid.c .........preview window id 1 created for cap_dev=0 rend_dev=2 10:35:36.267 pjsua_vid.c .........Window 1 created 10:35:36.271 dshow_dev.c ........Starting dshow video stream 10:35:36.271 pjsua_media.c ......Video updated, stream #1: H263-1998 (sendrecv) 10:35:36.271 pjsua_app.c .....Call 0 media 0 [type=audio], status is Active 10:35:36.272 pjsua_aud.c .....Conf disconnect: 1 -x- 0 10:35:36.272 conference.c ......Port 1 (ringback) stop transmitting to port 0 (Wave mapper) 10:35:36.272 pjsua_aud.c .....Conf connect: 3 --> 0 10:35:36.272 conference.c ......Port 3 (sip:10.0.0.208) transmitting to port 0 (Wave mapper) 10:35:36.272 pjsua_aud.c .....Conf connect: 0 --> 3 10:35:36.272 conference.c ......Port 0 (Wave mapper) transmitting to port 3 (sip:10.0.0.208) 10:35:36.272 pjsua_app.c .....Call 0 media 1 [type=video], status is Active 10:35:36.273 inv02830C4C ....Received Response msg 200/INVITE/cseq=22858 (rdata027ECB34), sending ACK 10:35:36.273 endpoint ....Request msg ACK/cseq=22858 (tdta05FE1838) created. 10:35:36.273 dlg02830C4C .....Sending Request msg ACK/cseq=22858 (tdta05FE1838) 10:35:36.273 sip_resolve.c .....Target '10.0.0.208:5060' type=Unspecified resolved to '10.0.0.208:5060' type=UDP (UDP transport) 10:35:36.273 pjsua_core.c .....TX 330 bytes Request msg ACK/cseq=22858 (tdta05FE1838) to UDP 10.0.0.208:5060: ACK sip:4099 at 10.0.0.208:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPj8789667cd74740fa9e450704a8bb673e Max-Forwards: 70 From: <sip:10.0.0.193>;tag=00ef5f34d5a84440830eb9cb6e7e68cd To: sip:10.0.0.208;tag=FEC39C52-DD23E451 Call-ID: 8ab18011b5b94a8387c24df92c29da8d CSeq: 22858 ACK Content-Length: 0 --end msg-- 10:35:36.273 pjsua_app.c .....Call 0 state changed to CONFIRMED 10:35:36.274 tsx0284045C Timeout timer event 10:35:36.274 tsx0284045C .State changed from Terminated to Destroyed, event=TIMER 10:35:36.274 tsx0284045C ..Transaction destroyed! 10:35:36.284 strm028475BC !GET prefetch_cnt=0/0 10:35:36.284 strm028475BC Jitter buffer is bufferring (prefetch=0), plc invoked 10:35:36.304 strm028475BC GET prefetch_cnt=0/0 10:35:36.324 strm028475BC GET prefetch_cnt=0/0 10:35:36.344 strm028475BC GET prefetch_cnt=0/0 10:35:36.355 dshow_dev.c !Capture thread started 10:35:36.364 strm028475BC !GET prefetch_cnt=0/0 10:35:36.378 strm028475BC !RTP status: badpt=0, badssrc=0, dup=0, outorder=0, probation=-1, restart=0 10:35:36.378 strm028475BC PUT prefetch_cnt=1/0 10:35:36.384 strm028475BC !Jitter buffer starts returning normal frames (after 5 empty/lost) 10:35:36.464 strm028475BC jb updated(2), lvl=2 pre=0, size=1 10:35:36.551 vstdec0284B334 !RTP status: badpt=0, badssrc=0, dup=0, outorder=-1, probation=-1, restart=0 10:35:36.552 vstdec0284B334 RTP status: badpt=0, badssrc=0, dup=0, outorder=0, probation=-1, restart=0 10:35:36.552 vstdec0284B334 PUT prefetch_cnt=1/0 10:35:36.631 ffmpeg_vid_cod Unpacketize error: Ignored (PJ_EIGNORED) 10:35:36.631 ffmpeg h263: Bad picture start code 10:35:36.631 ffmpeg h263: header damaged 10:35:36.631 vstdec0284B334 codec decode() error: Bad or corrupted bitstream (PJMEDIA_CODEC_EBADBITSTREAM) [err:220087] 10:35:36.646 pjsua_media.c !Sending video keyframe request via SIP INFO 10:35:36.646 pjsua_call.c Call 0 sending INFO request.. 10:35:36.646 endpoint .Request msg INFO/cseq=22861 (tdta02874E08) created. 10:35:36.646 dlg02830C4C ..Sending Request msg INFO/cseq=22861 (tdta02874E08) 10:35:36.646 tsx0284045C ...Transaction created for Request msg INFO/cseq=22860 (tdta02874E08) 10:35:36.646 tsx0284045C ..Sending Request msg INFO/cseq=22860 (tdta02874E08) in state Null 10:35:36.646 sip_resolve.c ...Target '10.0.0.208:5060' type=Unspecified resolved to '10.0.0.208:5060' type=UDP (UDP transport) 10:35:36.646 pjsua_core.c ...TX 578 bytes Request msg INFO/cseq=22860 (tdta02874E08) to UDP 10.0.0.208:5060: INFO sip:4099 at 10.0.0.208:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPj70a20160b26641b2bdcd65b4f9f91f6c Max-Forwards: 70 From: <sip:10.0.0.193>;tag=00ef5f34d5a84440830eb9cb6e7e68cd To: sip:10.0.0.208;tag=FEC39C52-DD23E451 Call-ID: 8ab18011b5b94a8387c24df92c29da8d CSeq: 22860 INFO User-Agent: PJSUA v2.0-rc win32-6.1/i386/msvc-15.0 Content-Type: application/media_control+xml Content-Length: 146 <?xml version="1.0" encoding="utf-8" ?><media_control><vc_primitive><to_encoder><picture_fast_update/></to_encoder></vc_primitive></media_control> --end msg-- 10:35:36.647 tsx0284045C ...State changed from Null to Calling, event=TX_MSG 10:35:36.647 dlg02830C4C ....Transaction tsx0284045C state changed to Calling 10:35:36.647 pjsua_app.c Event IFRM 10:35:36.702 ffmpeg !h263: warning: first frame is no keyframe 10:35:36.702 ffmpeg h263: run overflow at 5x0 i:0 10:35:36.702 ffmpeg h263: Error at MB: 5 10:35:36.713 pjsua_app.c !Event IFRM 10:35:36.724 sip_endpoint.c !Processing incoming message: Response msg 200/INFO/cseq=22860 (rdata027ECB34) 10:35:36.724 pjsua_core.c .RX 404 bytes Response msg 200/INFO/cseq=22860 (rdata027ECB34) from UDP 10.0.0.208:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPj70a20160b26641b2bdcd65b4f9f91f6c From: <sip:10.0.0.193>;tag=00ef5f34d5a84440830eb9cb6e7e68cd To: "4099" <sip:10.0.0.208>;tag=FEC39C52-DD23E451 CSeq: 22860 INFO Call-ID: 8ab18011b5b94a8387c24df92c29da8d Contact: <sip:4099 at 10.0.0.208:5060> User-Agent: PolycomVVX-VVX_1500-UA/3.2.2.0481 Accept-Language: en Content-Length: 0 --end msg-- 10:35:36.726 tsx0284045C .Incoming Response msg 200/INFO/cseq=22860 (rdata027ECB34) in state Calling 10:35:36.726 tsx0284045C ..State changed from Calling to Completed, event=RX_MSG 10:35:36.726 dlg02830C4C ...Received Response msg 200/INFO/cseq=22860 (rdata027ECB34) 10:35:36.726 dlg02830C4C ...Transaction tsx0284045C state changed to Completed 10:35:36.785 strm028475BC !VAD re-enabled 10:35:36.785 strm028475BC Starting silence 10:35:36.802 pjsua_app.c !Event IFRM 10:35:36.890 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3510(~25)fps 10:35:36.890 sdl_dev.c Stopping sdl video stream 10:35:36.890 sdl_dev.c Starting sdl video stream 10:35:36.890 pjsua_app.c !Event FMCH 10:35:37.150 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/5760(~15)fps 10:35:37.150 sdl_dev.c Stopping sdl video stream 10:35:37.150 sdl_dev.c Starting sdl video stream 10:35:37.150 pjsua_app.c !Event FMCH 10:35:37.278 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/5760(~15)fps 10:35:37.278 sdl_dev.c Stopping sdl video stream 10:35:37.278 sdl_dev.c Starting sdl video stream 10:35:37.278 pjsua_app.c !Event FMCH 10:35:37.406 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3330(~27)fps 10:35:37.406 sdl_dev.c Stopping sdl video stream 10:35:37.406 sdl_dev.c Starting sdl video stream 10:35:37.406 pjsua_app.c !Event FMCH 10:35:37.479 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/2430(~37)fps 10:35:37.479 sdl_dev.c Stopping sdl video stream 10:35:37.479 sdl_dev.c Starting sdl video stream 10:35:37.479 pjsua_app.c !Event FMCH 10:35:37.533 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/2700(~33)fps 10:35:37.533 sdl_dev.c Stopping sdl video stream 10:35:37.533 sdl_dev.c Starting sdl video stream 10:35:37.533 pjsua_app.c !Event FMCH 10:35:37.572 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/2610(~34)fps 10:35:37.572 sdl_dev.c Stopping sdl video stream 10:35:37.572 sdl_dev.c Starting sdl video stream 10:35:37.572 pjsua_app.c !Event FMCH 10:35:37.630 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/2610(~34)fps 10:35:37.630 sdl_dev.c Stopping sdl video stream 10:35:37.630 sdl_dev.c Starting sdl video stream 10:35:37.630 pjsua_app.c !Event FMCH 10:35:37.707 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/2520(~35)fps 10:35:37.707 sdl_dev.c Stopping sdl video stream 10:35:37.707 sdl_dev.c Starting sdl video stream 10:35:37.707 pjsua_app.c !Event FMCH 10:35:37.875 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3510(~25)fps 10:35:37.875 sdl_dev.c Stopping sdl video stream 10:35:37.875 sdl_dev.c Starting sdl video stream 10:35:37.875 pjsua_app.c !Event FMCH 10:35:38.264 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3420(~26)fps 10:35:38.264 sdl_dev.c Stopping sdl video stream 10:35:38.264 sdl_dev.c Starting sdl video stream 10:35:38.264 pjsua_app.c !Event FMCH 10:35:38.466 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3510(~25)fps 10:35:38.466 sdl_dev.c Stopping sdl video stream 10:35:38.466 sdl_dev.c Starting sdl video stream 10:35:38.466 pjsua_app.c !Event FMCH 10:35:38.517 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3240(~27)fps 10:35:38.517 sdl_dev.c Stopping sdl video stream 10:35:38.517 sdl_dev.c Starting sdl video stream 10:35:38.517 pjsua_app.c !Event FMCH 10:35:38.613 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 25/1(~25)fps 10:35:38.613 sdl_dev.c Stopping sdl video stream 10:35:38.613 sdl_dev.c Starting sdl video stream 10:35:38.613 pjsua_app.c !Event FMCH 10:35:38.721 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3240(~27)fps 10:35:38.721 sdl_dev.c Stopping sdl video stream 10:35:38.721 sdl_dev.c Starting sdl video stream 10:35:38.721 pjsua_app.c !Event FMCH 10:35:38.841 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 25/1(~25)fps 10:35:38.841 sdl_dev.c Stopping sdl video stream 10:35:38.841 sdl_dev.c Starting sdl video stream 10:35:38.841 pjsua_app.c !Event FMCH 10:35:38.895 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3330(~27)fps 10:35:38.895 sdl_dev.c Stopping sdl video stream 10:35:38.895 sdl_dev.c Starting sdl video stream 10:35:38.895 pjsua_app.c !Event FMCH 10:35:38.968 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3330(~27)fps 10:35:38.968 sdl_dev.c Stopping sdl video stream 10:35:38.968 sdl_dev.c Starting sdl video stream 10:35:38.968 pjsua_app.c !Event FMCH 10:35:39.091 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3150(~28)fps 10:35:39.091 sdl_dev.c Stopping sdl video stream 10:35:39.091 sdl_dev.c Starting sdl video stream 10:35:39.091 pjsua_app.c !Event FMCH 10:35:39.208 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3420(~26)fps 10:35:39.208 sdl_dev.c Stopping sdl video stream 10:35:39.208 sdl_dev.c Starting sdl video stream 10:35:39.208 pjsua_app.c !Event FMCH 10:35:39.334 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3240(~27)fps 10:35:39.334 sdl_dev.c Stopping sdl video stream 10:35:39.334 sdl_dev.c Starting sdl video stream 10:35:39.334 pjsua_app.c !Event FMCH 10:35:39.454 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3420(~26)fps 10:35:39.454 sdl_dev.c Stopping sdl video stream 10:35:39.454 sdl_dev.c Starting sdl video stream 10:35:39.454 pjsua_app.c !Event FMCH 10:35:39.555 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3240(~27)fps 10:35:39.555 sdl_dev.c Stopping sdl video stream 10:35:39.555 sdl_dev.c Starting sdl video stream 10:35:39.555 pjsua_app.c !Event FMCH 10:35:39.625 strm028475BC !Start talksprut.. 10:35:39.645 strm028475BC Starting silence 10:35:39.675 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3150(~28)fps 10:35:39.675 sdl_dev.c Stopping sdl video stream 10:35:39.675 sdl_dev.c Starting sdl video stream 10:35:39.675 pjsua_app.c !Event FMCH 10:35:39.792 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3240(~27)fps 10:35:39.792 sdl_dev.c Stopping sdl video stream 10:35:39.792 sdl_dev.c Starting sdl video stream 10:35:39.792 pjsua_app.c !Event FMCH 10:35:39.830 tsx028440AC !Timeout timer event 10:35:39.830 tsx028440AC .State changed from Completed to Terminated, event=TIMER 10:35:39.830 dlg02830C4C ..Transaction tsx028440AC state changed to Terminated 10:35:39.830 tsx028440AC Timeout timer event 10:35:39.830 tsx028440AC .State changed from Terminated to Destroyed, event=TIMER 10:35:39.830 tdta02843018 ..Destroying txdata Request msg PRACK/cseq=22859 (tdta02843018) 10:35:39.830 tsx028440AC ..Transaction destroyed! 10:35:39.912 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3420(~26)fps 10:35:39.912 sdl_dev.c Stopping sdl video stream 10:35:39.912 sdl_dev.c Starting sdl video stream 10:35:39.912 pjsua_app.c !Event FMCH 10:35:40.089 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/2790(~32)fps 10:35:40.089 sdl_dev.c Stopping sdl video stream 10:35:40.089 sdl_dev.c Starting sdl video stream 10:35:40.089 pjsua_app.c !Event FMCH 10:35:40.172 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3420(~26)fps 10:35:40.172 sdl_dev.c Stopping sdl video stream 10:35:40.172 sdl_dev.c Starting sdl video stream 10:35:40.172 pjsua_app.c !Event FMCH 10:35:40.273 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 25/1(~25)fps 10:35:40.273 sdl_dev.c Stopping sdl video stream 10:35:40.273 sdl_dev.c Starting sdl video stream 10:35:40.273 pjsua_app.c !Event FMCH 10:35:40.326 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3420(~26)fps 10:35:40.326 sdl_dev.c Stopping sdl video stream 10:35:40.326 sdl_dev.c Starting sdl video stream 10:35:40.326 pjsua_app.c !Event FMCH 10:35:40.401 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 25/1(~25)fps 10:35:40.401 sdl_dev.c Stopping sdl video stream 10:35:40.401 sdl_dev.c Starting sdl video stream 10:35:40.401 pjsua_app.c !Event FMCH 10:35:40.454 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3510(~25)fps 10:35:40.454 sdl_dev.c Stopping sdl video stream 10:35:40.454 sdl_dev.c Starting sdl video stream 10:35:40.454 pjsua_app.c !Event FMCH 10:35:40.532 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3510(~25)fps 10:35:40.532 sdl_dev.c Stopping sdl video stream 10:35:40.532 sdl_dev.c Starting sdl video stream 10:35:40.532 pjsua_app.c !Event FMCH 10:35:40.609 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3510(~25)fps 10:35:40.609 sdl_dev.c Stopping sdl video stream 10:35:40.609 sdl_dev.c Starting sdl video stream 10:35:40.609 pjsua_app.c !Event FMCH 10:35:40.765 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 25/1(~25)fps 10:35:40.765 sdl_dev.c Stopping sdl video stream 10:35:40.765 silencedet.c !Re-adjust threshold (in silence)to 10 10:35:40.765 sdl_dev.c !Starting sdl video stream 10:35:40.765 pjsua_app.c !Event FMCH 10:35:40.818 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3510(~25)fps 10:35:40.818 sdl_dev.c Stopping sdl video stream 10:35:40.818 sdl_dev.c Starting sdl video stream 10:35:40.818 pjsua_app.c !Event FMCH 10:35:40.922 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/2700(~33)fps 10:35:40.922 sdl_dev.c Stopping sdl video stream 10:35:40.922 sdl_dev.c Starting sdl video stream 10:35:40.922 pjsua_app.c !Event FMCH 10:35:41.002 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3510(~25)fps 10:35:41.002 sdl_dev.c Stopping sdl video stream 10:35:41.002 sdl_dev.c Starting sdl video stream 10:35:41.002 pjsua_app.c !Event FMCH 10:35:41.132 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3510(~25)fps 10:35:41.132 sdl_dev.c Stopping sdl video stream 10:35:41.132 sdl_dev.c Starting sdl video stream 10:35:41.132 pjsua_app.c !Event FMCH 10:35:41.236 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3240(~27)fps 10:35:41.236 sdl_dev.c Stopping sdl video stream 10:35:41.236 sdl_dev.c Starting sdl video stream 10:35:41.236 pjsua_app.c !Event FMCH 10:35:41.356 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3240(~27)fps 10:35:41.356 sdl_dev.c Stopping sdl video stream 10:35:41.356 sdl_dev.c Starting sdl video stream 10:35:41.356 pjsua_app.c !Event FMCH 10:35:41.476 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3240(~27)fps 10:35:41.476 sdl_dev.c Stopping sdl video stream 10:35:41.476 sdl_dev.c Starting sdl video stream 10:35:41.476 pjsua_app.c !Event FMCH 10:35:41.596 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3240(~27)fps 10:35:41.596 sdl_dev.c Stopping sdl video stream 10:35:41.596 sdl_dev.c Starting sdl video stream 10:35:41.596 pjsua_app.c !Event FMCH 10:35:41.716 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3330(~27)fps 10:35:41.716 sdl_dev.c Stopping sdl video stream 10:35:41.716 sdl_dev.c Starting sdl video stream 10:35:41.716 pjsua_app.c !Event FMCH 10:35:41.726 tsx0284045C !Timeout timer event 10:35:41.726 tsx0284045C .State changed from Completed to Terminated, event=TIMER 10:35:41.726 dlg02830C4C ..Transaction tsx0284045C state changed to Terminated 10:35:41.726 tsx0284045C Timeout timer event 10:35:41.726 tsx0284045C .State changed from Terminated to Destroyed, event=TIMER 10:35:41.726 tdta02874E08 ..Destroying txdata Request msg INFO/cseq=22860 (tdta02874E08) 10:35:41.726 tsx0284045C ..Transaction destroyed! 10:35:41.839 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3420(~26)fps 10:35:41.839 sdl_dev.c Stopping sdl video stream 10:35:41.839 sdl_dev.c Starting sdl video stream 10:35:41.839 pjsua_app.c !Event FMCH 10:35:41.966 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3240(~27)fps 10:35:41.966 sdl_dev.c Stopping sdl video stream 10:35:41.966 sdl_dev.c Starting sdl video stream 10:35:41.966 pjsua_app.c !Event FMCH 10:35:42.086 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3060(~29)fps 10:35:42.086 sdl_dev.c Stopping sdl video stream 10:35:42.086 sdl_dev.c Starting sdl video stream 10:35:42.086 pjsua_app.c !Event FMCH 10:35:42.199 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3330(~27)fps 10:35:42.199 sdl_dev.c Stopping sdl video stream 10:35:42.199 sdl_dev.c Starting sdl video stream 10:35:42.199 pjsua_app.c !Event FMCH 10:35:42.322 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3330(~27)fps 10:35:42.322 sdl_dev.c Stopping sdl video stream 10:35:42.322 sdl_dev.c Starting sdl video stream 10:35:42.322 pjsua_app.c !Event FMCH 10:35:42.421 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 25/1(~25)fps 10:35:42.421 sdl_dev.c Stopping sdl video stream 10:35:42.421 sdl_dev.c Starting sdl video stream 10:35:42.421 pjsua_app.c !Event FMCH 10:35:42.529 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3510(~25)fps 10:35:42.529 sdl_dev.c Stopping sdl video stream 10:35:42.529 sdl_dev.c Starting sdl video stream 10:35:42.529 pjsua_app.c !Event FMCH 10:35:42.737 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/2880(~31)fps 10:35:42.737 sdl_dev.c Stopping sdl video stream 10:35:42.737 sdl_dev.c Starting sdl video stream 10:35:42.737 pjsua_app.c !Event FMCH 10:35:42.801 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3510(~25)fps 10:35:42.801 sdl_dev.c Stopping sdl video stream 10:35:42.801 sdl_dev.c Starting sdl video stream 10:35:42.801 pjsua_app.c !Event FMCH 10:35:42.879 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 25/1(~25)fps 10:35:42.879 sdl_dev.c Stopping sdl video stream 10:35:42.879 sdl_dev.c Starting sdl video stream 10:35:42.879 pjsua_app.c !Event FMCH 10:35:42.959 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 25/1(~25)fps 10:35:42.959 sdl_dev.c Stopping sdl video stream 10:35:42.959 sdl_dev.c Starting sdl video stream 10:35:42.959 pjsua_app.c !Event FMCH 10:35:43.012 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3510(~25)fps 10:35:43.012 sdl_dev.c Stopping sdl video stream 10:35:43.012 sdl_dev.c Starting sdl video stream 10:35:43.012 pjsua_app.c !Event FMCH 10:35:43.089 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3510(~25)fps 10:35:43.089 sdl_dev.c Stopping sdl video stream 10:35:43.089 sdl_dev.c Starting sdl video stream 10:35:43.089 pjsua_app.c !Event FMCH 10:35:43.166 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 25/1(~25)fps 10:35:43.166 sdl_dev.c Stopping sdl video stream 10:35:43.166 sdl_dev.c Starting sdl video stream 10:35:43.166 pjsua_app.c !Event FMCH 10:35:43.274 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3420(~26)fps 10:35:43.274 sdl_dev.c Stopping sdl video stream 10:35:43.274 sdl_dev.c Starting sdl video stream 10:35:43.274 pjsua_app.c !Event FMCH 10:35:43.451 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 25/1(~25)fps 10:35:43.451 sdl_dev.c Stopping sdl video stream 10:35:43.451 sdl_dev.c Starting sdl video stream 10:35:43.451 pjsua_app.c !Event FMCH 10:35:43.559 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3150(~28)fps 10:35:43.559 sdl_dev.c Stopping sdl video stream 10:35:43.559 sdl_dev.c Starting sdl video stream 10:35:43.559 pjsua_app.c !Event FMCH 10:35:43.675 vstdec0284B334 ! Decoding format changed: 352x288 I420<- 90000/3510(~25)fps 10:35:43.675 sdl_dev.c Stopping sdl video stream 10:35:43.675 sdl_dev.c Starting sdl video stream 10:35:43.675 pjsua_app.c !Event FMCH 10:35:43.678 pjsua_call.c !Call 0 hanging up: code=0.. 10:35:43.678 endpoint ..Request msg BYE/cseq=22862 (tdta02874E08) created. 10:35:43.678 inv02830C4C ..Sending Request msg BYE/cseq=22862 (tdta02874E08) 10:35:43.678 dlg02830C4C ...Sending Request msg BYE/cseq=22862 (tdta02874E08) 10:35:43.678 tsx0284045C ....Transaction created for Request msg BYE/cseq=22861 (tdta02874E08) 10:35:43.678 tsx0284045C ...Sending Request msg BYE/cseq=22861 (tdta02874E08) in state Null 10:35:43.678 sip_resolve.c ....Target '10.0.0.208:5060' type=Unspecified resolved to '10.0.0.208:5060' type=UDP (UDP transport) 10:35:43.679 pjsua_core.c ....TX 382 bytes Request msg BYE/cseq=22861 (tdta02874E08) to UDP 10.0.0.208:5060: BYE sip:4099 at 10.0.0.208:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPjd2647aebffa5485792b2682f210d6c09 Max-Forwards: 70 From: <sip:10.0.0.193>;tag=00ef5f34d5a84440830eb9cb6e7e68cd To: sip:10.0.0.208;tag=FEC39C52-DD23E451 Call-ID: 8ab18011b5b94a8387c24df92c29da8d CSeq: 22861 BYE User-Agent: PJSUA v2.0-rc win32-6.1/i386/msvc-15.0 Content-Length: 0 --end msg-- 10:35:43.680 tsx0284045C ....State changed from Null to Calling, event=TX_MSG 10:35:43.680 dlg02830C4C .....Transaction tsx0284045C state changed to Calling 10:35:43.698 sip_endpoint.c !Processing incoming message: Response msg 200/BYE/cseq=22861 (rdata027ECB34) 10:35:43.698 pjsua_core.c .RX 403 bytes Response msg 200/BYE/cseq=22861 (rdata027ECB34) from UDP 10.0.0.208:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.193:5060;rport;branch=z9hG4bKPjd2647aebffa5485792b2682f210d6c09 From: <sip:10.0.0.193>;tag=00ef5f34d5a84440830eb9cb6e7e68cd To: "4099" <sip:10.0.0.208>;tag=FEC39C52-DD23E451 CSeq: 22861 BYE Call-ID: 8ab18011b5b94a8387c24df92c29da8d Contact: <sip:4099 at 10.0.0.208:5060> User-Agent: PolycomVVX-VVX_1500-UA/3.2.2.0481 Accept-Language: en Content-Length: 0 --end msg-- 10:35:43.699 tsx0284045C .Incoming Response msg 200/BYE/cseq=22861 (rdata027ECB34) in state Calling 10:35:43.699 tsx0284045C ..State changed from Calling to Completed, event=RX_MSG 10:35:43.699 dlg02830C4C ...Received Response msg 200/BYE/cseq=22861 (rdata027ECB34) 10:35:43.699 dlg02830C4C ...Transaction tsx0284045C state changed to Completed 10:35:43.699 pjsua_app.c .....Call 0 is DISCONNECTED [reason=200 (Normal call clearing)] 10:35:43.700 pjsua_app.c .....Call 0 disconnected, dumping media stats.. 10:35:43.700 pjsua_app.c ..... [DISCONNCTD] To: sip:10.0.0.208;tag=FEC39C52-DD23E451 Call time: 00h:00m:07s, 1st res in 214 ms, conn in 1677ms #0 audio PCMU @8kHz, sendrecv, peer=10.0.0.208:2238 SRTP status: Not active Crypto-suite: (null) RX pt=0, last update:00h:00m:02.495s ago total 367pkt 58.7KB (73.4KB +IP hdr) @avg=62.1Kbps/77.7Kbps pkt loss=0 (0.0%), discrd=0 (0.0%), dup=0 (0.0%), reord=0 (0.0%) (msec) min avg max last dev loss period: 0.000 0.000 0.000 0.000 0.000 jitter : 0.000 0.543 1.000 0.250 0.219 TX pt=0, ptime=20, last update:00h:00m:02.549s ago total 32pkt 4.6KB (5.9KB +IP hdr) @avg=4.9Kbps/6.2Kbps pkt loss=0 (0.0%), dup=0 (0.0%), reorder=0 (0.0%) (msec) min avg max last dev loss period: 0.000 0.000 0.000 0.000 0.000 jitter : 0.000 0.000 0.000 0.000 0.000 RTT msec : 21.453 21.453 21.453 21.453 0.000 #1 video H263-1998, sendrecv, peer=10.0.0.208:2240 SRTP status: Not active Crypto-suite: (null) RX pt=96, size=352x288, fps=25.64, last update:00h:00m:02.013s ago total 352pkt 328.7KB (342.8KB +IP hdr) @avg=348.4Kbps/363.3Kbps pkt loss=0 (0.0%), discrd=1 (0.3%), dup=0 (0.0%), reord=0 (0.0%) (msec) min avg max last dev loss period: 0.000 0.000 0.000 0.000 0.000 jitter : 0.866 4.691 7.655 4.711 0.842 TX pt=96, size=352x288, fps=14.99, last update:00h:00m:02.530s ago total 293pkt 341.4KB (353.1KB +IP hdr) @avg=361.8Kbps/374.3Kbps pkt loss=0 (0.0%), dup=0 (0.0%), reorder=0 (0.0%) (msec) min avg max last dev loss period: 0.000 0.000 0.000 0.000 0.000 jitter : 0.011 0.011 0.011 0.011 0.000 RTT msec : 14.770 14.770 14.770 14.770 0.000 10:35:43.702 pjsua_media.c .....Call 0: deinitializing media.. 10:35:43.702 strm028475BC .......JB summary: size=2/eff=2 prefetch=0 level=2 delay (min/max/avg/dev)=20/20/20/0 ms burst (min/max/avg/dev)=2/2/2/0 frames lost=0 discard=0 empty=5 10:35:43.702 pjsua_media.c .......Media session call00:0 is destroyed 10:35:43.703 pjsua_vid.c .......Stopping video stream.. 10:35:43.704 clock !Thread stack max usage=1957 by c:\users\omar\documents\visual studio 2008\projects\pjproject-2.0-rc\pjlib\src\pj\sock_bsd.c:676 10:35:44.855 dshow_dev.c !........Stopping dshow video stream 10:35:44.855 pjsua_vid.c ........Window 1: destroying.. 10:35:45.855 dshow_dev.c .........Stopping dshow video stream 10:35:45.855 vid_port.c .........Closing Microsoft LifeCam NX-3000.. 10:35:46.855 dshow_dev.c .........Stopping dshow video stream 10:35:46.858 sdl_dev.c .........Stopping sdl video stream 10:35:46.858 vid_port.c .........Closing SDL renderer.. 10:35:46.858 sdl_dev.c .........Stopping sdl video stream 10:35:46.925 clock !Thread stack max usage=6081 by c:\users\omar\documents\visual studio 2008\projects\pjproject-2.0-rc\pjlib\src\pj\os_core_win32.c:1425 10:35:46.925 sdl_dev.c !........Stopping sdl video stream 10:35:46.925 pjsua_vid.c ........Window 0: destroying.. 10:35:46.925 sdl_dev.c .........Stopping sdl video stream 10:35:46.925 vid_port.c .........Closing SDL renderer.. 10:35:46.925 sdl_dev.c .........Stopping sdl video stream 10:35:46.971 vstdec0284B334 ........JB summary: size=1/eff=1 prefetch=0 level=0 delay (min/max/avg/dev)=0/0/0/0 ms burst (min/max/avg/dev)=0/0/0/0 frames lost=0 discard=0 empty=0 10:35:46.971 pjsua_media.c .......Media session call00:1 is destroyed 10:35:46.972 tdta05FE1838 ....Destroying txdata Request msg ACK/cseq=22858 (tdta05FE1838) 10:35:46.972 tdta0283E3F8 ....Destroying txdata Request msg INVITE/cseq=22858 (tdta0283E3F8) 10:35:46.972 dlg02830C4C .....Session count dec to 1 by mod-invite 10:35:46.972 pjsua_aud.c Closing sound device after idle for 1 seconds 10:35:46.972 pjsua_app.c .Turning sound device OFF 10:35:46.972 pjsua_aud.c .Closing Wave mapper sound playback device and Wave mapper sound capture device 10:35:46.973 wmme_dev.c .Stopped WMME playback stream 10:35:46.974 wmme_dev.c .Stopped WMME capture stream 10:35:46.974 wmme_dev.c .Stopped WMME playback stream 10:35:46.974 wmme_dev.c .Stopped WMME capture stream 10:35:46.974 wmme_dev.c !WMME: thread stopping.. 10:35:46.974 wmme Thread stack max usage=6301 by c:\users\omar\documents\visual studio 2008\projects\pjproject-2.0-rc\pjlib\src\pj\string.c:175 10:35:48.700 tsx0284045C !Timeout timer event 10:35:48.700 tsx0284045C .State changed from Completed to Terminated, event=TIMER 10:35:48.700 dlg02830C4C ..Transaction tsx0284045C state changed to Terminated 10:35:48.700 dlg02830C4C ...Dialog destroyed 10:35:48.701 tsx0284045C Timeout timer event 10:35:48.701 tsx0284045C .State changed from Terminated to Destroyed, event=TIMER 10:35:48.701 tdta02874E08 ..Destroying txdata Request msg BYE/cseq=22861 (tdta02874E08) 10:35:48.701 tsx0284045C ..Transaction destroyed! 10:36:40.238 main.c !Ctrl-C detected, quitting.. 10:36:40.238 pjsua_core.c Shutting down, flags=0... 10:36:40.238 pjsua_core.c PJSUA state changed: RUNNING --> CLOSING 10:36:40.240 pjsua !Thread stack max usage=14029 by c:\users\omar\documents\visual studio 2008\projects\pjproject-2.0-rc\pjlib\src\pj\os_core_win32.c:1425 10:36:40.240 pjsua_call.c !.Hangup all calls.. 10:36:40.240 pjsua_pres.c .Shutting down presence.. 10:36:40.240 pjsua_media.c .Shutting down media.. 10:36:40.240 pjsua_media.c ..Call 0: deinitializing media.. 10:36:40.240 pjsua_media.c ..Call 1: deinitializing media.. 10:36:40.240 pjsua_media.c ..Call 2: deinitializing media.. 10:36:40.240 pjsua_media.c ..Call 3: deinitializing media.. 10:36:40.240 pjsua_vid.c ..Destroying video subsystem.. 10:36:40.241 pjsua_vid.c ...Window 0: destroying.. 10:36:40.241 pjsua_vid.c ...Window 1: destroying.. 10:36:40.241 pjsua_vid.c ...Window 2: destroying.. 10:36:40.241 pjsua_vid.c ...Window 3: destroying.. 10:36:40.241 pjsua_vid.c ...Window 4: destroying.. 10:36:40.241 pjsua_vid.c ...Window 5: destroying.. 10:36:40.241 pjsua_vid.c ...Window 6: destroying.. 10:36:40.241 pjsua_vid.c ...Window 7: destroying.. 10:36:40.241 pjsua_vid.c ...Window 8: destroying.. 10:36:40.241 pjsua_vid.c ...Window 9: destroying.. 10:36:40.242 pjsua_vid.c ...Window 10: destroying.. 10:36:40.242 pjsua_vid.c ...Window 11: destroying.. 10:36:40.242 pjsua_vid.c ...Window 12: destroying.. 10:36:40.242 pjsua_vid.c ...Window 13: destroying.. 10:36:40.242 pjsua_vid.c ...Window 14: destroying.. 10:36:40.242 pjsua_vid.c ...Window 15: destroying.. 10:36:40.242 sdl_thread !Thread stack max usage=509 by c:\users\omar\documents\visual studio 2008\projects\pjproject-2.0-rc\pjlib\src\pj\os_core_win32.c:1139 10:36:40.242 job_th !Thread stack max usage=517 by c:\users\omar\documents\visual studio 2008\projects\pjproject-2.0-rc\pjlib\src\pj\os_core_win32.c:944 10:36:40.243 ev_thread !Thread stack max usage=7621 by c:\users\omar\documents\visual studio 2008\projects\pjproject-2.0-rc\pjlib\src\pj\os_core_win32.c:1425 10:36:40.474 media !Thread stack max usage=8333 by c:\users\omar\documents\visual studio 2008\projects\pjproject-2.0-rc\pjlib\src\pj\string.c:175 10:36:41.475 pjsua_core.c !.Destroying... 10:36:41.475 sip_endpoint.c .Destroying endpoing instance.. 10:36:41.475 sip_transactio .Stopping transaction layer module 10:36:41.475 sip_transactio .Stopped transaction layer module 10:36:41.475 sip_endpoint.c .Module "mod-default-handler" unregistered 10:36:41.475 sip_endpoint.c .Module "mod-unsolicited-mwi" unregistered 10:36:41.475 sip_endpoint.c .Module "mod-pjsua-options" unregistered 10:36:41.476 sip_endpoint.c .Module "mod-pjsua-im" unregistered 10:36:41.476 sip_endpoint.c .Module "mod-pjsua-pres" unregistered 10:36:41.476 sip_endpoint.c .Module "mod-pjsua" unregistered 10:36:41.476 sip_endpoint.c .Module "mod-stateful-util" unregistered 10:36:41.476 sip_endpoint.c .Module "mod-refer" unregistered 10:36:41.478 sip_endpoint.c .Module "mod-mwi" unregistered 10:36:41.478 sip_endpoint.c .Module "mod-presence" unregistered 10:36:41.478 sip_endpoint.c .Module "mod-evsub" unregistered 10:36:41.478 sip_endpoint.c .Module "mod-invite" unregistered 10:36:41.478 sip_endpoint.c .Module "mod-100rel" unregistered 10:36:41.479 sip_endpoint.c .Module "mod-ua" unregistered 10:36:41.479 sip_transactio .Transaction layer module destroyed 10:36:41.479 sip_endpoint.c .Module "mod-tsx-layer" unregistered 10:36:41.479 sip_endpoint.c .Module "mod-msg-print" unregistered 10:36:41.479 sip_endpoint.c .Module "mod-pjsua-log" unregistered 10:36:41.479 sip_transport. .Destroying transport manager 10:36:41.480 tcplis:5060 .SIP TCP listener destroyed 10:36:41.480 sip_endpoint.c .Endpoint 022145BC destroyed 10:36:41.480 pjsua_core.c .PJSUA state changed: CLOSING --> NULL 10:36:41.480 pjsua_core.c .PJSUA destroyed...
