Fusion PBX can't call between extensions or dial out

Status
Not open for further replies.

mevans1974

New Member
Jan 23, 2022
22
0
1
49
Hi All
I have this issue with my fusion PBX. The PBX is hosted in the cloud, The users can receive calls but can't make out going calls.
The soft phone on my mobile phones over 4g can make and receive calls and call extensions without any issue.

Any help would be greatly appreciated

.
022-08-13 02:27:20.289154 96.13% [NOTICE] switch_channel.c:1123 New Channel sofia/internal/399@blaac.abc.com [7db04122-b354-4a10-8ec2-c784b3d38e5f]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/399@ blaac.abc.com) Running State Change CS_NEW (Cur 1 Tot 75869)

2022-08-13 02:27:20.289154 96.13% [INFO] sofia.c:10462 sofia/internal/399@blaac.abc.com receiving invite from 24.231.52.82:1024 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 4bff4373ee5721f@192.168.20.48

2022-08-13 02:27:20.289154 96.13% [DEBUG] sofia.c:10556 verifying acl "domains" for ip/port 24.231.52.82:0.

2022-08-13 02:27:20.289154 96.13% [DEBUG] sofia.c:10585 IP 24.231.52.82 Approved by acl "domains[]". Access Granted.

2022-08-13 02:27:20.289154 96.13% [DEBUG] sofia.c:11672 Setting NAT mode based on via port

2022-08-13 02:27:20.289154 96.13% [DEBUG] sofia.c:7499 Channel sofia/internal/399@ blaac.abc.com entering state [received][100]

2022-08-13 02:27:20.289154 96.13% [DEBUG] sofia.c:7509 Remote SDP:

v=0

o=- 793 792 IN IP4 192.168.20.48

s=SIP Call

c=IN IP4 24.231.52.82

t=0 0

m=audio 12100 RTP/AVP 0 8 9 97 120 2 101

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:9 G722/8000

a=rtpmap:97 iLBC/8000

a=fmtp:97 mode=20

a=rtpmap:120 opus/48000/2

a=fmtp:120 useinbandfec=1;usedtx=1;maxaveragebitrate=64000

a=rtpmap:2 G726-32/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-11,16

a=ptime:20



2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:97:8000:20:0:1]/[G722:9:8000:20:64000:1]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:97:8000:20:0:1]/[PCMU:0:8000:20:64000:1]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:97:8000:20:0:1]/[PCMA:8:8000:20:64000:1]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [opus:120:48000:20:0:2]/[G722:9:8000:20:64000:1]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [opus:120:48000:20:0:2]/[PCMU:0:8000:20:64000:1]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [opus:120:48000:20:0:2]/[PCMA:8:8000:20:64000:1]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G726-32:2:8000:20:0:1]/[G722:9:8000:20:64000:1]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G726-32:2:8000:20:0:1]/[PCMU:0:8000:20:64000:1]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G726-32:2:8000:20:0:1]/[PCMA:8:8000:20:64000:1]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:3870 Set Codec sofia/internal/399@blaac.abc.com PCMU/8000 20 ms 160 samples 64000 bits 1 channels

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_codec.c:111 sofia/internal/399@blaac.abc.com Original read codec set to PCMU:0

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_media.c:5973 sofia/internal/399@blaac. abc.com Set 2833 dtmf send payload to 101 recv payload to 101



2022-08-13 02:27:20.289154 96.13% [DEBUG] sofia.c:7933 (sofia/internal/399@blaac.abc.com) State Change CS_NEW -> CS_INIT

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_state_machine.c:600 (sofia/internal/399@blaac.abc.com) State NEW

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/399@blaac.abc.com) Running State Change CS_INIT (Cur 1 Tot 75869)

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/399@blaac.abc.com) State INIT

2022-08-13 02:27:20.289154 96.13% [DEBUG] mod_sofia.c:97 sofia/internal/399@blaac.abc.com SOFIA INIT

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_state_machine.c:40 sofia/internal/399@blaac.abc.com Standard INIT

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_state_machine.c:48 (sofia/internal/399@blaac.abc.com) State Change CS_INIT -> CS_ROUTING

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/399@blaac.abc.com) State INIT going to sleep

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/399@blaac. abc.com) Running State Change CS_ROUTING (Cur 1 Tot 75869)



2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_channel.c:2380 (sofia/internal/399@blaac. abc.com) Callstate Change DOWN -> RINGING

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/399@blaac. abc.com) State ROUTING

2022-08-13 02:27:20.289154 96.13% [DEBUG] mod_sofia.c:158 sofia/internal/399@blaac. abc.com SOFIA ROUTING

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_state_machine.c:230 sofia/internal/399@blaac. abc.com Standard ROUTING

2022-08-13 02:27:20.289154 96.13% [INFO] mod_dialplan_xml.c:639 Processing 399 <399>->303 in context public

Dialplan: sofia/internal/399@blaac. abc.com parsing [public->2423027070] continue=false



Dialplan: sofia/internal/399@blaac. abc.com Regex (FAIL) [2423027070] destination_number(303) =~ /^(2423027070)$/ break=on-false

