Убрал рекомендуе строки из диал плана.
Лог получился такой
2015-12-08 15:49:10.877978 [NOTICE] switch_channel.c:1091 New Channel sofia/supptechttk/
20...@sip.svttk.ru [4eb54ea8-1165-411b-bf5c-afbc3f591ad8]
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:473 (sofia/supptechttk/
20...@sip.svttk.ru) Running State Change CS_NEW
2015-12-08 15:49:10.877978 [DEBUG] sofia.c:9240 sofia/supptechttk/
20...@sip.svttk.ru receiving invite from
10.10.50.240:5060 version: 1.6.5 git 70b8c17 2015-11-20 20:57:50Z 64bit
2015-12-08 15:49:10.877978 [DEBUG] sofia.c:6750 Channel sofia/supptechttk/
20...@sip.svttk.ru entering state [received][100]
2015-12-08 15:49:10.877978 [DEBUG] sofia.c:6760 Remote SDP:
v=0
o=Zoiper_user 0 0 IN IP4 10.10.50.240
s=Zoiper_session
c=IN IP4 10.10.50.240
t=0 0
m=audio 8000 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
2015-12-08 15:49:10.877978 [DEBUG] sofia.c:7115 (sofia/supptechttk/
20...@sip.svttk.ru) State Change CS_NEW -> CS_INIT
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:492 (sofia/supptechttk/
20...@sip.svttk.ru) State NEW
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:473 (sofia/supptechttk/
20...@sip.svttk.ru) Running State Change CS_INIT
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:516 (sofia/supptechttk/
20...@sip.svttk.ru) State INIT
2015-12-08 15:49:10.877978 [DEBUG] mod_sofia.c:88 sofia/supptechttk/
20...@sip.svttk.ru SOFIA INIT
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:40 sofia/supptechttk/
20...@sip.svttk.ru Standard INIT
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:48 (sofia/supptechttk/
20...@sip.svttk.ru) State Change CS_INIT -> CS_ROUTING
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:516 (sofia/supptechttk/
20...@sip.svttk.ru) State INIT going to sleep
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:473 (sofia/supptechttk/
20...@sip.svttk.ru) Running State Change CS_ROUTING
2015-12-08 15:49:10.877978 [DEBUG] switch_channel.c:2239 (sofia/supptechttk/
20...@sip.svttk.ru) Callstate Change DOWN -> RINGING
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:532 (sofia/supptechttk/
20...@sip.svttk.ru) State ROUTING
2015-12-08 15:49:10.877978 [DEBUG] mod_sofia.c:141 sofia/supptechttk/
20...@sip.svttk.ru SOFIA ROUTING
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:166 sofia/supptechttk/
20...@sip.svttk.ru Standard ROUTING
2015-12-08 15:49:10.877978 [INFO] mod_dialplan_xml.c:637 Processing 2003 <2003>->9735050 in context support_svttk
Dialplan: sofia/supptechttk/
20...@sip.svttk.ru parsing [support_svttk->unloop] continue=false
Dialplan: sofia/supptechttk/
20...@sip.svttk.ru Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/supptechttk/
20...@sip.svttk.ru Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/supptechttk/
20...@sip.svttk.ru parsing [support_svttk->call_internal] continue=false
Dialplan: sofia/supptechttk/
20...@sip.svttk.ru Regex (FAIL) [call_internal] destination_number(9735050) =~ /^([1-2][0-9]{3})$/ break=on-false
Dialplan: sofia/supptechttk/
20...@sip.svttk.ru parsing [support_svttk->call_in] continue=false
Dialplan: sofia/supptechttk/
20...@sip.svttk.ru Regex (FAIL) [call_in] destination_number(9735050) =~ /^(2056598)$/ break=on-false
Dialplan: sofia/supptechttk/
20...@sip.svttk.ru parsing [support_svttk->call_out] continue=false
Dialplan: sofia/supptechttk/
20...@sip.svttk.ru Regex (PASS) [call_out] destination_number(9735050) =~ /^([2-9][0-9]{6})$/ break=on-false
Dialplan: sofia/supptechttk/
20...@sip.svttk.ru Action bridge(sofia/gateway/samara-ast1/9735050)
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:216 (sofia/supptechttk/
20...@sip.svttk.ru) State Change CS_ROUTING -> CS_EXECUTE
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:532 (sofia/supptechttk/
20...@sip.svttk.ru) State ROUTING going to sleep
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:473 (sofia/supptechttk/
20...@sip.svttk.ru) Running State Change CS_EXECUTE
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:539 (sofia/supptechttk/
20...@sip.svttk.ru) State EXECUTE
2015-12-08 15:49:10.877978 [DEBUG] mod_sofia.c:196 sofia/supptechttk/
20...@sip.svttk.ru SOFIA EXECUTE
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:258 sofia/supptechttk/
20...@sip.svttk.ru Standard EXECUTE
2015-12-08 15:49:10.877978 [DEBUG] mod_dptools.c:1498 SET sofia/supptechttk/
20...@sip.svttk.ru [hangup_after_bridge]=[true]
2015-12-08 15:49:10.877978 [DEBUG] mod_dptools.c:1498 SET sofia/supptechttk/
20...@sip.svttk.ru [continue_on_fail]=[true]
2015-12-08 15:49:10.877978 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables
2015-12-08 15:49:10.877978 [NOTICE] switch_channel.c:1091 New Channel sofia/supptechttk/9735050 [898b5720-1482-4e7d-9d47-dcc8ae36f182]
2015-12-08 15:49:10.877978 [DEBUG] mod_sofia.c:4765 (sofia/supptechttk/9735050) State Change CS_NEW -> CS_INIT
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:473 (sofia/supptechttk/9735050) Running State Change CS_INIT
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:516 (sofia/supptechttk/9735050) State INIT
2015-12-08 15:49:10.877978 [DEBUG] mod_sofia.c:88 sofia/supptechttk/9735050 SOFIA INIT
2015-12-08 15:49:10.877978 [DEBUG] sofia_glue.c:1257 sofia/supptechttk/9735050 sending invite version: 1.6.5 git 70b8c17 2015-11-20 20:57:50Z 64bit
Local SDP:
v=0
o=FreeSWITCH 1449557914 1449557915 IN IP4 10.200.16.215
s=FreeSWITCH
c=IN IP4 10.200.16.215
t=0 0
m=audio 21036 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:40 sofia/supptechttk/9735050 Standard INIT
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:48 (sofia/supptechttk/9735050) State Change CS_INIT -> CS_ROUTING
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:516 (sofia/supptechttk/9735050) State INIT going to sleep
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:473 (sofia/supptechttk/9735050) Running State Change CS_ROUTING
2015-12-08 15:49:10.877978 [DEBUG] sofia.c:6750 Channel sofia/supptechttk/9735050 entering state [calling][0]
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:532 (sofia/supptechttk/9735050) State ROUTING
2015-12-08 15:49:10.877978 [DEBUG] mod_sofia.c:141 sofia/supptechttk/9735050 SOFIA ROUTING
2015-12-08 15:49:10.877978 [DEBUG] switch_ivr_originate.c:67 (sofia/supptechttk/9735050) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:532 (sofia/supptechttk/9735050) State ROUTING going to sleep
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:473 (sofia/supptechttk/9735050) Running State Change CS_CONSUME_MEDIA
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:551 (sofia/supptechttk/9735050) State CONSUME_MEDIA
2015-12-08 15:49:10.877978 [DEBUG] switch_core_state_machine.c:551 (sofia/supptechttk/9735050) State CONSUME_MEDIA going to sleep
2015-12-08 15:49:10.997983 [DEBUG] sofia.c:6750 Channel sofia/supptechttk/9735050 entering state [proceeding][183]
2015-12-08 15:49:10.997983 [DEBUG] sofia.c:6760 Remote SDP:
v=0
o=root 33551767 33551767 IN IP4 10.200.104.11
s=Asterisk PBX 1.8.20.1
c=IN IP4 10.200.104.11
t=0 0
m=audio 11982 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4153 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4153 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4208 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4153 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4208 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4153 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4069 Set telephone-event payload to 101@8000
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:2898 Set Codec sofia/supptechttk/9735050 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2015-12-08 15:49:10.997983 [DEBUG] switch_core_codec.c:111 sofia/supptechttk/9735050 Original read codec set to PCMA:8
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4417 Set telephone-event payload to 101@8000
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4473 sofia/supptechttk/9735050 Set 2833 dtmf send payload to 101 recv payload to 101
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:6027 AUDIO RTP [sofia/supptechttk/9735050] 10.200.16.215 port 21036 -> 10.200.104.11 port 11982 codec: 8 ms: 20
2015-12-08 15:49:10.997983 [DEBUG] switch_rtp.c:3788 Starting timer [soft] 160 bytes per 20ms
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:1939 Setting Jitterbuffer to 60ms (3 frames) (50 max frames)
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:6326 sofia/supptechttk/9735050 Set 2833 dtmf send payload to 101
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:6333 sofia/supptechttk/9735050 Set 2833 dtmf receive payload to 101
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:6356 sofia/supptechttk/9735050 Set rtp dtmf delay to 40
2015-12-08 15:49:10.997983 [NOTICE] sofia_media.c:92 Pre-Answer sofia/supptechttk/9735050!
2015-12-08 15:49:10.997983 [DEBUG] switch_channel.c:3460 (sofia/supptechttk/9735050) Callstate Change DOWN -> EARLY
2015-12-08 15:49:10.997983 [INFO] switch_ivr_originate.c:3556 Sending early media
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4153 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4153 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4153 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4208 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4153 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4153 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4153 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4153 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4153 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4208 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4069 Set telephone-event payload to 101@8000
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:2898 Set Codec sofia/supptechttk/
20...@sip.svttk.ru PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2015-12-08 15:49:10.997983 [DEBUG] switch_core_codec.c:111 sofia/supptechttk/
20...@sip.svttk.ru Original read codec set to PCMU:0
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4417 Set telephone-event payload to 101@8000
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:4473 sofia/supptechttk/
20...@sip.svttk.ru Set 2833 dtmf send payload to 101 recv payload to 101
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:6027 AUDIO RTP [sofia/supptechttk/
20...@sip.svttk.ru] 10.200.16.215 port 21374 -> 10.10.50.240 port 8000 codec: 0 ms: 20
2015-12-08 15:49:10.997983 [DEBUG] switch_rtp.c:3788 Starting timer [soft] 160 bytes per 20ms
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:1939 Setting Jitterbuffer to 60ms (3 frames) (50 max frames)
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:6326 sofia/supptechttk/
20...@sip.svttk.ru Set 2833 dtmf send payload to 101
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:6333 sofia/supptechttk/
20...@sip.svttk.ru Set 2833 dtmf receive payload to 101
2015-12-08 15:49:10.997983 [DEBUG] switch_core_media.c:6356 sofia/supptechttk/
20...@sip.svttk.ru Set rtp dtmf delay to 40
2015-12-08 15:49:10.997983 [NOTICE] sofia_media.c:92 Pre-Answer sofia/supptechttk/
20...@sip.svttk.ru!
2015-12-08 15:49:10.997983 [DEBUG] switch_channel.c:3460 (sofia/supptechttk/
20...@sip.svttk.ru) Callstate Change RINGING -> EARLY
2015-12-08 15:49:11.017970 [DEBUG] mod_sofia.c:2320 Ring SDP:
v=0
o=FreeSWITCH 1449557577 1449557578 IN IP4 10.200.16.215
s=FreeSWITCH
c=IN IP4 10.200.16.215
t=0 0
m=audio 21374 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
2015-12-08 15:49:11.017970 [DEBUG] sofia.c:6750 Channel sofia/supptechttk/
20...@sip.svttk.ru entering state [early][183]
2015-12-08 15:49:11.017970 [DEBUG] switch_ivr_originate.c:3607 Originate Resulted in Success: [sofia/supptechttk/9735050]
2015-12-08 15:49:11.017970 [DEBUG] switch_core_media.c:9118 sofia/supptechttk/9735050 PAUSE Jitterbuffer
2015-12-08 15:49:11.017970 [DEBUG] switch_core_media.c:9118 sofia/supptechttk/
20...@sip.svttk.ru PAUSE Jitterbuffer
2015-12-08 15:49:11.017970 [DEBUG] switch_ivr_bridge.c:1591 (sofia/supptechttk/9735050) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2015-12-08 15:49:11.017970 [DEBUG] switch_core_state_machine.c:473 (sofia/supptechttk/9735050) Running State Change CS_EXCHANGE_MEDIA
2015-12-08 15:49:11.017970 [DEBUG] switch_core_state_machine.c:542 (sofia/supptechttk/9735050) State EXCHANGE_MEDIA
2015-12-08 15:49:11.017970 [DEBUG] mod_sofia.c:613 SOFIA EXCHANGE_MEDIA
2015-12-08 15:49:11.037969 [DEBUG] switch_rtp.c:6640 Correct audio ip/port confirmed.
2015-12-08 15:49:11.097969 [DEBUG] switch_rtp.c:6640 Correct audio ip/port confirmed.
2015-12-08 15:49:11.257969 [DEBUG] sofia.c:6750 Channel sofia/supptechttk/9735050 entering state [proceeding][180]
2015-12-08 15:49:11.257969 [NOTICE] sofia.c:6852 Ring-Ready sofia/supptechttk/9735050!
2015-12-08 15:49:11.257969 [DEBUG] switch_channel.c:3332 (sofia/supptechttk/9735050) Callstate Change EARLY -> RINGING
2015-12-08 15:49:11.257969 [DEBUG] sofia.c:6750 Channel sofia/supptechttk/9735050 entering state [completing][200]
2015-12-08 15:49:11.257969 [DEBUG] sofia.c:6760 Remote SDP:
v=0
o=root 33551767 33551768 IN IP4 10.200.104.11
s=Asterisk PBX 1.8.20.1
c=IN IP4 10.200.104.11
t=0 0
m=audio 11982 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
2015-12-08 15:49:11.257969 [DEBUG] sofia.c:6750 Channel sofia/supptechttk/9735050 entering state [ready][200]
2015-12-08 15:49:11.257969 [NOTICE] sofia.c:7655 Channel [sofia/supptechttk/9735050] has been answered
2015-12-08 15:49:11.257969 [DEBUG] switch_channel.c:3759 (sofia/supptechttk/9735050) Callstate Change RINGING -> ACTIVE
2015-12-08 15:49:11.257969 [DEBUG] mod_sofia.c:799 Local SDP sofia/supptechttk/
20...@sip.svttk.ru:
v=0
o=FreeSWITCH 1449557577 1449557579 IN IP4 10.200.16.215
s=FreeSWITCH
c=IN IP4 10.200.16.215
t=0 0
m=audio 21374 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
2015-12-08 15:49:11.257969 [DEBUG] sofia.c:6750 Channel sofia/supptechttk/
20...@sip.svttk.ru entering state [completed][200]
2015-12-08 15:49:11.257969 [NOTICE] switch_ivr_bridge.c:616 Channel [sofia/supptechttk/
20...@sip.svttk.ru] has been answered
2015-12-08 15:49:11.257969 [DEBUG] switch_channel.c:3759 (sofia/supptechttk/
20...@sip.svttk.ru) Callstate Change EARLY -> ACTIVE
2015-12-08 15:49:11.277969 [DEBUG] sofia.c:6750 Channel sofia/supptechttk/
20...@sip.svttk.ru entering state [ready][200]
2015-12-08 15:49:11.297980 [DEBUG] switch_rtp.c:6640 Correct audio ip/port confirmed.
2015-12-08 15:49:11.317969 [DEBUG] switch_rtp.c:6640 Correct audio ip/port confirmed.
2015-12-08 15:49:16.278129 [DEBUG] switch_rtp.c:6859 RTP RECV DTMF 3:960
2015-12-08 15:49:16.278129 [INFO] switch_channel.c:502 RECV DTMF 3:960
Диаллан теперь выглядит так:
<extension name="call_out">