problems with outgoing calls

Status
Not open for further replies.
K

kristiam garcia

Member
Feb 21, 2017
74
3
8
40
good afternoon when making calls to a cell phone does not run and indicates that the call_direction is inbond when it should be outbound

2020-06-06 17:21:26.440093 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/2000@10.10.0.20 [33e8b6cc-64f7-456d-a7bb-63c45da0e438]
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2000@10.10.0.20) Running State Change CS_NEW (Cur 1 Tot 54)
2020-06-06 17:21:26.440093 [DEBUG] sofia.c:10279 sofia/internal/2000@10.10.0.20 receiving invite from 10.11.1.11:5068 version: 1.10.3 -release-15-129de34d84 64bit
2020-06-06 17:21:26.440093 [DEBUG] sofia.c:10373 verifying acl "domains" for ip/port 10.11.1.11:0.
2020-06-06 17:21:26.440093 [DEBUG] sofia.c:10402 IP 10.11.1.11 Approved by acl "domains[]". Access Granted.
2020-06-06 17:21:26.440093 [DEBUG] sofia.c:11476 Setting NAT mode based on via received
2020-06-06 17:21:26.440093 [DEBUG] sofia.c:7325 Channel sofia/internal/2000@10.10.0.20 entering state [received][100]
2020-06-06 17:21:26.440093 [DEBUG] sofia.c:7335 Remote SDP:
v=0
o=2000 8000 8000 IN IP4 192.168.1.137
s=SIP Call
c=IN IP4 192.168.1.137
t=0 0
m=audio 30348 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtcp:30349 IN IP4 192.168.1.137
a=ptime:20

