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
I can`t find what is the problem and similar threads that i read didn`t help me.
Thank you in advance
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