I recently moved from FreePBX to FS PBX and so far the experience has been really good. FS PBX is considerably easier to set up and manage than FreePBX. Everything works as expected (internal calls, outgoing calls), except for incoming calls. I've searched the forums and made sure that everything is set up correctly. However, it seems that the incoming call rules do not get evaluated at all, resulting in the incoming call not being routed. Log is pasted below.
I would expect the any incoming call rule to either FAIL or PASS, but it appears they're simply not being evaluated. The rule is public and enabled.
Any pointers would be greatly appreciated!
I would expect the any incoming call rule to either FAIL or PASS, but it appears they're simply not being evaluated. The rule is public and enabled.
Any pointers would be greatly appreciated!
Code:
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [NOTICE] switch_channel.c:1143 New Channel sofia/external/164655555555@sip.callcentric.net [afbb2951-9c5f-4661-b23e-69e3f5e4b354]
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:581 (sofia/external/164655555555@sip.callcentric.net) Running State Change CS_NEW (Cur 1 Tot 23)
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [INFO] sofia.c:10466 sofia/external/164655555555@sip.callcentric.net receiving invite from 199.87.144.69:5080 version: 1.10.12-release git ef885e7 2026-01-09 17:15:19Z 64bit call-id: f4ffef8b-869e-123f-2794-0ae34d66bf8d
2026-02-17 12:28:08.330312 93.93% [DEBUG] sofia.c:10560 verifying acl "providers" for ip/port 199.87.144.69:0.
2026-02-17 12:28:08.330312 93.93% [DEBUG] sofia.c:10589 IP 199.87.144.69 Approved by acl "providers[]". Access Granted.
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] sofia.c:7493 Channel sofia/external/164655555555@sip.callcentric.net entering state [received][100]
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] sofia.c:7503 Remote SDP:
afbb2951-9c5f-4661-b23e-69e3f5e4b354 v=0
afbb2951-9c5f-4661-b23e-69e3f5e4b354 o=CCSBC 1771310248 1771310249 IN IP4 199.87.144.69
afbb2951-9c5f-4661-b23e-69e3f5e4b354 s=CCSBC
afbb2951-9c5f-4661-b23e-69e3f5e4b354 t=0 0
afbb2951-9c5f-4661-b23e-69e3f5e4b354 m=audio 13382 RTP/AVP 0 18 101
afbb2951-9c5f-4661-b23e-69e3f5e4b354 c=IN IP4 199.87.144.69
afbb2951-9c5f-4661-b23e-69e3f5e4b354 a=rtpmap:0 PCMU/8000
afbb2951-9c5f-4661-b23e-69e3f5e4b354 a=rtpmap:18 G729/8000
afbb2951-9c5f-4661-b23e-69e3f5e4b354 a=fmtp:18 annexb=no
afbb2951-9c5f-4661-b23e-69e3f5e4b354 a=rtpmap:101 telephone-event/8000
afbb2951-9c5f-4661-b23e-69e3f5e4b354 a=fmtp:101 0-15
afbb2951-9c5f-4661-b23e-69e3f5e4b354 a=rtcp:13383
afbb2951-9c5f-4661-b23e-69e3f5e4b354 a=ptime:20
afbb2951-9c5f-4661-b23e-69e3f5e4b354
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] sofia.c:7906 (sofia/external/164655555555@sip.callcentric.net) State Change CS_NEW -> CS_INIT
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:600 (sofia/external/164655555555@sip.callcentric.net) State NEW
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:581 (sofia/external/164655555555@sip.callcentric.net) Running State Change CS_INIT (Cur 1 Tot 23)
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:624 (sofia/external/164655555555@sip.callcentric.net) State INIT
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] mod_sofia.c:97 sofia/external/164655555555@sip.callcentric.net SOFIA INIT
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:40 sofia/external/164655555555@sip.callcentric.net Standard INIT
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:48 (sofia/external/164655555555@sip.callcentric.net) State Change CS_INIT -> CS_ROUTING
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:624 (sofia/external/164655555555@sip.callcentric.net) State INIT going to sleep
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:581 (sofia/external/164655555555@sip.callcentric.net) Running State Change CS_ROUTING (Cur 1 Tot 23)
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_channel.c:2400 (sofia/external/164655555555@sip.callcentric.net) Callstate Change DOWN -> RINGING
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:640 (sofia/external/164655555555@sip.callcentric.net) State ROUTING
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] mod_sofia.c:158 sofia/external/164655555555@sip.callcentric.net SOFIA ROUTING
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:230 sofia/external/164655555555@sip.callcentric.net Standard ROUTING
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [INFO] mod_dialplan_xml.c:648 Processing BAUER,JACK <164655555555>->17771234567 in context public
2026-02-17 12:28:08.330312 93.93% [NOTICE] switch_cpp.cpp:1466 [xml_handler] single key:dialplan:public:gw+c71e9989-2832-44bb-8785-b200023b37d4
afbb2951-9c5f-4661-b23e-69e3f5e4b354 Dialplan: sofia/external/164655555555@sip.callcentric.net parsing [public->caller-details] continue=true
afbb2951-9c5f-4661-b23e-69e3f5e4b354 Dialplan: sofia/external/164655555555@sip.callcentric.net Regex (PASS) [caller-details] () =~ // break=never
afbb2951-9c5f-4661-b23e-69e3f5e4b354 Dialplan: sofia/external/164655555555@sip.callcentric.net Action set(caller_destination=${sip_to_user}) INLINE
afbb2951-9c5f-4661-b23e-69e3f5e4b354 EXECUTE [depth=0] sofia/external/164655555555@sip.callcentric.net set(caller_destination=18325555555)
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] mod_dptools.c:1671 SET sofia/external/164655555555@sip.callcentric.net [caller_destination]=[18325555555]
afbb2951-9c5f-4661-b23e-69e3f5e4b354 Dialplan: sofia/external/164655555555@sip.callcentric.net Action set(caller_id_name=${caller_id_name}) INLINE
afbb2951-9c5f-4661-b23e-69e3f5e4b354 EXECUTE [depth=0] sofia/external/164655555555@sip.callcentric.net set(caller_id_name=BAUER,JACK)
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] mod_dptools.c:1671 SET sofia/external/164655555555@sip.callcentric.net [caller_id_name]=[BAUER,JACK]
afbb2951-9c5f-4661-b23e-69e3f5e4b354 Dialplan: sofia/external/164655555555@sip.callcentric.net Action set(caller_id_number=${caller_id_number})
afbb2951-9c5f-4661-b23e-69e3f5e4b354 Dialplan: sofia/external/164655555555@sip.callcentric.net parsing [public->not-found] continue=false
afbb2951-9c5f-4661-b23e-69e3f5e4b354 Dialplan: sofia/external/164655555555@sip.callcentric.net Regex (PASS) [not-found] () =~ // break=on-false
afbb2951-9c5f-4661-b23e-69e3f5e4b354 Dialplan: sofia/external/164655555555@sip.callcentric.net Action set(call_direction=inbound) INLINE
afbb2951-9c5f-4661-b23e-69e3f5e4b354 EXECUTE [depth=0] sofia/external/164655555555@sip.callcentric.net set(call_direction=inbound)
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] mod_dptools.c:1671 SET sofia/external/164655555555@sip.callcentric.net [call_direction]=[inbound]
afbb2951-9c5f-4661-b23e-69e3f5e4b354 Dialplan: sofia/external/164655555555@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})
afbb2951-9c5f-4661-b23e-69e3f5e4b354 Dialplan: sofia/external/164655555555@sip.callcentric.net Action respond(404 Not Found)
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:281 (sofia/external/164655555555@sip.callcentric.net) State Change CS_ROUTING -> CS_EXECUTE
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:640 (sofia/external/164655555555@sip.callcentric.net) State ROUTING going to sleep
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:581 (sofia/external/164655555555@sip.callcentric.net) Running State Change CS_EXECUTE (Cur 1 Tot 23)
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:647 (sofia/external/164655555555@sip.callcentric.net) State EXECUTE
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] mod_sofia.c:213 sofia/external/164655555555@sip.callcentric.net SOFIA EXECUTE
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:323 sofia/external/164655555555@sip.callcentric.net Standard EXECUTE
afbb2951-9c5f-4661-b23e-69e3f5e4b354 EXECUTE [depth=0] sofia/external/164655555555@sip.callcentric.net set(caller_id_number=164655555555)
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] mod_dptools.c:1671 SET sofia/external/164655555555@sip.callcentric.net [caller_id_number]=[164655555555]
afbb2951-9c5f-4661-b23e-69e3f5e4b354 EXECUTE [depth=0] sofia/external/164655555555@sip.callcentric.net log(WARNING [inbound routes] 404 not found 199.87.144.69 destination_number 17771234567 sip_to_user 18325555555 sip_req_user gw+c71e9989-2832-44bb-8785-b200023b37d4)
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [WARNING] mod_dptools.c:1865 [inbound routes] 404 not found 199.87.144.69 destination_number 17771234567 sip_to_user 18325555555 sip_req_user gw+c71e9989-2832-44bb-8785-b200023b37d4
afbb2951-9c5f-4661-b23e-69e3f5e4b354 EXECUTE [depth=0] sofia/external/164655555555@sip.callcentric.net respond(404 Not Found)
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] mod_sofia.c:2368 Responding with 404 [Not Found]
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [NOTICE] mod_sofia.c:2436 Hangup sofia/external/164655555555@sip.callcentric.net [CS_EXECUTE] [UNALLOCATED_NUMBER]
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_session.c:2983 sofia/external/164655555555@sip.callcentric.net skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:647 (sofia/external/164655555555@sip.callcentric.net) State EXECUTE going to sleep
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:581 (sofia/external/164655555555@sip.callcentric.net) Running State Change CS_HANGUP (Cur 1 Tot 23)
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:844 (sofia/external/164655555555@sip.callcentric.net) Callstate Change RINGING -> HANGUP
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:846 (sofia/external/164655555555@sip.callcentric.net) State HANGUP
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] mod_sofia.c:477 Channel sofia/external/164655555555@sip.callcentric.net hanging up, cause: UNALLOCATED_NUMBER
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] mod_sofia.c:622 Responding to INVITE with: 404
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:59 sofia/external/164655555555@sip.callcentric.net Standard HANGUP, cause: UNALLOCATED_NUMBER
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:846 (sofia/external/164655555555@sip.callcentric.net) State HANGUP going to sleep
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:616 (sofia/external/164655555555@sip.callcentric.net) State Change CS_HANGUP -> CS_REPORTING
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:581 (sofia/external/164655555555@sip.callcentric.net) Running State Change CS_REPORTING (Cur 1 Tot 23)
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:932 (sofia/external/164655555555@sip.callcentric.net) State REPORTING
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:168 sofia/external/164655555555@sip.callcentric.net Standard REPORTING, cause: UNALLOCATED_NUMBER
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:932 (sofia/external/164655555555@sip.callcentric.net) State REPORTING going to sleep
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:607 (sofia/external/164655555555@sip.callcentric.net) State Change CS_REPORTING -> CS_DESTROY
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_session.c:1748 Session 23 (sofia/external/164655555555@sip.callcentric.net) Locked, Waiting on external entities
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [NOTICE] switch_core_session.c:1766 Session 23 (sofia/external/164655555555@sip.callcentric.net) Ended
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [NOTICE] switch_core_session.c:1770 Close Channel sofia/external/164655555555@sip.callcentric.net [CS_DESTROY]
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:735 (sofia/external/164655555555@sip.callcentric.net) Running State Change CS_DESTROY (Cur 0 Tot 23)
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] switch_core_state_machine.c:745 (sofia/external/164655555555@sip.callcentric.net) State DESTROY
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.330312 93.93% [DEBUG] mod_sofia.c:388 sofia/external/164655555555@sip.callcentric.net SOFIA DESTROY
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.450308 93.93% [DEBUG] switch_core_state_machine.c:175 sofia/external/164655555555@sip.callcentric.net Standard DESTROY
afbb2951-9c5f-4661-b23e-69e3f5e4b354 2026-02-17 12:28:08.450308 93.93% [DEBUG] switch_core_state_machine.c:745 (sofia/external/164655555555@sip.callcentric.net) State DESTROY going to sleep
