Not able to make any outbound or inbound calls

Status
Not open for further replies.
S

sayantan

New Member
Jan 25, 2021
7
0
1
46
Hi,

I am very new with PBX systems, I have setup a FusionPBX and i am trying to make outbound calls to another PBX (Matrix)
but somehow its not able to establish a call , State ROUTING going to sleep.

can anyone please help.
2021-03-25 00:54:48.650655 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/1001@103.112.252.18:5060 [98c1e164-8cd6-11eb-8efb-c5f2213591c4]
2021-03-25 00:54:48.650655 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/1001@103.112.252.18:5060) Running State Change CS_NEW (Cur 1 Tot 223)
2021-03-25 00:54:48.650655 [DEBUG] sofia.c:10279 sofia/internal/1001@103.112.252.18:5060 receiving invite from 103.215.135.245:61863 version: 1.10.3 -release 32bit
2021-03-25 00:54:48.650655 [DEBUG] sofia.c:10373 verifying acl "domains" for ip/port 103.215.135.245:0.
2021-03-25 00:54:48.650655 [WARNING] sofia_reg.c:1794 SIP auth challenge (INVITE) on sofia profile 'internal' for [127@103.112.252.18] from ip 103.215.135.245
2021-03-25 00:54:48.650655 [DEBUG] switch_core_state_machine.c:604 (sofia/internal/1001@103.112.252.18:5060) State NEW
2021-03-25 00:54:48.650655 [DEBUG] sofia.c:2434 detaching session 98c1e164-8cd6-11eb-8efb-c5f2213591c4
2021-03-25 00:54:48.750605 [DEBUG] sofia.c:2544 Re-attaching to session 98c1e164-8cd6-11eb-8efb-c5f2213591c4
2021-03-25 00:54:48.750605 [DEBUG] sofia.c:10279 sofia/internal/1001@103.112.252.18:5060 receiving invite from 103.215.135.245:61863 version: 1.10.3 -release 32bit
2021-03-25 00:54:48.750605 [DEBUG] sofia.c:10373 verifying acl "domains" for ip/port 103.215.135.245:0.
2021-03-25 00:54:48.770590 [DEBUG] sofia.c:11476 Setting NAT mode based on via received
2021-03-25 00:54:48.770590 [DEBUG] sofia.c:7325 Channel sofia/internal/1001@103.112.252.18:5060 entering state [received][100]
2021-03-25 00:54:48.770590 [DEBUG] sofia.c:7335 Remote SDP:
v=0
o=- 20139 20139 IN IP4 192.168.137.8
s=SDP data
c=IN IP4 192.168.137.8
t=0 0
m=audio 11786 RTP/AVP 9 0 8 18 101
a=rtpmap:9 G722/8000
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=ptime:20

