2017-10-16 14:33:41.242148 [NOTICE] mod_cdr_csv.c:127 Rotated CDR logfile /usr/local/freeswitch/log/cdr-csv/1001.csv 2017-10-16 14:33:41.242148 [NOTICE] mod_cdr_csv.c:127 Rotated CDR logfile /usr/local/freeswitch/log/cdr-csv/1002.csv 2017-10-16 14:33:41.242148 [NOTICE] mod_cdr_csv.c:127 Rotated CDR logfile /usr/local/freeswitch/log/cdr-csv/1003.csv 2017-10-16 14:33:41.242148 [NOTICE] mod_cdr_csv.c:127 Rotated CDR logfile /usr/local/freeswitch/log/cdr-csv/Master.csv 2017-10-16 14:33:41.242148 [NOTICE] mod_logfile.c:192 New log started. 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] mod_rtc.c:392 () State Change CS_NEW -> CS_INIT 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_session.c:641 N/A set UUID=68c56206-57c1-8899-2bfb-9bf56cfd267a 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [NOTICE] switch_channel.c:1104 New Channel verto.rtc/1001 [68c56206-57c1-8899-2bfb-9bf56cfd267a] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] mod_verto.c:3761 Remote SDP verto.rtc/1001: 68c56206-57c1-8899-2bfb-9bf56cfd267a v=0 68c56206-57c1-8899-2bfb-9bf56cfd267a o=- 7343342628449551433 2 IN IP4 127.0.0.1 68c56206-57c1-8899-2bfb-9bf56cfd267a s=- 68c56206-57c1-8899-2bfb-9bf56cfd267a t=0 0 68c56206-57c1-8899-2bfb-9bf56cfd267a a=group:BUNDLE audio video 68c56206-57c1-8899-2bfb-9bf56cfd267a a=msid-semantic: WMS woBQVcibfs5BIHUCwTb9kwoUw4iIY6s5bnUC 68c56206-57c1-8899-2bfb-9bf56cfd267a m=audio 22555 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126 68c56206-57c1-8899-2bfb-9bf56cfd267a c=IN IP4 166.177.186.163 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp:9 IN IP4 0.0.0.0 68c56206-57c1-8899-2bfb-9bf56cfd267a a=candidate:4017529521 1 udp 2122255103 2001::9d38:6ab8:18a7:176f:3f57:69cd 59687 typ host generation 0 network-id 2 network-cost 50 68c56206-57c1-8899-2bfb-9bf56cfd267a a=candidate:236887664 1 udp 2122194687 192.168.150.50 59688 typ host generation 0 network-id 1 68c56206-57c1-8899-2bfb-9bf56cfd267a a=candidate:3264455139 1 udp 1685987071 166.177.186.163 22555 typ srflx raddr 192.168.150.50 rport 59688 generation 0 network-id 1 68c56206-57c1-8899-2bfb-9bf56cfd267a a=candidate:2717212225 1 tcp 1518275327 2001::9d38:6ab8:18a7:176f:3f57:69cd 9 typ host tcptype active generation 0 network-id 2 network-cost 50 68c56206-57c1-8899-2bfb-9bf56cfd267a a=candidate:1084056192 1 tcp 1518214911 192.168.150.50 9 typ host tcptype active generation 0 network-id 1 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ice-ufrag:M7Nw 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ice-pwd:NiqMlQU9Xl2wtx1Ab29Icukq 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ice-options:trickle 68c56206-57c1-8899-2bfb-9bf56cfd267a a=fingerprint:sha-256 BD:E0:E1:FF:F3:A9:F6:B4:A2:EA:DA:C0:E9:15:18:9C:37:27:7A:B9:8C:F7:47:6B:FD:4D:C8:EE:66:6B:E8:99 68c56206-57c1-8899-2bfb-9bf56cfd267a a=setup:actpass 68c56206-57c1-8899-2bfb-9bf56cfd267a a=mid:audio 68c56206-57c1-8899-2bfb-9bf56cfd267a a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level 68c56206-57c1-8899-2bfb-9bf56cfd267a a=sendrecv 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-mux 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:111 opus/48000/2 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:111 transport-cc 68c56206-57c1-8899-2bfb-9bf56cfd267a a=fmtp:111 minptime=10;useinbandfec=1; stereo=1; sprop-stereo=1 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:103 ISAC/16000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:104 ISAC/32000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:9 G722/8000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:0 PCMU/8000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:8 PCMA/8000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:106 CN/32000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:105 CN/16000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:13 CN/8000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:110 telephone-event/48000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:112 telephone-event/32000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:113 telephone-event/16000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:126 telephone-event/8000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ssrc:3468867122 cname:OZeOw9gqrpwvXULk 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ssrc:3468867122 msid:woBQVcibfs5BIHUCwTb9kwoUw4iIY6s5bnUC 858e34e8-ab46-41fe-bcb3-4aa6ea716375 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ssrc:3468867122 mslabel:woBQVcibfs5BIHUCwTb9kwoUw4iIY6s5bnUC 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ssrc:3468867122 label:858e34e8-ab46-41fe-bcb3-4aa6ea716375 68c56206-57c1-8899-2bfb-9bf56cfd267a m=video 18290 UDP/TLS/RTP/SAVPF 96 98 100 102 127 97 99 101 125 68c56206-57c1-8899-2bfb-9bf56cfd267a c=IN IP4 166.177.186.163 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp:9 IN IP4 0.0.0.0 68c56206-57c1-8899-2bfb-9bf56cfd267a a=candidate:4017529521 1 udp 2122255103 2001::9d38:6ab8:18a7:176f:3f57:69cd 59689 typ host generation 0 network-id 2 network-cost 50 68c56206-57c1-8899-2bfb-9bf56cfd267a a=candidate:236887664 1 udp 2122194687 192.168.150.50 59690 typ host generation 0 network-id 1 68c56206-57c1-8899-2bfb-9bf56cfd267a a=candidate:3264455139 1 udp 1685987071 166.177.186.163 18290 typ srflx raddr 192.168.150.50 rport 59690 generation 0 network-id 1 68c56206-57c1-8899-2bfb-9bf56cfd267a a=candidate:2717212225 1 tcp 1518275327 2001::9d38:6ab8:18a7:176f:3f57:69cd 9 typ host tcptype active generation 0 network-id 2 network-cost 50 68c56206-57c1-8899-2bfb-9bf56cfd267a a=candidate:1084056192 1 tcp 1518214911 192.168.150.50 9 typ host tcptype active generation 0 network-id 1 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ice-ufrag:M7Nw 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ice-pwd:NiqMlQU9Xl2wtx1Ab29Icukq 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ice-options:trickle 68c56206-57c1-8899-2bfb-9bf56cfd267a a=fingerprint:sha-256 BD:E0:E1:FF:F3:A9:F6:B4:A2:EA:DA:C0:E9:15:18:9C:37:27:7A:B9:8C:F7:47:6B:FD:4D:C8:EE:66:6B:E8:99 68c56206-57c1-8899-2bfb-9bf56cfd267a a=setup:actpass 68c56206-57c1-8899-2bfb-9bf56cfd267a a=mid:video 68c56206-57c1-8899-2bfb-9bf56cfd267a a=extmap:2 urn:ietf:params:rtp-hdrext:toffset 68c56206-57c1-8899-2bfb-9bf56cfd267a a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time 68c56206-57c1-8899-2bfb-9bf56cfd267a a=extmap:4 urn:3gpp:video-orientation 68c56206-57c1-8899-2bfb-9bf56cfd267a a=extmap:5 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 68c56206-57c1-8899-2bfb-9bf56cfd267a a=extmap:6 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay 68c56206-57c1-8899-2bfb-9bf56cfd267a a=sendrecv 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-mux 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-rsize 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:96 VP8/90000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:96 ccm fir 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:96 nack 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:96 nack pli 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:96 goog-remb 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:96 transport-cc 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:98 VP9/90000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:98 ccm fir 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:98 nack 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:98 nack pli 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:98 goog-remb 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:98 transport-cc 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:100 H264/90000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:100 ccm fir 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:100 nack 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:100 nack pli 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:100 goog-remb 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:100 transport-cc 68c56206-57c1-8899-2bfb-9bf56cfd267a a=fmtp:100 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:102 red/90000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:127 ulpfec/90000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:97 rtx/90000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=fmtp:97 apt=96 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:99 rtx/90000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=fmtp:99 apt=98 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:101 rtx/90000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=fmtp:101 apt=100 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:125 rtx/90000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=fmtp:125 apt=102 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ssrc-group:FID 4028610140 2186692357 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ssrc:4028610140 cname:OZeOw9gqrpwvXULk 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ssrc:4028610140 msid:woBQVcibfs5BIHUCwTb9kwoUw4iIY6s5bnUC 5c35c3a7-7656-46c5-8ebd-f7f5eda45129 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ssrc:4028610140 mslabel:woBQVcibfs5BIHUCwTb9kwoUw4iIY6s5bnUC 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ssrc:4028610140 label:5c35c3a7-7656-46c5-8ebd-f7f5eda45129 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ssrc:2186692357 cname:OZeOw9gqrpwvXULk a=ssrc:2186692357 msid:woBQVcibfs5BIHUCwTb9kwoUw4iIY6s5bnUC 5c35c3a7-7656-46c5-8ebd-f7f5eda45129 a=ssrc:2186692357 mslabel:woBQVcibfs5BIHUCwTb9kwoUw4iIY6s5bnUC a=ssrc:2186692357 label:5c35c3a7-7656-46c5-8ebd-f7f5eda45129 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1001) Running State Change CS_INIT (Cur 1 Tot 8167) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/1001) State INIT 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_state_machine.c:40 verto.rtc/1001 Standard INIT 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/1001) State Change CS_INIT -> CS_ROUTING 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/1001) State INIT going to sleep 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1001) Running State Change CS_ROUTING (Cur 1 Tot 8167) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_channel.c:2249 (verto.rtc/1001) Callstate Change DOWN -> RINGING 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/1001) State ROUTING 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] mod_rtc.c:89 verto.rtc/1001 RTC ROUTING 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_state_machine.c:236 verto.rtc/1001 Standard ROUTING 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [INFO] mod_dialplan_xml.c:637 Processing 1002 ->1001 in context default 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->baremetal.joshebosh.com] continue=false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [baremetal.joshebosh.com] ${destination_number}(1001) =~ /^9(10[01][0-9])$/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->unloop] continue=false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->tod_example] continue=true 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Date/Time Match (PASS) [tod_example] break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action set(open=true) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->holiday_example] continue=true 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Date/TimeMatch (FAIL) [holiday_example] break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->global-intercept] continue=false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [global-intercept] destination_number(1001) =~ /^886$/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->group-intercept] continue=false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [group-intercept] destination_number(1001) =~ /^\*8$/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->intercept-ext] continue=false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [intercept-ext] destination_number(1001) =~ /^\*\*(\d+)$/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->redial] continue=false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [redial] destination_number(1001) =~ /^(redial|870)$/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->global] continue=true 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [global] ${default_password}(j0sh3b0sh) =~ /^1234$/ break=never 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 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 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [global] ${endpoint_disposition}() =~ /^(DELAYED NEGOTIATION)/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->snom-demo-2] continue=false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [snom-demo-2] destination_number(1001) =~ /^9001$/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->snom-demo-1] continue=false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [snom-demo-1] destination_number(1001) =~ /^9000$/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->eavesdrop] continue=false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [eavesdrop] destination_number(1001) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->eavesdrop] continue=false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [eavesdrop] destination_number(1001) =~ /^779$/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->call_return] continue=false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [call_return] destination_number(1001) =~ /^\*69$|^869$|^lcr$/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->del-group] continue=false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [del-group] destination_number(1001) =~ /^80(\d{2})$/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->add-group] continue=false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [add-group] destination_number(1001) =~ /^81(\d{2})$/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->call-group-simo] continue=false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [call-group-simo] destination_number(1001) =~ /^82(\d{2})$/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->call-group-order] continue=false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [call-group-order] destination_number(1001) =~ /^83(\d{2})$/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->extension-intercom] continue=false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (FAIL) [extension-intercom] destination_number(1001) =~ /^8(10[01][0-9])$/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 parsing [default->Local_Extension] continue=false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Regex (PASS) [Local_Extension] destination_number(1001) =~ /^(10[01][0-9])$/ break=on-false 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action export(dialed_extension=1001) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action bind_meta_app(1 b s execute_extension::dx XML features) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action bind_meta_app(3 b s execute_extension::cf XML features) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action bind_meta_app(4 b s execute_extension::att_xfer XML features) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action pre_answer() 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action set(ringback=${us-ring}) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action set(transfer_ringback=local_stream://moh) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action set(call_timeout=30) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action set(hangup_after_bridge=true) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action set(continue_on_fail=true) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number}) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid}) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)}) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid}) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action hash(insert/${domain_name}-last_dial_ext/global/${uuid}) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid}) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action bridge(user/${dialed_extension}@${domain_name}) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action answer() 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action sleep(1000) 68c56206-57c1-8899-2bfb-9bf56cfd267a Dialplan: verto.rtc/1001 Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension}) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_state_machine.c:286 (verto.rtc/1001) State Change CS_ROUTING -> CS_EXECUTE 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/1001) State ROUTING going to sleep 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1001) Running State Change CS_EXECUTE (Cur 1 Tot 8167) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_state_machine.c:650 (verto.rtc/1001) State EXECUTE 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] mod_rtc.c:120 verto.rtc/1001 RTC EXECUTE 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_state_machine.c:328 verto.rtc/1001 Standard EXECUTE 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 set(open=true) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] mod_dptools.c:1588 SET verto.rtc/1001 [open]=[true] 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 export(dialed_extension=1001) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[1001] 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 bind_meta_app(1 b s execute_extension::dx XML features) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [INFO] switch_ivr_async.c:4273 Bound B-Leg: *1 execute_extension::dx XML features 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/joshua@freeswitch.org.2017-10-16-14-33-49.wav) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [INFO] switch_ivr_async.c:4273 Bound B-Leg: *2 record_session::/usr/local/freeswitch/recordings/joshua@freeswitch.org.2017-10-16-14-33-49.wav 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 bind_meta_app(3 b s execute_extension::cf XML features) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [INFO] switch_ivr_async.c:4273 Bound B-Leg: *3 execute_extension::cf XML features 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 bind_meta_app(4 b s execute_extension::att_xfer XML features) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [INFO] switch_ivr_async.c:4273 Bound B-Leg: *4 execute_extension::att_xfer XML features 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 pre_answer() 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5128 Audio Codec Compare [opus:111:48000:20:0:2]/[opus:116:48000:20:0:1] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5183 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5128 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[opus:116:48000:20:0:1] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5128 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[opus:116:48000:20:0:1] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5128 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5128 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5128 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5128 Audio Codec Compare [CN:106:32000:20:0:1]/[opus:116:48000:20:0:1] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5128 Audio Codec Compare [CN:105:16000:20:0:1]/[opus:116:48000:20:0:1] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5128 Audio Codec Compare [CN:13:8000:20:0:1]/[opus:116:48000:20:0:1] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5044 Set telephone-event payload to 110@48000 2017-10-16 14:33:49.092154 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [120000bps] 2017-10-16 14:33:49.092154 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [120000bps] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:3443 Set Codec verto.rtc/1001 opus/48000 20 ms 960 samples 0 bits 2 channels 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_codec.c:111 verto.rtc/1001 Original read codec set to opus:116 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:3867 Drop audio Candidate cid: 1 proto: udp type: host addr: 2001::9d38:6ab8:18a7:176f:3f57:69cd:59687 (no network path) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:3873 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.150.50:59688 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:3873 Save audio Candidate cid: 1 proto: udp type: srflx addr: 166.177.186.163:22555 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:3915 Searching for rtp candidate. 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:3920 Choose rtp candidate, index 1, 166.177.186.163:22555 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:3693 verto.rtc/1001 choosing family v4 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:3931 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 166.177.186.163:22555 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:3978 setting remote audio ice addr to index 1 166.177.186.163:22555 based on candidate 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:4013 Setting remote rtcp audio addr to 166.177.186.163:22555 based on candidate 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5387 Set telephone-event payload to 110@48000 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5445 verto.rtc/1001 Set 2833 dtmf send payload to 110 recv payload to 110 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5728 Video Codec Compare [VP8:96]/[VP8:99] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5760 Video Codec Compare [VP8:96] +++ is saved as a match 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5728 Video Codec Compare [VP9:98]/[VP8:99] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5728 Video Codec Compare [H264:100]/[VP8:99] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5728 Video Codec Compare [red:102]/[VP8:99] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5728 Video Codec Compare [ulpfec:127]/[VP8:99] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5728 Video Codec Compare [rtx:97]/[VP8:99] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5728 Video Codec Compare [rtx:99]/[VP8:99] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5728 Video Codec Compare [rtx:101]/[VP8:99] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:5728 Video Codec Compare [rtx:125]/[VP8:99] 2017-10-16 14:33:49.092154 [DEBUG] switch_vpx.c:636 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 2017-10-16 14:33:49.092154 [DEBUG] switch_vpx.c:636 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:3253 Set VIDEO Codec verto.rtc/1001 VP8/90000 0 ms 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:3867 Drop video Candidate cid: 1 proto: udp type: host addr: 2001::9d38:6ab8:18a7:176f:3f57:69cd:59689 (no network path) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:3873 Save video Candidate cid: 1 proto: udp type: host addr: 192.168.150.50:59690 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:3873 Save video Candidate cid: 1 proto: udp type: srflx addr: 166.177.186.163:18290 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:3915 Searching for rtp candidate. 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:3920 Choose rtp candidate, index 1, 166.177.186.163:18290 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:3693 verto.rtc/1001 choosing family v4 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:3931 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 166.177.186.163:18290 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:3978 setting remote video ice addr to index 1 166.177.186.163:18290 based on candidate 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:4013 Setting remote rtcp video addr to 166.177.186.163:18290 based on candidate 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:8162 AUDIO RTP [verto.rtc/1001] 74.208.165.83 port 16386 -> 166.177.186.163 port 22555 codec: 111 ms: 20 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_rtp.c:4191 Starting timer [soft] 960 bytes per 20ms 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [INFO] switch_core_media.c:8341 Activating Audio ICE 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [NOTICE] switch_rtp.c:4690 Activating RTP audio ICE: M7Nw:5FjjpHvcdkTwMslV 166.177.186.163:22555 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:8382 Activating RTCP PORT 22555 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_rtp.c:4587 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 22555 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [INFO] switch_core_media.c:8393 Skipping RTCP ICE (Same as RTP) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [INFO] switch_rtp.c:3643 Activate RTP/RTCP audio DTLS client 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [INFO] switch_rtp.c:3806 Changing audio DTLS state from OFF to HANDSHAKE 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:2204 Setting Jitterbuffer to 20ms (1 frames) (50 max frames) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:8466 verto.rtc/1001 Set 2833 dtmf send payload to 110 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_core_media.c:8473 verto.rtc/1001 Set 2833 dtmf receive payload to 110 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.092154 [DEBUG] switch_rtp.c:4206 Starting video timer. 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [DEBUG] switch_core_media.c:8991 VIDEO RTP [verto.rtc/1001] 74.208.165.83:16424->166.177.186.163:18290 codec: 96 ms: 0 [SUCCESS] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [DEBUG] switch_core_media.c:7154 verto.rtc/1001 Starting Video thread 2017-10-16 14:33:49.102149 [DEBUG] switch_core_media.c:7020 verto.rtc/1001 Video thread started. Echo is off 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [INFO] switch_core_media.c:9033 Activating Video ICE 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [NOTICE] switch_rtp.c:4690 Activating RTP video ICE: M7Nw:hxADhFx1mUeZXcuj 166.177.186.163:18290 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [INFO] switch_core_media.c:9071 Activating VIDEO RTCP PORT 18290 interval 1000 mux 1 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [DEBUG] switch_rtp.c:4587 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 18290 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [INFO] switch_core_media.c:9082 Skipping VIDEO RTCP ICE (Same as VIDEO RTP) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [INFO] switch_rtp.c:3643 Activate RTP/RTCP video DTLS client 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [INFO] switch_rtp.c:3806 Changing video DTLS state from OFF to HANDSHAKE 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [DEBUG] switch_core_media.c:8145 Audio params are unchanged for verto.rtc/1001. 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [DEBUG] switch_core_media.c:8849 Video params are unchanged for verto.rtc/1001. 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [DEBUG] mod_verto.c:2490 Local SDP verto.rtc/1001: 68c56206-57c1-8899-2bfb-9bf56cfd267a v=0 68c56206-57c1-8899-2bfb-9bf56cfd267a o=FreeSWITCH 1508148043 1508148044 IN IP4 74.208.165.83 68c56206-57c1-8899-2bfb-9bf56cfd267a s=FreeSWITCH 68c56206-57c1-8899-2bfb-9bf56cfd267a c=IN IP4 74.208.165.83 68c56206-57c1-8899-2bfb-9bf56cfd267a t=0 0 68c56206-57c1-8899-2bfb-9bf56cfd267a a=msid-semantic: WMS FQjpDftbmZq3oRl5eZiCnUxlWGZQtCJY 68c56206-57c1-8899-2bfb-9bf56cfd267a m=audio 16386 UDP/TLS/RTP/SAVPF 111 110 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:111 opus/48000/2 68c56206-57c1-8899-2bfb-9bf56cfd267a a=fmtp:111 useinbandfec=1; minptime=10; stereo=1; sprop-stereo=1 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:110 telephone-event/48000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=silenceSupp:off - - - - 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ptime:20 68c56206-57c1-8899-2bfb-9bf56cfd267a a=sendrecv 68c56206-57c1-8899-2bfb-9bf56cfd267a a=fingerprint:sha-256 A0:99:73:E3:3A:4A:21:B0:0B:C4:0B:C6:78:50:06:75:DE:85:4B:52:F1:50:38:20:B8:05:38:E7:59:2E:13:56 68c56206-57c1-8899-2bfb-9bf56cfd267a a=setup:active 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-mux 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp:16386 IN IP4 74.208.165.83 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ice-ufrag:5FjjpHvcdkTwMslV 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ice-pwd:bpxZMbZZ6TrIZOqUkz4z7pCa 68c56206-57c1-8899-2bfb-9bf56cfd267a a=candidate:2520885095 1 udp 659136 74.208.165.83 16386 typ host generation 0 68c56206-57c1-8899-2bfb-9bf56cfd267a a=end-of-candidates 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ssrc:569034677 cname:iSdMXxOan44BMfxR 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ssrc:569034677 msid:FQjpDftbmZq3oRl5eZiCnUxlWGZQtCJY a0 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ssrc:569034677 mslabel:FQjpDftbmZq3oRl5eZiCnUxlWGZQtCJY 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ssrc:569034677 label:FQjpDftbmZq3oRl5eZiCnUxlWGZQtCJYa0 68c56206-57c1-8899-2bfb-9bf56cfd267a m=video 16424 UDP/TLS/RTP/SAVPF 96 68c56206-57c1-8899-2bfb-9bf56cfd267a b=AS:250 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtpmap:96 VP8/90000 68c56206-57c1-8899-2bfb-9bf56cfd267a a=sendrecv 68c56206-57c1-8899-2bfb-9bf56cfd267a a=fingerprint:sha-256 A0:99:73:E3:3A:4A:21:B0:0B:C4:0B:C6:78:50:06:75:DE:85:4B:52:F1:50:38:20:B8:05:38:E7:59:2E:13:56 68c56206-57c1-8899-2bfb-9bf56cfd267a a=setup:active 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-mux 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp:16424 IN IP4 74.208.165.83 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:96 ccm fir 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:96 nack 68c56206-57c1-8899-2bfb-9bf56cfd267a a=rtcp-fb:96 nack pli 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ssrc:4109940734 cname:iSdMXxOan44BMfxR 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ssrc:4109940734 msid:FQjpDftbmZq3oRl5eZiCnUxlWGZQtCJY v0 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ssrc:4109940734 mslabel:FQjpDftbmZq3oRl5eZiCnUxlWGZQtCJY 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ssrc:4109940734 label:FQjpDftbmZq3oRl5eZiCnUxlWGZQtCJYv0 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ice-ufrag:hxADhFx1mUeZXcuj 68c56206-57c1-8899-2bfb-9bf56cfd267a a=ice-pwd:AT76cNXpHDli9BEhY4K5A7WN 68c56206-57c1-8899-2bfb-9bf56cfd267a a=candidate:7279357857 1 udp 659136 74.208.165.83 16424 typ host generation 0 68c56206-57c1-8899-2bfb-9bf56cfd267a a=end-of-candidates 68c56206-57c1-8899-2bfb-9bf56cfd267a 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [NOTICE] mod_dptools.c:1395 Pre-Answer verto.rtc/1001! 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [DEBUG] switch_channel.c:3482 (verto.rtc/1001) Callstate Change RINGING -> EARLY 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 set(ringback=%(2000,4000,440,480)) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [DEBUG] mod_dptools.c:1588 SET verto.rtc/1001 [ringback]=[%(2000,4000,440,480)] 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 set(transfer_ringback=local_stream://moh) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [DEBUG] mod_dptools.c:1588 SET verto.rtc/1001 [transfer_ringback]=[local_stream://moh] 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 set(call_timeout=30) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [DEBUG] mod_dptools.c:1588 SET verto.rtc/1001 [call_timeout]=[30] 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 set(hangup_after_bridge=true) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [DEBUG] mod_dptools.c:1588 SET verto.rtc/1001 [hangup_after_bridge]=[true] 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 set(continue_on_fail=true) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [DEBUG] mod_dptools.c:1588 SET verto.rtc/1001 [continue_on_fail]=[true] 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 hash(insert/74.208.165.83-call_return/1001/joshua@freeswitch.org) 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 hash(insert/74.208.165.83-last_dial_ext/1001/68c56206-57c1-8899-2bfb-9bf56cfd267a) 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 set(called_party_callgroup=techsupport) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [DEBUG] mod_dptools.c:1588 SET verto.rtc/1001 [called_party_callgroup]=[techsupport] 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 hash(insert/74.208.165.83-last_dial_ext/techsupport/68c56206-57c1-8899-2bfb-9bf56cfd267a) 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 hash(insert/74.208.165.83-last_dial_ext/global/68c56206-57c1-8899-2bfb-9bf56cfd267a) 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 hash(insert/74.208.165.83-last_dial/techsupport/68c56206-57c1-8899-2bfb-9bf56cfd267a) 68c56206-57c1-8899-2bfb-9bf56cfd267a EXECUTE verto.rtc/1001 bridge(user/1001@74.208.165.83) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [DEBUG] switch_channel.c:1250 verto.rtc/1001 EXPORTING[export_vars] [dialed_extension]=[1001] to event 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [DEBUG] switch_ivr_originate.c:2159 Parsing global variables 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [DEBUG] switch_channel.c:1250 verto.rtc/1001 EXPORTING[export_vars] [dialed_extension]=[1001] to event 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [DEBUG] switch_ivr_originate.c:2159 Parsing global variables 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.102149 [NOTICE] switch_ivr_originate.c:2868 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:49.102149 [DEBUG] mod_rtc.c:392 () State Change CS_NEW -> CS_INIT 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:49.102149 [NOTICE] switch_channel.c:1104 New Channel verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 [338902ab-4899-42cf-b722-68e29c934cc4] 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:49.112152 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) Running State Change CS_INIT (Cur 2 Tot 8168) 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:49.112152 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State INIT 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:49.112152 [DEBUG] mod_verto.c:2255 Local verto.invite SDP verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520: 338902ab-4899-42cf-b722-68e29c934cc4 v=0 338902ab-4899-42cf-b722-68e29c934cc4 o=FreeSWITCH 1508148017 1508148018 IN IP4 74.208.165.83 338902ab-4899-42cf-b722-68e29c934cc4 s=FreeSWITCH 338902ab-4899-42cf-b722-68e29c934cc4 c=IN IP4 74.208.165.83 338902ab-4899-42cf-b722-68e29c934cc4 t=0 0 338902ab-4899-42cf-b722-68e29c934cc4 a=msid-semantic: WMS HQ4cN8qtQYCQZ9nCxqbe8i4tXgUjnNsV 338902ab-4899-42cf-b722-68e29c934cc4 m=audio 16412 RTP/SAVPF 102 338902ab-4899-42cf-b722-68e29c934cc4 a=rtpmap:102 opus/48000/2 338902ab-4899-42cf-b722-68e29c934cc4 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40 338902ab-4899-42cf-b722-68e29c934cc4 a=fingerprint:sha-256 A0:99:73:E3:3A:4A:21:B0:0B:C4:0B:C6:78:50:06:75:DE:85:4B:52:F1:50:38:20:B8:05:38:E7:59:2E:13:56 338902ab-4899-42cf-b722-68e29c934cc4 a=setup:actpass 338902ab-4899-42cf-b722-68e29c934cc4 a=rtcp-mux 338902ab-4899-42cf-b722-68e29c934cc4 a=rtcp:16412 IN IP4 74.208.165.83 338902ab-4899-42cf-b722-68e29c934cc4 a=ssrc:651913525 cname:unX8ldr11THQTVjg 338902ab-4899-42cf-b722-68e29c934cc4 a=ssrc:651913525 msid:HQ4cN8qtQYCQZ9nCxqbe8i4tXgUjnNsV a0 338902ab-4899-42cf-b722-68e29c934cc4 a=ssrc:651913525 mslabel:HQ4cN8qtQYCQZ9nCxqbe8i4tXgUjnNsV 338902ab-4899-42cf-b722-68e29c934cc4 a=ssrc:651913525 label:HQ4cN8qtQYCQZ9nCxqbe8i4tXgUjnNsVa0 338902ab-4899-42cf-b722-68e29c934cc4 a=ice-ufrag:bklatfuhmeqzSIkd 338902ab-4899-42cf-b722-68e29c934cc4 a=ice-pwd:V7b6btY3dwKWiT2k4dkoiFvu 338902ab-4899-42cf-b722-68e29c934cc4 a=candidate:8966770351 1 udp 659136 74.208.165.83 16412 typ host generation 0 338902ab-4899-42cf-b722-68e29c934cc4 a=candidate:8966770351 2 udp 659136 74.208.165.83 16412 typ host generation 0 338902ab-4899-42cf-b722-68e29c934cc4 a=silenceSupp:off - - - - 338902ab-4899-42cf-b722-68e29c934cc4 a=ptime:20 338902ab-4899-42cf-b722-68e29c934cc4 a=sendrecv 338902ab-4899-42cf-b722-68e29c934cc4 m=video 16396 RTP/SAVPF 96 338902ab-4899-42cf-b722-68e29c934cc4 b=AS:1024 338902ab-4899-42cf-b722-68e29c934cc4 a=rtpmap:96 VP8/90000 338902ab-4899-42cf-b722-68e29c934cc4 a=sendrecv 338902ab-4899-42cf-b722-68e29c934cc4 a=fingerprint:sha-256 A0:99:73:E3:3A:4A:21:B0:0B:C4:0B:C6:78:50:06:75:DE:85:4B:52:F1:50:38:20:B8:05:38:E7:59:2E:13:56 338902ab-4899-42cf-b722-68e29c934cc4 a=setup:actpass 338902ab-4899-42cf-b722-68e29c934cc4 a=rtcp-mux 338902ab-4899-42cf-b722-68e29c934cc4 a=rtcp:16396 IN IP4 74.208.165.83 338902ab-4899-42cf-b722-68e29c934cc4 a=rtcp-fb:96 ccm fir 338902ab-4899-42cf-b722-68e29c934cc4 a=rtcp-fb:96 ccm tmmbr 338902ab-4899-42cf-b722-68e29c934cc4 a=rtcp-fb:96 nack 338902ab-4899-42cf-b722-68e29c934cc4 a=rtcp-fb:96 nack pli 338902ab-4899-42cf-b722-68e29c934cc4 a=ssrc:4192819582 cname:unX8ldr11THQTVjg 338902ab-4899-42cf-b722-68e29c934cc4 a=ssrc:4192819582 msid:HQ4cN8qtQYCQZ9nCxqbe8i4tXgUjnNsV v0 338902ab-4899-42cf-b722-68e29c934cc4 a=ssrc:4192819582 mslabel:HQ4cN8qtQYCQZ9nCxqbe8i4tXgUjnNsV 338902ab-4899-42cf-b722-68e29c934cc4 a=ssrc:4192819582 label:HQ4cN8qtQYCQZ9nCxqbe8i4tXgUjnNsVv0 338902ab-4899-42cf-b722-68e29c934cc4 a=ice-ufrag:NiytZld3JKfP9BQD 338902ab-4899-42cf-b722-68e29c934cc4 a=ice-pwd:75f9GcLRUGcwWs1zBqSrCWlb 338902ab-4899-42cf-b722-68e29c934cc4 a=candidate:6892578505 1 udp 659136 74.208.165.83 16396 typ host generation 0 338902ab-4899-42cf-b722-68e29c934cc4 a=candidate:6892578505 2 udp 659135 74.208.165.83 16396 typ host generation 0 338902ab-4899-42cf-b722-68e29c934cc4 a=end-of-candidates 338902ab-4899-42cf-b722-68e29c934cc4 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:49.112152 [NOTICE] mod_verto.c:2355 Ring-Ready verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520! 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:49.112152 [DEBUG] switch_channel.c:3354 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) Callstate Change DOWN -> RINGING 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:49.112152 [DEBUG] switch_core_state_machine.c:40 verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 Standard INIT 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:49.112152 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State Change CS_INIT -> CS_ROUTING 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:49.112152 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State INIT going to sleep 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:49.112152 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) Running State Change CS_ROUTING (Cur 2 Tot 8168) 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:49.112152 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State ROUTING 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:49.112152 [DEBUG] mod_rtc.c:89 verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 RTC ROUTING 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:49.112152 [DEBUG] switch_ivr_originate.c:67 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State Change CS_ROUTING -> CS_CONSUME_MEDIA 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:49.112152 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State ROUTING going to sleep 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:49.112152 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 8168) 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:49.112152 [DEBUG] switch_core_state_machine.c:662 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State CONSUME_MEDIA 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:49.112152 [DEBUG] switch_core_state_machine.c:662 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State CONSUME_MEDIA going to sleep 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.112152 [DEBUG] switch_ivr_originate.c:1278 Raw Codec Activation Success L16@48000hz 2 channel 20ms 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.112152 [DEBUG] switch_core_codec.c:223 verto.rtc/1001 Push codec L16:100 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.112152 [DEBUG] switch_ivr_originate.c:1347 Play Ringback Tone [%(2000,4000,440,480)] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.222147 [NOTICE] switch_vpx.c:410 VPX reset encoder picture from 320x240 to 640x480 250 BW 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.362148 [NOTICE] switch_rtp.c:1280 Auto Changing video stun/rtp/dtls port from 166.177.186.163:18290 to 166.177.169.250:59690 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:49.372153 [NOTICE] switch_rtp.c:1280 Auto Changing audio stun/rtp/dtls port from 166.177.186.163:22555 to 166.177.186.163:2835 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:50.572154 [INFO] switch_rtp.c:3191 Changing audio DTLS state from HANDSHAKE to SETUP 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:50.592149 [INFO] switch_rtp.c:3191 Changing video DTLS state from HANDSHAKE to SETUP 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:50.592149 [INFO] switch_rtp.c:3100 audio Fingerprint Verified. 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:50.592149 [INFO] switch_rtp.c:3995 Activating audio Secure RTP SEND 2017-10-16 14:33:50.592149 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:50.592149 [INFO] switch_rtp.c:3973 Activating audio Secure RTP RECV 2017-10-16 14:33:50.592149 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:50.592149 [INFO] switch_rtp.c:3140 Changing audio DTLS state from SETUP to READY 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:50.622161 [INFO] switch_rtp.c:3100 video Fingerprint Verified. 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:50.622161 [INFO] switch_rtp.c:3995 Activating video Secure RTP SEND 2017-10-16 14:33:50.622161 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:50.622161 [INFO] switch_rtp.c:3973 Activating video Secure RTP RECV 2017-10-16 14:33:50.622161 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:50.622161 [INFO] switch_rtp.c:3140 Changing video DTLS state from SETUP to READY 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:50.632153 [DEBUG] switch_rtp.c:1886 rtcp_stats_init: audio ssrc[3468867122] base_seq[11939] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:50.652151 [DEBUG] switch_rtp.c:1886 rtcp_stats_init: video ssrc[4028610140] base_seq[13761] 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] mod_verto.c:2721 Remote SDP verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520: 338902ab-4899-42cf-b722-68e29c934cc4 v=0 338902ab-4899-42cf-b722-68e29c934cc4 o=mozilla...THIS_IS_SDPARTA-56.0 7958488137139677696 0 IN IP4 0.0.0.0 338902ab-4899-42cf-b722-68e29c934cc4 s=- 338902ab-4899-42cf-b722-68e29c934cc4 t=0 0 338902ab-4899-42cf-b722-68e29c934cc4 a=sendrecv 338902ab-4899-42cf-b722-68e29c934cc4 a=fingerprint:sha-256 95:E9:FB:9D:E8:E2:A9:05:D0:0B:00:13:FC:9F:37:C5:FC:87:3C:B2:D3:72:F4:35:14:71:43:A2:BE:D7:ED:61 338902ab-4899-42cf-b722-68e29c934cc4 a=ice-options:trickle 338902ab-4899-42cf-b722-68e29c934cc4 a=msid-semantic:WMS * 338902ab-4899-42cf-b722-68e29c934cc4 m=audio 56688 RTP/SAVPF 102 338902ab-4899-42cf-b722-68e29c934cc4 c=IN IP4 166.177.186.163 338902ab-4899-42cf-b722-68e29c934cc4 a=candidate:0 1 UDP 2122252543 192.168.150.38 50298 typ host 338902ab-4899-42cf-b722-68e29c934cc4 a=candidate:2 1 UDP 2122187007 2001:0:9d38:6ab8:ce0:282c:3f57:69d9 50299 typ host 338902ab-4899-42cf-b722-68e29c934cc4 a=candidate:4 1 TCP 2105524479 192.168.150.38 9 typ host tcptype active 338902ab-4899-42cf-b722-68e29c934cc4 a=candidate:5 1 TCP 2105458943 2001:0:9d38:6ab8:ce0:282c:3f57:69d9 9 typ host tcptype active 338902ab-4899-42cf-b722-68e29c934cc4 a=candidate:1 1 UDP 1686052863 166.177.186.163 56688 typ srflx raddr 192.168.150.38 rport 50298 338902ab-4899-42cf-b722-68e29c934cc4 a=sendrecv 338902ab-4899-42cf-b722-68e29c934cc4 a=end-of-candidates 338902ab-4899-42cf-b722-68e29c934cc4 a=fmtp:102 maxplaybackrate=48000;stereo=1;useinbandfec=1 338902ab-4899-42cf-b722-68e29c934cc4 a=ice-pwd:267b29f3f9831ee932a8f361a25ee416 338902ab-4899-42cf-b722-68e29c934cc4 a=ice-ufrag:d824b20c 338902ab-4899-42cf-b722-68e29c934cc4 a=msid:{65a5c739-1e2d-4ac9-a886-023d986a84bf} {9e981d42-cc0b-44f9-a61a-aed0861dd4b4} 338902ab-4899-42cf-b722-68e29c934cc4 a=rtcp-mux 338902ab-4899-42cf-b722-68e29c934cc4 a=rtpmap:102 opus/48000/2 338902ab-4899-42cf-b722-68e29c934cc4 a=setup:active 338902ab-4899-42cf-b722-68e29c934cc4 a=ssrc:2201397410 cname:{ab2e782f-5705-4106-8e54-ab4bc8e8c0f4} 338902ab-4899-42cf-b722-68e29c934cc4 m=video 35376 RTP/SAVPF 96 338902ab-4899-42cf-b722-68e29c934cc4 c=IN IP4 166.177.186.163 338902ab-4899-42cf-b722-68e29c934cc4 a=candidate:0 1 UDP 2122252543 192.168.150.38 50300 typ host 338902ab-4899-42cf-b722-68e29c934cc4 a=candidate:2 1 UDP 2122187007 2001:0:9d38:6ab8:ce0:282c:3f57:69d9 50301 typ host 338902ab-4899-42cf-b722-68e29c934cc4 a=candidate:4 1 TCP 2105524479 192.168.150.38 9 typ host tcptype active 338902ab-4899-42cf-b722-68e29c934cc4 a=candidate:5 1 TCP 2105458943 2001:0:9d38:6ab8:ce0:282c:3f57:69d9 9 typ host tcptype active 338902ab-4899-42cf-b722-68e29c934cc4 a=candidate:1 1 UDP 1686052863 166.177.186.163 35376 typ srflx raddr 192.168.150.38 rport 50300 338902ab-4899-42cf-b722-68e29c934cc4 a=sendrecv 338902ab-4899-42cf-b722-68e29c934cc4 a=end-of-candidates 338902ab-4899-42cf-b722-68e29c934cc4 a=fmtp:96 max-fs=12288;max-fr=60 338902ab-4899-42cf-b722-68e29c934cc4 a=ice-pwd:267b29f3f9831ee932a8f361a25ee416 338902ab-4899-42cf-b722-68e29c934cc4 a=ice-ufrag:d824b20c 338902ab-4899-42cf-b722-68e29c934cc4 a=msid:{65a5c739-1e2d-4ac9-a886-023d986a84bf} {ad4845e2-4b48-4cef-be67-9335cd904217} 338902ab-4899-42cf-b722-68e29c934cc4 a=rtcp-fb:96 nack 338902ab-4899-42cf-b722-68e29c934cc4 a=rtcp-fb:96 nack pli 338902ab-4899-42cf-b722-68e29c934cc4 a=rtcp-fb:96 ccm fir 338902ab-4899-42cf-b722-68e29c934cc4 a=rtcp-mux 338902ab-4899-42cf-b722-68e29c934cc4 a=rtpmap:96 VP8/90000 338902ab-4899-42cf-b722-68e29c934cc4 a=setup:active 338902ab-4899-42cf-b722-68e29c934cc4 a=ssrc:1910727252 cname:{ab2e782f-5705-4106-8e54-ab4bc8e8c0f4} 338902ab-4899-42cf-b722-68e29c934cc4 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:5128 Audio Codec Compare [opus:102:48000:20:0:2]/[opus:116:48000:20:0:1] 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:5183 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match 2017-10-16 14:33:54.632150 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [120000bps] 2017-10-16 14:33:54.632150 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [120000bps] 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:3443 Set Codec verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 opus/48000 20 ms 960 samples 0 bits 2 channels 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_codec.c:111 verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 Original read codec set to opus:116 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:3873 Save audio Candidate cid: 1 proto: UDP type: host addr: 192.168.150.38:50298 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:3867 Drop audio Candidate cid: 1 proto: UDP type: host addr: 2001:0:9d38:6ab8:ce0:282c:3f57:69d9:50299 (no network path) 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:3873 Save audio Candidate cid: 1 proto: UDP type: srflx addr: 166.177.186.163:56688 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:3915 Searching for rtp candidate. 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:3920 Choose rtp candidate, index 1, 166.177.186.163:56688 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:3693 verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 choosing family v4 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:3931 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 166.177.186.163:56688 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:3978 setting remote audio ice addr to index 1 166.177.186.163:56688 based on candidate 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:4013 Setting remote rtcp audio addr to 166.177.186.163:56688 based on candidate 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:5416 No 2833 in SDP. Liberal DTMF mode adding 0 as telephone-event. 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:5452 No 2833 in SDP. Disable 2833 dtmf and switch to INFO 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:5728 Video Codec Compare [VP8:96]/[VP8:99] 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:5760 Video Codec Compare [VP8:96] +++ is saved as a match 2017-10-16 14:33:54.632150 [DEBUG] switch_vpx.c:636 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 2017-10-16 14:33:54.632150 [DEBUG] switch_vpx.c:636 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:3253 Set VIDEO Codec verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 VP8/90000 0 ms 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:3873 Save video Candidate cid: 1 proto: UDP type: host addr: 192.168.150.38:50300 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:3867 Drop video Candidate cid: 1 proto: UDP type: host addr: 2001:0:9d38:6ab8:ce0:282c:3f57:69d9:50301 (no network path) 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:3873 Save video Candidate cid: 1 proto: UDP type: srflx addr: 166.177.186.163:35376 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:3915 Searching for rtp candidate. 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:3920 Choose rtp candidate, index 1, 166.177.186.163:35376 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:3693 verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 choosing family v4 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:3931 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 166.177.186.163:35376 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:3978 setting remote video ice addr to index 1 166.177.186.163:35376 based on candidate 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:4013 Setting remote rtcp video addr to 166.177.186.163:35376 based on candidate 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:8162 AUDIO RTP [verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520] 74.208.165.83 port 16412 -> 166.177.186.163 port 56688 codec: 102 ms: 20 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_rtp.c:4191 Starting timer [soft] 960 bytes per 20ms 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [INFO] switch_core_media.c:8341 Activating Audio ICE 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [NOTICE] switch_rtp.c:4690 Activating RTP audio ICE: d824b20c:bklatfuhmeqzSIkd 166.177.186.163:56688 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:8382 Activating RTCP PORT 56688 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_rtp.c:4587 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 56688 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [INFO] switch_core_media.c:8393 Skipping RTCP ICE (Same as RTP) 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [INFO] switch_rtp.c:3643 Activate RTP/RTCP audio DTLS server 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [INFO] switch_rtp.c:3806 Changing audio DTLS state from OFF to HANDSHAKE 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:2204 Setting Jitterbuffer to 20ms (1 frames) (50 max frames) 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_rtp.c:4206 Starting video timer. 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:8991 VIDEO RTP [verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520] 74.208.165.83:16396->166.177.186.163:35376 codec: 96 ms: 0 [SUCCESS] 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:7154 verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 Starting Video thread 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:7020 verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 Video thread started. Echo is off 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [INFO] switch_core_media.c:9033 Activating Video ICE 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [NOTICE] switch_rtp.c:4690 Activating RTP video ICE: d824b20c:NiytZld3JKfP9BQD 166.177.186.163:35376 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [INFO] switch_core_media.c:9071 Activating VIDEO RTCP PORT 35376 interval 1000 mux 1 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_rtp.c:4587 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 35376 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [INFO] switch_core_media.c:9082 Skipping VIDEO RTCP ICE (Same as VIDEO RTP) 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [INFO] switch_rtp.c:3643 Activate RTP/RTCP video DTLS server 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [INFO] switch_rtp.c:3806 Changing video DTLS state from OFF to HANDSHAKE 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:8145 Audio params are unchanged for verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520. 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [DEBUG] switch_core_media.c:8849 Video params are unchanged for verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520. 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.632150 [NOTICE] mod_verto.c:2755 Channel [verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520] has been answered 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.642147 [DEBUG] switch_channel.c:3781 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) Callstate Change RINGING -> ACTIVE 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:54.642147 [DEBUG] switch_core_codec.c:248 verto.rtc/1001 Restore previous codec opus:116. 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:54.642147 [NOTICE] switch_ivr_originate.c:3664 Channel [verto.rtc/1001] has been answered 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:54.642147 [DEBUG] switch_channel.c:3781 (verto.rtc/1001) Callstate Change EARLY -> ACTIVE 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:54.642147 [DEBUG] switch_ivr_originate.c:3722 Originate Resulted in Success: [verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:33:54.642147 [DEBUG] switch_ivr_originate.c:3722 Originate Resulted in Success: [verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520] 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.642147 [DEBUG] switch_core_media.c:12204 verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 PAUSE Jitterbuffer 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.642147 [DEBUG] switch_ivr_bridge.c:1744 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.642147 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 8168) 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.642147 [DEBUG] switch_core_state_machine.c:653 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State EXCHANGE_MEDIA 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.642147 [DEBUG] mod_rtc.c:146 RTC EXCHANGE_MEDIA 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.652149 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0] 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:33:54.652149 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0] 2017-10-16 14:33:54.652149 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [120000bps] 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.842148 [NOTICE] mod_verto.c:2835 Hangup verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.852149 [DEBUG] switch_ivr_bridge.c:255 verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 video thread ended. 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.852149 [DEBUG] switch_ivr_bridge.c:257 verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 skip receive message [DISPLAY] (channel is hungup already) 2017-10-16 14:34:03.852149 [DEBUG] switch_core_media.c:7121 verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 Video thread ended 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.852149 [DEBUG] switch_ivr_bridge.c:831 verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 ending bridge by request from read function 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.852149 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520] 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.852149 [DEBUG] switch_core_state_machine.c:653 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State EXCHANGE_MEDIA going to sleep 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.852149 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) Running State Change CS_HANGUP (Cur 2 Tot 8168) 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.852149 [DEBUG] switch_core_state_machine.c:850 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) Callstate Change ACTIVE -> HANGUP 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.852149 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State HANGUP 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.852149 [DEBUG] switch_core_state_machine.c:60 verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 Standard HANGUP, cause: NORMAL_CLEARING 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.852149 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State HANGUP going to sleep 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.852149 [DEBUG] switch_core_state_machine.c:619 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State Change CS_HANGUP -> CS_REPORTING 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.852149 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) Running State Change CS_REPORTING (Cur 2 Tot 8168) 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.852149 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State REPORTING 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.852149 [DEBUG] switch_core_state_machine.c:174 verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 Standard REPORTING, cause: NORMAL_CLEARING 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.852149 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State REPORTING going to sleep 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.852149 [DEBUG] switch_core_state_machine.c:610 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State Change CS_REPORTING -> CS_DESTROY 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.852149 [DEBUG] switch_core_session.c:1713 Session 8168 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) Locked, Waiting on external entities 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.852149 [DEBUG] switch_ivr_bridge.c:825 verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 ending bridge by request from write function 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.852149 [DEBUG] switch_ivr_bridge.c:848 Ending video thread. 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.852149 [DEBUG] switch_ivr_bridge.c:906 Ending video thread. 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.852149 [DEBUG] switch_ivr_bridge.c:255 verto.rtc/1001 video thread ended. 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.862147 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [verto.rtc/1001] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.862147 [NOTICE] switch_ivr_bridge.c:1895 Hangup verto.rtc/1001 [CS_EXECUTE] [NORMAL_CLEARING] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.862147 [DEBUG] switch_core_session.c:2885 verto.rtc/1001 skip receive message [PHONE_EVENT] (channel is hungup already) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.862147 [DEBUG] switch_core_state_machine.c:650 (verto.rtc/1001) State EXECUTE going to sleep 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.862147 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1001) Running State Change CS_HANGUP (Cur 2 Tot 8168) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.862147 [DEBUG] switch_core_state_machine.c:850 (verto.rtc/1001) Callstate Change ACTIVE -> HANGUP 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.862147 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/1001) State HANGUP 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.862147 [DEBUG] switch_core_state_machine.c:60 verto.rtc/1001 Standard HANGUP, cause: NORMAL_CLEARING 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.862147 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/1001) State HANGUP going to sleep 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.862147 [DEBUG] switch_core_state_machine.c:619 (verto.rtc/1001) State Change CS_HANGUP -> CS_REPORTING 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.872148 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1001) Running State Change CS_REPORTING (Cur 2 Tot 8168) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.872148 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/1001) State REPORTING 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.872148 [DEBUG] switch_core_state_machine.c:174 verto.rtc/1001 Standard REPORTING, cause: NORMAL_CLEARING 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.872148 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/1001) State REPORTING going to sleep 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.872148 [DEBUG] switch_core_state_machine.c:610 (verto.rtc/1001) State Change CS_REPORTING -> CS_DESTROY 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.872148 [DEBUG] switch_core_session.c:1713 Session 8167 (verto.rtc/1001) Locked, Waiting on external entities 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.872148 [NOTICE] switch_core_session.c:1731 Session 8168 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) Ended 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.872148 [NOTICE] switch_core_session.c:1735 Close Channel verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 [CS_DESTROY] 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.872148 [DEBUG] switch_core_state_machine.c:741 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) Running State Change CS_DESTROY (Cur 1 Tot 8168) 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.872148 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State DESTROY 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.872148 [DEBUG] mod_rtc.c:132 verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 RTC DESTROY 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.872148 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0] 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.872148 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0] 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.872148 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0] 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.872148 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0] 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.872148 [DEBUG] switch_core_state_machine.c:181 verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520 Standard DESTROY 338902ab-4899-42cf-b722-68e29c934cc4 2017-10-16 14:34:03.872148 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/de136efa-f179-20b9-ee20-92e16ccea520) State DESTROY going to sleep 2017-10-16 14:34:03.902146 [DEBUG] switch_core_media.c:7121 verto.rtc/1001 Video thread ended 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.902146 [NOTICE] switch_core_session.c:1731 Session 8167 (verto.rtc/1001) Ended 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.902146 [NOTICE] switch_core_session.c:1735 Close Channel verto.rtc/1001 [CS_DESTROY] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.902146 [DEBUG] switch_core_state_machine.c:741 (verto.rtc/1001) Running State Change CS_DESTROY (Cur 0 Tot 8168) 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.902146 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/1001) State DESTROY 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.902146 [DEBUG] mod_rtc.c:132 verto.rtc/1001 RTC DESTROY 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.902146 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[201] PLC[0] FEC[0] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.902146 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.902146 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.902146 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[276] Bytes encoded[38968] Encoded length ms[5520] Average encoded bitrate bps[62348] 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.902146 [DEBUG] switch_core_state_machine.c:181 verto.rtc/1001 Standard DESTROY 68c56206-57c1-8899-2bfb-9bf56cfd267a 2017-10-16 14:34:03.902146 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/1001) State DESTROY going to sleep