freeswitchforum.com

Добрый вечер.

зарегистрировал транк на фрисвитче. Настроил рутинг на ivr. Попадает в ivr и после до набора начинает звонить внутренний номер.если со стороны звонящего дается отбой то фс его не видит и телефон звенит.


<gateway name="G4">
<param name="from-domain" value="172.16.122.55"/>
<param name="proxy" value="172.16.122.55" />
<param name="realm" value="172.16.122.55"/>
<param name="username" value="2000"/>
<param name="password" value="pincode2000"/>
<param name="register" value="true"/>
<param name="caller-id-in-from" value="true"/>
<param name="dtmf-type" value="rfc2833"/>

2016-07-01 18:49:39.795789 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/+994774711191@172.16.122.55 [09c8aa5c-3f9b-11e6-9440-ab0bf946bd1a]
2016-07-01 18:49:39.795789 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/+994774711191@172.16.122.55) Running State Change CS_NEW
2016-07-01 18:49:39.795789 [DEBUG] sofia.c:9520 sofia/internal/+994774711191@172.16.122.55 receiving invite from 172.16.122.55:5060 version: 1.7.0 git dc13f59 2016-06-20 15:59:04Z 64bit
2016-07-01 18:49:39.795789 [DEBUG] sofia.c:9632 IP 172.16.122.55 Approved by acl "172.16.0.0/16[]". Access Granted.
2016-07-01 18:49:39.795789 [DEBUG] sofia.c:6939 Channel sofia/internal/+994774711191@172.16.122.55 entering state [received][100]
2016-07-01 18:49:39.795789 [DEBUG] sofia.c:6949 Remote SDP:
v=0
o=root 2145074859 2145074859 IN IP4 172.16.122.55
s=Asterisk PBX 1.6.2.6
c=IN IP4 172.16.122.55
t=0 0
m=audio 11666 RTP/AVP 0 8 3 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

