SOLVED Unable to call extension to extension or external

[mevans1974]

Hi
I have an issue where we can't make extension to extension calls or external calls. everything was working fine before this started. It works fine outside the network from remote phones anything internal to the network it failed. I know it's a network issue but to what is I'm unable to say.
Have anyone experiencing this issue?







5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [NOTICE] switch_channel.c:1142 New Channel sofia/internal/4000@rmhbailey.a2kcommunications.com [5f0b3750-005e-4073-aef6-904b337610a9]
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/4000@rmhbailey.a2kcommunications.com) Running State Change CS_NEW (Cur 1 Tot 3927)
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [INFO] sofia.c:10459 sofia/internal/4000@rmhbailey.a2kcommunications.com receiving invite from 108.60.224.235:12229 version: 1.10.10 -release 64bit call-id: 1035694612-5060-3049@BHC.CI.HE.HA
2024-09-29 23:20:59.357307 98.63% [DEBUG] sofia.c:10553 verifying acl "providers" for ip/port 108.60.224.235:0.
2024-09-29 23:20:59.357307 98.63% [DEBUG] sofia.c:10582 IP 108.60.224.235 Approved by acl "providers[]". Access Granted.
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [DEBUG] sofia.c:11674 Setting NAT mode based on via port
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [DEBUG] sofia.c:7493 Channel sofia/internal/4000@rmhbailey.a2kcommunications.com entering state [received][100]
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [DEBUG] sofia.c:7503 Remote SDP:
5f0b3750-005e-4073-aef6-904b337610a9 v=0
5f0b3750-005e-4073-aef6-904b337610a9 o=4000 8000 8000 IN IP4 108.60.224.235
5f0b3750-005e-4073-aef6-904b337610a9 s=SIP Call
5f0b3750-005e-4073-aef6-904b337610a9 c=IN IP4 108.60.224.235
5f0b3750-005e-4073-aef6-904b337610a9 t=0 0
5f0b3750-005e-4073-aef6-904b337610a9 m=audio 65306 RTP/AVP 0 8 4 18 9 97 2 123 101
5f0b3750-005e-4073-aef6-904b337610a9 a=rtpmap:0 PCMU/8000
5f0b3750-005e-4073-aef6-904b337610a9 a=rtpmap:8 PCMA/8000
5f0b3750-005e-4073-aef6-904b337610a9 a=rtpmap:4 G723/8000
5f0b3750-005e-4073-aef6-904b337610a9 a=rtpmap:18 G729/8000
5f0b3750-005e-4073-aef6-904b337610a9 a=fmtp:18 annexb=no
5f0b3750-005e-4073-aef6-904b337610a9 a=rtpmap:9 G722/8000
5f0b3750-005e-4073-aef6-904b337610a9 a=rtpmap:97 iLBC/8000
5f0b3750-005e-4073-aef6-904b337610a9 a=fmtp:97 mode=30
5f0b3750-005e-4073-aef6-904b337610a9 a=rtpmap:2 G726-32/8000
5f0b3750-005e-4073-aef6-904b337610a9 a=rtpmap:123 opus/48000/2
5f0b3750-005e-4073-aef6-904b337610a9 a=rtpmap:101 telephone-event/8000
5f0b3750-005e-4073-aef6-904b337610a9 a=fmtp:101 0-15
5f0b3750-005e-4073-aef6-904b337610a9 a=ptime:20
5f0b3750-005e-4073-aef6-904b337610a9
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [DEBUG] sofia.c:7906 (sofia/internal/4000@rmhbailey.a2kcommunications.com) State Change CS_NEW -> CS_INIT
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [DEBUG] switch_core_state_machine.c:600 (sofia/internal/4000@rmhbailey.a2kcommunications.com) State NEW
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/4000@rmhbailey.a2kcommunications.com) Running State Change CS_INIT (Cur 1 Tot 3927)
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/4000@rmhbailey.a2kcommunications.com) State INIT
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [DEBUG] mod_sofia.c:97 sofia/internal/4000@rmhbailey.a2kcommunications.com SOFIA INIT
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [DEBUG] switch_core_state_machine.c:40 sofia/internal/4000@rmhbailey.a2kcommunications.com Standard INIT
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [DEBUG] switch_core_state_machine.c:48 (sofia/internal/4000@rmhbailey.a2kcommunications.com) State Change CS_INIT -> CS_ROUTING
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/4000@rmhbailey.a2kcommunications.com) State INIT going to sleep
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/4000@rmhbailey.a2kcommunications.com) Running State Change CS_ROUTING (Cur 1 Tot 3927)
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [DEBUG] switch_channel.c:2399 (sofia/internal/4000@rmhbailey.a2kcommunications.com) Callstate Change DOWN -> RINGING
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/4000@rmhbailey.a2kcommunications.com) State ROUTING
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [DEBUG] mod_sofia.c:158 sofia/internal/4000@rmhbailey.a2kcommunications.com SOFIA ROUTING
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [DEBUG] switch_core_state_machine.c:230 sofia/internal/4000@rmhbailey.a2kcommunications.com Standard ROUTING
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.357307 98.63% [INFO] mod_dialplan_xml.c:639 Processing 4000 <4000>->4495620 in context public
2024-09-29 23:20:59.397349 98.57% [NOTICE] switch_cpp.cpp:1465 [xml_handler] multiple key:dialplanublic
5f0b3750-005e-4073-aef6-904b337610a9 Dialplan: sofia/internal/4000@rmhbailey.a2kcommunications.com parsing [public->2423975568] continue=false
5f0b3750-005e-4073-aef6-904b337610a9 Dialplan: sofia/internal/4000@rmhbailey.a2kcommunications.com Regex (FAIL) [2423975568] destination_number(4495620) =~ /^(2423975568)$/ break=on-false
5f0b3750-005e-4073-aef6-904b337610a9 Dialplan: sofia/internal/4000@rmhbailey.a2kcommunications.com parsing [public->2423975561] continue=false
5f0b3750-005e-4073-aef6-904b337610a9 Dialplan: sofia/internal/4000@rmhbailey.a2kcommunications.com Regex (FAIL) [2423975561] destination_number(4495620) =~ /^(2423975561)$/ break=on-false
5f0b3750-005e-4073-aef6-904b337610a9 Dialplan: sofia/internal/4000@rmhbailey.a2kcommunications.com parsing [public->2423975560] continue=false
5f0b3750-005e-4073-aef6-904b337610a9 Dialplan: sofia/internal/4000@rmhbailey.a2kcommunications.com Regex (FAIL) [2423975560] destination_number(4495620) =~ /^(2423975560)$/ break=on-false
5f0b3750-005e-4073-aef6-904b337610a9 Dialplan: sofia/internal/4000@rmhbailey.a2kcommunications.com parsing [public->2423975566] continue=false
5f0b3750-005e-4073-aef6-904b337610a9 Dialplan: sofia/internal/4000@rmhbailey.a2kcommunications.com Regex (FAIL) [2423975566] destination_number(4495620) =~ /^(2423975566)$/ break=on-false
5f0b3750-005e-4073-aef6-904b337610a9 Dialplan: sofia/internal/4000@rmhbailey.a2kcommunications.com parsing [public->2423975567] continue=false
5f0b3750-005e-4073-aef6-904b337610a9 Dialplan: sofia/internal/4000@rmhbailey.a2kcommunications.com Regex (FAIL) [2423975567] destination_number(4495620) =~ /^(2423975567)$/ break=on-false
5f0b3750-005e-4073-aef6-904b337610a9 Dialplan: sofia/internal/4000@rmhbailey.a2kcommunications.com parsing [public->2423975565] continue=false
5f0b3750-005e-4073-aef6-904b337610a9 Dialplan: sofia/internal/4000@rmhbailey.a2kcommunications.com Regex (FAIL) [2423975565] destination_number(4495620) =~ /^(2423975565)$/ break=on-false
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [INFO] switch_core_state_machine.c:306 No Route, Aborting
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/internal/4000@rmhbailey.a2kcommunications.com [CS_ROUTING] [NO_ROUTE_DESTINATION]
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/4000@rmhbailey.a2kcommunications.com) State ROUTING going to sleep
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/4000@rmhbailey.a2kcommunications.com) Running State Change CS_HANGUP (Cur 1 Tot 3927)
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] switch_core_state_machine.c:844 (sofia/internal/4000@rmhbailey.a2kcommunications.com) Callstate Change RINGING -> HANGUP
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/4000@rmhbailey.a2kcommunications.com) State HANGUP
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] mod_sofia.c:469 Channel sofia/internal/4000@rmhbailey.a2kcommunications.com hanging up, cause: NO_ROUTE_DESTINATION
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] mod_sofia.c:614 Responding to INVITE with: 404
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] switch_core_state_machine.c:59 sofia/internal/4000@rmhbailey.a2kcommunications.com Standard HANGUP, cause: NO_ROUTE_DESTINATION
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/4000@rmhbailey.a2kcommunications.com) State HANGUP going to sleep
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] switch_core_state_machine.c:616 (sofia/internal/4000@rmhbailey.a2kcommunications.com) State Change CS_HANGUP -> CS_REPORTING
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/4000@rmhbailey.a2kcommunications.com) Running State Change CS_REPORTING (Cur 1 Tot 3927)
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/4000@rmhbailey.a2kcommunications.com) State REPORTING
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] switch_core_state_machine.c:168 sofia/internal/4000@rmhbailey.a2kcommunications.com Standard REPORTING, cause: NO_ROUTE_DESTINATION
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/4000@rmhbailey.a2kcommunications.com) State REPORTING going to sleep
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] switch_core_state_machine.c:607 (sofia/internal/4000@rmhbailey.a2kcommunications.com) State Change CS_REPORTING -> CS_DESTROY
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] switch_core_session.c:1744 Session 3927 (sofia/internal/4000@rmhbailey.a2kcommunications.com) Locked, Waiting on external entities
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [NOTICE] switch_core_session.c:1762 Session 3927 (sofia/internal/4000@rmhbailey.a2kcommunications.com) Ended
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [NOTICE] switch_core_session.c:1766 Close Channel sofia/internal/4000@rmhbailey.a2kcommunications.com [CS_DESTROY]
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] switch_core_state_machine.c:735 (sofia/internal/4000@rmhbailey.a2kcommunications.com) Running State Change CS_DESTROY (Cur 0 Tot 3927)
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/4000@rmhbailey.a2kcommunications.com) State DESTROY
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] mod_sofia.c:380 sofia/internal/4000@rmhbailey.a2kcommunications.com SOFIA DESTROY
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] switch_core_state_machine.c:175 sofia/internal/4000@rmhbailey.a2kcommunications.com Standard DESTROY
5f0b3750-005e-4073-aef6-904b337610a9 2024-09-29 23:20:59.397349 98.57% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/4000@rmhbailey.a2kcommunications.com) State DESTROY going to sleep

 

[DigitalDaz]

Hi, I see the problem immediately:

2024-09-29 23:20:59.357307 98.63% [DEBUG] sofia.c:10582 IP 108.60.224.235 Approved by acl "providers[]". Access Granted.

You have modified the ACL that should not have been done, the PBX is now treating you as a provider and it is using the public dialplan. Only your providers should go in that ACL.

 

[mevans1974]

Hi,
Thanks for your response I will give this a try and revert.



Thanks.

 

[mevans1974]

Hi@DigitalDaz
Thank you!!! that solved my problem.