Making calls from fs_cli - not working

Status
Not open for further replies.
M

MrGlasspoole

Member
Nov 11, 2017
31
0
6
47
Next thing I cant figure out since hours...

I tried all of this:
Code: 
originate sofia/internal/1009@10.1.0.11 &echo()
originate sofia/internal/1009@10.1.0.11 *9196
originate sofia/internal/1009@10.1.0.11 &tone_stream-copy()
originate sofia/internal/1009@10.1.0.11 *2222
originate sofia/internal/017xxxxxx35@Easybell_Bjoern &echo()
originate sofia/internal/9017xxxxxx35@Easybell_Bjoern &echo()
originate sofia/external/017xxxxxx35@Easybell_Bjoern &echo()
originate sofia/external/9017xxxxxx35@Easybell_Bjoern &echo()
originate sofia/external/9017xxxxxx35@Easybell_Bjoern *2222

Code: 
originate sofia/internal/1009@10.1.0.11 &echo()
-ERR NO_ROUTE_DESTINATION

2017-11-17 12:18:43.980021 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
2017-11-17 12:18:43.980021 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1009@10.1.0.11 [a21ed5dd-76f0-4b22-a49b-c5e4432068be]
2017-11-17 12:18:43.980021 [DEBUG] mod_sofia.c:4819 (sofia/internal/1009@10.1.0.11) State Change CS_NEW -> CS_INIT
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1009@10.1.0.11) Running State Change CS_INIT (Cur 1 Tot 55)
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1009@10.1.0.11) State INIT
2017-11-17 12:18:43.980021 [DEBUG] mod_sofia.c:90 sofia/internal/1009@10.1.0.11 SOFIA INIT
2017-11-17 12:18:43.980021 [DEBUG] sofia_glue.c:1295 sofia/internal/1009@10.1.0.11 sending invite version: 1.6.19 -36-7a77e0b 64bit
Local SDP:
v=0
o=FreeSWITCH 1510900241 1510900242 IN IP4 10.1.0.11
s=FreeSWITCH
c=IN IP4 10.1.0.11
t=0 0
m=audio 17282 RTP/AVP 102 9 8 0 104 105 101 13
a=rtpmap:102 opus/48000/2
a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:104 telephone-event/48000
a=fmtp:104 0-16
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:105 CN/48000
a=rtpmap:13 CN/8000
a=ptime:20
a=sendrecv
m=video 21346 RTP/AVP 103
b=AS:1024
a=rtpmap:103 VP8/90000
a=rtcp-fb:103 ccm fir
a=rtcp-fb:103 ccm tmmbr
a=rtcp-fb:103 nack
a=rtcp-fb:103 nack pli

2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1009@10.1.0.11 Standard INIT
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1009@10.1.0.11) State Change CS_INIT -> CS_ROUTING
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1009@10.1.0.11) State INIT going to sleep
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1009@10.1.0.11) Running State Change CS_ROUTING (Cur 1 Tot 55)
2017-11-17 12:18:43.980021 [DEBUG] sofia.c:7084 Channel sofia/internal/1009@10.1.0.11 entering state [calling][0]
2017-11-17 12:18:43.980021 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/0000000000@10.1.0.11 [9c6851f7-9c62-4d39-9441-5c2936d31e93]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1009@10.1.0.11) State ROUTING
2017-11-17 12:18:43.980021 [DEBUG] mod_sofia.c:143 sofia/internal/1009@10.1.0.11 SOFIA ROUTING
2017-11-17 12:18:43.980021 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1009@10.1.0.11) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1009@10.1.0.11) State ROUTING going to sleep
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1009@10.1.0.11) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 56)
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/0000000000@10.1.0.11) Running State Change CS_NEW (Cur 2 Tot 56)
2017-11-17 12:18:43.980021 [DEBUG] sofia.c:9873 sofia/internal/0000000000@10.1.0.11 receiving invite from 10.1.0.11:5060 version: 1.6.19 -36-7a77e0b 64bit
2017-11-17 12:18:43.980021 [DEBUG] sofia.c:10044 IP 10.1.0.11 Rejected by acl "domains". Falling back to Digest auth.
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1009@10.1.0.11) State CONSUME_MEDIA
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1009@10.1.0.11) State CONSUME_MEDIA going to sleep
2017-11-17 12:18:43.980021 [DEBUG] sofia.c:7084 Channel sofia/internal/0000000000@10.1.0.11 entering state [received][100]
2017-11-17 12:18:43.980021 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=FreeSWITCH 1510900241 1510900242 IN IP4 10.1.0.11
s=FreeSWITCH
c=IN IP4 10.1.0.11
t=0 0
m=audio 17282 RTP/AVP 102 9 8 0 104 105 101 13
a=rtpmap:102 opus/48000/2
a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:104 telephone-event/48000
a=fmtp:104 0-16
a=rtpmap:105 CN/48000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
m=video 21346 RTP/AVP 103
b=AS:1024
a=rtpmap:103 VP8/90000
a=rtcp-fb:103 ccm fir
a=rtcp-fb:103 ccm tmmbr
a=rtcp-fb:103 nack
a=rtcp-fb:103 nack pli