2016-07-01 18:49:39.795789 [DEBUG] sofia.c:7317 (sofia/internal/+994774711191@172.16.122.55) State Change CS_NEW -> CS_INIT
2016-07-01 18:49:39.795789 [DEBUG] switch_core_state_machine.c:562 (sofia/internal/+994774711191@172.16.122.55) State NEW
2016-07-01 18:49:39.795789 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/+994774711191@172.16.122.55) Running State Change CS_INIT
2016-07-01 18:49:39.795789 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/+994774711191@172.16.122.55) State INIT
2016-07-01 18:49:39.795789 [DEBUG] mod_sofia.c:89 sofia/internal/+994774711191@172.16.122.55 SOFIA INIT
2016-07-01 18:49:39.795789 [DEBUG] switch_core_state_machine.c:40 sofia/internal/+994774711191@172.16.122.55 Standard INIT
2016-07-01 18:49:39.795789 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/+994774711191@172.16.122.55) State Change CS_INIT -> CS_ROUTING
2016-07-01 18:49:39.795789 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/+994774711191@172.16.122.55) State INIT going to sleep
2016-07-01 18:49:39.795789 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/+994774711191@172.16.122.55) Running State Change CS_ROUTING
2016-07-01 18:49:39.795789 [DEBUG] switch_channel.c:2249 (sofia/internal/+994774711191@172.16.122.55) Callstate Change DOWN -> RINGING
2016-07-01 18:49:39.795789 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/+994774711191@172.16.122.55) State ROUTING
2016-07-01 18:49:39.795789 [DEBUG] mod_sofia.c:142 sofia/internal/+994774711191@172.16.122.55 SOFIA ROUTING
2016-07-01 18:49:39.795789 [DEBUG] switch_core_state_machine.c:236 sofia/internal/+994774711191@172.16.122.55 Standard ROUTING
2016-07-01 18:49:39.795789 [INFO] mod_dialplan_xml.c:637 Processing +994774711191 <+994774711191>->4444 in context prospect.loc
Dialplan: sofia/internal/+994774711191@172.16.122.55 parsing [prospect.loc->from_3101212_pronet] continue=false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Regex (FAIL) [from_3101212_pronet] destination_number(4444) =~ /^(1212.)$/ break=on-false
Dialplan: sofia/internal/+994774711191@172.16.122.55 parsing [prospect.loc->from_3101221_grandstream] continue=false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Regex (FAIL) [from_3101221_grandstream] destination_number(4444) =~ /^(1221.)$/ break=on-false
Dialplan: sofia/internal/+994774711191@172.16.122.55 parsing [prospect.loc->from_3101112_service] continue=false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Regex (FAIL) [from_3101112_service] destination_number(4444) =~ /^(1112.)$/ break=on-false
Dialplan: sofia/internal/+994774711191@172.16.122.55 parsing [prospect.loc->from_3102121_prospect] continue=false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Regex (FAIL) [from_3102121_prospect] destination_number(4444) =~ /^(2121.)$/ break=on-false
Dialplan: sofia/internal/+994774711191@172.16.122.55 parsing [prospect.loc->from_3103131_call_center] continue=false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Regex (FAIL) [from_3103131_call_center] destination_number(4444) =~ /^(3131.)$/ break=on-false
Dialplan: sofia/internal/+994774711191@172.16.122.55 parsing [prospect.loc->centron] continue=false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Regex (FAIL) [centron] destination_number(4444) =~ /^2064$/ break=on-false
Dialplan: sofia/internal/+994774711191@172.16.122.55 parsing [prospect.loc->Local_Extension_100] continue=false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Regex (FAIL) [Local_Extension_100] destination_number(4444) =~ /^(100)$/ break=on-false
Dialplan: sofia/internal/+994774711191@172.16.122.55 parsing [prospect.loc->Local_Extension] continue=false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Regex (FAIL) [Local_Extension] destination_number(4444) =~ /^(9[0-9][0-9])$/ break=on-false
Dialplan: sofia/internal/+994774711191@172.16.122.55 parsing [prospect.loc->start_pronet] continue=false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Regex (PASS) [start_pronet] destination_number(4444) =~ /^(4444)$/ break=on-false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Action answer()
Dialplan: sofia/internal/+994774711191@172.16.122.55 Action start_dtmf()
Dialplan: sofia/internal/+994774711191@172.16.122.55 Action sleep(1000)
Dialplan: sofia/internal/+994774711191@172.16.122.55 Action ivr(pronet)
Dialplan: sofia/internal/+994774711191@172.16.122.55 Action set(hangup_after_bridge=true)
2016-07-01 18:49:39.795789 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/+994774711191@172.16.122.55) State Change CS_ROUTING -> CS_EXECUTE
2016-07-01 18:49:39.795789 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/+994774711191@172.16.122.55) State ROUTING going to sleep
2016-07-01 18:49:39.795789 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/+994774711191@172.16.122.55) Running State Change CS_EXECUTE
2016-07-01 18:49:39.795789 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/+994774711191@172.16.122.55) State EXECUTE
2016-07-01 18:49:39.795789 [DEBUG] mod_sofia.c:197 sofia/internal/+994774711191@172.16.122.55 SOFIA EXECUTE
2016-07-01 18:49:39.795789 [DEBUG] switch_core_state_machine.c:328 sofia/internal/+994774711191@172.16.122.55 Standard EXECUTE
EXECUTE sofia/internal/+994774711191@172.16.122.55 answer()
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:4420 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:4420 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [GSM:3:8000:20:13200:1]/[GSM:3:8000:20:13200:1]
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:4420 Audio Codec Compare [GSM:3:8000:20:13200:1] ++++ is saved as a match
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:4281 Set telephone-event payload to 101@8000
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:3091 Set Codec sofia/internal/+994774711191@172.16.122.55 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2016-07-01 18:49:39.795789 [DEBUG] switch_core_codec.c:111 sofia/internal/+994774711191@172.16.122.55 Original read codec set to PCMU:0
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:4634 Set telephone-event payload to 101@8000
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:4693 sofia/internal/+994774711191@172.16.122.55 Set 2833 dtmf send payload to 101 recv payload to 101
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:6496 AUDIO RTP [sofia/internal/+994774711191@172.16.122.55] 172.16.200.201 port 27810 -> 172.16.122.55 port 11666 codec: 0 ms: 20
2016-07-01 18:49:39.795789 [DEBUG] switch_rtp.c:3832 Starting timer [soft] 160 bytes per 20ms
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:6795 sofia/internal/+994774711191@172.16.122.55 Set 2833 dtmf send payload to 101
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:6802 sofia/internal/+994774711191@172.16.122.55 Set 2833 dtmf receive payload to 101
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media.c:6825 sofia/internal/+994774711191@172.16.122.55 Set rtp dtmf delay to 40
2016-07-01 18:49:39.795789 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/+994774711191@172.16.122.55!
2016-07-01 18:49:39.795789 [DEBUG] switch_channel.c:3471 (sofia/internal/+994774711191@172.16.122.55) Callstate Change RINGING -> EARLY
2016-07-01 18:49:39.795789 [DEBUG] mod_sofia.c:821 Local SDP sofia/internal/+994774711191@172.16.122.55:
v=0
o=FreeSWITCH 1467356769 1467356770 IN IP4 172.16.200.201
s=FreeSWITCH
c=IN IP4 172.16.200.201
t=0 0
m=audio 27810 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

