- 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 <joshebosh@gmail.com>->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.