2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:1]/[G722:9:8000:20:64000:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 104@48000
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [CN:13:8000:20:0:1]/[opus:116:48000:20:0:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [CN:13:8000:20:0:1]/[G722:9:8000:20:64000:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [CN:13:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [CN:13:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
2017-11-17 12:18:43.980021 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [72000bps]
2017-11-17 12:18:43.980021 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [72000bps]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/0000000000@10.1.0.11 opus/48000 20 ms 960 samples 0 bits 1 channels
2017-11-17 12:18:43.980021 [DEBUG] switch_core_codec.c:111 sofia/internal/0000000000@10.1.0.11 Original read codec set to opus:116
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 104@48000
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4767 sofia/internal/0000000000@10.1.0.11 Set 2833 dtmf send payload to 104 recv payload to 104
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4950 Video Codec Compare [VP8:103]/[VP8:99]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:4982 Video Codec Compare [VP8:103] +++ is saved as a match
2017-11-17 12:18:43.980021 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
2017-11-17 12:18:43.980021 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
2017-11-17 12:18:43.980021 [DEBUG] switch_core_media.c:2871 Set VIDEO Codec sofia/internal/0000000000@10.1.0.11 VP8/90000 0 ms
2017-11-17 12:18:43.980021 [DEBUG] sofia.c:7507 (sofia/internal/0000000000@10.1.0.11) State Change CS_NEW -> CS_INIT
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/0000000000@10.1.0.11) State NEW
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/0000000000@10.1.0.11) Running State Change CS_INIT (Cur 2 Tot 56)
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/0000000000@10.1.0.11) State INIT
2017-11-17 12:18:43.980021 [DEBUG] mod_sofia.c:90 sofia/internal/0000000000@10.1.0.11 SOFIA INIT
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:40 sofia/internal/0000000000@10.1.0.11 Standard INIT
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/0000000000@10.1.0.11) State Change CS_INIT -> CS_ROUTING
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/0000000000@10.1.0.11) State INIT going to sleep
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/0000000000@10.1.0.11) Running State Change CS_ROUTING (Cur 2 Tot 56)
2017-11-17 12:18:43.980021 [DEBUG] switch_channel.c:2249 (sofia/internal/0000000000@10.1.0.11) Callstate Change DOWN -> RINGING
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/0000000000@10.1.0.11) State ROUTING
2017-11-17 12:18:43.980021 [DEBUG] mod_sofia.c:143 sofia/internal/0000000000@10.1.0.11 SOFIA ROUTING
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:236 sofia/internal/0000000000@10.1.0.11 Standard ROUTING
2017-11-17 12:18:43.980021 [INFO] mod_dialplan_xml.c:637 Processing  <0000000000>->1009 in context public
Dialplan: sofia/internal/0000000000@10.1.0.11 parsing [public->0049xxxxxxxx385] continue=false
Dialplan: sofia/internal/0000000000@10.1.0.11 Regex (FAIL) [0049xxxxxxxx385] destination_number(1009) =~ /^(0049xxxxxxxx385)$/ break=on-false
Dialplan: sofia/internal/0000000000@10.1.0.11 parsing [public->0049xxxxxxx685] continue=false
Dialplan: sofia/internal/0000000000@10.1.0.11 Regex (FAIL) [0049xxxxxxx685] destination_number(1009) =~ /^(0049xxxxxxx685)$/ break=on-false
2017-11-17 12:18:43.980021 [INFO] switch_core_state_machine.c:311 No Route, Aborting
2017-11-17 12:18:43.980021 [NOTICE] switch_core_state_machine.c:312 Hangup sofia/internal/0000000000@10.1.0.11 [CS_ROUTING] [NO_ROUTE_DESTINATION]
2017-11-17 12:18:43.980021 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/0000000000@10.1.0.11) State ROUTING going to sleep
2017-11-17 12:18:44.000017 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/0000000000@10.1.0.11) Running State Change CS_HANGUP (Cur 2 Tot 56)
2017-11-17 12:18:44.000017 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/0000000000@10.1.0.11) Callstate Change RINGING -> HANGUP
2017-11-17 12:18:44.000017 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/0000000000@10.1.0.11) State HANGUP
2017-11-17 12:18:44.000017 [DEBUG] mod_sofia.c:438 Channel sofia/internal/0000000000@10.1.0.11 hanging up, cause: NO_ROUTE_DESTINATION
2017-11-17 12:18:44.000017 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 404
2017-11-17 12:18:44.000017 [DEBUG] switch_core_state_machine.c:60 sofia/internal/0000000000@10.1.0.11 Standard HANGUP, cause: NO_ROUTE_DESTINATION
2017-11-17 12:18:44.000017 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/0000000000@10.1.0.11) State HANGUP going to sleep
2017-11-17 12:18:44.000017 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/0000000000@10.1.0.11) State Change CS_HANGUP -> CS_REPORTING
2017-11-17 12:18:44.000017 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/0000000000@10.1.0.11) Running State Change CS_REPORTING (Cur 2 Tot 56)
2017-11-17 12:18:44.000017 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/0000000000@10.1.0.11) State REPORTING
2017-11-17 12:18:44.000017 [DEBUG] sofia.c:6257 Remote Reason: 3
2017-11-17 12:18:44.000017 [DEBUG] sofia.c:7084 Channel sofia/internal/1009@10.1.0.11 entering state [terminated][404]
2017-11-17 12:18:44.000017 [NOTICE] sofia.c:8273 Hangup sofia/internal/1009@10.1.0.11 [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION]
2017-11-17 12:18:44.000017 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1009@10.1.0.11) Running State Change CS_HANGUP (Cur 2 Tot 56)
2017-11-17 12:18:44.000017 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1009@10.1.0.11) Callstate Change DOWN -> HANGUP
2017-11-17 12:18:44.000017 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1009@10.1.0.11) State HANGUP
2017-11-17 12:18:44.000017 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1009@10.1.0.11 hanging up, cause: NO_ROUTE_DESTINATION
2017-11-17 12:18:44.000017 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1009@10.1.0.11 Standard HANGUP, cause: NO_ROUTE_DESTINATION
2017-11-17 12:18:44.000017 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1009@10.1.0.11) State HANGUP going to sleep
2017-11-17 12:18:44.000017 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1009@10.1.0.11) State Change CS_HANGUP -> CS_REPORTING
2017-11-17 12:18:44.000017 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1009@10.1.0.11) Running State Change CS_REPORTING (Cur 2 Tot 56)
2017-11-17 12:18:44.000017 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1009@10.1.0.11) State REPORTING
2017-11-17 12:18:44.000017 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 3 [NO_ROUTE_DESTINATION]
2017-11-17 12:18:44.159981 [DEBUG] switch_core_state_machine.c:174 sofia/internal/0000000000@10.1.0.11 Standard REPORTING, cause: NO_ROUTE_DESTINATION
2017-11-17 12:18:44.159981 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/0000000000@10.1.0.11) State REPORTING going to sleep
2017-11-17 12:18:44.159981 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/0000000000@10.1.0.11) State Change CS_REPORTING -> CS_DESTROY
2017-11-17 12:18:44.159981 [DEBUG] switch_core_session.c:1665 Session 56 (sofia/internal/0000000000@10.1.0.11) Locked, Waiting on external entities
2017-11-17 12:18:44.159981 [NOTICE] switch_core_session.c:1683 Session 56 (sofia/internal/0000000000@10.1.0.11) Ended
2017-11-17 12:18:44.159981 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/0000000000@10.1.0.11 [CS_DESTROY]
2017-11-17 12:18:44.159981 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/0000000000@10.1.0.11) Running State Change CS_DESTROY (Cur 1 Tot 56)
2017-11-17 12:18:44.159981 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/0000000000@10.1.0.11) State DESTROY
2017-11-17 12:18:44.159981 [DEBUG] mod_sofia.c:343 sofia/internal/0000000000@10.1.0.11 SOFIA DESTROY
2017-11-17 12:18:44.159981 [DEBUG] mod_opus.c:711 Opus decoder stats: Frames[0] PLC[0] FEC[0]
2017-11-17 12:18:44.159981 [DEBUG] mod_opus.c:726 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
2017-11-17 12:18:44.159981 [DEBUG] mod_opus.c:711 Opus decoder stats: Frames[0] PLC[0] FEC[0]
2017-11-17 12:18:44.159981 [DEBUG] mod_opus.c:726 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
2017-11-17 12:18:44.159981 [DEBUG] switch_core_state_machine.c:181 sofia/internal/0000000000@10.1.0.11 Standard DESTROY
2017-11-17 12:18:44.159981 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/0000000000@10.1.0.11) State DESTROY going to sleep
2017-11-17 12:18:44.180015 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1009@10.1.0.11 Standard REPORTING, cause: NO_ROUTE_DESTINATION
2017-11-17 12:18:44.180015 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1009@10.1.0.11) State REPORTING going to sleep
2017-11-17 12:18:44.180015 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1009@10.1.0.11) State Change CS_REPORTING -> CS_DESTROY
2017-11-17 12:18:44.180015 [DEBUG] switch_core_session.c:1665 Session 55 (sofia/internal/1009@10.1.0.11) Locked, Waiting on external entities
2017-11-17 12:18:44.180015 [NOTICE] switch_core_session.c:1683 Session 55 (sofia/internal/1009@10.1.0.11) Ended
2017-11-17 12:18:44.180015 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/1009@10.1.0.11 [CS_DESTROY]
2017-11-17 12:18:44.180015 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1009@10.1.0.11) Running State Change CS_DESTROY (Cur 0 Tot 56)
2017-11-17 12:18:44.180015 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1009@10.1.0.11) State DESTROY
2017-11-17 12:18:44.180015 [DEBUG] mod_sofia.c:343 sofia/internal/1009@10.1.0.11 SOFIA DESTROY
2017-11-17 12:18:44.180015 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1009@10.1.0.11 Standard DESTROY
2017-11-17 12:18:44.180015 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1009@10.1.0.11) State DESTROY going to sleep