2016-07-01 18:49:39.795789 [NOTICE] mod_dptools.c:1309 Channel [sofia/internal/+994774711191@172.16.122.55] has been answered
2016-07-01 18:49:39.795789 [DEBUG] switch_channel.c:3770 (sofia/internal/+994774711191@172.16.122.55) Callstate Change EARLY -> ACTIVE
2016-07-01 18:49:39.795789 [DEBUG] sofia.c:6939 Channel sofia/internal/+994774711191@172.16.122.55 entering state [completed][200]
EXECUTE sofia/internal/+994774711191@172.16.122.55 start_dtmf()
2016-07-01 18:49:39.795789 [DEBUG] switch_core_media_bug.c:828 Attaching BUG to sofia/internal/+994774711191@172.16.122.55
EXECUTE sofia/internal/+994774711191@172.16.122.55 sleep(1000)
2016-07-01 18:49:39.815793 [DEBUG] sofia.c:6939 Channel sofia/internal/+994774711191@172.16.122.55 entering state [ready][200]
2016-07-01 18:49:39.855786 [DEBUG] switch_rtp.c:6711 Correct audio ip/port confirmed.
2016-07-01 18:49:39.855786 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMU:0
EXECUTE sofia/internal/+994774711191@172.16.122.55 ivr(pronet)
2016-07-01 18:49:40.795801 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-exit'
2016-07-01 18:49:40.795801 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-sub'
2016-07-01 18:49:40.795801 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-exec-app'
2016-07-01 18:49:40.795801 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-play-sound'
2016-07-01 18:49:40.795801 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-back'
2016-07-01 18:49:40.795801 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-top'
2016-07-01 18:49:40.795801 [DEBUG] switch_ivr_menu.c:880 building menu 'pronet'
2016-07-01 18:49:40.795801 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '/^([0-9][0-9][0-9])$/'
2016-07-01 18:49:40.795801 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '0'
2016-07-01 18:49:40.795801 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-top' to '9'
2016-07-01 18:49:40.795801 [DEBUG] switch_ivr_menu.c:483 Executing IVR menu pronet
2016-07-01 18:49:40.795801 [DEBUG] switch_ivr_play_say.c:1467 Codec Activated L16@8000hz 1 channels 20ms
2016-07-01 18:49:43.775787 [DEBUG] switch_rtp.c:6931 RTP RECV DTMF 9:1440
2016-07-01 18:49:43.775787 [INFO] switch_channel.c:515 RECV DTMF 9:1440
2016-07-01 18:49:43.775787 [DEBUG] switch_ivr_play_say.c:1910 done playing file /usr/local/freeswitch/recordings/sounds/pronet/pronet.gsm
2016-07-01 18:49:43.775787 [DEBUG] switch_ivr_menu.c:377 waiting for 2/3 digits t/o 2000
2016-07-01 18:49:44.375805 [DEBUG] switch_rtp.c:6931 RTP RECV DTMF 9:480
2016-07-01 18:49:44.375805 [INFO] switch_channel.c:515 RECV DTMF 9:480
2016-07-01 18:49:45.055790 [DEBUG] switch_rtp.c:6931 RTP RECV DTMF 9:480
2016-07-01 18:49:45.055790 [INFO] switch_channel.c:515 RECV DTMF 9:480
2016-07-01 18:49:45.055790 [DEBUG] switch_ivr_menu.c:424 digits '999'
2016-07-01 18:49:45.055790 [DEBUG] switch_ivr_menu.c:559 action regex [999] [/^([0-9][0-9][0-9])$/] [2]
2016-07-01 18:49:45.055790 [DEBUG] switch_ivr_menu.c:577 IVR action on menu 'pronet' matched '999' param 'transfer 999 XML prospect.loc'
2016-07-01 18:49:45.055790 [DEBUG] switch_ivr_menu.c:581 switch_ivr_menu_execute todo=[2]
EXECUTE sofia/internal/+994774711191@172.16.122.55 transfer(999 XML prospect.loc)
2016-07-01 18:49:45.055790 [DEBUG] switch_ivr.c:2160 (sofia/internal/+994774711191@172.16.122.55) State Change CS_EXECUTE -> CS_ROUTING
2016-07-01 18:49:45.055790 [NOTICE] switch_ivr.c:2167 Transfer sofia/internal/+994774711191@172.16.122.55 to XML[999@prospect.loc]
2016-07-01 18:49:45.055790 [DEBUG] switch_ivr_menu.c:673 exit-sound '(null)'
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/+994774711191@172.16.122.55) State EXECUTE going to sleep
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/+994774711191@172.16.122.55) Running State Change CS_ROUTING
2016-07-01 18:49:45.055790 [DEBUG] switch_channel.c:2249 (sofia/internal/+994774711191@172.16.122.55) Callstate Change ACTIVE -> RINGING
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/+994774711191@172.16.122.55) State ROUTING
2016-07-01 18:49:45.055790 [DEBUG] mod_sofia.c:142 sofia/internal/+994774711191@172.16.122.55 SOFIA ROUTING
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:236 sofia/internal/+994774711191@172.16.122.55 Standard ROUTING
2016-07-01 18:49:45.055790 [INFO] mod_dialplan_xml.c:637 Processing +994774711191 <+994774711191>->999 in context prospect.loc
Dialplan: sofia/internal/+994774711191@172.16.122.55 parsing [prospect.loc->from_3101212_pronet] continue=false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Regex (FAIL) [from_3101212_pronet] destination_number(999) =~ /^(1212.)$/ break=on-false
Dialplan: sofia/internal/+994774711191@172.16.122.55 parsing [prospect.loc->from_3101221_grandstream] continue=false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Regex (FAIL) [from_3101221_grandstream] destination_number(999) =~ /^(1221.)$/ break=on-false
Dialplan: sofia/internal/+994774711191@172.16.122.55 parsing [prospect.loc->from_3101112_service] continue=false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Regex (FAIL) [from_3101112_service] destination_number(999) =~ /^(1112.)$/ break=on-false
Dialplan: sofia/internal/+994774711191@172.16.122.55 parsing [prospect.loc->from_3102121_prospect] continue=false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Regex (FAIL) [from_3102121_prospect] destination_number(999) =~ /^(2121.)$/ break=on-false
Dialplan: sofia/internal/+994774711191@172.16.122.55 parsing [prospect.loc->from_3103131_call_center] continue=false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Regex (FAIL) [from_3103131_call_center] destination_number(999) =~ /^(3131.)$/ break=on-false
Dialplan: sofia/internal/+994774711191@172.16.122.55 parsing [prospect.loc->centron] continue=false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Regex (FAIL) [centron] destination_number(999) =~ /^2064$/ break=on-false
Dialplan: sofia/internal/+994774711191@172.16.122.55 parsing [prospect.loc->Local_Extension_100] continue=false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Regex (FAIL) [Local_Extension_100] destination_number(999) =~ /^(100)$/ break=on-false
Dialplan: sofia/internal/+994774711191@172.16.122.55 parsing [prospect.loc->Local_Extension] continue=false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Regex (PASS) [Local_Extension] destination_number(999) =~ /^(9[0-9][0-9])$/ break=on-false
Dialplan: sofia/internal/+994774711191@172.16.122.55 Action set(hangup_after_bridge=true)
Dialplan: sofia/internal/+994774711191@172.16.122.55 Action bridge(user/999@prospect.loc)
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/+994774711191@172.16.122.55) State Change CS_ROUTING -> CS_EXECUTE
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/+994774711191@172.16.122.55) State ROUTING going to sleep
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/+994774711191@172.16.122.55) Running State Change CS_EXECUTE
2016-07-01 18:49:45.055790 [DEBUG] switch_channel.c:2251 (sofia/internal/+994774711191@172.16.122.55) Callstate Change RINGING -> ACTIVE
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/+994774711191@172.16.122.55) State EXECUTE
2016-07-01 18:49:45.055790 [DEBUG] mod_sofia.c:197 sofia/internal/+994774711191@172.16.122.55 SOFIA EXECUTE
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:328 sofia/internal/+994774711191@172.16.122.55 Standard EXECUTE
EXECUTE sofia/internal/+994774711191@172.16.122.55 set(hangup_after_bridge=true)
2016-07-01 18:49:45.055790 [DEBUG] mod_dptools.c:1519 SET sofia/internal/+994774711191@172.16.122.55 [hangup_after_bridge]=[true]
EXECUTE sofia/internal/+994774711191@172.16.122.55 bridge(user/999@prospect.loc)
2016-07-01 18:49:45.055790 [DEBUG] switch_channel.c:1823 (sofia/internal/+994774711191@172.16.122.55) Callstate Change ACTIVE -> RING_WAIT
2016-07-01 18:49:45.055790 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables
2016-07-01 18:49:45.055790 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables
2016-07-01 18:49:45.055790 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/999@172.16.16.17:5062 [0cec8d3e-3f9b-11e6-944e-ab0bf946bd1a]
2016-07-01 18:49:45.055790 [DEBUG] mod_sofia.c:4757 (sofia/internal/999@172.16.16.17:5062) State Change CS_NEW -> CS_INIT
2016-07-01 18:49:45.055790 [NOTICE] switch_ivr_originate.c:2762 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/999@172.16.16.17:5062) Running State Change CS_INIT
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/999@172.16.16.17:5062) State INIT
2016-07-01 18:49:45.055790 [DEBUG] mod_sofia.c:89 sofia/internal/999@172.16.16.17:5062 SOFIA INIT
2016-07-01 18:49:45.055790 [DEBUG] sofia_glue.c:1257 sofia/internal/999@172.16.16.17:5062 sending invite version: 1.7.0 git dc13f59 2016-06-20 15:59:04Z 64bit
Local SDP:
v=0
o=FreeSWITCH 1467355777 1467355778 IN IP4 172.16.200.201
s=FreeSWITCH
c=IN IP4 172.16.200.201
t=0 0
m=audio 28808 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