2020-06-06 17:21:26.440093 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2020-06-06 17:21:26.440093 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2020-06-06 17:21:26.440093 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2020-06-06 17:21:26.440093 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2020-06-06 17:21:26.440093 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2020-06-06 17:21:26.440093 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2020-06-06 17:21:26.440093 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2020-06-06 17:21:26.440093 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2020-06-06 17:21:26.440093 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000
2020-06-06 17:21:26.440093 [DEBUG] switch_core_media.c:3837 Set Codec sofia/internal/2000@10.10.0.20 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2020-06-06 17:21:26.440093 [DEBUG] switch_core_codec.c:111 sofia/internal/2000@10.10.0.20 Original read codec set to PCMU:0
2020-06-06 17:21:26.440093 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000
2020-06-06 17:21:26.440093 [DEBUG] switch_core_media.c:5909 sofia/internal/2000@10.10.0.20 Set 2833 dtmf send payload to 101 recv payload to 101
2020-06-06 17:21:26.440093 [DEBUG] sofia.c:7759 (sofia/internal/2000@10.10.0.20) State Change CS_NEW -> CS_INIT
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:604 (sofia/internal/2000@10.10.0.20) State NEW
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2000@10.10.0.20) Running State Change CS_INIT (Cur 1 Tot 54)
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/2000@10.10.0.20) State INIT
2020-06-06 17:21:26.440093 [DEBUG] mod_sofia.c:93 sofia/internal/2000@10.10.0.20 SOFIA INIT
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:40 sofia/internal/2000@10.10.0.20 Standard INIT
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/2000@10.10.0.20) State Change CS_INIT -> CS_ROUTING
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/2000@10.10.0.20) State INIT going to sleep
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2000@10.10.0.20) Running State Change CS_ROUTING (Cur 1 Tot 54)
2020-06-06 17:21:26.440093 [DEBUG] switch_channel.c:2332 (sofia/internal/2000@10.10.0.20) Callstate Change DOWN -> RINGING
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/2000@10.10.0.20) State ROUTING
2020-06-06 17:21:26.440093 [DEBUG] mod_sofia.c:154 sofia/internal/2000@10.10.0.20 SOFIA ROUTING
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:236 sofia/internal/2000@10.10.0.20 Standard ROUTING
2020-06-06 17:21:26.440093 [INFO] mod_dialplan_xml.c:637 Processing Kristiam Garcia <2000>->904265197955 in context public
Dialplan: sofia/internal/2000@10.10.0.20 parsing [public->caller-details] continue=true
Dialplan: sofia/internal/2000@10.10.0.20 Regex (PASS) [caller-details] () =~ // break=never
Dialplan: sofia/internal/2000@10.10.0.20 Action export(call_direction=inbound) INLINE
EXECUTE [depth=0] sofia/internal/2000@10.10.0.20 export(call_direction=inbound)
2020-06-06 17:21:26.440093 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_direction]=[inbound]
Dialplan: sofia/internal/2000@10.10.0.20 Action set(caller_destination=${sip_to_user}) INLINE
EXECUTE [depth=0] sofia/internal/2000@10.10.0.20 set(caller_destination=904265197955)
2020-06-06 17:21:26.440093 [DEBUG] mod_dptools.c:1672 SET sofia/internal/2000@10.10.0.20 [caller_destination]=[904265197955]
Dialplan: sofia/internal/2000@10.10.0.20 Action set(caller_id_name=${caller_id_name}) INLINE
EXECUTE [depth=0] sofia/internal/2000@10.10.0.20 set(caller_id_name=Kristiam Garcia)
2020-06-06 17:21:26.440093 [DEBUG] mod_dptools.c:1672 SET sofia/internal/2000@10.10.0.20 [caller_id_name]=[Kristiam Garcia]
Dialplan: sofia/internal/2000@10.10.0.20 Action set(caller_id_number=${caller_id_number})
Dialplan: sofia/internal/2000@10.10.0.20 parsing [public->not-found] continue=false
Dialplan: sofia/internal/2000@10.10.0.20 Regex (PASS) [not-found] () =~ // break=on-false
Dialplan: sofia/internal/2000@10.10.0.20 Action export(call_direction=inbound) INLINE
EXECUTE [depth=0] sofia/internal/2000@10.10.0.20 export(call_direction=inbound)
2020-06-06 17:21:26.440093 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_direction]=[inbound]
Dialplan: sofia/internal/2000@10.10.0.20 Action set(call_direction=inbound) INLINE
EXECUTE [depth=0] sofia/internal/2000@10.10.0.20 set(call_direction=inbound)
2020-06-06 17:21:26.440093 [DEBUG] mod_dptools.c:1672 SET sofia/internal/2000@10.10.0.20 [call_direction]=[inbound]
Dialplan: sofia/internal/2000@10.10.0.20 Action log(WARNING [inbound routes] 404 not found ${sip_network_ip})
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/2000@10.10.0.20) State Change CS_ROUTING -> CS_EXECUTE
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/2000@10.10.0.20) State ROUTING going to sleep
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2000@10.10.0.20) Running State Change CS_EXECUTE (Cur 1 Tot 54)
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/2000@10.10.0.20) State EXECUTE
2020-06-06 17:21:26.440093 [DEBUG] mod_sofia.c:209 sofia/internal/2000@10.10.0.20 SOFIA EXECUTE
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:329 sofia/internal/2000@10.10.0.20 Standard EXECUTE
EXECUTE [depth=0] sofia/internal/2000@10.10.0.20 set(caller_id_number=2000)
2020-06-06 17:21:26.440093 [DEBUG] mod_dptools.c:1672 SET sofia/internal/2000@10.10.0.20 [caller_id_number]=[2000]
EXECUTE [depth=0] sofia/internal/2000@10.10.0.20 log(WARNING [inbound routes] 404 not found 10.11.1.11)
2020-06-06 17:21:26.440093 [WARNING] mod_dptools.c:1866 [inbound routes] 404 not found 10.11.1.11
2020-06-06 17:21:26.440093 [NOTICE] switch_core_state_machine.c:386 sofia/internal/2000@10.10.0.20 has executed the last dialplan instruction, hanging up.
2020-06-06 17:21:26.440093 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/internal/2000@10.10.0.20 [CS_EXECUTE] [NORMAL_CLEARING]
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/2000@10.10.0.20) State EXECUTE going to sleep
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2000@10.10.0.20) Running State Change CS_HANGUP (Cur 1 Tot 54)
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/2000@10.10.0.20) Callstate Change RINGING -> HANGUP
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/2000@10.10.0.20) State HANGUP
2020-06-06 17:21:26.440093 [DEBUG] mod_sofia.c:453 Channel sofia/internal/2000@10.10.0.20 hanging up, cause: NORMAL_CLEARING
2020-06-06 17:21:26.440093 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:60 sofia/internal/2000@10.10.0.20 Standard HANGUP, cause: NORMAL_CLEARING
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/2000@10.10.0.20) State HANGUP going to sleep
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/2000@10.10.0.20) State Change CS_HANGUP -> CS_REPORTING
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2000@10.10.0.20) Running State Change CS_REPORTING (Cur 1 Tot 54)
2020-06-06 17:21:26.440093 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/2000@10.10.0.20) State REPORTING
2020-06-06 17:21:26.480041 [DEBUG] switch_core_state_machine.c:174 sofia/internal/2000@10.10.0.20 Standard REPORTING, cause: NORMAL_CLEARING
2020-06-06 17:21:26.480041 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/2000@10.10.0.20) State REPORTING going to sleep
2020-06-06 17:21:26.480041 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/2000@10.10.0.20) State Change CS_REPORTING -> CS_DESTROY
2020-06-06 17:21:26.480041 [DEBUG] switch_core_session.c:1726 Session 54 (sofia/internal/2000@10.10.0.20) Locked, Waiting on external entities
2020-06-06 17:21:26.480041 [NOTICE] switch_core_session.c:1744 Session 54 (sofia/internal/2000@10.10.0.20) Ended
2020-06-06 17:21:26.480041 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/2000@10.10.0.20 [CS_DESTROY]
2020-06-06 17:21:26.480041 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/2000@10.10.0.20) Running State Change CS_DESTROY (Cur 0 Tot 54)
2020-06-06 17:21:26.480041 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/2000@10.10.0.20) State DESTROY
2020-06-06 17:21:26.480041 [DEBUG] mod_sofia.c:364 sofia/internal/2000@10.10.0.20 SOFIA DESTROY
2020-06-06 17:21:26.480041 [DEBUG] switch_core_state_machine.c:181 sofia/internal/2000@10.10.0.20 Standard DESTROY
2020-06-06 17:21:26.480041 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/2000@10.10.0.20) State DESTROY going to sleep
 
A

ad5ou

Active Member
Jun 12, 2018
884
197
43
2020-06-06 17:21:26.440093 [DEBUG] sofia.c:10402 IP 10.11.1.11 Approved by acl "domains[]". Access Granted.
Click to expand...
Your ACL configuration is wrong.

Access Controls — FusionPBX Docs documentation

docs.fusionpbx.com docs.fusionpbx.com

  • The main purpose is for your carriers ip addresses. Add the carrier IP addresses to the CIDR.
  • Be careful with what and how you use ACL.
  • Most common mistakes result in calls not working between extensions and other undesirable results.
  • Be sure to keep Domains access control to default deny.
  • Do not put your public ip or phone IP addresses in the domains access control list.
  • Don’t supply both the domain and the cidr on the same node.
  • If adding a single IP address to the CIDR field make sure to add /32 on the end of the IP address.
 
Status
Not open for further replies.
Top Bottom