Code: 
originate sofia/external/9017xxxxxx35@Easybell_Bjoern &echo()
-ERR NORMAL_TEMPORARY_FAILURE

2017-11-17 12:22:52.139996 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
2017-11-17 12:22:52.160004 [NOTICE] switch_channel.c:1104 New Channel sofia/external/9017xxxxxx35@Easybell_Bjoern [41a4d075-3344-4b48-8f18-c8b8519a8c66]
2017-11-17 12:22:52.160004 [DEBUG] mod_sofia.c:4819 (sofia/external/9017xxxxxx35@Easybell_Bjoern) State Change CS_NEW -> CS_INIT
2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:584 (sofia/external/9017xxxxxx35@Easybell_Bjoern) Running State Change CS_INIT (Cur 1 Tot 59)
2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:627 (sofia/external/9017xxxxxx35@Easybell_Bjoern) State INIT
2017-11-17 12:22:52.160004 [DEBUG] mod_sofia.c:90 sofia/external/9017xxxxxx35@Easybell_Bjoern SOFIA INIT
2017-11-17 12:22:52.160004 [DEBUG] sofia_glue.c:1295 sofia/external/9017xxxxxx35@Easybell_Bjoern sending invite version: 1.6.19 -36-7a77e0b 64bit
Local SDP:
v=0
o=FreeSWITCH 1510897910 1510897911 IN IP4 10.1.0.11
s=FreeSWITCH
c=IN IP4 10.1.0.11
t=0 0
m=audio 19862 RTP/AVP 9 8 0 101 13
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
a=sendrecv