2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:40 sofia/internal/999@172.16.16.17:5062 Standard INIT
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/999@172.16.16.17:5062) State Change CS_INIT -> CS_ROUTING
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/999@172.16.16.17:5062) State INIT going to sleep
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/999@172.16.16.17:5062) Running State Change CS_ROUTING
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/999@172.16.16.17:5062) State ROUTING
2016-07-01 18:49:45.055790 [DEBUG] mod_sofia.c:142 sofia/internal/999@172.16.16.17:5062 SOFIA ROUTING
2016-07-01 18:49:45.055790 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/999@172.16.16.17:5062) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/999@172.16.16.17:5062) State ROUTING going to sleep
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/999@172.16.16.17:5062) Running State Change CS_CONSUME_MEDIA
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:621 (sofia/internal/999@172.16.16.17:5062) State CONSUME_MEDIA
2016-07-01 18:49:45.055790 [DEBUG] switch_core_state_machine.c:621 (sofia/internal/999@172.16.16.17:5062) State CONSUME_MEDIA going to sleep
2016-07-01 18:49:45.075834 [DEBUG] sofia.c:6939 Channel sofia/internal/999@172.16.16.17:5062 entering state [calling][0]
2016-07-01 18:49:45.095789 [DEBUG] sofia.c:6939 Channel sofia/internal/999@172.16.16.17:5062 entering state [proceeding][180]
2016-07-01 18:49:45.095789 [NOTICE] sofia.c:7047 Ring-Ready sofia/internal/999@172.16.16.17:5062!
2016-07-01 18:49:45.095789 [DEBUG] switch_channel.c:3343 (sofia/internal/999@172.16.16.17:5062) Callstate Change DOWN -> RINGING
freeswitch@localhost.localdomain>