2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000
2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:3837 Set Codec sofia/internal/1001@103.112.252.18:5060 G722/8000 20 ms 160 samples 64000 bits 1 channels
2021-03-25 00:54:48.770590 [DEBUG] switch_core_codec.c:111 sofia/internal/1001@103.112.252.18:5060 Original read codec set to G722:9
2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000
2021-03-25 00:54:48.770590 [DEBUG] switch_core_media.c:5909 sofia/internal/1001@103.112.252.18:5060 Set 2833 dtmf send payload to 101 recv payload to 101
2021-03-25 00:54:48.770590 [DEBUG] sofia.c:7759 (sofia/internal/1001@103.112.252.18:5060) State Change CS_NEW -> CS_INIT
2021-03-25 00:54:48.770590 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/1001@103.112.252.18:5060) Running State Change CS_INIT (Cur 1 Tot 223)
2021-03-25 00:54:48.770590 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/1001@103.112.252.18:5060) State INIT
2021-03-25 00:54:48.770590 [DEBUG] mod_sofia.c:93 sofia/internal/1001@103.112.252.18:5060 SOFIA INIT
2021-03-25 00:54:48.770590 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1001@103.112.252.18:5060 Standard INIT
2021-03-25 00:54:48.770590 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1001@103.112.252.18:5060) State Change CS_INIT -> CS_ROUTING
2021-03-25 00:54:48.770590 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/1001@103.112.252.18:5060) State INIT going to sleep
2021-03-25 00:54:48.770590 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/1001@103.112.252.18:5060) Running State Change CS_ROUTING (Cur 1 Tot 223)
2021-03-25 00:54:48.770590 [DEBUG] switch_channel.c:2332 (sofia/internal/1001@103.112.252.18:5060) Callstate Change DOWN -> RINGING
2021-03-25 00:54:48.770590 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/1001@103.112.252.18:5060) State ROUTING
2021-03-25 00:54:48.770590 [DEBUG] mod_sofia.c:154 sofia/internal/1001@103.112.252.18:5060 SOFIA ROUTING
2021-03-25 00:54:48.770590 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1001@103.112.252.18:5060 Standard ROUTING
2021-03-25 00:54:48.770590 [INFO] mod_dialplan_xml.c:637 Processing Sayantan <1001>->127 in context 103.112.252.18
Dialplan: sofia/internal/1001@103.112.252.18:5060 parsing [103.112.252.18->user_exists] continue=true
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_exists] ${loopback_leg}() =~ /^B$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (PASS) [user_exists] () =~ // break=on-false
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(user_exists=false)
2021-03-25 00:54:48.830639 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [user_exists]=[false]
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(from_user_exists=true)
2021-03-25 00:54:48.830639 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [from_user_exists]=[true]
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1001@103.112.252.18:5060 parsing [103.112.252.18->caller-details] continue=true
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (PASS) [caller-details] ${caller_destination}() =~ /^$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(caller_destination=${destination_number}) INLINE
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(caller_destination=127)
2021-03-25 00:54:48.830639 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [caller_destination]=[127]
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(caller_id_name=${caller_id_name}) INLINE
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(caller_id_name=1001)
2021-03-25 00:54:48.830639 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [caller_id_name]=[1001]
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(caller_id_number=${caller_id_number})
Dialplan: sofia/internal/1001@103.112.252.18:5060 parsing [103.112.252.18->call-direction] continue=true
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 ANTI-Action export(call_direction=local) INLINE
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 export(call_direction=local)
2021-03-25 00:54:48.830639 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_direction]=[local]
Dialplan: sofia/internal/1001@103.112.252.18:5060 parsing [103.112.252.18->global-variables] continue=true
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (PASS) [global-variables] () =~ // break=on-false
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/1001@103.112.252.18:5060 parsing [103.112.252.18->domain-variables] continue=true
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (PASS) [domain-variables] () =~ // break=on-false
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action export(origination_callee_id_name=${destination_number})
Dialplan: sofia/internal/1001@103.112.252.18:5060 parsing [103.112.252.18->is_loopback] continue=true
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false
Dialplan: sofia/internal/1001@103.112.252.18:5060 parsing [103.112.252.18->user_record] continue=true
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(from_user_record=)
2021-03-25 00:54:48.850587 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [from_user_record]=[UNDEF]
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/1001@103.112.252.18:5060 parsing [103.112.252.18->rtp_has_crypto] continue=true
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [rtp_has_crypto] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=on-false
Dialplan: sofia/internal/1001@103.112.252.18:5060 parsing [103.112.252.18->redial] continue=true
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [redial] destination_number(127) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (PASS) [redial] () =~ // break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/1001@103.112.252.18:5060 parsing [103.112.252.18->speed_dial] continue=false
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [speed_dial] destination_number(127) =~ /^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/1001@103.112.252.18:5060 parsing [103.112.252.18->default_caller_id] continue=true
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (PASS) [default_caller_id] ${emergency_caller_id_name}() =~ /^$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(emergency_caller_id_name=${default_emergency_caller_id_name}) INLINE
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(emergency_caller_id_name=)
2021-03-25 00:54:48.850587 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [emergency_caller_id_name]=[UNDEF]
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (PASS) [default_caller_id] ${emergency_caller_id_number}() =~ /^$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(emergency_caller_id_number=${default_emergency_caller_id_number}) INLINE
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(emergency_caller_id_number=)
2021-03-25 00:54:48.850587 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [emergency_caller_id_number]=[UNDEF]
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (PASS) [default_caller_id] ${outbound_caller_id_name}() =~ /^$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(outbound_caller_id_name=${default_outbound_caller_id_name}) INLINE
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(outbound_caller_id_name=)
2021-03-25 00:54:48.850587 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [outbound_caller_id_name]=[UNDEF]
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (PASS) [default_caller_id] ${outbound_caller_id_number}() =~ /^$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(outbound_caller_id_number=${default_outbound_caller_id_number}) INLINE
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(outbound_caller_id_number=)
2021-03-25 00:54:48.850587 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [outbound_caller_id_number]=[UNDEF]
Dialplan: sofia/internal/1001@103.112.252.18:5060 parsing [103.112.252.18->user_hold_music] continue=true
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_hold_music] ${hold_music}(local_stream://default) =~ /^$/ break=never
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (FAIL) [user_hold_music] ${user_exists}(false) =~ /true/ break=on-false
Dialplan: sofia/internal/1001@103.112.252.18:5060 parsing [103.112.252.18->Matrix.d3] continue=false
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (PASS) [Matrix.d3] ${user_exists}(false) =~ /false/ break=on-false
Dialplan: sofia/internal/1001@103.112.252.18:5060 Regex (PASS) [Matrix.d3] destination_number(127) =~ /^(\d{3})$/ break=on-false
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(sip_h_X-accountcode=${accountcode})
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action export(call_direction=outbound)
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action unset(call_timeout)
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(hangup_after_bridge=true)
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(effective_caller_id_name=${outbound_caller_id_name})
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(effective_caller_id_number=${outbound_caller_id_number})
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(inherit_codec=true)
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(ignore_display_updates=true)
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(callee_id_number=127)
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action set(continue_on_fail=true)
Dialplan: sofia/internal/1001@103.112.252.18:5060 Action bridge(sofia/gateway/88fdd52e-92b5-452b-b65e-b5a4e68020a1/127)
2021-03-25 00:54:48.850587 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/1001@103.112.252.18:5060) State Change CS_ROUTING -> CS_EXECUTE
2021-03-25 00:54:48.850587 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/1001@103.112.252.18:5060) State ROUTING going to sleep
2021-03-25 00:54:48.850587 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/1001@103.112.252.18:5060) Running State Change CS_EXECUTE (Cur 1 Tot 223)
2021-03-25 00:54:48.850587 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/1001@103.112.252.18:5060) State EXECUTE
2021-03-25 00:54:48.850587 [DEBUG] mod_sofia.c:209 sofia/internal/1001@103.112.252.18:5060 SOFIA EXECUTE
2021-03-25 00:54:48.850587 [DEBUG] switch_core_state_machine.c:329 sofia/internal/1001@103.112.252.18:5060 Standard EXECUTE
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(caller_id_number=1001)
2021-03-25 00:54:48.850587 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [caller_id_number]=[1001]
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(RFC2822_DATE=Thu, 25 Mar 2021 00:54:48 +0530)
2021-03-25 00:54:48.850587 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [RFC2822_DATE]=[Thu, 25 Mar 2021 00:54:48 +0530]
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 export(origination_callee_id_name=127)
2021-03-25 00:54:48.850587 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [origination_callee_id_name]=[127]
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 hash(insert/103.112.252.18-last_dial/1001/127)
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(sip_h_X-accountcode=)
2021-03-25 00:54:48.850587 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [sip_h_X-accountcode]=[UNDEF]
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 export(call_direction=outbound)
2021-03-25 00:54:48.850587 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_direction]=[outbound]
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 unset(call_timeout)
2021-03-25 00:54:48.850587 [DEBUG] mod_dptools.c:1817 UNSET [call_timeout]
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(hangup_after_bridge=true)
2021-03-25 00:54:48.850587 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [hangup_after_bridge]=[true]
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(effective_caller_id_name=)
2021-03-25 00:54:48.850587 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [effective_caller_id_name]=[UNDEF]
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(effective_caller_id_number=)
2021-03-25 00:54:48.850587 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [effective_caller_id_number]=[UNDEF]
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(inherit_codec=true)
2021-03-25 00:54:48.850587 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [inherit_codec]=[true]
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(ignore_display_updates=true)
2021-03-25 00:54:48.850587 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [ignore_display_updates]=[true]
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(callee_id_number=127)
2021-03-25 00:54:48.850587 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [callee_id_number]=[127]
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 set(continue_on_fail=true)
2021-03-25 00:54:48.850587 [DEBUG] mod_dptools.c:1672 SET sofia/internal/1001@103.112.252.18:5060 [continue_on_fail]=[true]
EXECUTE [depth=0] sofia/internal/1001@103.112.252.18:5060 bridge(sofia/gateway/88fdd52e-92b5-452b-b65e-b5a4e68020a1/127)
2021-03-25 00:54:48.850587 [DEBUG] switch_channel.c:1264 sofia/internal/1001@103.112.252.18:5060 EXPORTING[export_vars] [domain_name]=[103.112.252.18] to event
2021-03-25 00:54:48.850587 [DEBUG] switch_channel.c:1264 sofia/internal/1001@103.112.252.18:5060 EXPORTING[export_vars] [call_direction]=[outbound] to event
2021-03-25 00:54:48.850587 [DEBUG] switch_channel.c:1264 sofia/internal/1001@103.112.252.18:5060 EXPORTING[export_vars] [origination_callee_id_name]=[127] to event
2021-03-25 00:54:48.850587 [DEBUG] switch_channel.c:1264 sofia/internal/1001@103.112.252.18:5060 EXPORTING[export_vars] [call_direction]=[outbound] to event
2021-03-25 00:54:48.850587 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
2021-03-25 00:54:48.850587 [NOTICE] switch_channel.c:1118 New Channel sofia/external/127 [98dfc21a-8cd6-11eb-8f19-c5f2213591c4]
2021-03-25 00:54:48.850587 [DEBUG] mod_sofia.c:5089 (sofia/external/127) State Change CS_NEW -> CS_INIT
2021-03-25 00:54:48.850587 [DEBUG] switch_core_state_machine.c:585 (sofia/external/127) Running State Change CS_INIT (Cur 2 Tot 224)
2021-03-25 00:54:48.850587 [DEBUG] switch_core_state_machine.c:628 (sofia/external/127) State INIT
2021-03-25 00:54:48.850587 [DEBUG] mod_sofia.c:93 sofia/external/127 SOFIA INIT
2021-03-25 00:54:48.850587 [DEBUG] sofia_glue.c:1618 sofia/external/127 sending invite version: 1.10.3 -release 32bit
Local SDP:
v=0
o=FreeSWITCH 1616591506 1616591507 IN IP4 103.112.252.18
s=FreeSWITCH
c=IN IP4 103.112.252.18
t=0 0
m=audio 22382 RTP/AVP 9 0 8 101
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:eek:ff - - - -
a=ptime:20
a=sendrecv