2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:40 sofia/external/9017xxxxxx35@Easybell_Bjoern Standard INIT
2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:48 (sofia/external/9017xxxxxx35@Easybell_Bjoern) State Change CS_INIT -> CS_ROUTING
2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:627 (sofia/external/9017xxxxxx35@Easybell_Bjoern) State INIT going to sleep
2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:584 (sofia/external/9017xxxxxx35@Easybell_Bjoern) Running State Change CS_ROUTING (Cur 1 Tot 59)
2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:643 (sofia/external/9017xxxxxx35@Easybell_Bjoern) State ROUTING
2017-11-17 12:22:52.160004 [DEBUG] mod_sofia.c:143 sofia/external/9017xxxxxx35@Easybell_Bjoern SOFIA ROUTING
2017-11-17 12:22:52.160004 [DEBUG] switch_ivr_originate.c:67 (sofia/external/9017xxxxxx35@Easybell_Bjoern) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:643 (sofia/external/9017xxxxxx35@Easybell_Bjoern) State ROUTING going to sleep
2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:584 (sofia/external/9017xxxxxx35@Easybell_Bjoern) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 59)
2017-11-17 12:22:52.160004 [DEBUG] sofia.c:7084 Channel sofia/external/9017xxxxxx35@Easybell_Bjoern entering state [calling][0]
2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:662 (sofia/external/9017xxxxxx35@Easybell_Bjoern) State CONSUME_MEDIA
2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:662 (sofia/external/9017xxxxxx35@Easybell_Bjoern) State CONSUME_MEDIA going to sleep
2017-11-17 12:22:52.160004 [DEBUG] sofia.c:7084 Channel sofia/external/9017xxxxxx35@Easybell_Bjoern entering state [terminated][503]
2017-11-17 12:22:52.160004 [NOTICE] sofia.c:8273 Hangup sofia/external/9017xxxxxx35@Easybell_Bjoern [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:584 (sofia/external/9017xxxxxx35@Easybell_Bjoern) Running State Change CS_HANGUP (Cur 1 Tot 59)
2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:850 (sofia/external/9017xxxxxx35@Easybell_Bjoern) Callstate Change DOWN -> HANGUP
2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:852 (sofia/external/9017xxxxxx35@Easybell_Bjoern) State HANGUP
2017-11-17 12:22:52.160004 [DEBUG] mod_sofia.c:438 Channel sofia/external/9017xxxxxx35@Easybell_Bjoern hanging up, cause: NORMAL_TEMPORARY_FAILURE
2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:60 sofia/external/9017xxxxxx35@Easybell_Bjoern Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:852 (sofia/external/9017xxxxxx35@Easybell_Bjoern) State HANGUP going to sleep
2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:619 (sofia/external/9017xxxxxx35@Easybell_Bjoern) State Change CS_HANGUP -> CS_REPORTING
2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:584 (sofia/external/9017xxxxxx35@Easybell_Bjoern) Running State Change CS_REPORTING (Cur 1 Tot 59)
2017-11-17 12:22:52.160004 [DEBUG] switch_core_state_machine.c:938 (sofia/external/9017xxxxxx35@Easybell_Bjoern) State REPORTING
2017-11-17 12:22:52.180020 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
2017-11-17 12:22:52.259987 [DEBUG] switch_core_state_machine.c:174 sofia/external/9017xxxxxx35@Easybell_Bjoern Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
2017-11-17 12:22:52.259987 [DEBUG] switch_core_state_machine.c:938 (sofia/external/9017xxxxxx35@Easybell_Bjoern) State REPORTING going to sleep
2017-11-17 12:22:52.259987 [DEBUG] switch_core_state_machine.c:610 (sofia/external/9017xxxxxx35@Easybell_Bjoern) State Change CS_REPORTING -> CS_DESTROY
2017-11-17 12:22:52.259987 [DEBUG] switch_core_session.c:1665 Session 59 (sofia/external/9017xxxxxx35@Easybell_Bjoern) Locked, Waiting on external entities
2017-11-17 12:22:52.259987 [NOTICE] switch_core_session.c:1683 Session 59 (sofia/external/9017xxxxxx35@Easybell_Bjoern) Ended
2017-11-17 12:22:52.259987 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/9017xxxxxx35@Easybell_Bjoern [CS_DESTROY]
2017-11-17 12:22:52.259987 [DEBUG] switch_core_state_machine.c:741 (sofia/external/9017xxxxxx35@Easybell_Bjoern) Running State Change CS_DESTROY (Cur 0 Tot 59)
2017-11-17 12:22:52.259987 [DEBUG] switch_core_state_machine.c:751 (sofia/external/9017xxxxxx35@Easybell_Bjoern) State DESTROY
2017-11-17 12:22:52.259987 [DEBUG] mod_sofia.c:343 sofia/external/9017xxxxxx35@Easybell_Bjoern SOFIA DESTROY
2017-11-17 12:22:52.259987 [DEBUG] switch_core_state_machine.c:181 sofia/external/9017xxxxxx35@Easybell_Bjoern Standard DESTROY
2017-11-17 12:22:52.259987 [DEBUG] switch_core_state_machine.c:751 (sofia/external/9017xxxxxx35@Easybell_Bjoern) State DESTROY going to sleep

Why do i get "NO_ROUTE_DESTINATION" with internal?

*2222 is this i did in the dialplan manager for testing.
The phone should ring and if i pick up it should play after 2 seconds?
Code: 
Name =  tone_stream-copy
condition = destination_number |  data = ^\*2222$
action application = sleep |  data="2000"
action application = playback |  data = {loops=10}tone_stream://path=/etc/freeswitch/tetris.ttml
 
M

MrGlasspoole

Member
Nov 11, 2017
31
0
6
47
Ok, trying to ring extension 1001:
Code: 
freeswitch@GORT>
originate sofia/internal/1001@10.1.0.11 *2222
-ERR NO_ROUTE_DESTINATION

2017-11-17 17:17:06.360002 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
2017-11-17 17:17:06.360002 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1001@10.1.0.11 [b1596d91-3215-4aad-9b42-66205778299c]
2017-11-17 17:17:06.360002 [DEBUG] mod_sofia.c:4819 (sofia/internal/1001@10.1.0.11) State Change CS_NEW -> CS_INIT
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@10.1.0.11) Running State Change CS_INIT (Cur 1 Tot 81)
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1001@10.1.0.11) State INIT
2017-11-17 17:17:06.360002 [DEBUG] mod_sofia.c:90 sofia/internal/1001@10.1.0.11 SOFIA INIT
2017-11-17 17:17:06.360002 [DEBUG] sofia_glue.c:1295 sofia/internal/1001@10.1.0.11 sending invite version: 1.6.19 -36-7a77e0b 64bit
Local SDP:
v=0
o=FreeSWITCH 1510904238 1510904239 IN IP4 10.1.0.11
s=FreeSWITCH
c=IN IP4 10.1.0.11
t=0 0
m=audio 31188 RTP/AVP 102 9 8 0 104 105 101 13
a=rtpmap:102 opus/48000/2
a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:104 telephone-event/48000
a=fmtp:104 0-16
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:105 CN/48000
a=rtpmap:13 CN/8000
a=ptime:20
a=sendrecv
m=video 23244 RTP/AVP 103
b=AS:1024
a=rtpmap:103 VP8/90000
a=rtcp-fb:103 ccm fir
a=rtcp-fb:103 ccm tmmbr
a=rtcp-fb:103 nack
a=rtcp-fb:103 nack pli