Это лог после отбоя.телефон еще звенит и приходится снять трубку и дать отбой

2016-07-01 18:50:23.095818 [DEBUG] sofia.c:6939 Channel sofia/internal/999@172.16.16.17:5062 entering state [completing][200]
2016-07-01 18:50:23.095818 [DEBUG] sofia.c:6949 Remote SDP:
v=0
o=999 8000 8000 IN IP4 172.16.16.17
s=SIP Call
c=IN IP4 172.16.16.17
t=0 0
m=audio 5074 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=ptime:20

2016-07-01 18:50:23.095818 [DEBUG] sofia.c:6939 Channel sofia/internal/999@172.16.16.17:5062 entering state [ready][200]
2016-07-01 18:50:23.095818 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-07-01 18:50:23.095818 [DEBUG] switch_core_media.c:4420 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2016-07-01 18:50:23.095818 [DEBUG] switch_core_media.c:3091 Set Codec sofia/internal/999@172.16.16.17:5062 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2016-07-01 18:50:23.095818 [DEBUG] switch_core_codec.c:111 sofia/internal/999@172.16.16.17:5062 Original read codec set to PCMU:0
2016-07-01 18:50:23.095818 [DEBUG] switch_core_media.c:4664 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
2016-07-01 18:50:23.095818 [DEBUG] switch_core_media.c:4693 sofia/internal/999@172.16.16.17:5062 Set 2833 dtmf send payload to 101 recv payload to 101
2016-07-01 18:50:23.095818 [DEBUG] switch_core_media.c:6496 AUDIO RTP [sofia/internal/999@172.16.16.17:5062] 172.16.200.201 port 28808 -> 172.16.16.17 port 5074 codec: 0 ms: 20
2016-07-01 18:50:23.095818 [DEBUG] switch_rtp.c:3832 Starting timer [soft] 160 bytes per 20ms
2016-07-01 18:50:23.095818 [DEBUG] switch_core_media.c:6795 sofia/internal/999@172.16.16.17:5062 Set 2833 dtmf send payload to 101
2016-07-01 18:50:23.095818 [DEBUG] switch_core_media.c:6802 sofia/internal/999@172.16.16.17:5062 Set 2833 dtmf receive payload to 101
2016-07-01 18:50:23.095818 [DEBUG] switch_core_media.c:6825 sofia/internal/999@172.16.16.17:5062 Set rtp dtmf delay to 40
2016-07-01 18:50:23.095818 [NOTICE] sofia.c:7970 Channel [sofia/internal/999@172.16.16.17:5062] has been answered
2016-07-01 18:50:23.095818 [DEBUG] switch_channel.c:3770 (sofia/internal/999@172.16.16.17:5062) Callstate Change RINGING -> ACTIVE
2016-07-01 18:50:23.115825 [DEBUG] switch_ivr_originate.c:3607 Originate Resulted in Success: [sofia/internal/999@172.16.16.17:5062]
2016-07-01 18:50:23.115825 [DEBUG] switch_channel.c:2047 (sofia/internal/+994774711191@172.16.122.55) Callstate Change RING_WAIT -> ACTIVE
2016-07-01 18:50:23.115825 [DEBUG] switch_ivr_originate.c:3607 Originate Resulted in Success: [sofia/internal/999@172.16.16.17:5062]
2016-07-01 18:50:23.115825 [DEBUG] switch_ivr_bridge.c:1599 (sofia/internal/999@172.16.16.17:5062) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2016-07-01 18:50:23.115825 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/999@172.16.16.17:5062) Running State Change CS_EXCHANGE_MEDIA
2016-07-01 18:50:23.115825 [DEBUG] switch_core_state_machine.c:612 (sofia/internal/999@172.16.16.17:5062) State EXCHANGE_MEDIA
2016-07-01 18:50:23.115825 [DEBUG] mod_sofia.c:630 SOFIA EXCHANGE_MEDIA
2016-07-01 18:50:23.136313 [DEBUG] switch_rtp.c:6711 Correct audio ip/port confirmed.
2016-07-01 18:50:23.136313 [DEBUG] sofia.c:6939 Channel sofia/internal/999@172.16.16.17:5062 entering state [calling][0]
2016-07-01 18:50:23.195808 [DEBUG] sofia.c:6939 Channel sofia/internal/999@172.16.16.17:5062 entering state [ready][200]
2016-07-01 18:50:23.195808 [DEBUG] sofia.c:6949 Remote SDP:
v=0
o=999 8000 8001 IN IP4 172.16.16.17
s=SIP Call
c=IN IP4 172.16.16.17
t=0 0
m=audio 5074 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=ptime:20