Dialplan: sofia/internal/399@blaac. abc.com parsing [public->12422256980] continue=false



Dialplan: sofia/internal/399@blaac. abc.com Regex (FAIL) [12422256980] destination_number(303) =~ /^(12422256980)$/ break=on-false

Dialplan: sofia/internal/399@blaac. abc.com parsing [public->5956679933] continue=false



Dialplan: sofia/internal/399@blaac. abc.com Regex (FAIL) [5956679933] destination_number(303) =~ /^(5956679933)$/ break=on-false



Dialplan: sofia/internal/399@blaac. abc.com parsing [public->2426980123] continue=false

Dialplan: sofia/internal/399@blaac. abc.com Regex (FAIL) [2426980123] destination_number(303) =~ /^(2426980123)$/ break=on-false

Dialplan: sofia/internal/399@blaac. abc.com parsing [public->2426980124] continue=false



Dialplan: sofia/internal/399@blaac. abc.com Regex (FAIL) [2426980124] destination_number(303) =~ /^(2426980124)$/ break=on-false

2022-08-13 02:27:20.289154 96.13% [INFO] switch_core_state_machine.c:306 No Route, Aborting

2022-08-13 02:27:20.289154 96.13% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/internal/399@blaac. abc.com [CS_ROUTING] [NO_ROUTE_DESTINATION]

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/399@blaac. abc.com) State ROUTING going to sleep

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/399@blaac. abc.com) Running State Change CS_HANGUP (Cur 1 Tot 75869)



2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_state_machine.c:844 (sofia/internal/399@blaac. abc.com) Callstate Change RINGING -> HANGUP

2022-08-13 02:27:20.289154 96.13% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/399@blaac. abc.com) State HANGUP

2022-08-13 02:27:20.289154 96.13% [DEBUG] mod_sofia.c:468 Channel sofia/internal/399@blaac. abc.com hanging up, cause: NO_ROUTE_DESTINATION

2022-08-13 02:27:20.289154 96.13% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404

2022-08-13 02:27:20.309100 96.13% [DEBUG] switch_core_state_machine.c:59 sofia/internal/399@blaac. abc.com Standard HANGUP, cause: NO_ROUTE_DESTINATION



2022-08-13 02:27:20.309100 96.13% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/399@blaac. abc.com) State HANGUP going to sleep

2022-08-13 02:27:20.309100 96.13% [DEBUG] switch_core_state_machine.c:616 (sofia/internal/399@blaac. abc.com) State Change CS_HANGUP -> CS_REPORTING

2022-08-13 02:27:20.309100 96.13% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/399@blaac. abc.com) Running State Change CS_REPORTING (Cur 1 Tot 75869)

2022-08-13 02:27:20.309100 96.13% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/399@blaac. abc.com) State REPORTING

2022-08-13 02:27:20.409095 96.13% [DEBUG] switch_core_state_machine.c:168



sofia/internal/399@blaac. abc.com Standard REPORTING, cause: NO_ROUTE_DESTINATION

2022-08-13 02:27:20.409095 96.13% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/399@blaac. abc.com) State REPORTING going to sleep

2022-08-13 02:27:20.409095 96.13% [DEBUG] switch_core_state_machine.c:607 (sofia/internal/399@blaac. abc.com) State Change CS_REPORTING -> CS_DESTROY

2022-08-13 02:27:20.409095 96.13% [DEBUG] switch_core_session.c:1753 Session 75869 (sofia/internal/399@blaac. abc.com) Locked, Waiting on external entities

2022-08-13 02:27:20.409095 96.13% [NOTICE] switch_core_session.c:1771 Session 75869 (sofia/internal/399@blaac. abc.com) Ended

2022-08-13 02:27:20.409095 96.13% [NOTICE] switch_core_session.c:1775 Close Channel sofia/internal/399@blaac. abc.com [CS_DESTROY]

2022-08-13 02:27:20.409095 96.13% [DEBUG] switch_core_state_machine.c:735 (sofia/internal/399@blaac. abc.com) Running State Change CS_DESTROY (Cur 0 Tot 75869)



2022-08-13 02:27:20.409095 96.13% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/399@blaac. abc.com) State DESTROY

2022-08-13 02:27:20.409095 96.13% [DEBUG] mod_sofia.c:379 sofia/internal/399@blaac. abc.com SOFIA DESTROY

2022-08-13 02:27:20.409095 96.13% [DEBUG] switch_core_state_machine.c:175 sofia/internal/399@blaac. abc.com Standard DESTROY

2022-08-13 02:27:20.409095 96.13% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/399@blaac. abc.com) State DESTROY going to sleep
 

Adrian Fretwell

Well-Known Member
Aug 13, 2017
1,412
376
83
It looks like a network related issue, just like the type of thing experienced with NAT problems. Try to find out why the INVITE is sent from port 1024 but port 1025 is advertised in the Contact header.
 

mevans1974

New Member
Jan 23, 2022
22
0
1
49
I believe it's and ISP issue. I tried the phones on a different ISP and it works, I put them back on the ISP that they will be using I get this issue.
 
Status
Not open for further replies.