2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1001@10.1.0.11 Standard INIT
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1001@10.1.0.11) State Change CS_INIT -> CS_ROUTING
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1001@10.1.0.11) State INIT going to sleep
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@10.1.0.11) Running State Change CS_ROUTING (Cur 1 Tot 81)
2017-11-17 17:17:06.360002 [DEBUG] sofia.c:7084 Channel sofia/internal/1001@10.1.0.11 entering state [calling][0]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1001@10.1.0.11) State ROUTING
2017-11-17 17:17:06.360002 [DEBUG] mod_sofia.c:143 sofia/internal/1001@10.1.0.11 SOFIA ROUTING
2017-11-17 17:17:06.360002 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1001@10.1.0.11) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1001@10.1.0.11) State ROUTING going to sleep
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@10.1.0.11) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 81)
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1001@10.1.0.11) State CONSUME_MEDIA
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1001@10.1.0.11) State CONSUME_MEDIA going to sleep
2017-11-17 17:17:06.360002 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/0000000000@10.1.0.11 [47f0b246-47d9-47dd-97e1-1dd85a62ca69]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/0000000000@10.1.0.11) Running State Change CS_NEW (Cur 2 Tot 82)
2017-11-17 17:17:06.360002 [DEBUG] sofia.c:9873 sofia/internal/0000000000@10.1.0.11 receiving invite from 10.1.0.11:5060 version: 1.6.19 -36-7a77e0b 64bit
2017-11-17 17:17:06.360002 [DEBUG] sofia.c:10044 IP 10.1.0.11 Rejected by acl "domains". Falling back to Digest auth.
2017-11-17 17:17:06.360002 [DEBUG] sofia.c:7084 Channel sofia/internal/0000000000@10.1.0.11 entering state [received][100]
2017-11-17 17:17:06.360002 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=FreeSWITCH 1510904238 1510904239 IN IP4 10.1.0.11
s=FreeSWITCH
c=IN IP4 10.1.0.11
t=0 0
m=audio 31188 RTP/AVP 102 9 8 0 104 105 101 13
a=rtpmap:102 opus/48000/2
a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:104 telephone-event/48000
a=fmtp:104 0-16
a=rtpmap:105 CN/48000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
m=video 23244 RTP/AVP 103
b=AS:1024
a=rtpmap:103 VP8/90000
a=rtcp-fb:103 ccm fir
a=rtcp-fb:103 ccm tmmbr
a=rtcp-fb:103 nack
a=rtcp-fb:103 nack pli