2016-07-01 18:50:23.195808 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-07-01 18:50:23.195808 [DEBUG] switch_core_media.c:4420 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2016-07-01 18:50:23.195808 [DEBUG] switch_core_media.c:4664 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
2016-07-01 18:50:23.195808 [DEBUG] switch_core_media.c:4693 sofia/internal/999@172.16.16.17:5062 Set 2833 dtmf send payload to 101 recv payload to 101
2016-07-01 18:50:23.195808 [DEBUG] sofia.c:7813 Processing updated SDP
2016-07-01 18:50:23.195808 [DEBUG] switch_core_media.c:6479 Audio params are unchanged for sofia/internal/999@172.16.16.17:5062.
2016-07-01 18:50:23.595803 [NOTICE] sofia.c:988 Hangup sofia/internal/999@172.16.16.17:5062 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2016-07-01 18:50:23.595803 [DEBUG] switch_ivr_bridge.c:785 BRIDGE THREAD DONE [sofia/internal/999@172.16.16.17:5062]
2016-07-01 18:50:23.595803 [DEBUG] switch_core_state_machine.c:612 (sofia/internal/999@172.16.16.17:5062) State EXCHANGE_MEDIA going to sleep
2016-07-01 18:50:23.595803 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/999@172.16.16.17:5062) Running State Change CS_HANGUP
2016-07-01 18:50:23.595803 [DEBUG] switch_core_state_machine.c:809 (sofia/internal/999@172.16.16.17:5062) Callstate Change ACTIVE -> HANGUP
2016-07-01 18:50:23.595803 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/999@172.16.16.17:5062) State HANGUP
2016-07-01 18:50:23.595803 [DEBUG] mod_sofia.c:437 Channel sofia/internal/999@172.16.16.17:5062 hanging up, cause: NORMAL_CLEARING
2016-07-01 18:50:23.595803 [DEBUG] switch_core_state_machine.c:60 sofia/internal/999@172.16.16.17:5062 Standard HANGUP, cause: NORMAL_CLEARING
2016-07-01 18:50:23.595803 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/999@172.16.16.17:5062) State HANGUP going to sleep
2016-07-01 18:50:23.595803 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/999@172.16.16.17:5062) State Change CS_HANGUP -> CS_REPORTING
2016-07-01 18:50:23.595803 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/999@172.16.16.17:5062) Running State Change CS_REPORTING
2016-07-01 18:50:23.595803 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/999@172.16.16.17:5062) State REPORTING
2016-07-01 18:50:23.595803 [DEBUG] switch_core_state_machine.c:174 sofia/internal/999@172.16.16.17:5062 Standard REPORTING, cause: NORMAL_CLEARING
2016-07-01 18:50:23.595803 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/999@172.16.16.17:5062) State REPORTING going to sleep
2016-07-01 18:50:23.595803 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/999@172.16.16.17:5062) State Change CS_REPORTING -> CS_DESTROY
2016-07-01 18:50:23.595803 [DEBUG] switch_core_session.c:1647 Session 62 (sofia/internal/999@172.16.16.17:5062) Locked, Waiting on external entities
2016-07-01 18:50:23.615823 [DEBUG] switch_ivr_bridge.c:706 sofia/internal/999@172.16.16.17:5062 ending bridge by request from write function
2016-07-01 18:50:23.615823 [DEBUG] switch_ivr_bridge.c:785 BRIDGE THREAD DONE [sofia/internal/+994774711191@172.16.122.55]
2016-07-01 18:50:23.615823 [NOTICE] switch_ivr_bridge.c:1749 Hangup sofia/internal/+994774711191@172.16.122.55 [CS_EXECUTE] [NORMAL_CLEARING]
2016-07-01 18:50:23.615823 [DEBUG] switch_core_session.c:2797 sofia/internal/+994774711191@172.16.122.55 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/+994774711191@172.16.122.55) State EXECUTE going to sleep
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/+994774711191@172.16.122.55) Running State Change CS_HANGUP
2016-07-01 18:50:23.615823 [DEBUG] switch_core_media_bug.c:1120 Removing BUG from sofia/internal/+994774711191@172.16.122.55
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:809 (sofia/internal/+994774711191@172.16.122.55) Callstate Change ACTIVE -> HANGUP
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/+994774711191@172.16.122.55) State HANGUP
2016-07-01 18:50:23.615823 [DEBUG] mod_sofia.c:431 sofia/internal/+994774711191@172.16.122.55 Overriding SIP cause 480 with 200 from the other leg
2016-07-01 18:50:23.615823 [DEBUG] mod_sofia.c:437 Channel sofia/internal/+994774711191@172.16.122.55 hanging up, cause: NORMAL_CLEARING
2016-07-01 18:50:23.615823 [DEBUG] mod_sofia.c:490 Sending BYE to sofia/internal/+994774711191@172.16.122.55
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:60 sofia/internal/+994774711191@172.16.122.55 Standard HANGUP, cause: NORMAL_CLEARING
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/+994774711191@172.16.122.55) State HANGUP going to sleep
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/+994774711191@172.16.122.55) State Change CS_HANGUP -> CS_REPORTING
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/+994774711191@172.16.122.55) Running State Change CS_REPORTING
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/+994774711191@172.16.122.55) State REPORTING
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:174 sofia/internal/+994774711191@172.16.122.55 Standard REPORTING, cause: NORMAL_CLEARING
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/+994774711191@172.16.122.55) State REPORTING going to sleep
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/+994774711191@172.16.122.55) State Change CS_REPORTING -> CS_DESTROY
2016-07-01 18:50:23.615823 [DEBUG] switch_core_session.c:1647 Session 61 (sofia/internal/+994774711191@172.16.122.55) Locked, Waiting on external entities
2016-07-01 18:50:23.615823 [NOTICE] switch_core_session.c:1665 Session 61 (sofia/internal/+994774711191@172.16.122.55) Ended
2016-07-01 18:50:23.615823 [NOTICE] switch_core_session.c:1669 Close Channel sofia/internal/+994774711191@172.16.122.55 [CS_DESTROY]
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:700 (sofia/internal/+994774711191@172.16.122.55) Running State Change CS_DESTROY
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/+994774711191@172.16.122.55) State DESTROY
2016-07-01 18:50:23.615823 [DEBUG] mod_sofia.c:342 sofia/internal/+994774711191@172.16.122.55 SOFIA DESTROY
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:181 sofia/internal/+994774711191@172.16.122.55 Standard DESTROY
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/+994774711191@172.16.122.55) State DESTROY going to sleep
2016-07-01 18:50:23.615823 [NOTICE] switch_core_session.c:1665 Session 62 (sofia/internal/999@172.16.16.17:5062) Ended
2016-07-01 18:50:23.615823 [NOTICE] switch_core_session.c:1669 Close Channel sofia/internal/999@172.16.16.17:5062 [CS_DESTROY]
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:700 (sofia/internal/999@172.16.16.17:5062) Running State Change CS_DESTROY
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/999@172.16.16.17:5062) State DESTROY
2016-07-01 18:50:23.615823 [DEBUG] mod_sofia.c:342 sofia/internal/999@172.16.16.17:5062 SOFIA DESTROY
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:181 sofia/internal/999@172.16.16.17:5062 Standard DESTROY
2016-07-01 18:50:23.615823 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/999@172.16.16.17:5062) State DESTROY going to sleep


Я думаю где то в dtmf у меня ошибка.Но сколько не менял разные варианты то ничего не получается. С другими настройками бывает еще хуже,сбивается до набор. при наборе 999 идет на 997 и т д.

Кто встречался с такой проблемой:???если кто сможет помочь буду благодарен!

еслиб со стороны звонящего прилетел BYE после передумывания звонить, то без вопросов это ошибка
но BYE нет
посему звоним до упора

_________________
ЛС: @rostel
Сообщество: @ru_freeswitch

Решил проблемы выставив на транке auto.и обязательно перезагрузить gsm гетвей.без этого не заработало.