Set server for testing

Status
Not open for further replies.

StefanIvanov

New Member
Sep 17, 2020
9
0
1
25
Hello, everybody

I`m working on a school project (small CRM system) and I encounter difficulties with the call central. I want to make calls from one extension to another. I`m using sipjs to make the connection between clients and the server. When I try to make a call freeswitch trows me a couple of warnings, but i think the problem comes in that one " [inbound routes] 404 not found ${sip_network_ip} "

Here is the log of test call

Code:
Today at 10:03 AM
Add bookmark
#13
Hi everyone,

I`m making a school project(mini CRM system). I think i have similar problem. When i try to make an inbound call I have an warning "[inbound routes] 404 not found ${sip_network_ip}". Here is the log of test call

Code:
FreeSWITCH Started
Max Sessions [1000]
Session Rate [30]
SQL [Enabled]
2020-09-17 15:20:08.807325 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'internal' for [1011@xxx.xxx.x.118] from ip xxx.xxx.x.165
2020-09-17 15:21:00.367301 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'internal' for [1010@xxx.xxx.x.118] from ip xxx.xxx.x.122
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.347326 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/1010@xxx.xxx.x.118 [e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.347326 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/1010@xxx.xxx.x.118) Running State Change CS_NEW (C
ur 1 Tot 1)
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.407271 [DEBUG] sofia.c:10280 sofia/internal/1010@xxx.xxx.x.118 receiving invite from xxx.xxx.x.122:52102 version: 1.10.5 -release-17-25569c1631 64bit
2020-09-17 15:21:12.447386 [DEBUG] sofia.c:10374 verifying acl "localnet.auto" for ip/port xxx.xxx.x.122:0.
2020-09-17 15:21:12.447386 [DEBUG] sofia.c:10403 IP xxx.xxx.x.122 Approved by acl "localnet.auto[]". Access Granted.
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.507342 [DEBUG] sofia.c:11477 Setting NAT mode based on via received
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.507342 [DEBUG] sofia.c:7326 Channel sofia/internal/1010@xxx.xxx.x.118 entering state [received][100]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.507342 [DEBUG] sofia.c:7336 Remote SDP:
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 v=0
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 o=- 6838702468214364159 2 IN IP4 127.0.0.1
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 s=-
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 t=0 0
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=group:BUNDLE 0
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=msid-semantic: WMS 2bdcc949-4110-4bb5-8109-fd6674d9f389
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 m=audio 57622 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 c=IN IP4 37.157.169.84
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=rtpmap:111 opus/48000/2
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=fmtp:111 minptime=10;useinbandfec=1
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=rtpmap:103 ISAC/16000
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=rtpmap:104 ISAC/32000
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=rtpmap:9 G722/8000
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=rtpmap:0 PCMU/8000
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=rtpmap:8 PCMA/8000
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=rtpmap:106 CN/32000
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=rtpmap:105 CN/16000
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=rtpmap:13 CN/8000
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=rtpmap:110 telephone-event/48000
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=rtpmap:112 telephone-event/32000
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=rtpmap:113 telephone-event/16000
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=rtpmap:126 telephone-event/8000
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=rtcp:9 IN IP4 0.0.0.0
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=candidate:1668076467 1 udp 2113937151 xxx.xxx.x.122 57622 typ host generation 0 network-cost 999
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=candidate:842163049 1 udp 1677729535 37.157.169.84 57622 typ srflx raddr xxx.xxx.x.122 rport 57622 generation 0 network-cost 999
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=ice-ufrag:Y1H1
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=ice-pwd:bgGkQW2Q+QkKBVEVeJlM9pAv
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=ice-options:trickle
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=fingerprint:sha-256 DF:C9:FB:E0:B5:DB:9E:D3:74:08:52:95:41:EB:7E:54:57:10:05:0F:0D:44:C3:C3:3B:E8:D4:43:4E:A8:4F:EF
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=setup:actpass
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=mid:0
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=extmap:2 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=msid:2bdcc949-4110-4bb5-8109-fd6674d9f389 44d74464-519c-4317-b202-5c9b407c065b
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=rtcp-mux
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=rtcp-fb:111 transport-cc
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=ssrc:732787876 cname:/tqRPKnxJClA9pZO
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=ssrc:732787876 msid:2bdcc949-4110-4bb5-8109-fd6674d9f389 44d74464-519c-4317-b202-5c9b407c065b
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=ssrc:732787876 mslabel:2bdcc949-4110-4bb5-8109-fd6674d9f389
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 a=ssrc:732787876 label:44d74464-519c-4317-b202-5c9b407c065b
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.507342 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [opus:111:48000:20:0:2]/[G722:9:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.507342 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [opus:111:48000:20:0:2]/[PCMU:0:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.507342 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [opus:111:48000:20:0:2]/[PCMA:8:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.507342 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[G722:9:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.507342 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[PCMU:0:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.507342 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[PCMA:8:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.507342 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[G722:9:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.507342 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[PCMU:0:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.507342 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[PCMA:8:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.507342 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.507342 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.527218 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.527218 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.527218 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.527218 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.527218 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.527218 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.527218 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.527218 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.527218 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.527218 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.527218 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [CN:105:16000:20:0:1]/[G722:9:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.527218 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [CN:105:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.527218 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [CN:105:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.527218 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [CN:13:8000:20:0:1]/[G722:9:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.527218 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [CN:13:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.527218 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [CN:13:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.527218 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 110@48000
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.587258 [DEBUG] switch_core_media.c:3839 Set Codec sofia/internal/1010@xxx.xxx.x.118 G722/8000 20 ms 160 samples 64000 bits 1 channels
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.587258 [DEBUG] switch_core_codec.c:111 sofia/internal/1010@xxx.xxx.x.118 Original read codec set to G722:9
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.587258 [WARNING] switch_core_media.c:4254 NO candidate ACL defined, Defaulting to wan.auto
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.587258 [DEBUG] switch_core_media.c:4284 Save audio Candidate cid: 1 proto: udp type: host addr: xxx.xxx.x.122:57622
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.587258 [DEBUG] switch_core_media.c:4284 Save audio Candidate cid: 1 proto: udp type: srflx addr: 37.157.169.84:57622
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.707331 [DEBUG] switch_core_media.c:4329 Searching for rtp candidate.
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.707331 [DEBUG] switch_core_media.c:4338 Choose rtp candidate, index 1, 37.157.169.84:57622
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.707331 [DEBUG] switch_core_media.c:4104 sofia/internal/1010@xxx.xxx.x.118 choosing family v4
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.707331 [DEBUG] switch_core_media.c:4349 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 37.157.169.84:57622
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.707331 [DEBUG] switch_core_media.c:4401 setting remote audio ice addr to index 1 37.157.169.84:57622 based on candidate
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.707331 [DEBUG] switch_core_media.c:4436 Setting remote rtcp audio addr to 37.157.169.84:57622 based on candidate
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.707331 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 126@8000
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.707331 [DEBUG] switch_core_media.c:5911 sofia/internal/1010@xxx.xxx.x.118 Set 2833 dtmf send payload to 126 recv payload to 126
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.707331 [DEBUG] sofia.c:7760 (sofia/internal/1010@xxx.xxx.x.118) State Change CS_NEW -> CS_INIT
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.707331 [DEBUG] switch_core_state_machine.c:604 (sofia/internal/1010@xxx.xxx.x.118) State NEW
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.707331 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/1010@xxx.xxx.x.118) Running State Change CS_INIT (Cur 1 Tot 1)
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.707331 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/1010@xxx.xxx.x.118) State INIT
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.727249 [DEBUG] mod_sofia.c:93 sofia/internal/1010@xxx.xxx.x.118 SOFIA INIT
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.727249 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1010@xxx.xxx.x.118 Standard INIT
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.727249 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1010@xxx.xxx.x.118) State Change CS_INIT -> CS_ROUTING
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.727249 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/1010@xxx.xxx.x.118) State INIT going to sleep
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.727249 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/1010@xxx.xxx.x.118) Running State Change CS_ROUTING (Cur 1 Tot 1)
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.727249 [DEBUG] switch_channel.c:2332 (sofia/internal/1010@xxx.xxx.x.118) Callstate Change DOWN -> RINGING
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.727249 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/1010@xxx.xxx.x.118) State ROUTING
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.727249 [DEBUG] mod_sofia.c:154 sofia/internal/1010@xxx.xxx.x.118 SOFIA ROUTING
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.727249 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1010@xxx.xxx.x.118 Standard ROUTING
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:12.767373 [INFO] mod_dialplan_xml.c:637 Processing Stefan <1010>->1011 in context public
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 Dialplan: sofia/internal/1010@xxx.xxx.x.118 parsing [public->caller-details] continue=true
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 Dialplan: sofia/internal/1010@xxx.xxx.x.118 Regex (PASS) [caller-details] () =~ // break=never
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 Dialplan: sofia/internal/1010@xxx.xxx.x.118 Action export(call_direction=inbound) INLINE
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 EXECUTE [depth=0] sofia/internal/1010@xxx.xxx.x.118 export(call_direction=inbound)
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_direction]=[inbound]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 Dialplan: sofia/internal/1010@xxx.xxx.x.118 Action set(caller_destination=${sip_to_user}) INLINE
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 EXECUTE [depth=0] sofia/internal/1010@xxx.xxx.x.118 set(caller_destination=1011)
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1010@xxx.xxx.x.118 [caller_destination]=[1011]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 Dialplan: sofia/internal/1010@xxx.xxx.x.118 Action set(caller_id_name=${caller_id_name}) INLINE
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 EXECUTE [depth=0] sofia/internal/1010@xxx.xxx.x.118 set(caller_id_name=Stefan)
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1010@xxx.xxx.x.118 [caller_id_name]=[Stefan]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 Dialplan: sofia/internal/1010@xxx.xxx.x.118 Action set(caller_id_number=${caller_id_number})
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 Dialplan: sofia/internal/1010@xxx.xxx.x.118 parsing [public->not-found] continue=false
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 Dialplan: sofia/internal/1010@xxx.xxx.x.118 Regex (PASS) [not-found] () =~ // break=on-false
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 Dialplan: sofia/internal/1010@xxx.xxx.x.118 Action export(call_direction=inbound) INLINE
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 EXECUTE [depth=0] sofia/internal/1010@xxx.xxx.x.118 export(call_direction=inbound)
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_direction]=[inbound]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 Dialplan: sofia/internal/1010@xxx.xxx.x.118 Action set(call_direction=inbound) INLINE
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 EXECUTE [depth=0] sofia/internal/1010@xxx.xxx.x.118 set(call_direction=inbound)
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1010@xxx.xxx.x.118 [call_direction]=[inbound]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 Dialplan: sofia/internal/1010@xxx.xxx.x.118 Action log(WARNING [inbound routes] 404 not found ${sip_network_ip})
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/1010@xxx.xxx.x.118) State Change CS_ROUTING -> CS_EXECUTE
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/1010@xxx.xxx.x.118) State ROUTING going to sleep
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/1010@xxx.xxx.x.118) Running State Change CS_EXECUTE (Cur 1 Tot 1)
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/1010@xxx.xxx.x.118) State EXECUTE
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [DEBUG] mod_sofia.c:209 sofia/internal/1010@xxx.xxx.x.118 SOFIA EXECUTE
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [DEBUG] switch_core_state_machine.c:329 sofia/internal/1010@xxx.xxx.x.118 Standard EXECUTE
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 EXECUTE [depth=0] sofia/internal/1010@xxx.xxx.x.118 set(caller_id_number=1010)
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1010@xxx.xxx.x.118 [caller_id_number]=[1010]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 EXECUTE [depth=0] sofia/internal/1010@xxx.xxx.x.118 log(WARNING [inbound routes] 404 not found xxx.xxx.x.122)
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [WARNING] mod_dptools.c:1866 [inbound routes] 404 not found xxx.xxx.x.122
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [NOTICE] switch_core_state_machine.c:386 sofia/internal/1010@xxx.xxx.x.118 has executed the last dialplan instruction, hanging up.
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/internal/1010@xxx.xxx.x.118 [CS_EXECUTE] [NORMAL_CLEARING]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/1010@xxx.xxx.x.118) State EXECUTE going to sleep
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/1010@xxx.xxx.x.118) Running State Change CS_HANGUP (Cur 1 Tot 1)
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/1010@xxx.xxx.x.118) Callstate Change RINGING -> HANGUP
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1010@xxx.xxx.x.118) State HANGUP
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.187281 [DEBUG] mod_sofia.c:453 Channel sofia/internal/1010@xxx.xxx.x.118 hanging up, cause: NORMAL_CLEARING
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.247345 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.267334 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1010@xxx.xxx.x.118 Standard HANGUP, cause: NORMAL_CLEARING
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.267334 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1010@xxx.xxx.x.118) State HANGUP going to sleep
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.267334 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/1010@xxx.xxx.x.118) State Change CS_HANGUP -> CS_REPORTING
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.267334 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/1010@xxx.xxx.x.118) Running State Change CS_REPORTING (Cur 1 Tot 1)
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.267334 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/1010@xxx.xxx.x.118) State REPORTING
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.867284 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1010@xxx.xxx.x.118 Standard REPORTING, cause: NORMAL_CLEARING
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.867284 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/1010@xxx.xxx.x.118) State REPORTING going to sleep
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.867284 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/1010@xxx.xxx.x.118) State Change CS_REPORTING -> CS_DESTROY
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.867284 [DEBUG] switch_core_session.c:1726 Session 1 (sofia/internal/1010@xxx.xxx.x.118) Locked, Waiting on external entities
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.867284 [NOTICE] switch_core_session.c:1744 Session 1 (sofia/internal/1010@xxx.xxx.x.118) Ended
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.867284 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/1010@xxx.xxx.x.118 [CS_DESTROY]
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.867284 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/1010@xxx.xxx.x.118) Running State Change CS_DESTROY (Cur 0 Tot 1)
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.867284 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/1010@xxx.xxx.x.118) State DESTROY
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.867284 [DEBUG] mod_sofia.c:364 sofia/internal/1010@xxx.xxx.x.118 SOFIA DESTROY
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.867284 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1010@xxx.xxx.x.118 Standard DESTROY
e08e2acb-b4d6-44f7-9516-fc3d9ed73fa9 2020-09-17 15:21:13.867284 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/1010@xxx.xxx.x.118) State DESTROY going to sleep
2020-09-17 15:25:49.727281 [WARNING] switch_scheduler.c:117 Task was executed late by 2 seconds 1 heartbeat (core)
2020-09-17 15:25:49.747259 [WARNING] switch_scheduler.c:117 Task was executed late by 2 seconds 2 check_ip (core)
2020-09-17 15:30:07.707281 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'internal' for [1011@xxx.xxx.x.118] from ip xxx.xxx.x.165
2020-09-17 15:30:57.547337 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'internal' for [1010@xxx.xxx.x.118] from ip xxx.xxx.x.122
2020-09-17 15:34:42.507227 [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=299 [select call_id,sip_user,sip_host,contact,status,rpid,expires,user_agent,server_user,server_host,profile_name from sip_registrations where force_ping=1 and hostname='debian' and profile_name='internal' and orig_hostname='debian' and ping_expires > 0 and ping_expires <= 1600353282]

I can`t find what is the problem and similar threads that i read didn`t help me.

Thank you in advance
 

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,070
577
113
Its really easy, you have added your ip to acl, you should not have done that, this has now put you in the public context so your call is being treated as if it came from the carrier. Remove the acl entry and reload acl.
 

StefanIvanov

New Member
Sep 17, 2020
9
0
1
25
How ACL should be set

Maybe I can`t understand how ACL works. I have removed all acls and still not working.
 
Last edited:

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,070
577
113
Restart freeswitch maybe needed. You shouldn't touch the ACL except to add carriers.
 

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,070
577
113
You shouldn't have removed all acls just the ones you had tinkered with.
 
Status
Not open for further replies.