2021-03-25 00:54:48.850587 [DEBUG] switch_core_state_machine.c:40 sofia/external/127 Standard INIT
2021-03-25 00:54:48.850587 [DEBUG] switch_core_state_machine.c:48 (sofia/external/127) State Change CS_INIT -> CS_ROUTING
2021-03-25 00:54:48.850587 [DEBUG] switch_core_state_machine.c:628 (sofia/external/127) State INIT going to sleep
2021-03-25 00:54:48.850587 [DEBUG] switch_core_state_machine.c:585 (sofia/external/127) Running State Change CS_ROUTING (Cur 2 Tot 224)
2021-03-25 00:54:48.850587 [DEBUG] sofia.c:7325 Channel sofia/external/127 entering state [calling][0]
2021-03-25 00:54:48.850587 [DEBUG] switch_core_state_machine.c:644 (sofia/external/127) State ROUTING
2021-03-25 00:54:48.850587 [DEBUG] mod_sofia.c:154 sofia/external/127 SOFIA ROUTING
2021-03-25 00:54:48.850587 [DEBUG] switch_ivr_originate.c:67 (sofia/external/127) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2021-03-25 00:54:48.850587 [DEBUG] switch_core_state_machine.c:644 (sofia/external/127) State ROUTING going to sleep
2021-03-25 00:54:48.850587 [DEBUG] switch_core_state_machine.c:585 (sofia/external/127) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 224)
2021-03-25 00:54:48.850587 [DEBUG] switch_core_state_machine.c:663 (sofia/external/127) State CONSUME_MEDIA
2021-03-25 00:54:48.850587 [DEBUG] switch_core_state_machine.c:663 (sofia/external/127) State CONSUME_MEDIA going to sleep
2021-03-25 00:54:48.890583 [DEBUG] sofia.c:7325 Channel sofia/external/127 entering state [terminated][487]
2021-03-25 00:54:48.890583 [NOTICE] sofia.c:8558 Hangup sofia/external/127 [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL]
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:585 (sofia/external/127) Running State Change CS_HANGUP (Cur 2 Tot 224)
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:848 (sofia/external/127) Callstate Change DOWN -> HANGUP
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:850 (sofia/external/127) State HANGUP
2021-03-25 00:54:48.890583 [DEBUG] mod_sofia.c:453 Channel sofia/external/127 hanging up, cause: ORIGINATOR_CANCEL
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:60 sofia/external/127 Standard HANGUP, cause: ORIGINATOR_CANCEL
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:850 (sofia/external/127) State HANGUP going to sleep
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:620 (sofia/external/127) State Change CS_HANGUP -> CS_REPORTING
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:585 (sofia/external/127) Running State Change CS_REPORTING (Cur 2 Tot 224)
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:936 (sofia/external/127) State REPORTING
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:174 sofia/external/127 Standard REPORTING, cause: ORIGINATOR_CANCEL
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:936 (sofia/external/127) State REPORTING going to sleep
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:611 (sofia/external/127) State Change CS_REPORTING -> CS_DESTROY
2021-03-25 00:54:48.890583 [DEBUG] switch_core_session.c:1726 Session 224 (sofia/external/127) Locked, Waiting on external entities
2021-03-25 00:54:48.890583 [DEBUG] switch_ivr_originate.c:3995 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL]
2021-03-25 00:54:48.890583 [NOTICE] switch_core_session.c:1744 Session 224 (sofia/external/127) Ended
2021-03-25 00:54:48.890583 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/127 [CS_DESTROY]
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:739 (sofia/external/127) Running State Change CS_DESTROY (Cur 1 Tot 224)
2021-03-25 00:54:48.890583 [INFO] mod_dptools.c:3631 Originate Failed. Cause: ORIGINATOR_CANCEL
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:749 (sofia/external/127) State DESTROY
2021-03-25 00:54:48.890583 [DEBUG] mod_sofia.c:364 sofia/external/127 SOFIA DESTROY
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:181 sofia/external/127 Standard DESTROY
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:749 (sofia/external/127) State DESTROY going to sleep
2021-03-25 00:54:48.890583 [NOTICE] switch_core_state_machine.c:386 sofia/internal/1001@103.112.252.18:5060 has executed the last dialplan instruction, hanging up.
2021-03-25 00:54:48.890583 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/internal/1001@103.112.252.18:5060 [CS_EXECUTE] [NORMAL_CLEARING]
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/1001@103.112.252.18:5060) State EXECUTE going to sleep
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/1001@103.112.252.18:5060) Running State Change CS_HANGUP (Cur 1 Tot 224)
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/1001@103.112.252.18:5060) Callstate Change RINGING -> HANGUP
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1001@103.112.252.18:5060) State HANGUP
2021-03-25 00:54:48.890583 [DEBUG] mod_sofia.c:447 sofia/internal/1001@103.112.252.18:5060 Overriding SIP cause 480 with 487 from the other leg
2021-03-25 00:54:48.890583 [DEBUG] mod_sofia.c:453 Channel sofia/internal/1001@103.112.252.18:5060 hanging up, cause: NORMAL_CLEARING
2021-03-25 00:54:48.890583 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 487
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1001@103.112.252.18:5060 Standard HANGUP, cause: NORMAL_CLEARING
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1001@103.112.252.18:5060) State HANGUP going to sleep
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/1001@103.112.252.18:5060) State Change CS_HANGUP -> CS_REPORTING
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/1001@103.112.252.18:5060) Running State Change CS_REPORTING (Cur 1 Tot 224)
2021-03-25 00:54:48.890583 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/1001@103.112.252.18:5060) State REPORTING
2021-03-25 00:54:49.030590 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1001@103.112.252.18:5060 Standard REPORTING, cause: NORMAL_CLEARING
2021-03-25 00:54:49.030590 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/1001@103.112.252.18:5060) State REPORTING going to sleep
2021-03-25 00:54:49.030590 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/1001@103.112.252.18:5060) State Change CS_REPORTING -> CS_DESTROY
2021-03-25 00:54:49.030590 [DEBUG] switch_core_session.c:1726 Session 223 (sofia/internal/1001@103.112.252.18:5060) Locked, Waiting on external entities
2021-03-25 00:54:49.030590 [NOTICE] switch_core_session.c:1744 Session 223 (sofia/internal/1001@103.112.252.18:5060) Ended
2021-03-25 00:54:49.030590 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/1001@103.112.252.18:5060 [CS_DESTROY]
2021-03-25 00:54:49.030590 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/1001@103.112.252.18:5060) Running State Change CS_DESTROY (Cur 0 Tot 224)
2021-03-25 00:54:49.030590 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/1001@103.112.252.18:5060) State DESTROY
2021-03-25 00:54:49.030590 [DEBUG] mod_sofia.c:364 sofia/internal/1001@103.112.252.18:5060 SOFIA DESTROY
2021-03-25 00:54:49.030590 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1001@103.112.252.18:5060 Standard DESTROY
2021-03-25 00:54:49.030590 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/1001@103.112.252.18:5060) State DESTROY going to sleep
 
