2017-08-02 13:59:25.261418 [DEBUG] mod_verto.c:4217 163.172.154.141:43548 Client Connect from 163.172.154.141:43548 accepted 2017-08-02 13:59:25.271422 [DEBUG] mod_verto.c:2003 163.172.154.141:43548 Starting client thread. 2017-08-02 13:59:25.411454 [DEBUG] mod_verto.c:1292 163.172.154.141:43548 re-connecting session 0.1527022602341539 2017-08-02 13:59:25.531429 [DEBUG] mod_verto.c:1051 auth using username & password 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.971435 [DEBUG] mod_rtc.c:392 () State Change CS_NEW -> CS_INIT 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.971435 [DEBUG] switch_core_session.c:641 N/A set UUID=1c96b33a-443b-cf55-2bc7-811c69e9d8f7 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.971435 [NOTICE] switch_channel.c:1104 New Channel verto.rtc/70391-SCREENSHARE [1c96b33a-443b-cf55-2bc7-811c69e9d8f7] 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.971435 [DEBUG] mod_verto.c:3746 Remote SDP verto.rtc/70391-SCREENSHARE: 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 v=0^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 o=- 1908834382033998496 2 IN IP4 127.0.0.1^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 s=-^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 t=0 0^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=group:BUNDLE video^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=msid-semantic: WMS oWzCmzknePVK9j2SnpU3OJu73gu01mUat1WG^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 m=video 57945 UDP/TLS/RTP/SAVPF 96 98 100 102 127 97 99 101 125^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 c=IN IP4 47.55.4.205^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp:9 IN IP4 0.0.0.0^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=candidate:793450030 1 udp 2122260223 192.168.2.22 57945 typ host generation 0 network-id 1 network-cost 10^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=candidate:1640557278 1 tcp 1518280447 192.168.2.22 9 typ host tcptype active generation 0 network-id 1 network-cost 10^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=candidate:3646648314 1 udp 1686052607 47.55.4.205 57945 typ srflx raddr 192.168.2.22 rport 57945 generation 0 network-id 1 network-cost 10^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=ice-ufrag:1l4k^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=ice-pwd:NPMEHATvQjaY4gOir72Ns2gI^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=fingerprint:sha-256 54:14:36:2E:3C:3E:EE:07:99:C0:2F:FF:F7:91:24:DB:3D:5C:0B:4B:A5:A5:31:C9:3D:61:24:4E:55:E4:26:D7^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=setup:actpass^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=mid:video^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=extmap:2 urn:ietf:params:rtp-hdrext:toffset^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=extmap:4 urn:3gpp:video-orientation^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=extmap:5 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=extmap:6 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=sendonly^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-mux^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-rsize^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtpmap:96 VP8/90000^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-fb:96 ccm fir^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-fb:96 nack^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-fb:96 nack pli^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-fb:96 goog-remb^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-fb:96 transport-cc^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtpmap:98 VP9/90000^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-fb:98 ccm fir^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-fb:98 nack^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-fb:98 nack pli^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-fb:98 goog-remb^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-fb:98 transport-cc^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtpmap:100 H264/90000^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-fb:100 ccm fir^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-fb:100 nack^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-fb:100 nack pli^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-fb:100 goog-remb^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-fb:100 transport-cc^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=fmtp:100 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtpmap:102 red/90000^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtpmap:127 ulpfec/90000^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtpmap:97 rtx/90000^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=fmtp:97 apt=96^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtpmap:99 rtx/90000^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=fmtp:99 apt=98^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtpmap:101 rtx/90000^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=fmtp:101 apt=100^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtpmap:125 rtx/90000^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=fmtp:125 apt=102^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=ssrc-group:FID 2841259620 1601885676^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=ssrc:2841259620 cname:Dloikx80BgTgV2R6^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=ssrc:2841259620 msid:oWzCmzknePVK9j2SnpU3OJu73gu01mUat1WG 8cb978bf-d838-41ba-9dcb-c974e49a98e2^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=ssrc:2841259620 mslabel:oWzCmzknePVK9j2SnpU3OJu73gu01mUat1WG^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=ssrc:2841259620 label:8cb978bf-d838-41ba-9dcb-c974e49a98e2^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=ssrc:1601885676 cname:Dloikx80BgTgV2R6^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=ssrc:1601885676 msid:oWzCmzknePVK9j2SnpU3OJu73gu01mUat1WG 8cb978bf-d838-41ba-9dcb-c974e49a98e2^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=ssrc:1601885676 mslabel:oWzCmzknePVK9j2SnpU3OJu73gu01mUat1WG^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=ssrc:1601885676 label:8cb978bf-d838-41ba-9dcb-c974e49a98e2^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.971435 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/70391-SCREENSHARE) Running State Change CS_INIT (Cur 4 Tot 229) 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.971435 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/70391-SCREENSHARE) State INIT 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.971435 [DEBUG] switch_core_state_machine.c:40 verto.rtc/70391-SCREENSHARE Standard INIT 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.971435 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/70391-SCREENSHARE) State Change CS_INIT -> CS_ROUTING 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.971435 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/70391-SCREENSHARE) State INIT going to sleep 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.971435 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/70391-SCREENSHARE) Running State Change CS_ROUTING (Cur 4 Tot 229) 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.971435 [DEBUG] switch_channel.c:2249 (verto.rtc/70391-SCREENSHARE) Callstate Change DOWN -> RINGING 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/70391-SCREENSHARE) State ROUTING 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] mod_rtc.c:89 verto.rtc/70391-SCREENSHARE RTC ROUTING 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_state_machine.c:236 verto.rtc/70391-SCREENSHARE Standard ROUTING 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [INFO] mod_dialplan_xml.c:637 Processing Fred Dixon ->70391-SCREENSHARE in context default 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 Dialplan: verto.rtc/70391-SCREENSHARE parsing [default->unloop] continue=false 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 Dialplan: verto.rtc/70391-SCREENSHARE Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 Dialplan: verto.rtc/70391-SCREENSHARE Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 Dialplan: verto.rtc/70391-SCREENSHARE parsing [default->bbb_conferences] continue=false 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 Dialplan: verto.rtc/70391-SCREENSHARE Regex (FAIL) [bbb_conferences] ${bbb_authorized}() =~ /true/ break=on-false 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 Dialplan: verto.rtc/70391-SCREENSHARE parsing [default->bbb_echo_test_direct] continue=false 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 Dialplan: verto.rtc/70391-SCREENSHARE Regex (FAIL) [bbb_echo_test_direct] ${bbb_authorized}() =~ /true/ break=on-false 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 Dialplan: verto.rtc/70391-SCREENSHARE parsing [default->bbb_screenshare] continue=false 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 Dialplan: verto.rtc/70391-SCREENSHARE Regex (PASS) [bbb_screenshare] destination_number(70391-SCREENSHARE) =~ /^(\d{5}-SCREENSHARE)$/ break=on-false 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 Dialplan: verto.rtc/70391-SCREENSHARE Action set(jitterbuffer_msec=20:400) 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 Dialplan: verto.rtc/70391-SCREENSHARE Action answer() 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 Dialplan: verto.rtc/70391-SCREENSHARE Action video_decode(wait) 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 Dialplan: verto.rtc/70391-SCREENSHARE Action set(conference_force_canvas_size=${video_width}x${video_height}) 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 Dialplan: verto.rtc/70391-SCREENSHARE Action conference(${destination_number}@bbb-screenshare) 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_state_machine.c:286 (verto.rtc/70391-SCREENSHARE) State Change CS_ROUTING -> CS_EXECUTE 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/70391-SCREENSHARE) State ROUTING going to sleep 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/70391-SCREENSHARE) Running State Change CS_EXECUTE (Cur 4 Tot 229) 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_state_machine.c:650 (verto.rtc/70391-SCREENSHARE) State EXECUTE 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] mod_rtc.c:120 verto.rtc/70391-SCREENSHARE RTC EXECUTE 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_state_machine.c:328 verto.rtc/70391-SCREENSHARE Standard EXECUTE 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 EXECUTE verto.rtc/70391-SCREENSHARE set(jitterbuffer_msec=20:400) 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] mod_dptools.c:1588 SET verto.rtc/70391-SCREENSHARE [jitterbuffer_msec]=[20:400] 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 EXECUTE verto.rtc/70391-SCREENSHARE answer() 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:5715 Video Codec Compare [VP8:96]/[VP8:99] 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:5747 Video Codec Compare [VP8:96] +++ is saved as a match 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:5715 Video Codec Compare [VP9:98]/[VP8:99] 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:5715 Video Codec Compare [H264:100]/[VP8:99] 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:5715 Video Codec Compare [red:102]/[VP8:99] 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:5715 Video Codec Compare [ulpfec:127]/[VP8:99] 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:5715 Video Codec Compare [rtx:97]/[VP8:99] 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:5715 Video Codec Compare [rtx:99]/[VP8:99] 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:5715 Video Codec Compare [rtx:101]/[VP8:99] 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:5715 Video Codec Compare [rtx:125]/[VP8:99] 2017-08-02 13:59:29.981463 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 2017-08-02 13:59:29.981463 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:3240 Set VIDEO Codec verto.rtc/70391-SCREENSHARE VP8/90000 0 ms 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:3860 Save video Candidate cid: 1 proto: udp type: host addr: 192.168.2.22:57945 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:3860 Save video Candidate cid: 1 proto: udp type: srflx addr: 47.55.4.205:57945 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:3902 Searching for rtp candidate. 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:3907 Choose rtp candidate, index 1, 47.55.4.205:57945 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:3680 verto.rtc/70391-SCREENSHARE choosing family v4 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:3918 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 47.55.4.205:57945 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:3965 setting remote video ice addr to index 1 47.55.4.205:57945 based on candidate 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:4000 Setting remote rtcp video addr to 47.55.4.205:57945 based on candidate 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:3430 Set Codec verto.rtc/70391-SCREENSHARE L16/0 20 ms 441 samples 352800 bits 1 channels 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_codec.c:111 verto.rtc/70391-SCREENSHARE Original read codec set to L16:100 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:8149 AUDIO RTP [verto.rtc/70391-SCREENSHARE] 10.5.128.43 port 24642 -> 127.0.0.1 port 9999 codec: 97 ms: 20 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_rtp.c:4164 Starting timer [soft] 441 bytes per 20ms 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:8371 Activating RTCP PORT 0 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_rtp.c:4560 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 10000 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_rtp.c:2566 Setting RTCP remote addr to 127.0.0.1:10000 2 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:2192 Setting Jitterbuffer to 20ms (1 frames) (20 max frames) 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_rtp.c:4179 Starting video timer. 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:8977 VIDEO RTP [verto.rtc/70391-SCREENSHARE] 10.5.128.43:32280->47.55.4.205:57945 codec: 96 ms: 0 [SUCCESS] 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:7141 verto.rtc/70391-SCREENSHARE Starting Video thread 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:7007 verto.rtc/70391-SCREENSHARE Video thread started. Echo is off 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [INFO] switch_core_media.c:9019 Activating Video ICE 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [NOTICE] switch_rtp.c:4663 Activating RTP video ICE: 1l4k:YNldWeABTS7mX38Q 47.55.4.205:57945 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [INFO] switch_core_media.c:9057 Activating VIDEO RTCP PORT 57945 interval 1000 mux 1 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_rtp.c:4560 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 57945 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [INFO] switch_core_media.c:9068 Skipping VIDEO RTCP ICE (Same as VIDEO RTP) 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [INFO] switch_rtp.c:3620 Activate RTP/RTCP video DTLS client 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [INFO] switch_rtp.c:3783 Changing video DTLS state from OFF to HANDSHAKE 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:8132 Audio params are unchanged for verto.rtc/70391-SCREENSHARE. 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] switch_core_media.c:8835 Video params are unchanged for verto.rtc/70391-SCREENSHARE. 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [DEBUG] mod_verto.c:2480 Local SDP verto.rtc/70391-SCREENSHARE: 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 v=0^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 o=FreeSWITCH 1501657727 1501657728 IN IP4 163.172.154.141^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 s=FreeSWITCH^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 c=IN IP4 163.172.154.141^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 t=0 0^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 m=video 32280 UDP/TLS/RTP/SAVPF 96^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 b=AS:1024^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtpmap:96 VP8/90000^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=recvonly^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=fingerprint:sha-256 D4:9D:FA:F8:3F:3C:0A:17:64:C8:68:D5:16:FC:6E:00:B7:C2:6E:B6:FF:5E:8D:8C:59:42:82:17:15:C9:8B:26^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=setup:active^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-mux^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp:32280 IN IP4 163.172.154.141^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-fb:96 ccm fir^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-fb:96 nack^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=rtcp-fb:96 nack pli^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=ssrc:1690662904 cname:3bHaHKaB5sX0j709^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=ssrc:1690662904 msid:rYgWgFda4usw50ja01Xmoi2wOPsGDe5V v0^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=ssrc:1690662904 mslabel:rYgWgFda4usw50ja01Xmoi2wOPsGDe5V^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=ssrc:1690662904 label:rYgWgFda4usw50ja01Xmoi2wOPsGDe5Vv0^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=ice-ufrag:YNldWeABTS7mX38Q^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=ice-pwd:4FQBfR2aaQ0jNQjCuvGhA6Ij^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=candidate:7956953769 1 udp 659136 163.172.154.141 32280 typ host generation 0^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 a=end-of-candidates^M 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.981463 [NOTICE] mod_dptools.c:1352 Channel [verto.rtc/70391-SCREENSHARE] has been answered 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:29.991455 [DEBUG] switch_channel.c:3781 (verto.rtc/70391-SCREENSHARE) Callstate Change RINGING -> ACTIVE 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 EXECUTE verto.rtc/70391-SCREENSHARE video_decode(wait) 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:30.521432 [INFO] switch_rtp.c:3191 Changing video DTLS state from HANDSHAKE to SETUP 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:30.571445 [INFO] switch_rtp.c:3100 video Fingerprint Verified. 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:30.571445 [INFO] switch_rtp.c:3968 Activating video Secure RTP SEND 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:30.571445 [INFO] switch_rtp.c:3946 Activating video Secure RTP RECV 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:30.571445 [INFO] switch_rtp.c:3140 Changing video DTLS state from SETUP to READY 2017-08-02 13:59:30.571445 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80 2017-08-02 13:59:30.571445 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:30.591435 [DEBUG] switch_rtp.c:1886 rtcp_stats_init: video ssrc[2841259620] base_seq[11750] 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 EXECUTE verto.rtc/70391-SCREENSHARE set(conference_force_canvas_size=1440x900) 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:31.181443 [DEBUG] mod_dptools.c:1588 SET verto.rtc/70391-SCREENSHARE [conference_force_canvas_size]=[1440x900] 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 EXECUTE verto.rtc/70391-SCREENSHARE conference(70391-SCREENSHARE@bbb-screenshare) 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:303 Adding layout group grid 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 1x1 to layout group grid 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 2x1 to layout group grid 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 1x1+2x1 to layout group grid 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 2x2 to layout group grid 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 3x3 to layout group grid 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 4x4 to layout group grid 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 5x5 to layout group grid 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 6x6 to layout group grid 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 8x8 to layout group grid 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:303 Adding layout group grid-zoom 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 1x1 to layout group grid-zoom 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 2x1-zoom to layout group grid-zoom 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 3x1-zoom to layout group grid-zoom 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 2x2 to layout group grid-zoom 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 5-grid-zoom to layout group grid-zoom 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 3x2-zoom to layout group grid-zoom 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 7-grid-zoom to layout group grid-zoom 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 4x2-zoom to layout group grid-zoom 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 3x3 to layout group grid-zoom 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:303 Adding layout group 1up_top_left_plus 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 1up_top_left+5 to layout group 1up_top_left_plus 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 1up_top_left+7 to layout group 1up_top_left_plus 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 1up_top_left+9 to layout group 1up_top_left_plus 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:303 Adding layout group 3up_plus 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 3up+4 to layout group 3up_plus 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:312 Adding node 3up+9 to layout group 3up_plus 2017-08-02 13:59:31.181443 [DEBUG] mod_conference.c:3315 using channel sound prefix: /opt/freeswitch/share/freeswitch/sounds/en/us/callie 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:1575 Canvas position 1 applied layout 1x1 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:1696 Layout set to 1x1 2017-08-02 13:59:31.181443 [DEBUG] conference_video.c:1656 Canvas attached to position 0 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:31.181443 [DEBUG] conference_member.c:1716 Raw Codec Activation Success L16@22050hz 1 channel 20ms 2017-08-02 13:59:31.191448 [DEBUG] mod_conference.c:227 Setup timer success interval: 20 samples: 960 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:31.191448 [DEBUG] conference_member.c:1763 Raw Codec Activation Success L16@48000hz 2 channel 20ms 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:31.191448 [DEBUG] switch_core_codec.c:223 verto.rtc/70391-SCREENSHARE Push codec L16:100 1c96b33a-443b-cf55-2bc7-811c69e9d8f7 2017-08-02 13:59:31.211439 [CRIT] switch_channel.c:1436 Invalid data (${conference_call_key} contains a variable) 2017-08-02 13:59:31.211439 [DEBUG] conference_member.c:104 verto.rtc/70391-SCREENSHARE binding '0' to 'mute' 2017-08-02 13:59:31.211439 [INFO] switch_ivr_async.c:215 Digit parser mod_conference: Setting realm to 'conf' 2017-08-02 13:59:31.211439 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 0/conf/0 callback: 0x7f620036f150 data: 0x7f615028ac48 2017-08-02 13:59:31.211439 [DEBUG] conference_member.c:104 verto.rtc/70391-SCREENSHARE binding '*' to 'deaf mute' 2017-08-02 13:59:31.211439 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding */conf/0 callback: 0x7f620036f150 data: 0x7f615028ac78 2017-08-02 13:59:31.211439 [DEBUG] conference_member.c:104 verto.rtc/70391-SCREENSHARE binding '9' to 'energy up' 2017-08-02 13:59:31.211439 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 9/conf/0 callback: 0x7f620036f150 data: 0x7f615028aca8 2017-08-02 13:59:31.211439 [DEBUG] conference_member.c:104 verto.rtc/70391-SCREENSHARE binding '8' to 'energy equ' 2017-08-02 13:59:31.211439 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 8/conf/0 callback: 0x7f620036f150 data: 0x7f615028acd8 2017-08-02 13:59:31.211439 [DEBUG] conference_member.c:104 verto.rtc/70391-SCREENSHARE binding '7' to 'energy dn' 2017-08-02 13:59:31.211439 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 7/conf/0 callback: 0x7f620036f150 data: 0x7f615028ad08 2017-08-02 13:59:31.211439 [DEBUG] conference_member.c:104 verto.rtc/70391-SCREENSHARE binding '3' to 'vol talk up' 2017-08-02 13:59:31.211439 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 3/conf/0 callback: 0x7f620036f150 data: 0x7f615028ad38 2017-08-02 13:59:31.211439 [DEBUG] conference_member.c:104 verto.rtc/70391-SCREENSHARE binding '2' to 'vol talk zero' 2017-08-02 13:59:31.211439 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 2/conf/0 callback: 0x7f620036f150 data: 0x7f615028ad68 2017-08-02 13:59:31.211439 [DEBUG] conference_member.c:104 verto.rtc/70391-SCREENSHARE binding '1' to 'vol talk dn' 2017-08-02 13:59:31.211439 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 1/conf/0 callback: 0x7f620036f150 data: 0x7f615028ad98 2017-08-02 13:59:31.211439 [DEBUG] conference_member.c:104 verto.rtc/70391-SCREENSHARE binding '6' to 'vol listen up' 2017-08-02 13:59:31.211439 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 6/conf/0 callback: 0x7f620036f150 data: 0x7f615028adc8 2017-08-02 13:59:31.211439 [DEBUG] conference_member.c:104 verto.rtc/70391-SCREENSHARE binding '5' to 'vol listen zero' 2017-08-02 13:59:31.211439 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 5/conf/0 callback: 0x7f620036f150 data: 0x7f615028adf8 2017-08-02 13:59:31.211439 [DEBUG] conference_member.c:104 verto.rtc/70391-SCREENSHARE binding '4' to 'vol listen dn' 2017-08-02 13:59:31.211439 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 4/conf/0 callback: 0x7f620036f150 data: 0x7f615028ae28 2017-08-02 13:59:31.211439 [DEBUG] conference_member.c:104 verto.rtc/70391-SCREENSHARE binding '#' to 'hangup' 2017-08-02 13:59:31.211439 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding #/conf/0 callback: 0x7f620036f150 data: 0x7f615028ae58 2017-08-02 13:59:31.231430 [DEBUG] avformat.c:1919 sample rate: 48000, channels: 2 Parsing... Parsed protocol: 0 Parsed host : dev2.bigbluebutton.org Parsed app : video-broadcast/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1501681292496 RTMP_Connect1, ... connected, handshaking HandShake: Type Answer : 03 HandShake: Server Uptime : 46773 HandShake: FMS Version : 0.0.0.0 HandShake: Handshaking finished.... RTMP_Connect1, handshaked Invoking connect HandleServerBW: server BW = 10000000 HandleClientBW: client BW = 10000000 2 HandleCtrl, received ctrl. type: 0, len: 6 HandleCtrl, Stream Begin 0 RTMP_ClientPacket, received: invoke 225 bytes (object begin) Property: Property: Property: NULL Property: (object begin) Property: Property: Property: Property: Property: Property: Property: (object begin) Property: Property: (object end) (object end) (object end) HandleInvoke, server invoking <_result> HandleInvoke, received result for method call Invoking releaseStream Invoking FCPublish Invoking createStream RTMP_ClientPacket, received: invoke 21 bytes (object begin) Property: Property: Property: NULL Property: NULL (object end) HandleInvoke, server invoking <_result> HandleInvoke, received result id 2.000000 without matching request RTMP_ClientPacket, received: invoke 21 bytes (object begin) Property: Property: Property: NULL Property: NULL (object end) HandleInvoke, server invoking <_result> HandleInvoke, received result id 3.000000 without matching request RTMP_ClientPacket, received: invoke 29 bytes (object begin) Property: Property: Property: NULL Property: (object end) HandleInvoke, server invoking <_result> HandleInvoke, received result for method call Invoking publish RTMP_ClientPacket, received: invoke 188 bytes (object begin) Property: Property: Property: NULL Property: (object begin) Property: Property: Property: Property: Property: (object end) (object end) HandleInvoke, server invoking HandleInvoke, onStatus: NetStream.Publish.Start 2017-08-02 13:59:31.261413 [DEBUG] avformat.c:2037 use video codec: [28] h264 (H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10) 2017-08-02 13:59:31.261413 [WARNING] avformat.c:623 Codec [aac] is experimental feature in libavcodec, never mind 2017-08-02 13:59:31.271414 [NOTICE] avformat.c:648 sample_rate: 44100 nb_samples: 1024 2017-08-02 13:59:31.271414 [NOTICE] avformat.c:652 sample_fmt 8 != AV_SAMPLE_FMT_S16, start resampler Output #0, flv, to 'rtmp://dev2.bigbluebutton.org/video-broadcast/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1501681292496/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1501681292496-1501682371223': Stream #0:0: Audio: aac, 44100 Hz, stereo, fltp, 131 kb/s 2017-08-02 13:59:31.271414 [INFO] avformat.c:2061 Opening File [rtmp://dev2.bigbluebutton.org/video-broadcast/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1501681292496/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1501681292496-1501682371223] 44100hz with VIDEO 2017-08-02 13:59:31.271414 [DEBUG] switch_core_file.c:342 File dev2.bigbluebutton.org/video-broadcast/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1501681292496/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1501681292496-1501682371223 sample rate 44100 doesn't match requested rate 48000 2017-08-02 13:59:31.271414 [DEBUG] conference_record.c:302 Setup timer success interval: 20 samples: 960 using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX FMA3 AVX2 LZCNT BMI2 profile Constrained Baseline, level 4.1 264 - core 148 r2643 5c65704 - H.264/MPEG-4 AVC codec - Copyleft 2003-2015 - http://www.videolan.org/x264.html - options: cabac=0 ref=1 deblock=1:0:0 analyse=0x1:0x111 me=hex subme=2 psy=1 psy_rd=1.00:0.00 mixed_ref=0 me_range=16 chroma_me=1 trellis=0 8x8dct=0 cqm=0 deadzone=21,11 fast_pskip=1 chroma_qp_offset=0 threads=4 lookahead_threads=1 sliced_threads=0 nr=0 decimate=1 interlaced=0 bluray_compat=0 constrained_intra=0 bframes=0 weightp=0 keyint=30 keyint_min=16 scenecut=40 intra_refresh=0 rc_lookahead=10 rc=crf mbtree=1 crf=18.0 qcomp=0.60 qpmin=10 qpmax=31 qpstep=4 ip_ratio=1.41 aq=1:1.00 2017-08-02 13:59:31.331417 [INFO] avformat.c:2715 use video codec implementation Video: h264 (libx264), yuv420p(pc, gbr/unknown/unknown), 1440x900, q=10-31, 1392 kb/s 2017-08-02 13:59:31.331417 [NOTICE] avformat.c:730 video thread start ............. Lots of these logs ................... [start conversion] [convert] input to output 1024 samples - audio_convert: s16 to fltp (C) [end conversion] [start conversion] [convert] input to output 1024 samples - audio_convert: s16 to fltp (C) [end conversion] ..................................................... [start conversion] [convert] input to output 1024 samples - audio_convert: s16 to fltp (C) [end conversion] [start conversion] [convert] input to output 1024 samples - audio_convert: s16 to fltp (C) [end conversion] 2017-08-02 14:03:21.248392 [CONSOLE] switch_loadable_module.c:1540 Successfully Loaded [mod_logfile] 2017-08-02 14:03:21.249339 [CONSOLE] switch_loadable_module.c:1540 Successfully Loaded [mod_enum] 2017-08-02 14:03:21.249363 [NOTICE] switch_loadable_module.c:250 Adding Dialplan 'enum' 2017-08-02 14:03:21.249429 [NOTICE] switch_loadable_module.c:292 Adding Application 'enum' 2017-08-02 14:03:21.249461 [NOTICE] switch_loadable_module.c:338 Adding API Function 'enum' 2017-08-02 14:03:21.249490 [NOTICE] switch_loadable_module.c:338 Adding API Function 'enum_auto' 2017-08-02 14:03:21.249938 [DEBUG] mod_cdr_csv.c:368 Adding default template. 2017-08-02 14:03:21.249965 [DEBUG] mod_cdr_csv.c:415 Adding template sql. 2017-08-02 14:03:21.249974 [DEBUG] mod_cdr_csv.c:415 Adding template example. 2017-08-02 14:03:21.249979 [DEBUG] mod_cdr_csv.c:415 Adding template snom. 2017-08-02 14:03:21.249984 [DEBUG] mod_cdr_csv.c:415 Adding template linksys. 2017-08-02 14:03:21.249991 [DEBUG] mod_cdr_csv.c:415 Adding template asterisk. 2017-08-02 14:03:21.249995 [DEBUG] mod_cdr_csv.c:415 Adding template opencdrrate. 2017-08-02 14:03:21.250030 [CONSOLE] switch_loadable_module.c:1540 Successfully Loaded [mod_cdr_csv] 2017-08-02 14:03:21.250043 [NOTICE] switch_loadable_module.c:338 Adding API Function 'cdr_csv' 2017-08-02 14:03:21.250542 [CONSOLE] switch_loadable_module.c:1540 Successfully Loaded [mod_event_socket] 2017-08-02 14:03:21.250562 [NOTICE] switch_loadable_module.c:292 Adding Application 'socket' 2017-08-02 14:03:21.250591 [NOTICE] switch_loadable_module.c:338 Adding API Function 'event_sink' 2017-08-02 14:03:21.252565 [INFO] mod_sofia.c:6170 Starting initial message thread. 2017-08-02 14:03:21.252701 [DEBUG] sofia.c:4629 debug [0] 2017-08-02 14:03:21.252714 [DEBUG] sofia.c:4629 sip-trace [no] 2017-08-02 14:03:21.252723 [DEBUG] sofia.c:4629 sip-capture [no] 2017-08-02 14:03:21.252727 [DEBUG] sofia.c:4629 rfc2833-pt [101] 2017-08-02 14:03:21.252745 [DEBUG] sofia.c:4629 sip-port [5060] 2017-08-02 14:03:21.252760 [DEBUG] sofia.c:4629 dialplan [XML] 2017-08-02 14:03:21.252775 [DEBUG] sofia.c:4629 context [public] 2017-08-02 14:03:21.252787 [DEBUG] sofia.c:4629 dtmf-duration [2000] 2017-08-02 14:03:21.252800 [DEBUG] sofia.c:4629 inbound-codec-prefs [OPUS,speex@16000h@20i,speex@8000h@20i,G722,PCMU,PCMA,VP8] 2017-08-02 14:03:21.252815 [DEBUG] sofia.c:4629 outbound-codec-prefs [OPUS,speex@16000h@20i,G722,PCMU,PCMA,VP8] 2017-08-02 14:03:21.252836 [DEBUG] sofia.c:4629 hold-music [local_stream://moh] 2017-08-02 14:03:21.252845 [DEBUG] sofia.c:4629 rtp-timer-name [soft] 2017-08-02 14:03:21.252854 [DEBUG] sofia.c:4629 local-network-acl [localnet.auto] 2017-08-02 14:03:21.252860 [DEBUG] sofia.c:4629 manage-presence [false] 2017-08-02 14:03:21.252869 [DEBUG] sofia.c:4629 inbound-codec-negotiation [generous] 2017-08-02 14:03:21.252882 [DEBUG] sofia.c:4629 nonce-ttl [60] 2017-08-02 14:03:21.252894 [DEBUG] sofia.c:4629 auth-calls [false] 2017-08-02 14:03:21.252903 [DEBUG] sofia.c:4629 inbound-late-negotiation [true] 2017-08-02 14:03:21.252912 [DEBUG] sofia.c:4629 inbound-zrtp-passthru [true] 2017-08-02 14:03:21.252917 [DEBUG] sofia.c:4629 rtp-ip [::1] 2017-08-02 14:03:21.252927 [DEBUG] sofia.c:4629 sip-ip [::1] 2017-08-02 14:03:21.252936 [DEBUG] sofia.c:4629 rtp-timeout-sec [300] 2017-08-02 14:03:21.252941 [DEBUG] sofia.c:4629 rtp-hold-timeout-sec [1800] 2017-08-02 14:03:21.252949 [DEBUG] sofia.c:4629 tls [false] 2017-08-02 14:03:21.252960 [DEBUG] sofia.c:4629 tls-only [false] 2017-08-02 14:03:21.252973 [DEBUG] sofia.c:4629 tls-bind-params [transport=tls] 2017-08-02 14:03:21.252983 [DEBUG] sofia.c:4629 tls-sip-port [5081] 2017-08-02 14:03:21.252994 [DEBUG] sofia.c:4629 tls-passphrase [] 2017-08-02 14:03:21.253006 [DEBUG] sofia.c:4629 tls-verify-date [true] 2017-08-02 14:03:21.253016 [DEBUG] sofia.c:4629 tls-verify-policy [none] 2017-08-02 14:03:21.253028 [DEBUG] sofia.c:4629 tls-verify-depth [2] 2017-08-02 14:03:21.253038 [DEBUG] sofia.c:4629 tls-verify-in-subjects [] 2017-08-02 14:03:21.253048 [DEBUG] sofia.c:4629 tls-version [tlsv1,tlsv1.1,tlsv1.2] 2017-08-02 14:03:21.253065 [INFO] sofia.c:5979 Setting MAX Auth Validity to 0 Attempts 2017-08-02 14:03:21.253152 [NOTICE] sofia.c:6146 Started Profile external-ipv6 [sofia_reg_external-ipv6] 2017-08-02 14:03:21.253219 [DEBUG] sofia.c:4629 debug [0] 2017-08-02 14:03:21.253231 [DEBUG] sofia.c:4629 sip-trace [no] 2017-08-02 14:03:21.253235 [DEBUG] sofia.c:4629 sip-capture [no] 2017-08-02 14:03:21.253238 [DEBUG] sofia.c:4629 rfc2833-pt [101] 2017-08-02 14:03:21.253239 [DEBUG] sofia.c:3119 Creating agent for external-ipv6 2017-08-02 14:03:21.253251 [DEBUG] sofia.c:4629 sip-port [5060] 2017-08-02 14:03:21.253259 [DEBUG] sofia.c:4629 dialplan [XML] 2017-08-02 14:03:21.253267 [DEBUG] sofia.c:4629 context [public] 2017-08-02 14:03:21.253279 [DEBUG] sofia.c:4629 dtmf-duration [2000] 2017-08-02 14:03:21.253293 [DEBUG] sofia.c:4629 inbound-codec-prefs [OPUS,speex@16000h@20i,speex@8000h@20i,G722,PCMU,PCMA,VP8] 2017-08-02 14:03:21.253304 [DEBUG] sofia.c:4629 outbound-codec-prefs [OPUS,speex@16000h@20i,G722,PCMU,PCMA,VP8] 2017-08-02 14:03:21.253316 [DEBUG] sofia.c:4629 hold-music [local_stream://moh] 2017-08-02 14:03:21.253325 [DEBUG] sofia.c:4629 rtp-timer-name [soft] 2017-08-02 14:03:21.253336 [DEBUG] sofia.c:4629 local-network-acl [localnet.auto] 2017-08-02 14:03:21.253344 [DEBUG] sofia.c:4629 manage-presence [false] 2017-08-02 14:03:21.253355 [DEBUG] sofia.c:4629 inbound-codec-negotiation [generous] 2017-08-02 14:03:21.253378 [DEBUG] sofia.c:4629 nonce-ttl [60] 2017-08-02 14:03:21.253388 [DEBUG] sofia.c:4629 auth-calls [false] 2017-08-02 14:03:21.253400 [DEBUG] sofia.c:4629 inbound-late-negotiation [true] 2017-08-02 14:03:21.253409 [DEBUG] sofia.c:4629 inbound-zrtp-passthru [true] 2017-08-02 14:03:21.253414 [DEBUG] sofia.c:4629 rtp-ip [10.5.128.43] 2017-08-02 14:03:21.253426 [DEBUG] sofia.c:4629 sip-ip [10.5.128.43] 2017-08-02 14:03:21.253434 [DEBUG] sofia.c:4629 ext-rtp-ip [163.172.154.141] 2017-08-02 14:03:21.253445 [DEBUG] sofia.c:4629 ext-sip-ip [163.172.154.141] 2017-08-02 14:03:21.253454 [DEBUG] sofia.c:4629 rtp-timeout-sec [300] 2017-08-02 14:03:21.253464 [DEBUG] sofia.c:4629 rtp-hold-timeout-sec [1800] 2017-08-02 14:03:21.253475 [DEBUG] sofia.c:4629 tls [false] 2017-08-02 14:03:21.253485 [DEBUG] sofia.c:4629 tls-only [false] 2017-08-02 14:03:21.253493 [DEBUG] sofia.c:4629 tls-bind-params [transport=tls] 2017-08-02 14:03:21.253501 [DEBUG] sofia.c:4629 tls-sip-port [5081] 2017-08-02 14:03:21.253511 [DEBUG] sofia.c:4629 tls-passphrase [] 2017-08-02 14:03:21.253519 [DEBUG] sofia.c:4629 tls-verify-date [true] 2017-08-02 14:03:21.253526 [DEBUG] sofia.c:4629 tls-verify-policy [none] 2017-08-02 14:03:21.253538 [DEBUG] sofia.c:4629 tls-verify-depth [2] 2017-08-02 14:03:21.253546 [DEBUG] sofia.c:4629 tls-verify-in-subjects [] 2017-08-02 14:03:21.253560 [DEBUG] sofia.c:4629 tls-version [tlsv1,tlsv1.1,tlsv1.2] 2017-08-02 14:03:21.253569 [DEBUG] sofia.c:4629 wss-binding [:7443] 2017-08-02 14:03:21.253581 [DEBUG] sofia.c:4629 rtcp-audio-interval-msec [5000] 2017-08-02 14:03:21.253591 [DEBUG] sofia.c:4629 rtcp-video-interval-msec [5000] 2017-08-02 14:03:21.253598 [DEBUG] sofia.c:4629 dtmf-type [info] 2017-08-02 14:03:21.253606 [DEBUG] sofia.c:4629 liberal-dtmf [true] 2017-08-02 14:03:21.253613 [INFO] sofia.c:5979 Setting MAX Auth Validity to 0 Attempts 2017-08-02 14:03:21.253735 [NOTICE] sofia.c:6146 Started Profile external [sofia_reg_external] 2017-08-02 14:03:21.254087 [DEBUG] sofia.c:4629 debug [0] 2017-08-02 14:03:21.254089 [DEBUG] sofia.c:3119 Creating agent for external 2017-08-02 14:03:21.254112 [DEBUG] sofia.c:4629 sip-trace [no] 2017-08-02 14:03:21.254117 [DEBUG] sofia.c:4629 context [public] 2017-08-02 14:03:21.254128 [DEBUG] sofia.c:4629 rfc2833-pt [101] 2017-08-02 14:03:21.254139 [DEBUG] sofia.c:4629 sip-port [5090] 2017-08-02 14:03:21.254148 [DEBUG] sofia.c:4629 dialplan [XML] 2017-08-02 14:03:21.254155 [DEBUG] sofia.c:4629 dtmf-duration [2000] 2017-08-02 14:03:21.254162 [DEBUG] sofia.c:4629 inbound-codec-prefs [OPUS,speex@16000h@20i,speex@8000h@20i,G722,PCMU,PCMA,VP8] 2017-08-02 14:03:21.254174 [DEBUG] sofia.c:4629 outbound-codec-prefs [OPUS,speex@16000h@20i,speex@8000h@20i,G722,PCMU,PCMA,VP8] 2017-08-02 14:03:21.254185 [DEBUG] sofia.c:4629 use-rtp-timer [true] 2017-08-02 14:03:21.254196 [DEBUG] sofia.c:4629 rtp-timer-name [soft] 2017-08-02 14:03:21.254205 [DEBUG] sofia.c:4629 rtp-ip [::1] 2017-08-02 14:03:21.254212 [DEBUG] sofia.c:4629 sip-ip [::1] 2017-08-02 14:03:21.254222 [DEBUG] sofia.c:4629 hold-music [local_stream://moh] 2017-08-02 14:03:21.254231 [DEBUG] sofia.c:4629 apply-inbound-acl [domains] 2017-08-02 14:03:21.254241 [DEBUG] sofia.c:4629 record-template [/opt/freeswitch/var/lib/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav] 2017-08-02 14:03:21.254250 [DEBUG] sofia.c:4629 manage-presence [true] 2017-08-02 14:03:21.254259 [DEBUG] sofia.c:4629 inbound-codec-negotiation [generous] 2017-08-02 14:03:21.254270 [DEBUG] sofia.c:4629 tls [false] 2017-08-02 14:03:21.254280 [DEBUG] sofia.c:4629 tls-bind-params [transport=tls] 2017-08-02 14:03:21.254291 [DEBUG] sofia.c:4629 tls-sip-port [5061] 2017-08-02 14:03:21.254301 [DEBUG] sofia.c:4629 tls-cert-dir [] 2017-08-02 14:03:21.254315 [DEBUG] sofia.c:4629 tls-version [tlsv1,tlsv1.1,tlsv1.2] 2017-08-02 14:03:21.254326 [DEBUG] sofia.c:4629 inbound-late-negotiation [true] 2017-08-02 14:03:21.254334 [DEBUG] sofia.c:4629 inbound-zrtp-passthru [true] ................ 2017-08-02 14:03:23.384099 [CONSOLE] switch_core.c:1582 Created ip list domains default (allow) 2017-08-02 14:03:23.384174 [CONSOLE] switch_core.c:2433 ^[[m^[[33m^[[44m .=============================================================. | _____ ______ _____ _____ ____ _ _ | | | ___| __ ___ ___/ ___\ \ / /_ _|_ _/ ___| | | | | | | |_ | '__/ _ \/ _ \___ \\ \ /\ / / | | | || | | |_| | | | | _|| | | __/ __/___) |\ V V / | | | || |___| _ | | | |_| |_| \___|\___|____/ \_/\_/ |___| |_| \____|_| |_| | | | .=============================================================. | Anthony Minessale II, Michael Jerris, Brian West, Others | | FreeSWITCH (http://www.freeswitch.org) | | Paypal Donations Appreciated: paypal@freeswitch.org | | Brought to you by ClueCon http://www.cluecon.com/ | .=============================================================. .=======================================================================================================. | ____ _ ____ | | / ___| |_ _ ___ / ___|___ _ __ | | | | | | | | |/ _ \ | / _ \| '_ \ | | | |___| | |_| | __/ |__| (_) | | | | | | \____|_|\__,_|\___|\____\___/|_| |_| | | | | _____ _ _ ____ __ | | |_ _|__| | ___ _ __ | |__ ___ _ __ _ _ / ___|___ _ __ / _| ___ _ __ ___ _ __ ___ ___ | | | |/ _ \ |/ _ \ '_ \| '_ \ / _ \| '_ \| | | | | | / _ \| '_ \| |_ / _ \ '__/ _ \ '_ \ / __/ _ \ | | | | __/ | __/ |_) | | | | (_) | | | | |_| | | |__| (_) | | | | _| __/ | | __/ | | | (_| __/ | | |_|\___|_|\___| .__/|_| |_|\___/|_| |_|\__, | \____\___/|_| |_|_| \___|_| \___|_| |_|\___\___| | | |_| |___/ | | _____ _ _ | | | ____|_ _____ _ __ _ _ / \ _ _ __ _ _ _ ___| |_ | | | _| \ \ / / _ \ '__| | | | / _ \| | | |/ _` | | | / __| __| | | | |___ \ V / __/ | | |_| | / ___ \ |_| | (_| | |_| \__ \ |_ | | |_____| \_/ \___|_| \__, | /_/ \_\__,_|\__, |\__,_|___/\__| | | |___/ |___/ | | ____ _ ____ | | __ ____ ____ __ / ___| |_ _ ___ / ___|___ _ __ ___ ___ _ __ ___ | | \ \ /\ / /\ \ /\ / /\ \ /\ / / | | | | | | |/ _ \ | / _ \| '_ \ / __/ _ \| '_ ` _ \ | | \ V V / \ V V / \ V V / _ | |___| | |_| | __/ |__| (_) | | | | _ | (_| (_) | | | | | | | | \_/\_/ \_/\_/ \_/\_/ (_) \____|_|\__,_|\___|\____\___/|_| |_| (_) \___\___/|_| |_| |_| | | | .=======================================================================================================. ^[[m 2017-08-02 14:03:23.384194 [INFO] switch_core.c:2442 FreeSWITCH Version 1.9.0+git~20170725T210833Z~ba4eecbfbe~64bit (git ba4eecb 2017-07-25 21:08:33Z 64bit) FreeSWITCH Started Max Sessions [1000] Session Rate [30] SQL [Enabled] 2017-08-02 14:03:23.543867 [DEBUG] mod_verto.c:4217 163.172.154.141:43574 Client Connect from 163.172.154.141:43574 accepted 2017-08-02 14:03:23.543867 [DEBUG] mod_verto.c:2003 163.172.154.141:43574 Starting client thread. 2017-08-02 14:03:23.703865 [DEBUG] mod_verto.c:1292 163.172.154.141:43574 re-connecting session 0.1527022602341539 2017-08-02 14:03:23.823872 [DEBUG] mod_verto.c:1051 auth using username & password d1c3da42-5cef-6530-fd70-d8b36674ada0 2017-08-02 14:03:36.203877 [DEBUG] mod_rtc.c:392 () State Change CS_NEW -> CS_INIT d1c3da42-5cef-6530-fd70-d8b36674ada0 2017-08-02 14:03:36.203877 [DEBUG] switch_core_session.c:641 N/A set UUID=d1c3da42-5cef-6530-fd70-d8b36674ada0 d1c3da42-5cef-6530-fd70-d8b36674ada0 2017-08-02 14:03:36.203877 [NOTICE] switch_channel.c:1104 New Channel verto.rtc/70391-SCREENSHARE [d1c3da42-5cef-6530-fd70-d8b36674ada0] d1c3da42-5cef-6530-fd70-d8b36674ada0 2017-08-02 14:03:36.203877 [DEBUG] mod_verto.c:3746 Remote SDP verto.rtc/70391-SCREENSHARE: d1c3da42-5cef-6530-fd70-d8b36674ada0 v=0^M d1c3da42-5cef-6530-fd70-d8b36674ada0 o=- 3934602207005313996 2 IN IP4 127.0.0.1^M d1c3da42-5cef-6530-fd70-d8b36674ada0 s=-^M d1c3da42-5cef-6530-fd70-d8b36674ada0 t=0 0^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=group:BUNDLE video^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=msid-semantic: WMS I0be1Kw3E1jjYbJgM7RE5oSLEp9yBOCD21ZM^M d1c3da42-5cef-6530-fd70-d8b36674ada0 m=video 56731 UDP/TLS/RTP/SAVPF 96 98 100 102 127 97 99 101 125^M d1c3da42-5cef-6530-fd70-d8b36674ada0 c=IN IP4 47.55.4.205^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtcp:9 IN IP4 0.0.0.0^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=candidate:793450030 1 udp 2122260223 192.168.2.22 56731 typ host generation 0 network-id 1 network-cost 10^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=candidate:1640557278 1 tcp 1518280447 192.168.2.22 9 typ host tcptype active generation 0 network-id 1 network-cost 10^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=candidate:3646648314 1 udp 1686052607 47.55.4.205 56731 typ srflx raddr 192.168.2.22 rport 56731 generation 0 network-id 1 network-cost 10^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=ice-ufrag:cERs^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=ice-pwd:GFMQgga/eC/WARM2oG2AH1o5^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=fingerprint:sha-256 30:05:BF:65:49:07:C9:49:2B:40:8C:F5:38:53:39:0E:F1:F8:29:BF:58:24:A4:D2:50:81:2B:4E:B7:26:30:86^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=setup:actpass^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=mid:video^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=extmap:2 urn:ietf:params:rtp-hdrext:toffset^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=extmap:4 urn:3gpp:video-orientation^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=extmap:5 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=extmap:6 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=sendonly^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtcp-mux^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtcp-rsize^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtpmap:96 VP8/90000^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtcp-fb:96 ccm fir^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtcp-fb:96 nack^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtcp-fb:96 nack pli^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtcp-fb:96 goog-remb^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtcp-fb:96 transport-cc^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtpmap:98 VP9/90000^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtcp-fb:98 ccm fir^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtcp-fb:98 nack^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtcp-fb:98 nack pli^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtcp-fb:98 goog-remb^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtcp-fb:98 transport-cc^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtpmap:100 H264/90000^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtcp-fb:100 ccm fir^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtcp-fb:100 nack^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtcp-fb:100 nack pli^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtcp-fb:100 goog-remb^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtcp-fb:100 transport-cc^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=fmtp:100 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtpmap:102 red/90000^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtpmap:127 ulpfec/90000^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtpmap:97 rtx/90000^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=fmtp:97 apt=96^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtpmap:99 rtx/90000^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=fmtp:99 apt=98^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtpmap:101 rtx/90000^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=fmtp:101 apt=100^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=rtpmap:125 rtx/90000^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=fmtp:125 apt=102^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=ssrc-group:FID 356221317 3779520327^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=ssrc:356221317 cname:G7rgvzHqyxgkAEAy^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=ssrc:356221317 msid:I0be1Kw3E1jjYbJgM7RE5oSLEp9yBOCD21ZM 5a7296f1-d0d2-490a-987c-6534c9ea2b36^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=ssrc:356221317 mslabel:I0be1Kw3E1jjYbJgM7RE5oSLEp9yBOCD21ZM^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=ssrc:356221317 label:5a7296f1-d0d2-490a-987c-6534c9ea2b36^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=ssrc:3779520327 cname:G7rgvzHqyxgkAEAy^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=ssrc:3779520327 msid:I0be1Kw3E1jjYbJgM7RE5oSLEp9yBOCD21ZM 5a7296f1-d0d2-490a-987c-6534c9ea2b36^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=ssrc:3779520327 mslabel:I0be1Kw3E1jjYbJgM7RE5oSLEp9yBOCD21ZM^M d1c3da42-5cef-6530-fd70-d8b36674ada0 a=ssrc:3779520327 label:5a7296f1-d0d2-490a-987c-6534c9ea2b36^M d1c3da42-5cef-6530-fd70-d8b36674ada0 .................. c63d1bc1-5b58-4b3c-a29f-3f0b147dba95 2017-08-02 14:03:39.903986 [DEBUG] switch_channel.c:3781 (sofia/external/w_euvexyp06amo-bbbID-Fred Dixon@dev2.bigbluebutton.org) Callstate Change EARLY -> ACTIVE c63d1bc1-5b58-4b3c-a29f-3f0b147dba95 EXECUTE sofia/external/w_euvexyp06amo-bbbID-Fred Dixon@dev2.bigbluebutton.org conference(70391@cdquality) c63d1bc1-5b58-4b3c-a29f-3f0b147dba95 2017-08-02 14:03:39.903986 [DEBUG] conference_member.c:1716 Raw Codec Activation Success L16@48000hz 1 channel 20ms c63d1bc1-5b58-4b3c-a29f-3f0b147dba95 2017-08-02 14:03:39.903986 [DEBUG] conference_member.c:1763 Raw Codec Activation Success L16@48000hz 1 channel 20ms c63d1bc1-5b58-4b3c-a29f-3f0b147dba95 2017-08-02 14:03:39.903986 [DEBUG] switch_core_codec.c:223 sofia/external/w_euvexyp06amo-bbbID-Fred Dixon@dev2.bigbluebutton.org Push codec L16:100 2017-08-02 14:03:39.913981 [DEBUG] conference_member.c:104 sofia/external/w_euvexyp06amo-bbbID-Fred Dixon@dev2.bigbluebutton.org binding '0' to 'mute' 2017-08-02 14:03:39.913981 [INFO] switch_ivr_async.c:215 Digit parser mod_conference: Setting realm to 'conf' 2017-08-02 14:03:39.913981 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 0/conf/0 callback: 0x7fe6519b6150 data: 0x7fe5a8070ae8 2017-08-02 14:03:39.913981 [DEBUG] conference_member.c:104 sofia/external/w_euvexyp06amo-bbbID-Fred Dixon@dev2.bigbluebutton.org binding '*' to 'deaf mute' 2017-08-02 14:03:39.913981 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding */conf/0 callback: 0x7fe6519b6150 data: 0x7fe5a8070b18 2017-08-02 14:03:39.913981 [DEBUG] conference_member.c:104 sofia/external/w_euvexyp06amo-bbbID-Fred Dixon@dev2.bigbluebutton.org binding '9' to 'energy up' 2017-08-02 14:03:39.913981 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 9/conf/0 callback: 0x7fe6519b6150 data: 0x7fe5a8070b48 2017-08-02 14:03:39.913981 [DEBUG] conference_member.c:104 sofia/external/w_euvexyp06amo-bbbID-Fred Dixon@dev2.bigbluebutton.org binding '8' to 'energy equ' 2017-08-02 14:03:39.913981 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 8/conf/0 callback: 0x7fe6519b6150 data: 0x7fe5a8070b78 2017-08-02 14:03:39.913981 [DEBUG] conference_member.c:104 sofia/external/w_euvexyp06amo-bbbID-Fred Dixon@dev2.bigbluebutton.org binding '7' to 'energy dn' 2017-08-02 14:03:39.913981 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 7/conf/0 callback: 0x7fe6519b6150 data: 0x7fe5a8070ba8 2017-08-02 14:03:39.913981 [DEBUG] conference_member.c:104 sofia/external/w_euvexyp06amo-bbbID-Fred Dixon@dev2.bigbluebutton.org binding '3' to 'vol talk up' 2017-08-02 14:03:39.913981 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 3/conf/0 callback: 0x7fe6519b6150 data: 0x7fe5a8070bd8 2017-08-02 14:03:39.913981 [DEBUG] conference_member.c:104 sofia/external/w_euvexyp06amo-bbbID-Fred Dixon@dev2.bigbluebutton.org binding '2' to 'vol talk zero' 2017-08-02 14:03:39.913981 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 2/conf/0 callback: 0x7fe6519b6150 data: 0x7fe5a8070c08 2017-08-02 14:03:39.913981 [DEBUG] conference_member.c:104 sofia/external/w_euvexyp06amo-bbbID-Fred Dixon@dev2.bigbluebutton.org binding '1' to 'vol talk dn' 2017-08-02 14:03:39.913981 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 1/conf/0 callback: 0x7fe6519b6150 data: 0x7fe5a8070c38 2017-08-02 14:03:39.913981 [DEBUG] conference_member.c:104 sofia/external/w_euvexyp06amo-bbbID-Fred Dixon@dev2.bigbluebutton.org binding '6' to 'vol listen up' 2017-08-02 14:03:39.913981 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 6/conf/0 callback: 0x7fe6519b6150 data: 0x7fe5a8070c68 2017-08-02 14:03:39.913981 [DEBUG] conference_member.c:104 sofia/external/w_euvexyp06amo-bbbID-Fred Dixon@dev2.bigbluebutton.org binding '5' to 'vol listen zero' 2017-08-02 14:03:39.913981 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 5/conf/0 callback: 0x7fe6519b6150 data: 0x7fe5a8070c98 2017-08-02 14:03:39.913981 [DEBUG] conference_member.c:104 sofia/external/w_euvexyp06amo-bbbID-Fred Dixon@dev2.bigbluebutton.org binding '4' to 'vol listen dn' 2017-08-02 14:03:39.913981 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 4/conf/0 callback: 0x7fe6519b6150 data: 0x7fe5a8070cc8 2017-08-02 14:03:39.913981 [DEBUG] conference_member.c:104 sofia/external/w_euvexyp06amo-bbbID-Fred Dixon@dev2.bigbluebutton.org binding '#' to 'hangup' 2017-08-02 14:03:39.913981 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding #/conf/0 callback: 0x7fe6519b6150 data: 0x7fe5a8070cf8 c63d1bc1-5b58-4b3c-a29f-3f0b147dba95 2017-08-02 14:03:39.913981 [DEBUG] conference_loop.c:1306 Setup timer soft success interval: 20 samples: 960 from codec opus 497192ad-e1e0-44ce-aa56-15cd146cafc1 2017-08-02 14:03:51.653994 [DEBUG] conference_member.c:1448 Queueing file /opt/freeswitch/share/freeswitch/sounds/en/us/callie/conference/conf-muted.wav for play 497192ad-e1e0-44ce-aa56-15cd146cafc1 2017-08-02 14:03:54.975591 [DEBUG] conference_member.c:1448 Queueing file /opt/freeswitch/share/freeswitch/sounds/en/us/callie/conference/conf-unmuted.wav for play 497192ad-e1e0-44ce-aa56-15cd146cafc1 2017-08-02 14:03:54.975591 [DEBUG] switch_core_media.c:2819 [VBR]: Correcting calculated ptime value from [3340] to [20] to compensate for [166] lost packet(s) c63d1bc1-5b58-4b3c-a29f-3f0b147dba95 2017-08-02 14:03:57.834009 [DEBUG] switch_core_media.c:2819 [VBR]: Correcting calculated ptime value from [40] to [20] to compensate for [1] lost packet(s) c63d1bc1-5b58-4b3c-a29f-3f0b147dba95 2017-08-02 14:04:00.833997 [DEBUG] switch_core_media.c:2819 [VBR]: Correcting calculated ptime value from [40] to [20] to compensate for [1] lost packet(s) 497192ad-e1e0-44ce-aa56-15cd146cafc1 2017-08-02 14:04:06.474142 [DEBUG] conference_member.c:1448 Queueing file /opt/freeswitch/share/freeswitch/sounds/en/us/callie/conference/conf-muted.wav for play 2017-08-02 14:04:07.451133 [CONSOLE] switch_loadable_module.c:1540 Successfully Loaded [mod_logfile] 2017-08-02 14:04:07.451791 [CONSOLE] switch_loadable_module.c:1540 Successfully Loaded [mod_enum] 2017-08-02 14:04:07.451807 [NOTICE] switch_loadable_module.c:250 Adding Dialplan 'enum' 2017-08-02 14:04:07.451828 [NOTICE] switch_loadable_module.c:292 Adding Application 'enum' 2017-08-02 14:04:07.451844 [NOTICE] switch_loadable_module.c:338 Adding API Function 'enum' 2017-08-02 14:04:07.451861 [NOTICE] switch_loadable_module.c:338 Adding API Function 'enum_auto' 2017-08-02 14:04:07.452174 [DEBUG] mod_cdr_csv.c:368 Adding default template. 2017-08-02 14:04:07.452194 [DEBUG] mod_cdr_csv.c:415 Adding template sql. 2017-08-02 14:04:07.452199 [DEBUG] mod_cdr_csv.c:415 Adding template example. 2017-08-02 14:04:07.452202 [DEBUG] mod_cdr_csv.c:415 Adding template snom. 2017-08-02 14:04:07.452207 [DEBUG] mod_cdr_csv.c:415 Adding template linksys. 2017-08-02 14:04:07.452211 [DEBUG] mod_cdr_csv.c:415 Adding template asterisk. 2017-08-02 14:04:07.452218 [DEBUG] mod_cdr_csv.c:415 Adding template opencdrrate. 2017-08-02 14:04:07.452278 [CONSOLE] switch_loadable_module.c:1540 Successfully Loaded [mod_cdr_csv] 2017-08-02 14:04:07.452291 [NOTICE] switch_loadable_module.c:338 Adding API Function 'cdr_csv' 2017-08-02 14:04:07.452627 [CONSOLE] switch_loadable_module.c:1540 Successfully Loaded [mod_event_socket] 2017-08-02 14:04:07.452643 [NOTICE] switch_loadable_module.c:292 Adding Application 'socket' 2017-08-02 14:04:07.452659 [NOTICE] switch_loadable_module.c:338 Adding API Function 'event_sink' 2017-08-02 14:04:07.453930 [INFO] mod_sofia.c:6170 Starting initial message thread. 2017-08-02 14:04:07.454028 [DEBUG] sofia.c:4629 debug [0] 2017-08-02 14:04:07.454037 [DEBUG] sofia.c:4629 sip-trace [no] 2017-08-02 14:04:07.454041 [DEBUG] sofia.c:4629 sip-capture [no] ............ 2017-08-02 14:04:09.608254 [NOTICE] switch_utils.c:574 Adding 10.130.218.147/32 (allow) [] to list lan 2017-08-02 14:04:09.608259 [CONSOLE] switch_core.c:1582 Created ip list domains default (allow) 2017-08-02 14:04:09.608493 [CONSOLE] switch_core.c:2433 ^[[m^[[33m^[[44m .=============================================================. | _____ ______ _____ _____ ____ _ _ | | | ___| __ ___ ___/ ___\ \ / /_ _|_ _/ ___| | | | | | | |_ | '__/ _ \/ _ \___ \\ \ /\ / / | | | || | | |_| | | | | _|| | | __/ __/___) |\ V V / | | | || |___| _ | | | |_| |_| \___|\___|____/ \_/\_/ |___| |_| \____|_| |_| | | | .=============================================================. | Anthony Minessale II, Michael Jerris, Brian West, Others | | FreeSWITCH (http://www.freeswitch.org) | | Paypal Donations Appreciated: paypal@freeswitch.org | | Brought to you by ClueCon http://www.cluecon.com/ | .=============================================================. .===============================================================. | _ | | ___| |_ _ ___ ___ ___ _ __ ___ ___ _ __ ___ | | / __| | | | |/ _ \/ __/ _ \| '_ \ / __/ _ \| '_ ` _ \ | | | (__| | |_| | __/ (_| (_) | | | | _ | (_| (_) | | | | | | | | \___|_|\__,_|\___|\___\___/|_| |_| (_) \___\___/|_| |_| |_| | | | .===============================================================. ^[[m 2017-08-02 14:04:09.608554 [INFO] switch_core.c:2442 FreeSWITCH Version 1.9.0+git~20170725T210833Z~ba4eecbfbe~64bit (git ba4eecb 2017-07-25 21:08:33Z 64bit) FreeSWITCH Started Max Sessions [1000] Session Rate [30] SQL [Enabled] 2017-08-02 14:04:09.608563 [CONSOLE] switch_core.c:2450 [This app Best viewed at 160x60 or more..] 2017-08-02 14:04:09.608323 [DEBUG] switch_loadable_module.c:735 Chat Thread Started 2017-08-02 14:04:10.008215 [DEBUG] mod_verto.c:4217 163.172.154.141:43608 Client Connect from 163.172.154.141:43608 accepted 2017-08-02 14:04:10.008215 [DEBUG] mod_verto.c:2003 163.172.154.141:43608 Starting client thread. 2017-08-02 14:04:10.168215 [DEBUG] mod_verto.c:1292 163.172.154.141:43608 re-connecting session 0.1527022602341539 2017-08-02 14:04:10.268205 [DEBUG] mod_verto.c:1051 auth using username & password b367d49b-b18b-4b6a-9021-f83431dab617 2017-08-02 14:04:24.928203 [NOTICE] switch_channel.c:1104 New Channel sofia/external/w_5n3jvdsajzxz-bbbID-Josh@dev2.bigbluebutton.org [b367d49b-b18b-4b6a-9021-f83431dab617] b367d49b-b18b-4b6a-9021-f83431dab617 2017-08-02 14:04:24.928203 [DEBUG] switch_core_state_machine.c:584 (sofia/external/w_5n3jvdsajzxz-bbbID-Josh@dev2.bigbluebutton.org) Running State Change CS_NEW (Cur 1 Tot 1) b367d49b-b18b-4b6a-9021-f83431dab617 2017-08-02 14:04:24.928203 [DEBUG] sofia.c:10067 sofia/external/w_5n3jvdsajzxz-bbbID-Josh@dev2.bigbluebutton.org receiving invite from 163.172.154.141:40804 version: 1.9.0 git ba4eecb 2017-07-25 21:08:33Z 64bit b367d49b-b18b-4b6a-9021-f83431dab617 2017-08-02 14:04:24.928203 [DEBUG] sofia.c:11365 Setting NAT mode based on websockets b367d49b-b18b-4b6a-9021-f83431dab617 2017-08-02 14:04:24.928203 [DEBUG] sofia.c:7283 Channel sofia/external/w_5n3jvdsajzxz-bbbID-Josh@dev2.bigbluebutton.org entering state [received][100]