2017-06-21 17:45:04.747766 [DEBUG] mod_rtc.c:392 () State Change CS_NEW -> CS_INIT 2017-06-21 17:45:04.747766 [DEBUG] switch_core_session.c:640 N/A set UUID=71071c71-0351-b67c-2165-9bff65ccc4e4 2017-06-21 17:45:04.747766 [NOTICE] switch_channel.c:1104 New Channel verto.rtc/1005 [71071c71-0351-b67c-2165-9bff65ccc4e4] 2017-06-21 17:45:04.747766 [DEBUG] mod_verto.c:3728 Remote SDP verto.rtc/1005: v=0 o=mozilla...THIS_IS_SDPARTA-54.0 1285636339620956612 0 IN IP4 0.0.0.0 s=- t=0 0 a=sendrecv a=fingerprint:sha-256 63:F0:7B:49:87:65:43:E3:9E:83:10:A3:FA:B8:89:E5:A9:9B:CF:DB:8E:F6:EE:26:17:8A:26:71:9F:99:DF:DB a=group:BUNDLE sdparta_0 sdparta_1 a=ice-options:trickle a=msid-semantic:WMS * m=audio 51494 UDP/TLS/RTP/SAVPF 109 9 0 8 101 c=IN IP4 65.15.69.32 a=candidate:0 1 UDP 2122252543 192.168.150.12 51494 typ host a=candidate:2 1 TCP 2105524479 192.168.150.12 9 typ host tcptype active a=candidate:3 1 TCP 2105458943 2001:0:9d38:6ab8:ced:bee:3f57:69f3 9 typ host tcptype active a=candidate:0 2 UDP 2122252542 192.168.150.12 51495 typ host a=candidate:2 2 TCP 2105524478 192.168.150.12 9 typ host tcptype active a=candidate:3 2 TCP 2105458942 2001:0:9d38:6ab8:ced:bee:3f57:69f3 9 typ host tcptype active a=candidate:1 1 UDP 1686052863 65.15.69.32 51494 typ srflx raddr 192.168.150.12 rport 51494 a=candidate:1 2 UDP 1686052862 65.15.69.32 51495 typ srflx raddr 192.168.150.12 rport 51495 a=recvonly a=end-of-candidates a=extmap:1/sendonly urn:ietf:params:rtp-hdrext:ssrc-audio-level a=fmtp:109 maxplaybackrate=48000;stereo=1;useinbandfec=1; stereo=1; sprop-stereo=1 a=fmtp:101 0-15 a=ice-pwd:b7efc0bc9593d5b0b7d4bf501c141d09 a=ice-ufrag:9c79d98b a=mid:sdparta_0 a=rtcp:51495 IN IP4 65.15.69.32 a=rtcp-mux a=rtpmap:109 opus/48000/2 a=rtpmap:9 G722/8000/1 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=setup:actpass a=ssrc:775356116 cname:{76cc3af4-3034-4190-a6d2-916dace30a89} m=video 51496 UDP/TLS/RTP/SAVPF 120 121 126 97 c=IN IP4 65.15.69.32 a=candidate:0 1 UDP 2122252543 192.168.150.12 51496 typ host a=candidate:2 1 TCP 2105524479 192.168.150.12 9 typ host tcptype active a=candidate:3 1 TCP 2105458943 2001:0:9d38:6ab8:ced:bee:3f57:69f3 9 typ host tcptype active a=candidate:0 2 UDP 2122252542 192.168.150.12 51497 typ host a=candidate:2 2 TCP 2105524478 192.168.150.12 9 typ host tcptype active a=candidate:3 2 TCP 2105458942 2001:0:9d38:6ab8:ced:bee:3f57:69f3 9 typ host tcptype active a=candidate:1 1 UDP 1686052863 65.15.69.32 51496 typ srflx raddr 192.168.150.12 rport 51496 a=candidate:1 2 UDP 1686052862 65.15.69.32 51497 typ srflx raddr 192.168.150.12 rport 51497 a=sendrecv a=end-of-candidates a=extmap:1 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time a=extmap:2 urn:ietf:params:rtp-hdrext:toffset a=fmtp:126 profile-level-id=42e01f;level-asymmetry-allowed=1;packetization-mode=1 a=fmtp:97 profile-level-id=42e01f;level-asymmetry-allowed=1 a=fmtp:120 max-fs=12288;max-fr=60 a=fmtp:121 max-fs=12288;max-fr=60 a=ice-pwd:b7efc0bc9593d5b0b7d4bf501c141d09 a=ice-ufrag:9c79d98b a=mid:sdparta_1 a=msid:{a4fe56d7-6791-49bd-89d0-7ba5d5a93803} {d8d495a6-98b2-4210-bace-1d3870ddb6a9} a=rtcp:51497 IN IP4 65.15.69.32 a=rtcp-fb:120 nack a=rtcp-fb:120 nack pli a=rtcp-fb:120 ccm fir a=rtcp-fb:120 goog-remb a=rtcp-fb:121 nack a=rtcp-fb:121 nack pli a=rtcp-fb:121 ccm fir a=rtcp-fb:121 goog-remb a=rtcp-fb:126 nack a=rtcp-fb:126 nack pli a=rtcp-fb:126 ccm fir a=rtcp-fb:126 goog-remb a=rtcp-fb:97 nack a=rtcp-fb:97 nack pli a=rtcp-fb:97 ccm fir a=rtcp-fb:97 goog-remb a=rtcp-mux a=rtpmap:120 VP8/90000 a=rtpmap:121 VP9/90000 a=rtpmap:126 H264/90000 a=rtpmap:97 H264/90000 a=setup:actpass a=ssrc:2761950371 cname:{76cc3af4-3034-4190-a6d2-916dace30a89} 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1005) Running State Change CS_INIT (Cur 1 Tot 56) 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/1005) State INIT 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:40 verto.rtc/1005 Standard INIT 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/1005) State Change CS_INIT -> CS_ROUTING 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/1005) State INIT going to sleep 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1005) Running State Change CS_ROUTING (Cur 1 Tot 56) 2017-06-21 17:45:04.747766 [DEBUG] switch_channel.c:2249 (verto.rtc/1005) Callstate Change DOWN -> RINGING 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/1005) State ROUTING 2017-06-21 17:45:04.747766 [DEBUG] mod_rtc.c:89 verto.rtc/1005 RTC ROUTING 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:236 verto.rtc/1005 Standard ROUTING 2017-06-21 17:45:04.747766 [INFO] mod_dialplan_xml.c:637 Processing eboshxff ->1005 in context default Dialplan: verto.rtc/1005 parsing [default->unloop] continue=false Dialplan: verto.rtc/1005 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: verto.rtc/1005 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: verto.rtc/1005 parsing [default->tod_example] continue=true Dialplan: verto.rtc/1005 Date/Time Match (PASS) [tod_example] break=on-false Dialplan: verto.rtc/1005 Action set(open=true) Dialplan: verto.rtc/1005 parsing [default->holiday_example] continue=true Dialplan: verto.rtc/1005 Date/TimeMatch (FAIL) [holiday_example] break=on-false Dialplan: verto.rtc/1005 parsing [default->global-intercept] continue=false Dialplan: verto.rtc/1005 Regex (FAIL) [global-intercept] destination_number(1005) =~ /^886$/ break=on-false Dialplan: verto.rtc/1005 parsing [default->group-intercept] continue=false Dialplan: verto.rtc/1005 Regex (FAIL) [group-intercept] destination_number(1005) =~ /^\*8$/ break=on-false Dialplan: verto.rtc/1005 parsing [default->intercept-ext] continue=false Dialplan: verto.rtc/1005 Regex (FAIL) [intercept-ext] destination_number(1005) =~ /^\*\*(\d+)$/ break=on-false Dialplan: verto.rtc/1005 parsing [default->redial] continue=false Dialplan: verto.rtc/1005 Regex (FAIL) [redial] destination_number(1005) =~ /^(redial|870)$/ break=on-false Dialplan: verto.rtc/1005 parsing [default->global] continue=true Dialplan: verto.rtc/1005 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never Dialplan: verto.rtc/1005 Regex (FAIL) [global] ${default_password}(j0sh3b0sh) =~ /^1234$/ break=never Dialplan: verto.rtc/1005 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/1005 Regex (FAIL) [global] ${endpoint_disposition}() =~ /^(DELAYED NEGOTIATION)/ break=on-false Dialplan: verto.rtc/1005 parsing [default->snom-demo-2] continue=false Dialplan: verto.rtc/1005 Regex (FAIL) [snom-demo-2] destination_number(1005) =~ /^9001$/ break=on-false Dialplan: verto.rtc/1005 parsing [default->snom-demo-1] continue=false Dialplan: verto.rtc/1005 Regex (FAIL) [snom-demo-1] destination_number(1005) =~ /^9000$/ break=on-false Dialplan: verto.rtc/1005 parsing [default->eavesdrop] continue=false Dialplan: verto.rtc/1005 Regex (FAIL) [eavesdrop] destination_number(1005) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false Dialplan: verto.rtc/1005 parsing [default->eavesdrop] continue=false Dialplan: verto.rtc/1005 Regex (FAIL) [eavesdrop] destination_number(1005) =~ /^779$/ break=on-false Dialplan: verto.rtc/1005 parsing [default->call_return] continue=false Dialplan: verto.rtc/1005 Regex (FAIL) [call_return] destination_number(1005) =~ /^\*69$|^869$|^lcr$/ break=on-false Dialplan: verto.rtc/1005 parsing [default->del-group] continue=false Dialplan: verto.rtc/1005 Regex (FAIL) [del-group] destination_number(1005) =~ /^80(\d{2})$/ break=on-false Dialplan: verto.rtc/1005 parsing [default->add-group] continue=false Dialplan: verto.rtc/1005 Regex (FAIL) [add-group] destination_number(1005) =~ /^81(\d{2})$/ break=on-false Dialplan: verto.rtc/1005 parsing [default->call-group-simo] continue=false Dialplan: verto.rtc/1005 Regex (FAIL) [call-group-simo] destination_number(1005) =~ /^82(\d{2})$/ break=on-false Dialplan: verto.rtc/1005 parsing [default->call-group-order] continue=false Dialplan: verto.rtc/1005 Regex (FAIL) [call-group-order] destination_number(1005) =~ /^83(\d{2})$/ break=on-false Dialplan: verto.rtc/1005 parsing [default->extension-intercom] continue=false Dialplan: verto.rtc/1005 Regex (FAIL) [extension-intercom] destination_number(1005) =~ /^8(10[01][0-9])$/ break=on-false Dialplan: verto.rtc/1005 parsing [default->Local_Extension] continue=false Dialplan: verto.rtc/1005 Regex (PASS) [Local_Extension] destination_number(1005) =~ /^(10[01][0-9])$/ break=on-false Dialplan: verto.rtc/1005 Action export(dialed_extension=1005) Dialplan: verto.rtc/1005 Action bind_meta_app(1 b s execute_extension::dx XML features) Dialplan: verto.rtc/1005 Action bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav) Dialplan: verto.rtc/1005 Action bind_meta_app(3 b s execute_extension::cf XML features) Dialplan: verto.rtc/1005 Action bind_meta_app(4 b s execute_extension::att_xfer XML features) Dialplan: verto.rtc/1005 Action set(ringback=${us-ring}) Dialplan: verto.rtc/1005 Action set(transfer_ringback=local_stream://moh) Dialplan: verto.rtc/1005 Action set(call_timeout=30) Dialplan: verto.rtc/1005 Action set(hangup_after_bridge=true) Dialplan: verto.rtc/1005 Action set(continue_on_fail=true) Dialplan: verto.rtc/1005 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number}) Dialplan: verto.rtc/1005 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid}) Dialplan: verto.rtc/1005 Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)}) Dialplan: verto.rtc/1005 Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid}) Dialplan: verto.rtc/1005 Action hash(insert/${domain_name}-last_dial_ext/global/${uuid}) Dialplan: verto.rtc/1005 Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid}) Dialplan: verto.rtc/1005 Action bridge(user/${dialed_extension}@${domain_name}) Dialplan: verto.rtc/1005 Action answer() Dialplan: verto.rtc/1005 Action sleep(1000) Dialplan: verto.rtc/1005 Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension}) 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:286 (verto.rtc/1005) State Change CS_ROUTING -> CS_EXECUTE 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/1005) State ROUTING going to sleep 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1005) Running State Change CS_EXECUTE (Cur 1 Tot 56) 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:650 (verto.rtc/1005) State EXECUTE 2017-06-21 17:45:04.747766 [DEBUG] mod_rtc.c:120 verto.rtc/1005 RTC EXECUTE 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:328 verto.rtc/1005 Standard EXECUTE EXECUTE verto.rtc/1005 set(open=true) 2017-06-21 17:45:04.747766 [DEBUG] mod_dptools.c:1570 SET verto.rtc/1005 [open]=[true] EXECUTE verto.rtc/1005 export(dialed_extension=1005) 2017-06-21 17:45:04.747766 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[1005] EXECUTE verto.rtc/1005 bind_meta_app(1 b s execute_extension::dx XML features) 2017-06-21 17:45:04.747766 [INFO] switch_ivr_async.c:4260 Bound B-Leg: *1 execute_extension::dx XML features EXECUTE verto.rtc/1005 bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/eboshxff@joshebosh.com.2017-06-21-17-45-04.wav) 2017-06-21 17:45:04.747766 [INFO] switch_ivr_async.c:4260 Bound B-Leg: *2 record_session::/usr/local/freeswitch/recordings/eboshxff@joshebosh.com.2017-06-21-17-45-04.wav EXECUTE verto.rtc/1005 bind_meta_app(3 b s execute_extension::cf XML features) 2017-06-21 17:45:04.747766 [INFO] switch_ivr_async.c:4260 Bound B-Leg: *3 execute_extension::cf XML features EXECUTE verto.rtc/1005 bind_meta_app(4 b s execute_extension::att_xfer XML features) 2017-06-21 17:45:04.747766 [INFO] switch_ivr_async.c:4260 Bound B-Leg: *4 execute_extension::att_xfer XML features EXECUTE verto.rtc/1005 set(ringback=%(2000,4000,440,480)) 2017-06-21 17:45:04.747766 [DEBUG] mod_dptools.c:1570 SET verto.rtc/1005 [ringback]=[%(2000,4000,440,480)] EXECUTE verto.rtc/1005 set(transfer_ringback=local_stream://moh) 2017-06-21 17:45:04.747766 [DEBUG] mod_dptools.c:1570 SET verto.rtc/1005 [transfer_ringback]=[local_stream://moh] EXECUTE verto.rtc/1005 set(call_timeout=30) 2017-06-21 17:45:04.747766 [DEBUG] mod_dptools.c:1570 SET verto.rtc/1005 [call_timeout]=[30] EXECUTE verto.rtc/1005 set(hangup_after_bridge=true) 2017-06-21 17:45:04.747766 [DEBUG] mod_dptools.c:1570 SET verto.rtc/1005 [hangup_after_bridge]=[true] EXECUTE verto.rtc/1005 set(continue_on_fail=true) 2017-06-21 17:45:04.747766 [DEBUG] mod_dptools.c:1570 SET verto.rtc/1005 [continue_on_fail]=[true] EXECUTE verto.rtc/1005 hash(insert/192.168.150.3-call_return/1005/eboshxff@joshebosh.com) EXECUTE verto.rtc/1005 hash(insert/192.168.150.3-last_dial_ext/1005/71071c71-0351-b67c-2165-9bff65ccc4e4) EXECUTE verto.rtc/1005 set(called_party_callgroup=techsupport) 2017-06-21 17:45:04.747766 [DEBUG] mod_dptools.c:1570 SET verto.rtc/1005 [called_party_callgroup]=[techsupport] EXECUTE verto.rtc/1005 hash(insert/192.168.150.3-last_dial_ext/techsupport/71071c71-0351-b67c-2165-9bff65ccc4e4) EXECUTE verto.rtc/1005 hash(insert/192.168.150.3-last_dial_ext/global/71071c71-0351-b67c-2165-9bff65ccc4e4) EXECUTE verto.rtc/1005 hash(insert/192.168.150.3-last_dial/techsupport/71071c71-0351-b67c-2165-9bff65ccc4e4) EXECUTE verto.rtc/1005 bridge(user/1005@192.168.150.3) 2017-06-21 17:45:04.747766 [DEBUG] switch_channel.c:1250 verto.rtc/1005 EXPORTING[export_vars] [dialed_extension]=[1005] to event 2017-06-21 17:45:04.747766 [DEBUG] switch_ivr_originate.c:2147 Parsing global variables 2017-06-21 17:45:04.747766 [DEBUG] switch_channel.c:1250 verto.rtc/1005 EXPORTING[export_vars] [dialed_extension]=[1005] to event 2017-06-21 17:45:04.747766 [DEBUG] switch_ivr_originate.c:2147 Parsing global variables 2017-06-21 17:45:04.747766 [NOTICE] switch_ivr_originate.c:2856 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2017-06-21 17:45:04.747766 [DEBUG] mod_rtc.c:392 () State Change CS_NEW -> CS_INIT 2017-06-21 17:45:04.747766 [NOTICE] switch_channel.c:1104 New Channel verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d [07f50d5d-defd-4d0b-bec6-51bcebb24a3d] 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Running State Change CS_INIT (Cur 2 Tot 57) 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State INIT 2017-06-21 17:45:04.757737 [DEBUG] mod_verto.c:2245 Local verto.invite SDP verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d: v=0 o=FreeSWITCH 1498056132 1498056133 IN IP4 192.168.150.3 s=FreeSWITCH c=IN IP4 192.168.150.3 t=0 0 a=msid-semantic: WMS tA7iD69oTjc5eSeFAdyV3woEj3pT1LNl m=audio 25372 RTP/SAVPF 102 9 0 8 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=fingerprint:sha-256 A8:37:47:E9:6B:02:93:D6:06:AF:E4:10:72:A8:44:32:A2:EB:3D:03:E9:48:BC:9B:28:CA:59:4A:A6:B6:02:90 a=setup:actpass a=rtcp-mux a=rtcp:25372 IN IP4 192.168.150.3 a=ssrc:693004200 cname:bLuEHuTqEVmIDre3 a=ssrc:693004200 msid:tA7iD69oTjc5eSeFAdyV3woEj3pT1LNl a0 a=ssrc:693004200 mslabel:tA7iD69oTjc5eSeFAdyV3woEj3pT1LNl a=ssrc:693004200 label:tA7iD69oTjc5eSeFAdyV3woEj3pT1LNla0 a=ice-ufrag:vCPoY4T7Y8RPJv1L a=ice-pwd:7lfFFYWaK9IdqM7LfM03HJ1v a=candidate:4561521124 1 udp 659136 192.168.150.3 25372 typ host generation 0 a=candidate:4561521124 2 udp 659136 192.168.150.3 25372 typ host generation 0 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv m=video 25374 RTP/SAVPF 120 121 b=AS:1024 a=rtpmap:120 VP8/90000 a=fmtp:120 max-fs=12288;max-fr=60 a=rtpmap:121 VP9/90000 a=fmtp:121 max-fs=12288;max-fr=60 a=sendrecv a=fingerprint:sha-256 A8:37:47:E9:6B:02:93:D6:06:AF:E4:10:72:A8:44:32:A2:EB:3D:03:E9:48:BC:9B:28:CA:59:4A:A6:B6:02:90 a=setup:actpass a=rtcp-mux a=rtcp:25374 IN IP4 192.168.150.3 a=rtcp-fb:120 ccm fir a=rtcp-fb:120 ccm tmmbr a=rtcp-fb:120 nack a=rtcp-fb:120 nack pli a=rtcp-fb:121 ccm fir a=rtcp-fb:121 ccm tmmbr a=rtcp-fb:121 nack a=rtcp-fb:121 nack pli a=ssrc:4238951720 cname:bLuEHuTqEVmIDre3 a=ssrc:4238951720 msid:tA7iD69oTjc5eSeFAdyV3woEj3pT1LNl v0 a=ssrc:4238951720 mslabel:tA7iD69oTjc5eSeFAdyV3woEj3pT1LNl a=ssrc:4238951720 label:tA7iD69oTjc5eSeFAdyV3woEj3pT1LNlv0 a=ice-ufrag:PAutM4IWe0n0gpR8 a=ice-pwd:hDyJgkJt6QCMHEwm5QGHLeuP a=candidate:4750157512 1 udp 659136 192.168.150.3 25374 typ host generation 0 a=candidate:4750157512 2 udp 659135 192.168.150.3 25374 typ host generation 0 a=end-of-candidates 2017-06-21 17:45:04.757737 [NOTICE] mod_verto.c:2345 Ring-Ready verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d! 2017-06-21 17:45:04.757737 [DEBUG] switch_channel.c:3354 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Callstate Change DOWN -> RINGING 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:40 verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d Standard INIT 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State Change CS_INIT -> CS_ROUTING 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State INIT going to sleep 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Running State Change CS_ROUTING (Cur 2 Tot 57) 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State ROUTING 2017-06-21 17:45:04.757737 [DEBUG] mod_rtc.c:89 verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d RTC ROUTING 2017-06-21 17:45:04.757737 [DEBUG] switch_ivr_originate.c:67 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State ROUTING going to sleep 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 57) 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:662 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State CONSUME_MEDIA 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:662 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State CONSUME_MEDIA going to sleep 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [opus:109:48000:20:0:2]/[opus:116:48000:20:0:1] 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5168 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1] 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1] 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5029 Set telephone-event payload to 101@8000 2017-06-21 17:45:04.757737 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [120000bps] 2017-06-21 17:45:04.757737 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [120000bps] 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3428 Set Codec verto.rtc/1005 opus/48000 20 ms 960 samples 0 bits 2 channels 2017-06-21 17:45:04.757737 [DEBUG] switch_core_codec.c:111 verto.rtc/1005 Original read codec set to opus:116 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3858 Save audio Candidate cid: 1 proto: UDP type: host addr: 192.168.150.12:51494 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3858 Save audio Candidate cid: 2 proto: UDP type: host addr: 192.168.150.12:51495 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3858 Save audio Candidate cid: 1 proto: UDP type: srflx addr: 65.15.69.32:51494 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3858 Save audio Candidate cid: 2 proto: UDP type: srflx addr: 65.15.69.32:51495 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3900 Searching for rtp candidate. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3905 Choose rtp candidate, index 0, 192.168.150.12:51494 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3678 verto.rtc/1005 choosing family v4 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3916 Choose same candidate, index 2, for rtcp based on rtcp-mux attribute 192.168.150.12:51494 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3963 setting remote audio ice addr to index 0 192.168.150.12:51494 based on candidate 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3998 Setting remote rtcp audio addr to 192.168.150.12:51494 based on candidate 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5430 verto.rtc/1005 Set 2833 dtmf send payload to 101 recv payload to 101 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5713 Video Codec Compare [VP8:120]/[VP8:99] 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5745 Video Codec Compare [VP8:120] +++ is saved as a match 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5713 Video Codec Compare [VP9:121]/[VP8:99] 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5713 Video Codec Compare [H264:126]/[VP8:99] 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5713 Video Codec Compare [H264:97]/[VP8:99] 2017-06-21 17:45:04.757737 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 2017-06-21 17:45:04.757737 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3238 Set VIDEO Codec verto.rtc/1005 VP8/90000 0 ms 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3858 Save video Candidate cid: 1 proto: UDP type: host addr: 192.168.150.12:51496 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3858 Save video Candidate cid: 2 proto: UDP type: host addr: 192.168.150.12:51497 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3858 Save video Candidate cid: 1 proto: UDP type: srflx addr: 65.15.69.32:51496 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3858 Save video Candidate cid: 2 proto: UDP type: srflx addr: 65.15.69.32:51497 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3900 Searching for rtp candidate. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3905 Choose rtp candidate, index 0, 192.168.150.12:51496 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3678 verto.rtc/1005 choosing family v4 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3916 Choose same candidate, index 2, for rtcp based on rtcp-mux attribute 192.168.150.12:51496 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3963 setting remote video ice addr to index 0 192.168.150.12:51496 based on candidate 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3998 Setting remote rtcp video addr to 192.168.150.12:51496 based on candidate 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:8147 AUDIO RTP [verto.rtc/1005] 192.168.150.3 port 24600 -> 192.168.150.12 port 51494 codec: 109 ms: 20 2017-06-21 17:45:04.757737 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 960 bytes per 20ms 2017-06-21 17:45:04.757737 [INFO] switch_core_media.c:8326 Activating Audio ICE 2017-06-21 17:45:04.757737 [NOTICE] switch_rtp.c:4610 Activating RTP audio ICE: 9c79d98b:6S63hfj6seFahKmN 192.168.150.12:51494 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:8369 Activating RTCP PORT 51494 2017-06-21 17:45:04.757737 [DEBUG] switch_rtp.c:4507 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 51494 2017-06-21 17:45:04.757737 [INFO] switch_core_media.c:8377 Skipping RTCP ICE (Same as RTP) 2017-06-21 17:45:04.757737 [INFO] switch_rtp.c:3581 Activate RTP/RTCP audio DTLS client 2017-06-21 17:45:04.757737 [INFO] switch_rtp.c:3730 Changing audio DTLS state from OFF to HANDSHAKE 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:2190 Setting Jitterbuffer to 20ms (1 frames) (50 max frames) 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:8450 verto.rtc/1005 Set 2833 dtmf send payload to 101 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:8457 verto.rtc/1005 Set 2833 dtmf receive payload to 101 2017-06-21 17:45:04.757737 [DEBUG] switch_rtp.c:4126 Starting video timer. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:8975 VIDEO RTP [verto.rtc/1005] 192.168.150.3:29308->192.168.150.12:51496 codec: 120 ms: 0 [SUCCESS] 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:7139 verto.rtc/1005 Starting Video thread 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:7005 verto.rtc/1005 Video thread started. Echo is off 2017-06-21 17:45:04.757737 [INFO] switch_core_media.c:9017 Activating Video ICE 2017-06-21 17:45:04.757737 [NOTICE] switch_rtp.c:4610 Activating RTP video ICE: 9c79d98b:MOuVMxLyYGUb3veX 192.168.150.12:51496 2017-06-21 17:45:04.757737 [INFO] switch_core_media.c:9055 Activating VIDEO RTCP PORT 51496 interval 1000 mux 1 2017-06-21 17:45:04.757737 [DEBUG] switch_rtp.c:4507 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 51496 2017-06-21 17:45:04.757737 [INFO] switch_core_media.c:9066 Skipping VIDEO RTCP ICE (Same as VIDEO RTP) 2017-06-21 17:45:04.757737 [INFO] switch_rtp.c:3581 Activate RTP/RTCP video DTLS client 2017-06-21 17:45:04.757737 [INFO] switch_rtp.c:3730 Changing video DTLS state from OFF to HANDSHAKE 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:8130 Audio params are unchanged for verto.rtc/1005. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:8833 Video params are unchanged for verto.rtc/1005. 2017-06-21 17:45:04.757737 [DEBUG] mod_verto.c:2480 Local SDP verto.rtc/1005: v=0 o=FreeSWITCH 1498056904 1498056905 IN IP4 192.168.150.3 s=FreeSWITCH c=IN IP4 192.168.150.3 t=0 0 a=msid-semantic: WMS EBIGD7cdXgAKtazIII947yipZn5JRdem m=audio 24600 UDP/TLS/RTP/SAVPF 109 101 a=rtpmap:109 opus/48000/2 a=fmtp:109 useinbandfec=1; stereo=1; sprop-stereo=1 a=rtpmap:101 telephone-event/8000 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv a=fingerprint:sha-256 A8:37:47:E9:6B:02:93:D6:06:AF:E4:10:72:A8:44:32:A2:EB:3D:03:E9:48:BC:9B:28:CA:59:4A:A6:B6:02:90 a=setup:active a=rtcp-mux a=rtcp:24600 IN IP4 192.168.150.3 a=ice-ufrag:6S63hfj6seFahKmN a=ice-pwd:n5LYQNlHgjSKHBMDkJxrOGo6 a=candidate:1477249693 1 udp 659136 192.168.150.3 24600 typ host generation 0 a=end-of-candidates a=ssrc:89116584 cname:FNS8KUcx1C7kDxSc a=ssrc:89116584 msid:EBIGD7cdXgAKtazIII947yipZn5JRdem a0 a=ssrc:89116584 mslabel:EBIGD7cdXgAKtazIII947yipZn5JRdem a=ssrc:89116584 label:EBIGD7cdXgAKtazIII947yipZn5JRdema0 m=video 29308 UDP/TLS/RTP/SAVPF 120 b=AS:1024 a=rtpmap:120 VP8/90000 a=fmtp:120 max-fs=12288;max-fr=60 a=sendrecv a=fingerprint:sha-256 A8:37:47:E9:6B:02:93:D6:06:AF:E4:10:72:A8:44:32:A2:EB:3D:03:E9:48:BC:9B:28:CA:59:4A:A6:B6:02:90 a=setup:active a=rtcp-mux a=rtcp:29308 IN IP4 192.168.150.3 a=rtcp-fb:120 ccm fir a=rtcp-fb:120 nack a=rtcp-fb:120 nack pli a=ssrc:3635064104 cname:FNS8KUcx1C7kDxSc a=ssrc:3635064104 msid:EBIGD7cdXgAKtazIII947yipZn5JRdem v0 a=ssrc:3635064104 mslabel:EBIGD7cdXgAKtazIII947yipZn5JRdem a=ssrc:3635064104 label:EBIGD7cdXgAKtazIII947yipZn5JRdemv0 a=ice-ufrag:MOuVMxLyYGUb3veX a=ice-pwd:5Ew4sXKZMmuIAJdcnyXZVzoK a=candidate:0268092962 1 udp 659136 192.168.150.3 29308 typ host generation 0 a=end-of-candidates 2017-06-21 17:45:04.757737 [NOTICE] switch_ivr_originate.c:1220 Pre-Answer verto.rtc/1005! 2017-06-21 17:45:04.767737 [DEBUG] switch_channel.c:3482 (verto.rtc/1005) Callstate Change RINGING -> EARLY 2017-06-21 17:45:04.767737 [DEBUG] switch_ivr_originate.c:1278 Raw Codec Activation Success L16@48000hz 2 channel 20ms 2017-06-21 17:45:04.767737 [DEBUG] switch_core_codec.c:223 verto.rtc/1005 Push codec L16:100 2017-06-21 17:45:04.767737 [DEBUG] switch_ivr_originate.c:1347 Play Ringback Tone [%(2000,4000,440,480)] 2017-06-21 17:45:04.817767 [NOTICE] mod_verto.c:2807 Hangup verto.rtc/1005 [CS_EXECUTE] [NORMAL_CLEARING] 2017-06-21 17:45:04.817767 [DEBUG] switch_core_codec.c:248 verto.rtc/1005 Restore previous codec opus:116. 2017-06-21 17:45:04.817767 [NOTICE] switch_ivr_originate.c:3617 Hangup verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL] 2017-06-21 17:45:04.817767 [DEBUG] switch_ivr_originate.c:3842 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL] 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Running State Change CS_HANGUP (Cur 2 Tot 57) 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:850 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Callstate Change RINGING -> HANGUP 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State HANGUP 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:60 verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d Standard HANGUP, cause: ORIGINATOR_CANCEL 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State HANGUP going to sleep 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:619 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State Change CS_HANGUP -> CS_REPORTING 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Running State Change CS_REPORTING (Cur 2 Tot 57) 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State REPORTING 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:174 verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d Standard REPORTING, cause: ORIGINATOR_CANCEL 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State REPORTING going to sleep 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:610 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State Change CS_REPORTING -> CS_DESTROY 2017-06-21 17:45:04.817767 [DEBUG] switch_core_session.c:1712 Session 57 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Locked, Waiting on external entities 2017-06-21 17:45:04.817767 [NOTICE] switch_core_session.c:1730 Session 57 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Ended 2017-06-21 17:45:04.817767 [NOTICE] switch_ivr_originate.c:2856 Cannot create outgoing channel of type [user] cause: [ORIGINATOR_CANCEL] 2017-06-21 17:45:04.817767 [NOTICE] switch_core_session.c:1734 Close Channel verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d [CS_DESTROY] 2017-06-21 17:45:04.817767 [DEBUG] switch_ivr_originate.c:3853 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL] 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:741 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Running State Change CS_DESTROY (Cur 1 Tot 57) 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State DESTROY 2017-06-21 17:45:04.817767 [DEBUG] mod_rtc.c:132 verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d RTC DESTROY 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:181 verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d Standard DESTROY 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State DESTROY going to sleep 2017-06-21 17:45:04.817767 [INFO] mod_dptools.c:3490 Originate Failed. Cause: ORIGINATOR_CANCEL 2017-06-21 17:45:04.817767 [DEBUG] switch_core_session.c:2884 verto.rtc/1005 skip receive message [PHONE_EVENT] (channel is hungup already) 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:650 (verto.rtc/1005) State EXECUTE going to sleep 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1005) Running State Change CS_HANGUP (Cur 1 Tot 57) 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:850 (verto.rtc/1005) Callstate Change EARLY -> HANGUP 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/1005) State HANGUP 2017-06-21 17:45:04.817767 [DEBUG] switch_core_media.c:13746 verto.rtc/1005 skip receive message [DISPLAY] (channel is hungup already) 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:60 verto.rtc/1005 Standard HANGUP, cause: NORMAL_CLEARING 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/1005) State HANGUP going to sleep 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:619 (verto.rtc/1005) State Change CS_HANGUP -> CS_REPORTING 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1005) Running State Change CS_REPORTING (Cur 1 Tot 57) 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/1005) State REPORTING 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:174 verto.rtc/1005 Standard REPORTING, cause: NORMAL_CLEARING 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/1005) State REPORTING going to sleep 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:610 (verto.rtc/1005) State Change CS_REPORTING -> CS_DESTROY 2017-06-21 17:45:04.817767 [DEBUG] switch_core_session.c:1712 Session 56 (verto.rtc/1005) Locked, Waiting on external entities 2017-06-21 17:45:04.827747 [DEBUG] switch_core_media.c:7106 verto.rtc/1005 Video thread ended 2017-06-21 17:45:04.827747 [NOTICE] switch_core_session.c:1730 Session 56 (verto.rtc/1005) Ended 2017-06-21 17:45:04.827747 [NOTICE] switch_core_session.c:1734 Close Channel verto.rtc/1005 [CS_DESTROY] 2017-06-21 17:45:04.827747 [DEBUG] switch_core_state_machine.c:741 (verto.rtc/1005) Running State Change CS_DESTROY (Cur 0 Tot 57) 2017-06-21 17:45:04.827747 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/1005) State DESTROY 2017-06-21 17:45:04.827747 [DEBUG] mod_rtc.c:132 verto.rtc/1005 RTC DESTROY 2017-06-21 17:45:04.827747 [DEBUG] mod_opus.c:711 Opus decoder stats: Frames[0] PLC[0] FEC[0] 2017-06-21 17:45:04.827747 [DEBUG] mod_opus.c:726 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0] 2017-06-21 17:45:04.827747 [DEBUG] mod_opus.c:711 Opus decoder stats: Frames[0] PLC[0] FEC[0] 2017-06-21 17:45:04.827747 [DEBUG] mod_opus.c:726 Opus encoder stats: Frames[2] Bytes encoded[427] Encoded length ms[40] Average encoded bitrate bps[0] 2017-06-21 17:45:04.827747 [DEBUG] switch_core_state_machine.c:181 verto.rtc/1005 Standard DESTROY 2017-06-21 17:45:04.827747 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/1005) State DESTROY going to sleep