2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:1]/[G722:9:8000:20:64000:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 104@48000
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [CN:13:8000:20:0:1]/[opus:116:48000:20:0:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [CN:13:8000:20:0:1]/[G722:9:8000:20:64000:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [CN:13:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [CN:13:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
2017-11-17 17:17:06.360002 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [72000bps]
2017-11-17 17:17:06.360002 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [72000bps]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/0000000000@10.1.0.11 opus/48000 20 ms 960 samples 0 bits 1 channels
2017-11-17 17:17:06.360002 [DEBUG] switch_core_codec.c:111 sofia/internal/0000000000@10.1.0.11 Original read codec set to opus:116
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 104@48000
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4767 sofia/internal/0000000000@10.1.0.11 Set 2833 dtmf send payload to 104 recv payload to 104
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4950 Video Codec Compare [VP8:103]/[VP8:99]
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:4982 Video Codec Compare [VP8:103] +++ is saved as a match
2017-11-17 17:17:06.360002 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
2017-11-17 17:17:06.360002 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
2017-11-17 17:17:06.360002 [DEBUG] switch_core_media.c:2871 Set VIDEO Codec sofia/internal/0000000000@10.1.0.11 VP8/90000 0 ms
2017-11-17 17:17:06.360002 [DEBUG] sofia.c:7507 (sofia/internal/0000000000@10.1.0.11) State Change CS_NEW -> CS_INIT
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/0000000000@10.1.0.11) State NEW
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/0000000000@10.1.0.11) Running State Change CS_INIT (Cur 2 Tot 82)
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/0000000000@10.1.0.11) State INIT
2017-11-17 17:17:06.360002 [DEBUG] mod_sofia.c:90 sofia/internal/0000000000@10.1.0.11 SOFIA INIT
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:40 sofia/internal/0000000000@10.1.0.11 Standard INIT
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/0000000000@10.1.0.11) State Change CS_INIT -> CS_ROUTING
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/0000000000@10.1.0.11) State INIT going to sleep
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/0000000000@10.1.0.11) Running State Change CS_ROUTING (Cur 2 Tot 82)
2017-11-17 17:17:06.360002 [DEBUG] switch_channel.c:2249 (sofia/internal/0000000000@10.1.0.11) Callstate Change DOWN -> RINGING
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/0000000000@10.1.0.11) State ROUTING
2017-11-17 17:17:06.360002 [DEBUG] mod_sofia.c:143 sofia/internal/0000000000@10.1.0.11 SOFIA ROUTING
2017-11-17 17:17:06.360002 [DEBUG] switch_core_state_machine.c:236 sofia/internal/0000000000@10.1.0.11 Standard ROUTING
2017-11-17 17:17:06.360002 [INFO] mod_dialplan_xml.c:637 Processing  <0000000000>->1001 in context public
Dialplan: sofia/internal/0000000000@10.1.0.11 parsing [public->0049xxxxxxx385] continue=false
Dialplan: sofia/internal/0000000000@10.1.0.11 Regex (FAIL) [0049xxxxxxx385] destination_number(1001) =~ /^(0049xxxxxxx385)$/ break=on-false
Dialplan: sofia/internal/0000000000@10.1.0.11 parsing [public->0049xxxxxxx685] continue=false
Dialplan: sofia/internal/0000000000@10.1.0.11 Regex (FAIL) [0049xxxxxxx685] destination_number(1001) =~ /^(0049xxxxxxx685)$/ break=on-false
2017-11-17 17:17:06.380000 [INFO] switch_core_state_machine.c:311 No Route, Aborting
2017-11-17 17:17:06.380000 [NOTICE] switch_core_state_machine.c:312 Hangup sofia/internal/0000000000@10.1.0.11 [CS_ROUTING] [NO_ROUTE_DESTINATION]
2017-11-17 17:17:06.380000 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/0000000000@10.1.0.11) State ROUTING going to sleep
2017-11-17 17:17:06.380000 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/0000000000@10.1.0.11) Running State Change CS_HANGUP (Cur 2 Tot 82)
2017-11-17 17:17:06.380000 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/0000000000@10.1.0.11) Callstate Change RINGING -> HANGUP
2017-11-17 17:17:06.380000 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/0000000000@10.1.0.11) State HANGUP
2017-11-17 17:17:06.380000 [DEBUG] mod_sofia.c:438 Channel sofia/internal/0000000000@10.1.0.11 hanging up, cause: NO_ROUTE_DESTINATION
2017-11-17 17:17:06.380000 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 404
2017-11-17 17:17:06.380000 [DEBUG] switch_core_state_machine.c:60 sofia/internal/0000000000@10.1.0.11 Standard HANGUP, cause: NO_ROUTE_DESTINATION
2017-11-17 17:17:06.380000 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/0000000000@10.1.0.11) State HANGUP going to sleep
2017-11-17 17:17:06.380000 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/0000000000@10.1.0.11) State Change CS_HANGUP -> CS_REPORTING
2017-11-17 17:17:06.380000 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/0000000000@10.1.0.11) Running State Change CS_REPORTING (Cur 2 Tot 82)
2017-11-17 17:17:06.380000 [DEBUG] sofia.c:6257 Remote Reason: 3
2017-11-17 17:17:06.380000 [DEBUG] sofia.c:7084 Channel sofia/internal/1001@10.1.0.11 entering state [terminated][404]
2017-11-17 17:17:06.380000 [NOTICE] sofia.c:8273 Hangup sofia/internal/1001@10.1.0.11 [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION]
2017-11-17 17:17:06.380000 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@10.1.0.11) Running State Change CS_HANGUP (Cur 2 Tot 82)
2017-11-17 17:17:06.380000 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/0000000000@10.1.0.11) State REPORTING
2017-11-17 17:17:06.380000 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 3 [NO_ROUTE_DESTINATION]
2017-11-17 17:17:06.380000 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1001@10.1.0.11) Callstate Change DOWN -> HANGUP
2017-11-17 17:17:06.380000 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1001@10.1.0.11) State HANGUP
2017-11-17 17:17:06.380000 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1001@10.1.0.11 hanging up, cause: NO_ROUTE_DESTINATION
2017-11-17 17:17:06.380000 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1001@10.1.0.11 Standard HANGUP, cause: NO_ROUTE_DESTINATION
2017-11-17 17:17:06.380000 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1001@10.1.0.11) State HANGUP going to sleep
2017-11-17 17:17:06.380000 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1001@10.1.0.11) State Change CS_HANGUP -> CS_REPORTING
2017-11-17 17:17:06.380000 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@10.1.0.11) Running State Change CS_REPORTING (Cur 2 Tot 82)
2017-11-17 17:17:06.380000 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1001@10.1.0.11) State REPORTING
2017-11-17 17:17:06.619974 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1001@10.1.0.11 Standard REPORTING, cause: NO_ROUTE_DESTINATION
2017-11-17 17:17:06.619974 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1001@10.1.0.11) State REPORTING going to sleep
2017-11-17 17:17:06.619974 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1001@10.1.0.11) State Change CS_REPORTING -> CS_DESTROY
2017-11-17 17:17:06.619974 [DEBUG] switch_core_session.c:1665 Session 81 (sofia/internal/1001@10.1.0.11) Locked, Waiting on external entities
2017-11-17 17:17:06.619974 [NOTICE] switch_core_session.c:1683 Session 81 (sofia/internal/1001@10.1.0.11) Ended
2017-11-17 17:17:06.619974 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/1001@10.1.0.11 [CS_DESTROY]
2017-11-17 17:17:06.619974 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1001@10.1.0.11) Running State Change CS_DESTROY (Cur 1 Tot 82)
2017-11-17 17:17:06.619974 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1001@10.1.0.11) State DESTROY
2017-11-17 17:17:06.619974 [DEBUG] mod_sofia.c:343 sofia/internal/1001@10.1.0.11 SOFIA DESTROY
2017-11-17 17:17:06.619974 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1001@10.1.0.11 Standard DESTROY
2017-11-17 17:17:06.619974 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1001@10.1.0.11) State DESTROY going to sleep
2017-11-17 17:17:06.639968 [DEBUG] switch_core_state_machine.c:174 sofia/internal/0000000000@10.1.0.11 Standard REPORTING, cause: NO_ROUTE_DESTINATION
2017-11-17 17:17:06.639968 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/0000000000@10.1.0.11) State REPORTING going to sleep
2017-11-17 17:17:06.639968 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/0000000000@10.1.0.11) State Change CS_REPORTING -> CS_DESTROY
2017-11-17 17:17:06.639968 [DEBUG] switch_core_session.c:1665 Session 82 (sofia/internal/0000000000@10.1.0.11) Locked, Waiting on external entities
2017-11-17 17:17:06.639968 [NOTICE] switch_core_session.c:1683 Session 82 (sofia/internal/0000000000@10.1.0.11) Ended
2017-11-17 17:17:06.639968 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/0000000000@10.1.0.11 [CS_DESTROY]
2017-11-17 17:17:06.639968 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/0000000000@10.1.0.11) Running State Change CS_DESTROY (Cur 0 Tot 82)
2017-11-17 17:17:06.639968 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/0000000000@10.1.0.11) State DESTROY
2017-11-17 17:17:06.639968 [DEBUG] mod_sofia.c:343 sofia/internal/0000000000@10.1.0.11 SOFIA DESTROY
2017-11-17 17:17:06.639968 [DEBUG] mod_opus.c:711 Opus decoder stats: Frames[0] PLC[0] FEC[0]
2017-11-17 17:17:06.639968 [DEBUG] mod_opus.c:726 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
2017-11-17 17:17:06.639968 [DEBUG] mod_opus.c:711 Opus decoder stats: Frames[0] PLC[0] FEC[0]
2017-11-17 17:17:06.639968 [DEBUG] mod_opus.c:726 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
2017-11-17 17:17:06.639968 [DEBUG] switch_core_state_machine.c:181 sofia/internal/0000000000@10.1.0.11 Standard DESTROY
2017-11-17 17:17:06.639968 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/0000000000@10.1.0.11) State DESTROY going to sleep

