Incoming calls are not working [inbound routes] 404 not found

[akashdeep000]

Hi, I'm trying to setup FS PBX with callcentric DID.

The outgoing is working perfectly but the incoming call are not working.and HANGUP with cause: UNALLOCATED_NUMBER

I'm suspecting the problem here:

Callcentric rather than sending the invite like this:
INVITE sip:184****701@my-pbx-ip

It is sending like this:
INVITE sip:gw+UUID@my-pbx-ip
To: sip:184****7011@my-domain

I tried my best so that inbound calls being matched using sip_to_user but no success. I attached my current setup screen shoots bellow.



I guess this is the critical log:
```

EXECUTE [depth=0] sofia/external/918********3@sip.callcentric.net log(WARNING [inbound routes] 404 not found 199.87.144.69 destination_number 1777MYCCID sip_to_user 184****7011 sip_req_user gw+5186173e-28cd-48e7-aa3e-7b3188cad50e)
2026-02-21 12:20:24.548307 93.47% [WARNING] mod_dptools.c:1865 [inbound routes] 404 not found 199.87.144.69 destination_number 1777MYCCID sip_to_user 184****7011 sip_req_user gw+5186173e-28cd-48e7-aa3e-7b3188cad50e
EXECUTE [depth=0] sofia/external/918********3@sip.callcentric.net respond(404 Not Found)

```

Also here is the full log:

