freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> 2017-07-06 14:14:53.623053 [DEBUG] mod_rtc.c:392 () State Change CS_NEW -> CS_INIT 2017-07-06 14:14:53.623053 [DEBUG] switch_core_session.c:641 N/A set UUID=53ae2cf6-fcd9-3a7f-bb02-91871de7fac5 2017-07-06 14:14:53.623053 [NOTICE] switch_channel.c:1104 New Channel verto.rtc/3500 [53ae2cf6-fcd9-3a7f-bb02-91871de7fac5] 2017-07-06 14:14:53.623053 [DEBUG] mod_verto.c:3728 Remote SDP verto.rtc/3500: v=0 o=- 7154045974886751329 2 IN IP4 127.0.0.1 s=- t=0 0 a=group:BUNDLE audio video a=msid-semantic: WMS pOHXuGw4hvimKhVkOkYjd9Od3fRIh6Mwnxw2 m=audio 52043 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126 c=IN IP4 166.137.96.56 a=rtcp:9 IN IP4 0.0.0.0 a=candidate:2129710778 1 udp 2122255103 2001::9d38:6ab8:34aa:1fac:3f57:feb7 51878 typ host generation 0 network-id 2 network-cost 50 a=candidate:3594748867 1 udp 2122194687 192.168.1.72 51879 typ host generation 0 network-id 3 network-cost 10 a=candidate:812855882 1 tcp 1518275327 2001::9d38:6ab8:34aa:1fac:3f57:feb7 9 typ host tcptype active generation 0 network-id 2 network-cost 50 a=candidate:2562752307 1 tcp 1518214911 192.168.1.72 9 typ host tcptype active generation 0 network-id 3 network-cost 10 a=candidate:542374423 1 udp 1685987071 166.137.96.56 52043 typ srflx raddr 192.168.1.72 rport 51879 generation 0 network-id 3 network-cost 10 a=ice-ufrag:LNBR a=ice-pwd:PjEVXPr5tyIVuUhGmXtVaJYf a=fingerprint:sha-256 99:51:69:1D:B4:90:26:4D:84:F9:BC:5D:6C:D8:48:E6:9B:08:C5:71:C8:19:78:15:D7:AE:30:F5:6E:70:F9:E4 a=setup:actpass a=mid:audio a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level a=sendrecv a=rtcp-mux a=rtpmap:111 opus/48000/2 a=rtcp-fb:111 transport-cc a=fmtp:111 minptime=10;useinbandfec=1; stereo=1; sprop-stereo=1 a=rtpmap:103 ISAC/16000 a=rtpmap:104 ISAC/32000 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:106 CN/32000 a=rtpmap:105 CN/16000 a=rtpmap:13 CN/8000 a=rtpmap:110 telephone-event/48000 a=rtpmap:112 telephone-event/32000 a=rtpmap:113 telephone-event/16000 a=rtpmap:126 telephone-event/8000 a=ssrc:3115677395 cname:+/Y5FyCr60SqkA4m a=ssrc:3115677395 msid:pOHXuGw4hvimKhVkOkYjd9Od3fRIh6Mwnxw2 5b76b1b1-5df6-4093-94f6-ade5cbb10243 a=ssrc:3115677395 mslabel:pOHXuGw4hvimKhVkOkYjd9Od3fRIh6Mwnxw2 a=ssrc:3115677395 label:5b76b1b1-5df6-4093-94f6-ade5cbb10243 m=video 20141 UDP/TLS/RTP/SAVPF 96 98 100 102 127 97 99 101 125 c=IN IP4 166.137.96.56 a=rtcp:9 IN IP4 0.0.0.0 a=candidate:2129710778 1 udp 2122255103 2001::9d38:6ab8:34aa:1fac:3f57:feb7 51880 typ host generation 0 network-id 2 network-cost 50 a=candidate:3594748867 1 udp 2122194687 192.168.1.72 51881 typ host generation 0 network-id 3 network-cost 10 a=candidate:812855882 1 tcp 1518275327 2001::9d38:6ab8:34aa:1fac:3f57:feb7 9 typ host tcptype active generation 0 network-id 2 network-cost 50 a=candidate:2562752307 1 tcp 1518214911 192.168.1.72 9 typ host tcptype active generation 0 network-id 3 network-cost 10 a=candidate:542374423 1 udp 1685987071 166.137.96.56 20141 typ srflx raddr 192.168.1.72 rport 51881 generation 0 network-id 3 network-cost 10 a=ice-ufrag:LNBR a=ice-pwd:PjEVXPr5tyIVuUhGmXtVaJYf a=fingerprint:sha-256 99:51:69:1D:B4:90:26:4D:84:F9:BC:5D:6C:D8:48:E6:9B:08:C5:71:C8:19:78:15:D7:AE:30:F5:6E:70:F9:E4 a=setup:actpass a=mid:video a=extmap:2 urn:ietf:params:rtp-hdrext:toffset a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time a=extmap:4 urn:3gpp:video-orientation a=extmap:5 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 a=extmap:6 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay a=sendrecv a=rtcp-mux a=rtcp-rsize a=rtpmap:96 VP8/90000 a=rtcp-fb:96 ccm fir a=rtcp-fb:96 nack a=rtcp-fb:96 nack pli a=rtcp-fb:96 goog-remb a=rtcp-fb:96 transport-cc a=rtpmap:98 VP9/90000 a=rtcp-fb:98 ccm fir a=rtcp-fb:98 nack a=rtcp-fb:98 nack pli a=rtcp-fb:98 goog-remb a=rtcp-fb:98 transport-cc a=rtpmap:100 H264/90000 a=rtcp-fb:100 ccm fir a=rtcp-fb:100 nack a=rtcp-fb:100 nack pli a=rtcp-fb:100 goog-remb a=rtcp-fb:100 transport-cc a=fmtp:100 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f a=rtpmap:102 red/90000 a=rtpmap:127 ulpfec/90000 a=rtpmap:97 rtx/90000 a=fmtp:97 apt=96 a=rtpmap:99 rtx/90000 a=fmtp:99 apt=98 a=rtpmap:101 rtx/90000 a=fmtp:101 apt=100 a=rtpmap:125 rtx/90000 a=fmtp:125 apt=102 a=ssrc-group:FID 2631570493 3143608125 a=ssrc:2631570493 cname:+/Y5FyCr60SqkA4m a=ssrc:2631570493 msid:pOHXuGw4hvimKhVkOkYjd9Od3fRIh6Mwnxw2 0fe76717-39a2-4992-be11-f7ea36c834b9 a=ssrc:2631570493 mslabel:pOHXuGw4hvimKhVkOkYjd9Od3fRIh6Mwnxw2 a=ssrc:2631570493 label:0fe76717-39a2-4992-be11-f7ea36c834b9 a=ssrc:3143608125 cname:+/Y5FyCr60SqkA4m a=ssrc:3143608125 msid:pOHXuGw4hvimKhVkOkYjd9Od3fRIh6Mwnxw2 0fe76717-39a2-4992-be11-f7ea36c834b9 a=ssrc:3143608125 mslabel:pOHXuGw4hvimKhVkOkYjd9Od3fRIh6Mwnxw2 a=ssrc:3143608125 label:0fe76717-39a2-4992-be11-f7ea36c834b9 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/3500) Running State Change CS_INIT (Cur 1 Tot 5) 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/3500) State INIT 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:40 verto.rtc/3500 Standard INIT 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/3500) State Change CS_INIT -> CS_ROUTING 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/3500) State INIT going to sleep 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/3500) Running State Change CS_ROUTING (Cur 1 Tot 5) 2017-07-06 14:14:53.623053 [DEBUG] switch_channel.c:2249 (verto.rtc/3500) Callstate Change DOWN -> RINGING 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/3500) State ROUTING 2017-07-06 14:14:53.623053 [DEBUG] mod_rtc.c:89 verto.rtc/3500 RTC ROUTING 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:236 verto.rtc/3500 Standard ROUTING 2017-07-06 14:14:53.623053 [INFO] mod_dialplan_xml.c:637 Processing Joshua Young ->3500 in context default Dialplan: verto.rtc/3500 parsing [default->home-group] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [home-group] destination_number(3500) =~ /^(1030)$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->unloop] continue=false Dialplan: verto.rtc/3500 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: verto.rtc/3500 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->tod_example] continue=true Dialplan: verto.rtc/3500 Date/Time Match (PASS) [tod_example] break=on-false Dialplan: verto.rtc/3500 Action set(open=true) Dialplan: verto.rtc/3500 parsing [default->holiday_example] continue=true Dialplan: verto.rtc/3500 Date/TimeMatch (FAIL) [holiday_example] break=on-false Dialplan: verto.rtc/3500 parsing [default->global-intercept] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [global-intercept] destination_number(3500) =~ /^886$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->group-intercept] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [group-intercept] destination_number(3500) =~ /^\*8$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->intercept-ext] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [intercept-ext] destination_number(3500) =~ /^\*\*(\d+)$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->redial] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [redial] destination_number(3500) =~ /^(redial|870)$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->global] continue=true Dialplan: verto.rtc/3500 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never Dialplan: verto.rtc/3500 Regex (FAIL) [global] ${default_password}(j0sh3b0sh) =~ /^1234$/ break=never Dialplan: verto.rtc/3500 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never Dialplan: verto.rtc/3500 Regex (FAIL) [global] ${endpoint_disposition}() =~ /^(DELAYED NEGOTIATION)/ break=on-false Dialplan: verto.rtc/3500 parsing [default->snom-demo-2] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [snom-demo-2] destination_number(3500) =~ /^9001$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->snom-demo-1] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [snom-demo-1] destination_number(3500) =~ /^9000$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->eavesdrop] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [eavesdrop] destination_number(3500) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->eavesdrop] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [eavesdrop] destination_number(3500) =~ /^779$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->call_return] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [call_return] destination_number(3500) =~ /^\*69$|^869$|^lcr$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->del-group] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [del-group] destination_number(3500) =~ /^80(\d{2})$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->add-group] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [add-group] destination_number(3500) =~ /^81(\d{2})$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->call-group-simo] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [call-group-simo] destination_number(3500) =~ /^82(\d{2})$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->call-group-order] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [call-group-order] destination_number(3500) =~ /^83(\d{2})$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->extension-intercom] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [extension-intercom] destination_number(3500) =~ /^8(10[01][0-9])$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->Local_Extension] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [Local_Extension] destination_number(3500) =~ /^(10[01][0-9])$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->Local_Extension_Skinny] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [Local_Extension_Skinny] destination_number(3500) =~ /^(11[01][0-9])$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->group_dial_sales] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [group_dial_sales] destination_number(3500) =~ /^2000$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->group_dial_support] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [group_dial_support] destination_number(3500) =~ /^2001$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->group_dial_billing] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [group_dial_billing] destination_number(3500) =~ /^2002$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->operator] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [operator] destination_number(3500) =~ /^(operator|0)$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->vmain] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [vmain] destination_number(3500) =~ /^vmain$|^4000$|^\*98$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->sip_uri] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [sip_uri] destination_number(3500) =~ /^sip:(.*)$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->nb_conferences] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [nb_conferences] destination_number(3500) =~ /^(30\d{2})$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->wb_conferences] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [wb_conferences] destination_number(3500) =~ /^(31\d{2})$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->uwb_conferences] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [uwb_conferences] destination_number(3500) =~ /^(32\d{2})$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->cdquality_conferences] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [cdquality_conferences] destination_number(3500) =~ /^(33\d{2})$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->cdquality_stereo_conferences] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [cdquality_stereo_conferences] destination_number(3500) =~ /^(35\d{2}).*?-screen$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->conference-canvases] continue=true Dialplan: verto.rtc/3500 Regex (FAIL) [conference-canvases] destination_number(3500) =~ /(35\d{2})-canvas-(\d+)/ break=on-false Dialplan: verto.rtc/3500 parsing [default->conf mod] continue=false Dialplan: verto.rtc/3500 Regex (FAIL) [conf mod] destination_number(3500) =~ /^6070-moderator$/ break=on-false Dialplan: verto.rtc/3500 parsing [default->cdquality_conferences] continue=false Dialplan: verto.rtc/3500 Regex (PASS) [cdquality_conferences] destination_number(3500) =~ /^(35\d{2})$/ break=on-false Dialplan: verto.rtc/3500 Action answer() Dialplan: verto.rtc/3500 Action conference(3500-${domain_name}@video-mcu-stereo) 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:286 (verto.rtc/3500) State Change CS_ROUTING -> CS_EXECUTE 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/3500) State ROUTING going to sleep 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/3500) Running State Change CS_EXECUTE (Cur 1 Tot 5) 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:650 (verto.rtc/3500) State EXECUTE 2017-07-06 14:14:53.623053 [DEBUG] mod_rtc.c:120 verto.rtc/3500 RTC EXECUTE 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:328 verto.rtc/3500 Standard EXECUTE EXECUTE verto.rtc/3500 set(open=true) 2017-07-06 14:14:53.623053 [DEBUG] mod_dptools.c:1588 SET verto.rtc/3500 [open]=[true] EXECUTE verto.rtc/3500 answer() 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [opus:111:48000:20:0:2]/[opus:116:48000:20:0:1] 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5168 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[opus:116:48000:20:0:1] 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[opus:116:48000:20:0:1] 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1] 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1] 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [CN:106:32000:20:0:1]/[opus:116:48000:20:0:1] 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [CN:105:16000:20:0:1]/[opus:116:48000:20:0:1] 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [CN:13:8000:20:0:1]/[opus:116:48000:20:0:1] 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5029 Set telephone-event payload to 110@48000 2017-07-06 14:14:53.623053 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [120000bps] 2017-07-06 14:14:53.623053 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [120000bps] 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3428 Set Codec verto.rtc/3500 opus/48000 20 ms 960 samples 0 bits 2 channels 2017-07-06 14:14:53.623053 [DEBUG] switch_core_codec.c:111 verto.rtc/3500 Original read codec set to opus:116 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3852 Drop audio Candidate cid: 1 proto: udp type: host addr: 2001::9d38:6ab8:34aa:1fac:3f57:feb7:51878 (no network path) 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3858 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.1.72:51879 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3858 Save audio Candidate cid: 1 proto: udp type: srflx addr: 166.137.96.56:52043 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3900 Searching for rtp candidate. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3905 Choose rtp candidate, index 1, 166.137.96.56:52043 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3678 verto.rtc/3500 choosing family v4 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3916 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 166.137.96.56:52043 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3963 setting remote audio ice addr to index 1 166.137.96.56:52043 based on candidate 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3998 Setting remote rtcp audio addr to 166.137.96.56:52043 based on candidate 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5372 Set telephone-event payload to 110@48000 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5430 verto.rtc/3500 Set 2833 dtmf send payload to 110 recv payload to 110 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5713 Video Codec Compare [VP8:96]/[VP8:99] 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5745 Video Codec Compare [VP8:96] +++ is saved as a match 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5713 Video Codec Compare [VP9:98]/[VP8:99] 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5713 Video Codec Compare [H264:100]/[VP8:99] 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5713 Video Codec Compare [red:102]/[VP8:99] 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5713 Video Codec Compare [ulpfec:127]/[VP8:99] 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5713 Video Codec Compare [rtx:97]/[VP8:99] 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5713 Video Codec Compare [rtx:99]/[VP8:99] 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5713 Video Codec Compare [rtx:101]/[VP8:99] 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5713 Video Codec Compare [rtx:125]/[VP8:99] 2017-07-06 14:14:53.623053 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 2017-07-06 14:14:53.623053 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3238 Set VIDEO Codec verto.rtc/3500 VP8/90000 0 ms 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3852 Drop video Candidate cid: 1 proto: udp type: host addr: 2001::9d38:6ab8:34aa:1fac:3f57:feb7:51880 (no network path) 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3858 Save video Candidate cid: 1 proto: udp type: host addr: 192.168.1.72:51881 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3858 Save video Candidate cid: 1 proto: udp type: srflx addr: 166.137.96.56:20141 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3900 Searching for rtp candidate. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3905 Choose rtp candidate, index 1, 166.137.96.56:20141 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3678 verto.rtc/3500 choosing family v4 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3916 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 166.137.96.56:20141 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3963 setting remote video ice addr to index 1 166.137.96.56:20141 based on candidate 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3998 Setting remote rtcp video addr to 166.137.96.56:20141 based on candidate 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:8147 AUDIO RTP [verto.rtc/3500] 74.208.165.83 port 18730 -> 166.137.96.56 port 52043 codec: 111 ms: 20 2017-07-06 14:14:53.623053 [DEBUG] switch_rtp.c:4164 Starting timer [soft] 960 bytes per 20ms 2017-07-06 14:14:53.623053 [INFO] switch_core_media.c:8326 Activating Audio ICE 2017-07-06 14:14:53.623053 [NOTICE] switch_rtp.c:4663 Activating RTP audio ICE: LNBR:HsCUKJ0kd6teAxf1 166.137.96.56:52043 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:8369 Activating RTCP PORT 52043 2017-07-06 14:14:53.623053 [DEBUG] switch_rtp.c:4560 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 52043 2017-07-06 14:14:53.623053 [INFO] switch_core_media.c:8377 Skipping RTCP ICE (Same as RTP) 2017-07-06 14:14:53.623053 [INFO] switch_rtp.c:3620 Activate RTP/RTCP audio DTLS client 2017-07-06 14:14:53.633057 [INFO] switch_rtp.c:3783 Changing audio DTLS state from OFF to HANDSHAKE 2017-07-06 14:14:53.633057 [DEBUG] switch_core_media.c:2190 Setting Jitterbuffer to 20ms (1 frames) (50 max frames) 2017-07-06 14:14:53.633057 [DEBUG] switch_core_media.c:8450 verto.rtc/3500 Set 2833 dtmf send payload to 110 2017-07-06 14:14:53.633057 [DEBUG] switch_core_media.c:8457 verto.rtc/3500 Set 2833 dtmf receive payload to 110 2017-07-06 14:14:53.633057 [DEBUG] switch_rtp.c:4179 Starting video timer. 2017-07-06 14:14:53.633057 [DEBUG] switch_core_media.c:8975 VIDEO RTP [verto.rtc/3500] 74.208.165.83:21586->166.137.96.56:20141 codec: 96 ms: 0 [SUCCESS] 2017-07-06 14:14:53.633057 [DEBUG] switch_core_media.c:7139 verto.rtc/3500 Starting Video thread 2017-07-06 14:14:53.633057 [DEBUG] switch_core_media.c:7005 verto.rtc/3500 Video thread started. Echo is off 2017-07-06 14:14:53.633057 [INFO] switch_core_media.c:9017 Activating Video ICE 2017-07-06 14:14:53.633057 [NOTICE] switch_rtp.c:4663 Activating RTP video ICE: LNBR:YFcf4rxz0T1O6nye 166.137.96.56:20141 2017-07-06 14:14:53.633057 [INFO] switch_core_media.c:9055 Activating VIDEO RTCP PORT 20141 interval 1000 mux 1 2017-07-06 14:14:53.633057 [DEBUG] switch_rtp.c:4560 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 20141 2017-07-06 14:14:53.633057 [INFO] switch_core_media.c:9066 Skipping VIDEO RTCP ICE (Same as VIDEO RTP) 2017-07-06 14:14:53.633057 [INFO] switch_rtp.c:3620 Activate RTP/RTCP video DTLS client 2017-07-06 14:14:53.633057 [INFO] switch_rtp.c:3783 Changing video DTLS state from OFF to HANDSHAKE 2017-07-06 14:14:53.633057 [DEBUG] switch_core_media.c:8130 Audio params are unchanged for verto.rtc/3500. 2017-07-06 14:14:53.633057 [DEBUG] switch_core_media.c:8833 Video params are unchanged for verto.rtc/3500. 2017-07-06 14:14:53.633057 [DEBUG] mod_verto.c:2480 Local SDP verto.rtc/3500: v=0 o=FreeSWITCH 1499331763 1499331764 IN IP4 74.208.165.83 s=FreeSWITCH c=IN IP4 74.208.165.83 t=0 0 a=msid-semantic: WMS 96oSh5M9KKWmPGTvaXuMmsggKEfiaEda m=audio 18730 UDP/TLS/RTP/SAVPF 111 110 a=rtpmap:111 opus/48000/2 a=fmtp:111 useinbandfec=1; minptime=10; stereo=1; sprop-stereo=1 a=rtpmap:110 telephone-event/48000 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv a=fingerprint:sha-256 35:3E:E4:67:65:B2:1D:93:39:69:06:CE:88:86:A8:DB:D9:06:5D:08:E9:D2:9F:C4:12:C6:28:55:1E:D9:3D:AC a=setup:active a=rtcp-mux a=rtcp:18730 IN IP4 74.208.165.83 a=ice-ufrag:HsCUKJ0kd6teAxf1 a=ice-pwd:PYz3k6RhqSTfCR6a9yUJ8KUc a=candidate:2182278095 1 udp 659136 74.208.165.83 18730 typ host generation 0 a=end-of-candidates a=ssrc:1530617941 cname:AsTInvHY6taMZU70 a=ssrc:1530617941 msid:96oSh5M9KKWmPGTvaXuMmsggKEfiaEda a0 a=ssrc:1530617941 mslabel:96oSh5M9KKWmPGTvaXuMmsggKEfiaEda a=ssrc:1530617941 label:96oSh5M9KKWmPGTvaXuMmsggKEfiaEdaa0 m=video 21586 UDP/TLS/RTP/SAVPF 96 b=AS:1024 a=rtpmap:96 VP8/90000 a=sendrecv a=fingerprint:sha-256 35:3E:E4:67:65:B2:1D:93:39:69:06:CE:88:86:A8:DB:D9:06:5D:08:E9:D2:9F:C4:12:C6:28:55:1E:D9:3D:AC a=setup:active a=rtcp-mux a=rtcp:21586 IN IP4 74.208.165.83 a=rtcp-fb:96 ccm fir a=rtcp-fb:96 nack a=rtcp-fb:96 nack pli a=ssrc:780963670 cname:AsTInvHY6taMZU70 a=ssrc:780963670 msid:96oSh5M9KKWmPGTvaXuMmsggKEfiaEda v0 a=ssrc:780963670 mslabel:96oSh5M9KKWmPGTvaXuMmsggKEfiaEda a=ssrc:780963670 label:96oSh5M9KKWmPGTvaXuMmsggKEfiaEdav0 a=ice-ufrag:YFcf4rxz0T1O6nye a=ice-pwd:9lAUkX8g0P0rMKLAfNGa44zU a=candidate:3621849758 1 udp 659136 74.208.165.83 21586 typ host generation 0 a=end-of-candidates 2017-07-06 14:14:53.633057 [NOTICE] mod_dptools.c:1352 Channel [verto.rtc/3500] has been answered 2017-07-06 14:14:53.633057 [DEBUG] switch_channel.c:3781 (verto.rtc/3500) Callstate Change RINGING -> ACTIVE EXECUTE verto.rtc/3500 conference(3500-74.208.165.83@video-mcu-stereo) 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:303 Adding layout group grid 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 1x1 to layout group grid 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 2x1 to layout group grid 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 1x1+2x1 to layout group grid 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 2x2 to layout group grid 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 3x3 to layout group grid 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 4x4 to layout group grid 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 5x5 to layout group grid 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 6x6 to layout group grid 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 8x8 to layout group grid 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:303 Adding layout group grid-zoom 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 1x1 to layout group grid-zoom 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 2x1-zoom to layout group grid-zoom 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 3x1-zoom to layout group grid-zoom 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 2x2 to layout group grid-zoom 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 5-grid-zoom to layout group grid-zoom 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 3x2-zoom to layout group grid-zoom 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 7-grid-zoom to layout group grid-zoom 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 4x2-zoom to layout group grid-zoom 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 3x3 to layout group grid-zoom 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:303 Adding layout group 1up_top_left_plus 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 1up_top_left+5 to layout group 1up_top_left_plus 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 1up_top_left+7 to layout group 1up_top_left_plus 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 1up_top_left+9 to layout group 1up_top_left_plus 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:303 Adding layout group 3up_plus 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 3up+4 to layout group 3up_plus 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 3up+9 to layout group 3up_plus 2017-07-06 14:14:53.633057 [DEBUG] mod_conference.c:3305 using channel sound prefix: /usr/local/freeswitch/sounds/en/us/callie 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:1546 Canvas position 1 applied layout 1x1 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:1667 Layout set to 1x1 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:1627 Canvas attached to position 0 2017-07-06 14:14:53.633057 [DEBUG] conference_member.c:1715 Raw Codec Activation Success L16@48000hz 2 channel 20ms 2017-07-06 14:14:53.633057 [DEBUG] conference_member.c:1762 Raw Codec Activation Success L16@48000hz 2 channel 20ms 2017-07-06 14:14:53.633057 [DEBUG] switch_core_codec.c:223 verto.rtc/3500 Push codec L16:100 2017-07-06 14:14:53.643052 [WARNING] switch_core_file.c:360 File has 1 channels, muxing to 2 channels will occur. 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '0' to 'mute' 2017-07-06 14:14:53.643052 [INFO] switch_ivr_async.c:215 Digit parser mod_conference: Setting realm to 'conf' 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 0/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac127460 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '*' to 'deaf mute' 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding */conf/0 callback: 0x7f47b569f840 data: 0x7f47ac127490 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '9' to 'energy up' 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 9/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac1274c0 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '8' to 'energy equ' 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 8/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac1274f0 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '7' to 'energy dn' 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 7/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac127520 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '3' to 'vol talk up' 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 3/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac127550 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '2' to 'vol talk zero' 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 2/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac127580 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '1' to 'vol talk dn' 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 1/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac1275b0 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '6' to 'vol listen up' 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 6/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac1275e0 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '5' to 'vol listen zero' 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 5/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac127610 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '4' to 'vol listen dn' 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 4/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac127640 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '#' to 'hangup' 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding #/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac127670 2017-07-06 14:14:53.643052 [DEBUG] conference_loop.c:1306 Setup timer soft success interval: 20 samples: 960 from codec opus 2017-07-06 14:14:53.643052 [DEBUG] mod_conference.c:227 Setup timer success interval: 20 samples: 960 2017-07-06 14:14:53.713052 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 2017-07-06 14:14:53.713052 [DEBUG] conference_video.c:3138 Setting up video write codec VP8 at slot 0 group _none_ 2017-07-06 14:14:53.713052 [NOTICE] switch_vpx.c:406 VPX reset encoder picture from 320x240 to 1920x1080 1024 BW 2017-07-06 14:14:53.803058 [ERR] mod_verto.c:3989 MCAST Data Sent 2017-07-06 14:14:56.403054 [DEBUG] mod_local_stream.c:901 Opening Stream [moh/48000] 48000hz 2017-07-06 14:14:56.403054 [WARNING] switch_core_file.c:360 File has 1 channels, muxing to 2 channels will occur.