S

sayantan

New Member
Jan 25, 2021
7
0
1
46
Dear All,

I am attaching the snapshot of the gateway/outbound dialplan/access control. still i am not able to figure out why nothing is coming in the active call when i am trying to call the external extension. Someone please help me with some hint.
 

Attachments

  • Outbound-Dialplan.jpg
    Outbound-Dialplan.jpg
    120.6 KB · Views: 9
  • Gateway-Cfg.jpg
    Gateway-Cfg.jpg
    93.3 KB · Views: 9
  • Access-control.jpg
    Access-control.jpg
    32.1 KB · Views: 9
PBXMePlz

PBXMePlz

Member
Mar 1, 2019
102
10
18
31
Is your Gateway appropriately registering? Also have you worked with your carrier?
Typically for me, I have to have the Gateway setup a certain way to my carrier's standards, then I have to have an outbound route 10 digit, then I have to have CID set in my extension before outbound calls will function as I would expect.
 
K

KitchM

Member
Jul 15, 2019
168
6
18
I agree with PBXMePlz. First make sure you have gateway registration with your provider. This is foundational.
 
S

sayantan

New Member
Jan 25, 2021
7
0
1
46
Hi All, Thank you for responding.
Gateway is in running and REGED state. Here i am trying to connect to another PBX located on a different subnet of public IP. In the other PBX there is a 3 digit extension to which it should transfer the calls. I dont now if i need to add any prefix for such scenario.
I have also allowed the port 55060 in the iptables
ACCEPT tcp -- anywhere anywhere tcp dpt:55060
ACCEPT udp -- anywhere anywhere udp dpt:55060
but no change, calls will not go out.
 

Attachments

  • gateway-status.jpg
    gateway-status.jpg
    43.9 KB · Views: 9
mcs3ss2

mcs3ss2

Active Member
Sep 8, 2020
250
27
28
AU
Create Ext on first PBX
register that Ext on second PBX as Gateway
Creat Bridge on First gateway and throw (route) call on second pbx
second pbx assign ext as per normal to second PBX's ext
 
S

sayantan

New Member
Jan 25, 2021
7
0
1
46
There was some issue with the iptables, i did a fresh installation, reconfigured and now i am able to make calls from one to other pbx, but all calls are showing 1 extension, independent of the extension, of the end device. is there a way to reveal the actual device number?
 
Status
Not open for further replies.
Top Bottom