```

2026-02-21 12:20:24.508335 93.47% [DEBUG] sofia.c:10560 verifying acl "providers" for ip/port 199.87.144.69:0.
2026-02-21 12:20:24.508335 93.47% [DEBUG] sofia.c:10589 IP 199.87.144.69 Approved by acl "providers[]". Access Granted.
2026-02-21 12:20:24.508335 93.47% [DEBUG] sofia.c:7493 Channel sofia/external/918********3@sip.callcentric.net entering state [received][100]
2026-02-21 12:20:24.508335 93.47% [DEBUG] sofia.c:7503 Remote SDP:
v=0
o=CCSBC 1771653696 1771653697 IN IP4 199.87.144.69
s=CCSBC
t=0 0
m=audio 11610 RTP/AVP 0 8 18 101
c=IN IP4 199.87.144.69
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtcp:11611
a=ptime:20

2026-02-21 12:20:24.508335 93.47% [DEBUG] sofia.c:7906 (sofia/external/918********3@sip.callcentric.net) State Change CS_NEW -> CS_INIT
2026-02-21 12:20:24.508335 93.47% [DEBUG] switch_core_state_machine.c:600 (sofia/external/918********3@sip.callcentric.net) State NEW
2026-02-21 12:20:24.508335 93.47% [DEBUG] switch_core_state_machine.c:581 (sofia/external/918********3@sip.callcentric.net) Running State Change CS_INIT (Cur 1 Tot 11)
2026-02-21 12:20:24.508335 93.47% [DEBUG] switch_core_state_machine.c:624 (sofia/external/918********3@sip.callcentric.net) State INIT
2026-02-21 12:20:24.508335 93.47% [DEBUG] mod_sofia.c:97 sofia/external/918********3@sip.callcentric.net SOFIA INIT
2026-02-21 12:20:24.508335 93.47% [DEBUG] switch_core_state_machine.c:40 sofia/external/918********3@sip.callcentric.net Standard INIT
2026-02-21 12:20:24.508335 93.47% [DEBUG] switch_core_state_machine.c:48 (sofia/external/918********3@sip.callcentric.net) State Change CS_INIT -> CS_ROUTING
2026-02-21 12:20:24.508335 93.47% [DEBUG] switch_core_state_machine.c:624 (sofia/external/918********3@sip.callcentric.net) State INIT going to sleep
2026-02-21 12:20:24.508335 93.47% [DEBUG] switch_core_state_machine.c:581 (sofia/external/918********3@sip.callcentric.net) Running State Change CS_ROUTING (Cur 1 Tot 11)
2026-02-21 12:20:24.508335 93.47% [DEBUG] switch_channel.c:2400 (sofia/external/918********3@sip.callcentric.net) Callstate Change DOWN -> RINGING
2026-02-21 12:20:24.508335 93.47% [DEBUG] switch_core_state_machine.c:640 (sofia/external/918********3@sip.callcentric.net) State ROUTING
2026-02-21 12:20:24.508335 93.47% [DEBUG] mod_sofia.c:158 sofia/external/918********3@sip.callcentric.net SOFIA ROUTING
2026-02-21 12:20:24.508335 93.47% [DEBUG] switch_core_state_machine.c:230 sofia/external/918********3@sip.callcentric.net Standard ROUTING
2026-02-21 12:20:24.508335 93.47% [INFO] mod_dialplan_xml.c:648 Processing 918********3 <918********3>->1777MYCCID in context public
2026-02-21 12:20:24.548307 93.47% [NOTICE] switch_cpp.cpp:1466 [xml_handler] single key:dialplan:public:gw+5186173e-28cd-48e7-aa3e-7b3188cad50e
Dialplan: sofia/external/918********3@sip.callcentric.net parsing [public->caller-details] continue=true
Dialplan: sofia/external/918********3@sip.callcentric.net Regex (PASS) [caller-details] () =~ // break=never
Dialplan: sofia/external/918********3@sip.callcentric.net Action set(caller_destination=${sip_to_user}) INLINE
EXECUTE [depth=0] sofia/external/918********3@sip.callcentric.net set(caller_destination=184****7011)
2026-02-21 12:20:24.548307 93.47% [DEBUG] mod_dptools.c:1671 SET sofia/external/918********3@sip.callcentric.net [caller_destination]=[184****7011]
Dialplan: sofia/external/918********3@sip.callcentric.net Action set(caller_id_name=${caller_id_name}) INLINE
EXECUTE [depth=0] sofia/external/918********3@sip.callcentric.net set(caller_id_name=918********3)
2026-02-21 12:20:24.548307 93.47% [DEBUG] mod_dptools.c:1671 SET sofia/external/918********3@sip.callcentric.net [caller_id_name]=[918********3]
Dialplan: sofia/external/918********3@sip.callcentric.net Action set(caller_id_number=${caller_id_number})
Dialplan: sofia/external/918********3@sip.callcentric.net parsing [public->not-found] continue=false
Dialplan: sofia/external/918********3@sip.callcentric.net Regex (PASS) [not-found] () =~ // break=on-false
Dialplan: sofia/external/918********3@sip.callcentric.net Action set(call_direction=inbound) INLINE
EXECUTE [depth=0] sofia/external/918********3@sip.callcentric.net set(call_direction=inbound)
2026-02-21 12:20:24.548307 93.47% [DEBUG] mod_dptools.c:1671 SET sofia/external/918********3@sip.callcentric.net [call_direction]=[inbound]
Dialplan: sofia/external/918********3@sip.callcentric.net Action log(WARNING [inbound routes] 404 not found ${sip_network_ip} destination_number ${destination_number} sip_to_user ${sip_to_user} sip_req_user ${sip_req_user})
Dialplan: sofia/external/918********3@sip.callcentric.net Action respond(404 Not Found)
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:281 (sofia/external/918********3@sip.callcentric.net) State Change CS_ROUTING -> CS_EXECUTE
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:640 (sofia/external/918********3@sip.callcentric.net) State ROUTING going to sleep
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:581 (sofia/external/918********3@sip.callcentric.net) Running State Change CS_EXECUTE (Cur 1 Tot 11)
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:647 (sofia/external/918********3@sip.callcentric.net) State EXECUTE
2026-02-21 12:20:24.548307 93.47% [DEBUG] mod_sofia.c:213 sofia/external/918********3@sip.callcentric.net SOFIA EXECUTE
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:323 sofia/external/918********3@sip.callcentric.net Standard EXECUTE
EXECUTE [depth=0] sofia/external/918********3@sip.callcentric.net set(caller_id_number=918********3)
2026-02-21 12:20:24.548307 93.47% [DEBUG] mod_dptools.c:1671 SET sofia/external/918********3@sip.callcentric.net [caller_id_number]=[918********3]
EXECUTE [depth=0] sofia/external/918********3@sip.callcentric.net log(WARNING [inbound routes] 404 not found 199.87.144.69 destination_number 1777MYCCID sip_to_user 184****7011 sip_req_user gw+5186173e-28cd-48e7-aa3e-7b3188cad50e)
2026-02-21 12:20:24.548307 93.47% [WARNING] mod_dptools.c:1865 [inbound routes] 404 not found 199.87.144.69 destination_number 1777MYCCID sip_to_user 184****7011 sip_req_user gw+5186173e-28cd-48e7-aa3e-7b3188cad50e
EXECUTE [depth=0] sofia/external/918********3@sip.callcentric.net respond(404 Not Found)
2026-02-21 12:20:24.548307 93.47% [DEBUG] mod_sofia.c:2368 Responding with 404 [Not Found]
2026-02-21 12:20:24.548307 93.47% [NOTICE] mod_sofia.c:2436 Hangup sofia/external/918********3@sip.callcentric.net [CS_EXECUTE] [UNALLOCATED_NUMBER]
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_session.c:2983 sofia/external/918********3@sip.callcentric.net skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:647 (sofia/external/918********3@sip.callcentric.net) State EXECUTE going to sleep
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:581 (sofia/external/918********3@sip.callcentric.net) Running State Change CS_HANGUP (Cur 1 Tot 11)
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:844 (sofia/external/918********3@sip.callcentric.net) Callstate Change RINGING -> HANGUP
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:846 (sofia/external/918********3@sip.callcentric.net) State HANGUP
2026-02-21 12:20:24.548307 93.47% [DEBUG] mod_sofia.c:477 Channel sofia/external/918********3@sip.callcentric.net hanging up, cause: UNALLOCATED_NUMBER
2026-02-21 12:20:24.548307 93.47% [DEBUG] mod_sofia.c:622 Responding to INVITE with: 404
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:59 sofia/external/918********3@sip.callcentric.net Standard HANGUP, cause: UNALLOCATED_NUMBER
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:846 (sofia/external/918********3@sip.callcentric.net) State HANGUP going to sleep
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:616 (sofia/external/918********3@sip.callcentric.net) State Change CS_HANGUP -> CS_REPORTING
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:581 (sofia/external/918********3@sip.callcentric.net) Running State Change CS_REPORTING (Cur 1 Tot 11)
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:932 (sofia/external/918********3@sip.callcentric.net) State REPORTING
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:168 sofia/external/918********3@sip.callcentric.net Standard REPORTING, cause: UNALLOCATED_NUMBER
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:932 (sofia/external/918********3@sip.callcentric.net) State REPORTING going to sleep
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:607 (sofia/external/918********3@sip.callcentric.net) State Change CS_REPORTING -> CS_DESTROY
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_session.c:1748 Session 11 (sofia/external/918********3@sip.callcentric.net) Locked, Waiting on external entities
2026-02-21 12:20:24.548307 93.47% [NOTICE] switch_core_session.c:1766 Session 11 (sofia/external/918********3@sip.callcentric.net) Ended
2026-02-21 12:20:24.548307 93.47% [NOTICE] switch_core_session.c:1770 Close Channel sofia/external/918********3@sip.callcentric.net [CS_DESTROY]
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:735 (sofia/external/918********3@sip.callcentric.net) Running State Change CS_DESTROY (Cur 0 Tot 11)
2026-02-21 12:20:24.548307 93.47% [DEBUG] switch_core_state_machine.c:745 (sofia/external/918********3@sip.callcentric.net) State DESTROY
2026-02-21 12:20:24.548307 93.47% [DEBUG] mod_sofia.c:388 sofia/external/918********3@sip.callcentric.net SOFIA DESTROY
2026-02-21 12:20:24.648364 93.47% [DEBUG] switch_core_state_machine.c:175 sofia/external/918********3@sip.callcentric.net Standard DESTROY
2026-02-21 12:20:24.648364 93.47% [DEBUG] switch_core_state_machine.c:745 (sofia/external/918********3@sip.callcentric.net) State DESTROY going to sleep

```