通过命令无法拨号成功

[smart]

感谢社区的各位，请教一个问题
1. 这边配置好了网关，在 X-Lite中，可以正常的拨打电话，比如 13XXXXXXXXX（手机）
2.但是通过如下命令， originate user/1000 &bridge(sofia/gateway/gw1/13XXXXXXXXX)后，出现如下情况：
A. X-Lite中提示有来电
B. 点击接听后，过一会，就自动挂断了，无法正常拨打手机

下面是错误信息：

2018-01-10 19:46:44.687449 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
2018-01-10 19:46:44.687449 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.687449 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1000@192.168.31.68:57115 [c826fe6a-41de-4616-a1a9-01189a5fffac]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.687449 [DEBUG] mod_sofia.c:4819 (sofia/internal/1000@192.168.31.68:57115) State Change CS_NEW -> CS_INIT
2018-01-10 19:46:44.687449 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.687449 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@192.168.31.68:57115) Running State Change CS_INIT (Cur 1 Tot 1)
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.687449 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1000@192.168.31.68:57115) State INIT
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.687449 [DEBUG] mod_sofia.c:90 sofia/internal/1000@192.168.31.68:57115 SOFIA INIT
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.687449 [DEBUG] sofia_glue.c:1295 sofia/internal/1000@192.168.31.68:57115 sending invite version: 1.6.18 git 6e79667 2017-06-12 21:14:49Z 64bit
c826fe6a-41de-4616-a1a9-01189a5fffac Local SDP:
c826fe6a-41de-4616-a1a9-01189a5fffac v=0
c826fe6a-41de-4616-a1a9-01189a5fffac o=FreeSWITCH 1515562946 1515562947 IN IP4 192.168.31.68
c826fe6a-41de-4616-a1a9-01189a5fffac s=FreeSWITCH
c826fe6a-41de-4616-a1a9-01189a5fffac c=IN IP4 192.168.31.68
c826fe6a-41de-4616-a1a9-01189a5fffac t=0 0
c826fe6a-41de-4616-a1a9-01189a5fffac m=audio 21858 RTP/AVP 102 9 0 18 8 104 101
c826fe6a-41de-4616-a1a9-01189a5fffac a=rtpmap:102 opus/48000/2
c826fe6a-41de-4616-a1a9-01189a5fffac a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
c826fe6a-41de-4616-a1a9-01189a5fffac a=rtpmap:9 G722/8000
c826fe6a-41de-4616-a1a9-01189a5fffac a=rtpmap:0 PCMU/8000
c826fe6a-41de-4616-a1a9-01189a5fffac a=rtpmap:18 G729/8000
c826fe6a-41de-4616-a1a9-01189a5fffac a=rtpmap:8 PCMA/8000
c826fe6a-41de-4616-a1a9-01189a5fffac a=rtpmap:104 telephone-event/48000
c826fe6a-41de-4616-a1a9-01189a5fffac a=fmtp:104 0-16
c826fe6a-41de-4616-a1a9-01189a5fffac a=rtpmap:101 telephone-event/8000
c826fe6a-41de-4616-a1a9-01189a5fffac a=fmtp:101 0-16
c826fe6a-41de-4616-a1a9-01189a5fffac a=ptime:20
c826fe6a-41de-4616-a1a9-01189a5fffac a=sendrecv
c826fe6a-41de-4616-a1a9-01189a5fffac m=video 31346 RTP/AVP 103
c826fe6a-41de-4616-a1a9-01189a5fffac b=AS:1024
c826fe6a-41de-4616-a1a9-01189a5fffac a=rtpmap:103 VP8/90000
c826fe6a-41de-4616-a1a9-01189a5fffac a=rtcp-fb:103 ccm fir
c826fe6a-41de-4616-a1a9-01189a5fffac a=rtcp-fb:103 ccm tmmbr
c826fe6a-41de-4616-a1a9-01189a5fffac a=rtcp-fb:103 nack
c826fe6a-41de-4616-a1a9-01189a5fffac a=rtcp-fb:103 nack pli
c826fe6a-41de-4616-a1a9-01189a5fffac
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.687449 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1000@192.168.31.68:57115 Standard INIT
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.687449 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1000@192.168.31.68:57115) State Change CS_INIT -> CS_ROUTING
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.687449 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1000@192.168.31.68:57115) State INIT going to sleep
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.687449 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@192.168.31.68:57115) Running State Change CS_ROUTING (Cur 1 Tot 1)
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.697457 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1000@192.168.31.68:57115) State ROUTING
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.697457 [DEBUG] mod_sofia.c:143 sofia/internal/1000@192.168.31.68:57115 SOFIA ROUTING
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.697457 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1000@192.168.31.68:57115) State Change CS_ROUTING -> CS_CONSUME_MEDIA
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.697457 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1000@192.168.31.68:57115) State ROUTING going to sleep
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.697457 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@192.168.31.68:57115) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 1)
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.697457 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1000@192.168.31.68:57115) State CONSUME_MEDIA
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.697457 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1000@192.168.31.68:57115) State CONSUME_MEDIA going to sleep
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.697457 [DEBUG] sofia.c:7048 Channel sofia/internal/1000@192.168.31.68:57115 entering state [calling][0]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.897137 [DEBUG] sofia.c:7048 Channel sofia/internal/1000@192.168.31.68:57115 entering state [proceeding][180]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.897137 [NOTICE] sofia.c:7156 Ring-Ready sofia/internal/1000@192.168.31.68:57115!
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:44.897137 [DEBUG] switch_channel.c:3346 (sofia/internal/1000@192.168.31.68:57115) Callstate Change DOWN -> RINGING
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.677551 [DEBUG] sofia.c:7048 Channel sofia/internal/1000@192.168.31.68:57115 entering state [completing][200]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.677551 [DEBUG] sofia.c:7058 Remote SDP:
c826fe6a-41de-4616-a1a9-01189a5fffac v=0
c826fe6a-41de-4616-a1a9-01189a5fffac o=- 3465303089 3 IN IP4 192.168.31.68
c826fe6a-41de-4616-a1a9-01189a5fffac s=X-Lite release 5.1.0 stamp 89322
c826fe6a-41de-4616-a1a9-01189a5fffac c=IN IP4 192.168.31.68
c826fe6a-41de-4616-a1a9-01189a5fffac t=0 0
c826fe6a-41de-4616-a1a9-01189a5fffac m=audio 61222 RTP/AVP 102 9 0 8 101
c826fe6a-41de-4616-a1a9-01189a5fffac a=rtpmap:102 opus/48000/2
c826fe6a-41de-4616-a1a9-01189a5fffac a=fmtp:102 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
c826fe6a-41de-4616-a1a9-01189a5fffac a=rtpmap:101 telephone-event/8000
c826fe6a-41de-4616-a1a9-01189a5fffac a=fmtp:101 0-15
c826fe6a-41de-4616-a1a9-01189a5fffac m=video 0 RTP/AVP 19
c826fe6a-41de-4616-a1a9-01189a5fffac
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.677551 [DEBUG] sofia.c:7048 Channel sofia/internal/1000@192.168.31.68:57115 entering state [ready][200]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [opus:102:48000:20:0:1]/[G722:9:8000:20:64000:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [opus:102:48000:20:0:1]/[G729:18:8000:20:8000:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G722:9:8000:20:64000:1]/[G729:18:8000:20:8000:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000
2018-01-10 19:46:46.687058 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [72000bps]
2018-01-10 19:46:46.687058 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [72000bps]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:3057 Set Codec sofia/internal/1000@192.168.31.68:57115 opus/48000 20 ms 960 samples 0 bits 1 channels
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_codec.c:111 sofia/internal/1000@192.168.31.68:57115 Original read codec set to opus:116
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:4763 sofia/internal/1000@192.168.31.68:57115 Set 2833 dtmf send payload to 101 recv payload to 101
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:6874 AUDIO RTP [sofia/internal/1000@192.168.31.68:57115] 192.168.31.68 port 21858 -> 192.168.31.68 port 61222 codec: 102 ms: 20
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_rtp.c:4108 Starting timer [soft] 960 bytes per 20ms
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:7175 sofia/internal/1000@192.168.31.68:57115 Set 2833 dtmf send payload to 101
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:7182 sofia/internal/1000@192.168.31.68:57115 Set 2833 dtmf receive payload to 101
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_core_media.c:7205 sofia/internal/1000@192.168.31.68:57115 Set rtp dtmf delay to 40
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [NOTICE] sofia.c:8182 Channel [sofia/internal/1000@192.168.31.68:57115] has been answered
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.687058 [DEBUG] switch_channel.c:3773 (sofia/internal/1000@192.168.31.68:57115) Callstate Change RINGING -> ACTIVE
2018-01-10 19:46:46.698070 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/1000@192.168.31.68:57115]
2018-01-10 19:46:46.698070 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/1000@192.168.31.68:57115]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.698070 [INFO] switch_channel.c:3129 sofia/internal/1000@192.168.31.68:57115 Flipping CID from "" <0000000000> to "Outbound Call" <1000>
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.698070 [DEBUG] mod_commands.c:4845 (sofia/internal/1000@192.168.31.68:57115) State Change CS_CONSUME_MEDIA -> CS_EXECUTE
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.698070 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@192.168.31.68:57115) Running State Change CS_EXECUTE (Cur 1 Tot 1)
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.698070 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1000@192.168.31.68:57115) State EXECUTE
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.698070 [DEBUG] mod_sofia.c:198 sofia/internal/1000@192.168.31.68:57115 SOFIA EXECUTE
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.698070 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1000@192.168.31.68:57115 Standard EXECUTE
c826fe6a-41de-4616-a1a9-01189a5fffac EXECUTE sofia/internal/1000@192.168.31.68:57115 bridge(sofia/gateway/gw1/拨打手机号码)
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.698070 [DEBUG] switch_channel.c:1823 (sofia/internal/1000@192.168.31.68:57115) Callstate Change ACTIVE -> RING_WAIT
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.698070 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.698070 [NOTICE] switch_channel.c:1104 New Channel sofia/external/拨打手机号码 [714bf4be-1085-482f-9096-ed4ca9556e6b]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.698070 [DEBUG] mod_sofia.c:4819 (sofia/external/拨打手机号码) State Change CS_NEW -> CS_INIT
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.698070 [DEBUG] switch_core_state_machine.c:584 (sofia/external/拨打手机号码) Running State Change CS_INIT (Cur 2 Tot 2)
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.698070 [DEBUG] switch_core_state_machine.c:627 (sofia/external/拨打手机号码) State INIT
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.698070 [DEBUG] mod_sofia.c:90 sofia/external/拨打手机号码 SOFIA INIT
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.737093 [DEBUG] sofia_glue.c:1295 sofia/external/拨打手机号码 sending invite version: 1.6.18 git 6e79667 2017-06-12 21:14:49Z 64bit
714bf4be-1085-482f-9096-ed4ca9556e6b Local SDP:
714bf4be-1085-482f-9096-ed4ca9556e6b v=0
714bf4be-1085-482f-9096-ed4ca9556e6b o=FreeSWITCH 1515554642 1515554643 IN IP4 172.26.8.114
714bf4be-1085-482f-9096-ed4ca9556e6b s=FreeSWITCH
714bf4be-1085-482f-9096-ed4ca9556e6b c=IN IP4 172.26.8.114
714bf4be-1085-482f-9096-ed4ca9556e6b t=0 0
714bf4be-1085-482f-9096-ed4ca9556e6b m=audio 30164 RTP/AVP 102 101
714bf4be-1085-482f-9096-ed4ca9556e6b a=rtpmap:102 opus/48000/2
714bf4be-1085-482f-9096-ed4ca9556e6b a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
714bf4be-1085-482f-9096-ed4ca9556e6b a=rtpmap:101 telephone-event/48000
714bf4be-1085-482f-9096-ed4ca9556e6b a=fmtp:101 0-16
714bf4be-1085-482f-9096-ed4ca9556e6b a=ptime:20
714bf4be-1085-482f-9096-ed4ca9556e6b a=sendrecv
714bf4be-1085-482f-9096-ed4ca9556e6b
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.737093 [DEBUG] switch_core_state_machine.c:40 sofia/external/拨打手机号码 Standard INIT
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.737093 [DEBUG] switch_core_state_machine.c:48 (sofia/external/拨打手机号码) State Change CS_INIT -> CS_ROUTING
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.737093 [DEBUG] switch_core_state_machine.c:627 (sofia/external/拨打手机号码) State INIT going to sleep
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.737093 [DEBUG] switch_core_state_machine.c:584 (sofia/external/拨打手机号码) Running State Change CS_ROUTING (Cur 2 Tot 2)
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.737093 [DEBUG] switch_core_state_machine.c:643 (sofia/external/拨打手机号码) State ROUTING
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.737093 [DEBUG] mod_sofia.c:143 sofia/external/拨打手机号码 SOFIA ROUTING
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.737093 [DEBUG] switch_ivr_originate.c:67 (sofia/external/拨打手机号码) State Change CS_ROUTING -> CS_CONSUME_MEDIA
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.737093 [DEBUG] switch_core_state_machine.c:643 (sofia/external/拨打手机号码) State ROUTING going to sleep
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.737093 [DEBUG] switch_core_state_machine.c:584 (sofia/external/拨打手机号码) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 2)
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.737093 [DEBUG] sofia.c:7048 Channel sofia/external/拨打手机号码 entering state [calling][0]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.737093 [DEBUG] switch_core_state_machine.c:662 (sofia/external/拨打手机号码) State CONSUME_MEDIA
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.737093 [DEBUG] switch_core_state_machine.c:662 (sofia/external/拨打手机号码) State CONSUME_MEDIA going to sleep
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.778122 [DEBUG] switch_rtp.c:7254 Correct audio ip/port confirmed.
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.817149 [DEBUG] sofia.c:7048 Channel sofia/external/拨打手机号码 entering state [proceeding][183]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.817149 [DEBUG] sofia.c:7058 Remote SDP:
714bf4be-1085-482f-9096-ed4ca9556e6b v=0
714bf4be-1085-482f-9096-ed4ca9556e6b o=- 64970 64970 IN IP4 网关IP
714bf4be-1085-482f-9096-ed4ca9556e6b s=VOS3000
714bf4be-1085-482f-9096-ed4ca9556e6b c=IN IP4 网关IP
714bf4be-1085-482f-9096-ed4ca9556e6b t=0 0
714bf4be-1085-482f-9096-ed4ca9556e6b m=audio 25890 RTP/AVP 102 101
714bf4be-1085-482f-9096-ed4ca9556e6b a=rtpmap:102 opus/48000
714bf4be-1085-482f-9096-ed4ca9556e6b a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
714bf4be-1085-482f-9096-ed4ca9556e6b a=rtpmap:101 telephone-event/8000
714bf4be-1085-482f-9096-ed4ca9556e6b a=fmtp:101 0-16
714bf4be-1085-482f-9096-ed4ca9556e6b
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.817149 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.817149 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.817149 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.817149 [WARNING] switch_core_media.c:4615 Invalid SDP for opus. Don't ask.. but it needs a /2
2018-01-10 19:46:46.817149 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [72000bps]
2018-01-10 19:46:46.817149 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [72000bps]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.817149 [DEBUG] switch_core_media.c:3057 Set Codec sofia/external/拨打手机号码 opus/48000 20 ms 960 samples 0 bits 1 channels
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.817149 [DEBUG] switch_core_codec.c:111 sofia/external/拨打手机号码 Original read codec set to opus:116
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.817149 [DEBUG] switch_core_media.c:4763 sofia/external/拨打手机号码 Set 2833 dtmf send payload to 101 recv payload to 101
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.817149 [DEBUG] switch_core_media.c:6874 AUDIO RTP [sofia/external/拨打手机号码] 192.168.31.68 port 30164 -> 网关IP port 25890 codec: 102 ms: 20
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.817149 [DEBUG] switch_rtp.c:4108 Starting timer [soft] 960 bytes per 20ms
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.817149 [DEBUG] switch_core_media.c:7175 sofia/external/拨打手机号码 Set 2833 dtmf send payload to 101
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.817149 [DEBUG] switch_core_media.c:7182 sofia/external/拨打手机号码 Set 2833 dtmf receive payload to 101
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.817149 [DEBUG] switch_core_media.c:7205 sofia/external/拨打手机号码 Set rtp dtmf delay to 40
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.817149 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/拨打手机号码!
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.817149 [DEBUG] switch_channel.c:3474 (sofia/external/拨打手机号码) Callstate Change DOWN -> EARLY
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.837167 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/external/拨打手机号码]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:46.837167 [DEBUG] switch_channel.c:2047 (sofia/internal/1000@192.168.31.68:57115) Callstate Change RING_WAIT -> ACTIVE
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.837167 [DEBUG] switch_ivr_bridge.c:1614 (sofia/external/拨打手机号码) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.837167 [DEBUG] switch_core_state_machine.c:584 (sofia/external/拨打手机号码) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 2)
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.837167 [DEBUG] switch_core_state_machine.c:653 (sofia/external/拨打手机号码) State EXCHANGE_MEDIA
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:46.837167 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.417751 [DEBUG] sofia.c:7048 Channel sofia/external/拨打手机号码 entering state [proceeding][183]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.417751 [DEBUG] sofia.c:7055 Duplicate SDP
714bf4be-1085-482f-9096-ed4ca9556e6b v=0
714bf4be-1085-482f-9096-ed4ca9556e6b o=- 64970 64970 IN IP4 网关IP
714bf4be-1085-482f-9096-ed4ca9556e6b s=VOS3000
714bf4be-1085-482f-9096-ed4ca9556e6b c=IN IP4 网关IP
714bf4be-1085-482f-9096-ed4ca9556e6b t=0 0
714bf4be-1085-482f-9096-ed4ca9556e6b m=audio 25890 RTP/AVP 102 101
714bf4be-1085-482f-9096-ed4ca9556e6b a=rtpmap:102 opus/48000
714bf4be-1085-482f-9096-ed4ca9556e6b a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
714bf4be-1085-482f-9096-ed4ca9556e6b a=rtpmap:101 telephone-event/8000
714bf4be-1085-482f-9096-ed4ca9556e6b a=fmtp:101 0-16
714bf4be-1085-482f-9096-ed4ca9556e6b
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.417751 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.417751 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.417751 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.417751 [WARNING] switch_core_media.c:4615 Invalid SDP for opus. Don't ask.. but it needs a /2
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.417751 [DEBUG] switch_core_media.c:4763 sofia/external/拨打手机号码 Set 2833 dtmf send payload to 101 recv payload to 101
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.417751 [DEBUG] switch_core_media.c:6857 Audio params are unchanged for sofia/external/拨打手机号码.
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.458280 [DEBUG] sofia.c:7048 Channel sofia/external/拨打手机号码 entering state [proceeding][183]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.458280 [DEBUG] sofia.c:7055 Duplicate SDP
714bf4be-1085-482f-9096-ed4ca9556e6b v=0
714bf4be-1085-482f-9096-ed4ca9556e6b o=- 64970 64970 IN IP4 网关IP
714bf4be-1085-482f-9096-ed4ca9556e6b s=VOS3000
714bf4be-1085-482f-9096-ed4ca9556e6b c=IN IP4 网关IP
714bf4be-1085-482f-9096-ed4ca9556e6b t=0 0
714bf4be-1085-482f-9096-ed4ca9556e6b m=audio 25890 RTP/AVP 102 101
714bf4be-1085-482f-9096-ed4ca9556e6b a=rtpmap:102 opus/48000
714bf4be-1085-482f-9096-ed4ca9556e6b a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
714bf4be-1085-482f-9096-ed4ca9556e6b a=rtpmap:101 telephone-event/8000
714bf4be-1085-482f-9096-ed4ca9556e6b a=fmtp:101 0-16
714bf4be-1085-482f-9096-ed4ca9556e6b
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.458280 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.458280 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.458280 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.458280 [WARNING] switch_core_media.c:4615 Invalid SDP for opus. Don't ask.. but it needs a /2
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.458280 [DEBUG] switch_core_media.c:4763 sofia/external/拨打手机号码 Set 2833 dtmf send payload to 101 recv payload to 101
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.458280 [DEBUG] switch_core_media.c:6857 Audio params are unchanged for sofia/external/拨打手机号码.
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.497332 [DEBUG] sofia.c:7048 Channel sofia/external/拨打手机号码 entering state [proceeding][183]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.497332 [DEBUG] sofia.c:7055 Duplicate SDP
714bf4be-1085-482f-9096-ed4ca9556e6b v=0
714bf4be-1085-482f-9096-ed4ca9556e6b o=- 64970 64970 IN IP4 网关IP
714bf4be-1085-482f-9096-ed4ca9556e6b s=VOS3000
714bf4be-1085-482f-9096-ed4ca9556e6b c=IN IP4 网关IP
714bf4be-1085-482f-9096-ed4ca9556e6b t=0 0
714bf4be-1085-482f-9096-ed4ca9556e6b m=audio 25890 RTP/AVP 102 101
714bf4be-1085-482f-9096-ed4ca9556e6b a=rtpmap:102 opus/48000
714bf4be-1085-482f-9096-ed4ca9556e6b a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
714bf4be-1085-482f-9096-ed4ca9556e6b a=rtpmap:101 telephone-event/8000
714bf4be-1085-482f-9096-ed4ca9556e6b a=fmtp:101 0-16
714bf4be-1085-482f-9096-ed4ca9556e6b
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.497332 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.497332 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.497332 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.497332 [WARNING] switch_core_media.c:4615 Invalid SDP for opus. Don't ask.. but it needs a /2
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.497332 [DEBUG] switch_core_media.c:4763 sofia/external/拨打手机号码 Set 2833 dtmf send payload to 101 recv payload to 101
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:49.497332 [DEBUG] switch_core_media.c:6857 Audio params are unchanged for sofia/external/拨打手机号码.
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.157327 [DEBUG] sofia.c:7048 Channel sofia/external/拨打手机号码 entering state [proceeding][183]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.157327 [DEBUG] sofia.c:7055 Duplicate SDP
714bf4be-1085-482f-9096-ed4ca9556e6b v=0
714bf4be-1085-482f-9096-ed4ca9556e6b o=- 64970 64970 IN IP4 网关IP
714bf4be-1085-482f-9096-ed4ca9556e6b s=VOS3000
714bf4be-1085-482f-9096-ed4ca9556e6b c=IN IP4 网关IP
714bf4be-1085-482f-9096-ed4ca9556e6b t=0 0
714bf4be-1085-482f-9096-ed4ca9556e6b m=audio 25890 RTP/AVP 102 101
714bf4be-1085-482f-9096-ed4ca9556e6b a=rtpmap:102 opus/48000
714bf4be-1085-482f-9096-ed4ca9556e6b a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
714bf4be-1085-482f-9096-ed4ca9556e6b a=rtpmap:101 telephone-event/8000
714bf4be-1085-482f-9096-ed4ca9556e6b a=fmtp:101 0-16
714bf4be-1085-482f-9096-ed4ca9556e6b