Dialing *2222 from the phone on 1001 works.

Do i need something in destinations, inbound or outbound route for this? From my understanding no.
 
DigitalDaz

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,045
566
113
I cannot understand where that 00000 is coming from, I have never tried what you are doing but I will do tomorrow and I'll suss it out. Its a little late here in the UK now.
 
M

MrGlasspoole

Member
Nov 11, 2017
31
0
6
47
Ok, i'm a step further.

This two examples work:
Code: 
originate user/1001 &echo()

originate user/1001 &playback(/usr/share/freeswitch/sounds/music/default/8000/ponce-preludio-in-e-major.wav)

But trying echo by number or tetris not:
Code: 
originate user/1001 *9196 < No Route, Aborting

originate user/1001 &tone_stream() < Invalid Application tone_stream

The first two also work from the Webapi:
Code: 
http://freeswitch:works@10.1.0.11:8080/webapi/originate?user/1001 &echo

http://freeswitch:works@10.1.0.11:8080/webapi/originate?user/1001 &playback(/usr/share/freeswitch/sounds/music/default/8000/ponce-preludio-in-e-major.wav)

What i tried when i started this thread and did not work came from here:
https://www.safaribooksonline.com/library/view/freeswitch-12-/9781782161004/ch09s07.html
https://alexn.org/blog/2009/02/20/tips-for-creating-voip-dialer.html

What now works partly came from here by going through the answers:
http://lists.freeswitch.org/pipermail/freeswitch-users/2013-February/092506.html
 
DigitalDaz

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,045
566
113
I'm trying to find what this & means, &echo etc

Found it:
  • "&" indicates what follows is an application name, not an exten
 
DigitalDaz

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,045
566
113
@MrGlasspoole I have it :)

Just done some testing, what the problem seems to be is you are not passing it the domain so the target eg *9196 or whatever, gets searched for in the default context, this would probably explain why it worked for you in freeswitch as you were probably using the default context.

If in your case, you tried for example:

Code: 
originate user/1001@10.1.0.11 *9664 XML 10.1.0.11

You will probably find it works, you should get music on hold :)
 
Status
Not open for further replies.
Top Bottom