[smart]

714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.157327 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.157327 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.157327 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.157327 [WARNING] switch_core_media.c:4615 Invalid SDP for opus. Don't ask.. but it needs a /2
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.157327 [DEBUG] switch_core_media.c:4763 sofia/external/拨打手机号码 Set 2833 dtmf send payload to 101 recv payload to 101
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.157327 [DEBUG] switch_core_media.c:6857 Audio params are unchanged for sofia/external/拨打手机号码.
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.197158 [DEBUG] sofia.c:7048 Channel sofia/external/拨打手机号码 entering state [proceeding][183]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.197158 [DEBUG] sofia.c:7055 Duplicate SDP
714bf4be-1085-482f-9096-ed4ca9556e6b v=0
714bf4be-1085-482f-9096-ed4ca9556e6b o=- 64970 64970 IN IP4 网关IP
714bf4be-1085-482f-9096-ed4ca9556e6b s=VOS3000
714bf4be-1085-482f-9096-ed4ca9556e6b c=IN IP4 网关IP
714bf4be-1085-482f-9096-ed4ca9556e6b t=0 0
714bf4be-1085-482f-9096-ed4ca9556e6b m=audio 25890 RTP/AVP 102 101
714bf4be-1085-482f-9096-ed4ca9556e6b a=rtpmap:102 opus/48000
714bf4be-1085-482f-9096-ed4ca9556e6b a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
714bf4be-1085-482f-9096-ed4ca9556e6b a=rtpmap:101 telephone-event/8000
714bf4be-1085-482f-9096-ed4ca9556e6b a=fmtp:101 0-16
714bf4be-1085-482f-9096-ed4ca9556e6b
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.197158 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.197158 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.197158 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.197158 [WARNING] switch_core_media.c:4615 Invalid SDP for opus. Don't ask.. but it needs a /2
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.197158 [DEBUG] switch_core_media.c:4763 sofia/external/拨打手机号码 Set 2833 dtmf send payload to 101 recv payload to 101
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.197158 [DEBUG] switch_core_media.c:6857 Audio params are unchanged for sofia/external/拨打手机号码.
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [DEBUG] sofia.c:7048 Channel sofia/external/拨打手机号码 entering state [terminated][488]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [NOTICE] sofia.c:8237 Hangup sofia/external/拨打手机号码 [CS_EXCHANGE_MEDIA] [INCOMPATIBLE_DESTINATION]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [DEBUG] switch_ivr_bridge.c:712 sofia/external/拨打手机号码 ending bridge by request from read function
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/external/拨打手机号码]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [DEBUG] switch_core_state_machine.c:653 (sofia/external/拨打手机号码) State EXCHANGE_MEDIA going to sleep
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [DEBUG] switch_core_state_machine.c:584 (sofia/external/拨打手机号码) Running State Change CS_HANGUP (Cur 2 Tot 2)
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [DEBUG] switch_core_state_machine.c:850 (sofia/external/拨打手机号码) Callstate Change EARLY -> HANGUP
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [DEBUG] switch_core_state_machine.c:852 (sofia/external/拨打手机号码) State HANGUP
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [DEBUG] mod_sofia.c:438 Channel sofia/external/拨打手机号码 hanging up, cause: INCOMPATIBLE_DESTINATION
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [DEBUG] switch_core_state_machine.c:60 sofia/external/拨打手机号码 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [DEBUG] switch_core_state_machine.c:852 (sofia/external/拨打手机号码) State HANGUP going to sleep
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [DEBUG] switch_core_state_machine.c:619 (sofia/external/拨打手机号码) State Change CS_HANGUP -> CS_REPORTING
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [DEBUG] switch_core_state_machine.c:584 (sofia/external/拨打手机号码) Running State Change CS_REPORTING (Cur 2 Tot 2)
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [DEBUG] switch_core_state_machine.c:938 (sofia/external/拨打手机号码) State REPORTING
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [DEBUG] switch_core_state_machine.c:174 sofia/external/拨打手机号码 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [DEBUG] switch_core_state_machine.c:938 (sofia/external/拨打手机号码) State REPORTING going to sleep
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [DEBUG] switch_core_state_machine.c:610 (sofia/external/拨打手机号码) State Change CS_REPORTING -> CS_DESTROY
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [DEBUG] switch_core_session.c:1664 Session 2 (sofia/external/拨打手机号码) Locked, Waiting on external entities
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_ivr_bridge.c:706 sofia/external/拨打手机号码 ending bridge by request from write function
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/1000@192.168.31.68:57115]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [NOTICE] switch_channel.c:4825 Hangup sofia/internal/1000@192.168.31.68:57115 [CS_EXECUTE] [INCOMPATIBLE_DESTINATION]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.257690 [NOTICE] switch_core_session.c:1682 Session 2 (sofia/external/拨打手机号码) Ended
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.257690 [NOTICE] switch_core_session.c:1686 Close Channel sofia/external/拨打手机号码 [CS_DESTROY]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_core_session.c:2814 sofia/internal/1000@192.168.31.68:57115 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1000@192.168.31.68:57115) State EXECUTE going to sleep
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@192.168.31.68:57115) Running State Change CS_HANGUP (Cur 1 Tot 2)
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.257690 [DEBUG] switch_core_state_machine.c:741 (sofia/external/拨打手机号码) Running State Change CS_DESTROY (Cur 1 Tot 2)
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1000@192.168.31.68:57115) Callstate Change ACTIVE -> HANGUP
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.257690 [DEBUG] switch_core_state_machine.c:751 (sofia/external/拨打手机号码) State DESTROY
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.257690 [DEBUG] mod_sofia.c:343 sofia/external/拨打手机号码 SOFIA DESTROY
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.257690 [DEBUG] mod_opus.c:711 Opus decoder stats: Frames[0] PLC[0] FEC[0]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.257690 [DEBUG] mod_opus.c:726 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1000@192.168.31.68:57115) State HANGUP
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.257690 [DEBUG] mod_opus.c:711 Opus decoder stats: Frames[0] PLC[0] FEC[0]
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.257690 [DEBUG] mod_opus.c:726 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] mod_sofia.c:432 sofia/internal/1000@192.168.31.68:57115 Overriding SIP cause 488 with 488 from the other leg
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1000@192.168.31.68:57115 hanging up, cause: INCOMPATIBLE_DESTINATION
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/1000@192.168.31.68:57115
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1000@192.168.31.68:57115 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1000@192.168.31.68:57115) State HANGUP going to sleep
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1000@192.168.31.68:57115) State Change CS_HANGUP -> CS_REPORTING
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@192.168.31.68:57115) Running State Change CS_REPORTING (Cur 1 Tot 2)
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1000@192.168.31.68:57115) State REPORTING
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1000@192.168.31.68:57115 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1000@192.168.31.68:57115) State REPORTING going to sleep
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1000@192.168.31.68:57115) State Change CS_REPORTING -> CS_DESTROY
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_core_session.c:1664 Session 1 (sofia/internal/1000@192.168.31.68:57115) Locked, Waiting on external entities
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [NOTICE] switch_core_session.c:1682 Session 1 (sofia/internal/1000@192.168.31.68:57115) Ended
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/1000@192.168.31.68:57115 [CS_DESTROY]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1000@192.168.31.68:57115) Running State Change CS_DESTROY (Cur 0 Tot 2)
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1000@192.168.31.68:57115) State DESTROY
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] mod_sofia.c:343 sofia/internal/1000@192.168.31.68:57115 SOFIA DESTROY
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] mod_opus.c:711 Opus decoder stats: Frames[0] PLC[0] FEC[0]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] mod_opus.c:726 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] mod_opus.c:711 Opus decoder stats: Frames[0] PLC[0] FEC[0]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] mod_opus.c:726 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1000@192.168.31.68:57115 Standard DESTROY
c826fe6a-41de-4616-a1a9-01189a5fffac 2018-01-10 19:46:52.257690 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1000@192.168.31.68:57115) State DESTROY going to sleep
2018-01-10 19:46:52.267196 [DEBUG] switch_nat.c:542 unmapped public port 30164 protocol UDP to localport 30164
2018-01-10 19:46:52.267196 [DEBUG] switch_nat.c:542 unmapped public port 30165 protocol UDP to localport 30165
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.267196 [DEBUG] switch_core_state_machine.c:181 sofia/external/拨打手机号码 Standard DESTROY
714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.267196 [DEBUG] switch_core_state_machine.c:751 (sofia/external/拨打手机号码) State DESTROY going to sleep

[huangpuhuijing]

714bf4be-1085-482f-9096-ed4ca9556e6b 2018-01-10 19:46:52.237749 [DEBUG] sofia.c:7048 Channel sofia/external/拨打手机号码 entering state [terminated][488]

我看LOG中显示了183 SIP状态码后，显示了488的消息，应该吧sip_trace打开，看看错误的SIP状态码是从哪一个IP地址发过来的。确认是不是网关侧返回来的。

[smart]

非常感谢，在这么多LOG中，帮忙分析问题，我按您提示的方法，确认一下

[smart]

感谢各位的关注，问题解决了，主要是X-lite中设置的编码，有些网关不支持导致，现在将X-Lite中支持的编码只保留G711就可以了