Hi, I'm new to fusionpbx and installed fusionpbx in debian 12. sip trunk is registered, inbound calls are working fine as well but i'm unable to place outbound call. I checked logs using sngrep and found that fusion isn't sending call to gateway, i'm attaching screenshots of sngrep below. I'm unable to figure out why it isn't working, i will be thankful if anyone can help me out.
Log message attached below :-
e.c:581 (sofia/internal/1001@122.160.138.110) Running State Change CS_ROUTING (Cur 1 Tot 17)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.841792 82.67% [DEBUG] switch_channel.c:2399 (sofia/internal/1001@122.160.138.110) Callstate Change DOWN -> RINGING
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.841792 82.67% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1001@122.160.138.110) State ROUTING
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.841792 82.67% [DEBUG] mod_sofia.c:158 sofia/internal/1001@122.160.138.110 SOFIA ROUTING
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.841792 82.67% [DEBUG] switch_core_state_machine.c:230 sofia/internal/1001@122.160.138.110 Standard ROUTING
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.841792 82.67% [INFO] mod_dialplan_xml.c:639 Processing 1001 <1001>->09971901254 in context 122.160.138.110
2024-01-08 11:23:36.861800 82.67% [NOTICE] switch_cpp.cpp:1465 [xml_handler] multiple key:dialplan:122.160.138.110
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->user_exists] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_exists] ${loopback_leg}() =~ /^B$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [user_exists] () =~ // break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(user_exists=false)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [user_exists]=[false]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(from_user_exists=true)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [from_user_exists]=[true]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->caller-details] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [caller-details] ${caller_destination}() =~ /^$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(caller_destination=${destination_number}) INLINE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(caller_destination=09971901254)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [caller_destination]=[09971901254]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(caller_id_name=${caller_id_name}) INLINE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(caller_id_name=1001)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [caller_id_name]=[1001]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(caller_id_number=${caller_id_number})
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->global-variables] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [global-variables] () =~ // break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->domain-variables] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [domain-variables] () =~ // break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action export(origination_callee_id_name=${caller_destination})
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(operator=1000) INLINE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(operator=1000)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [operator]=[1000]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->clear_sip_auto_answer] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->is_loopback] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->call-direction] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [call-direction] ${call_direction}() =~ /^$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action export(call_direction=local) INLINE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 export(call_direction=local)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [call_direction]=[local]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->user_record] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(from_user_record=)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [from_user_record]=[UNDEF]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->rtp_has_crypto] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 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
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->redial] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [redial] destination_number(09971901254) =~ /^(redial|\*870)$/ break=on-true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [redial] () =~ // break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->speed_dial] continue=false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [speed_dial] destination_number(09971901254) =~ /^\*0(.*)$/ break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->user_hold_music] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_hold_music] ${user_exists}(false) =~ /true/ break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->default_caller_id] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [default_caller_id] ${call_direction}(local) =~ /outbound/ break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->AirSip.11d] continue=false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [AirSip.11d] ${user_exists}(false) =~ /false/ break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [AirSip.11d] destination_number(09971901254) =~ /^\+?(\d{11})$/ break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action export(call_direction=outbound) INLINE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 export(call_direction=outbound)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [call_direction]=[outbound]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action unset(call_timeout)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(hangup_after_bridge=true)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(effective_caller_id_name=${outbound_caller_id_name})
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(effective_caller_id_number=${outbound_caller_id_number})
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(inherit_codec=true)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(ignore_display_updates=true)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(callee_id_number=09971901254)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(continue_on_fail=1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action bridge(sofia/gateway/c9cc607f-aa7f-46e7-8f59-2089a51d7162/09971901254)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_core_state_machine.c:281 (sofia/internal/1001@122.160.138.110) State Change CS_ROUTING -> CS_EXECUTE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1001@122.160.138.110) State ROUTING going to sleep
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1001@122.160.138.110) Running State Change CS_EXECUTE (Cur 1 Tot 17)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/1001@122.160.138.110) State EXECUTE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_sofia.c:213 sofia/internal/1001@122.160.138.110 SOFIA EXECUTE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_core_state_machine.c:323 sofia/internal/1001@122.160.138.110 Standard EXECUTE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(caller_id_number=1001)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [caller_id_number]=[1001]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(RFC2822_DATE=Mon, 08 Jan 2024 11:23:36 +0530)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [RFC2822_DATE]=[Mon, 08 Jan 2024 11:23:36 +0530]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 export(origination_callee_id_name=09971901254)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [origination_callee_id_name]=[09971901254]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 hash(insert/122.160.138.110-last_dial/1001/09971901254)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 unset(call_timeout)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1816 UNSET [call_timeout]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(hangup_after_bridge=true)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [hangup_after_bridge]=[true]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(effective_caller_id_name=)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [effective_caller_id_name]=[UNDEF]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(effective_caller_id_number=+911149116000)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [effective_caller_id_number]=[+911149116000]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(inherit_codec=true)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [inherit_codec]=[true]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(ignore_display_updates=true)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [ignore_display_updates]=[true]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(callee_id_number=09971901254)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [callee_id_number]=[09971901254]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(continue_on_fail=1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [continue_on_fail]=[1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 bridge(sofia/gateway/c9cc607f-aa7f-46e7-8f59-2089a51d7162/09971901254)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_channel.c:1288 sofia/internal/1001@122.160.138.110 EXPORTING[export_vars] [domain_name]=[122.160.138.110] to event
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_channel.c:1288 sofia/internal/1001@122.160.138.110 EXPORTING[export_vars] [domain_uuid]=[40b025cb-8011-4f35-bb58-161a93e4ddb7] to event
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_channel.c:1288 sofia/internal/1001@122.160.138.110 EXPORTING[export_vars] [call_direction]=[outbound] to event
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_channel.c:1288 sofia/internal/1001@122.160.138.110 EXPORTING[export_vars] [call_direction]=[outbound] to event
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_channel.c:1288 sofia/internal/1001@122.160.138.110 EXPORTING[export_vars] [origination_callee_id_name]=[09971901254] to event
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_ivr_originate.c:2297 Parsing global variables
9fe2fe4b-f298-4b5d-b935-e77bd2837819 2024-01-08 11:23:36.861800 82.67% [NOTICE] switch_channel.c:1142 New Channel sofia/Airsip/09971901254 [9fe2fe4b-f298-4b5d-b935-e77bd2837819]
9fe2fe4b-f298-4b5d-b935-e77bd2837819 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_sofia.c:5094 sofia/Airsip/09971901254 setting variable [sip_cid_type]=[none]
9fe2fe4b-f298-4b5d-b935-e77bd2837819 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_sofia.c:5110 (sofia/Airsip/09971901254) State Change CS_NEW -> CS_INIT
9fe2fe4b-f298-4b5d-b935-e77bd2837819 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_core_state_machine.c:581 (sofia/Airsip/09971901254) Running State Change CS_INIT (Cur 2 Tot 18)
9fe2fe4b-f298-4b5d-b935-e77bd2837819 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_core_state_machine.c:624 (sofia/Airsip/09971901254) State INIT
9fe2fe4b-f298-4b5d-b935-e77bd2837819 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_sofia.c:97 sofia/Airsip/09971901254 SOFIA INIT
9fe2fe4b-f298-4b5d-b935-e77bd2837819 2024-01-08 11:23:36.861800 82.67% [INFO] sofia_glue.c:1659 sofia/Airsip/09971901254 sending invite call-id: (null)
9fe2fe4b-f298-4b5d-b935-e77bd2837819 2024-01-08 11:23:36.861800 82.67% [DEBUG] sofia_glue.c:1662 sofia/Airsip/09971901254 sending invite version: 1.10.10 -release 64bit
9fe2fe4b-f298-4b5d-b935-e77bd2837819 Local SDP:
9fe2fe4b-f298-4b5d-b935-e77bd2837819 v=0
9fe2fe4b-f298-4b5d-b935-e77bd2837819 o=FreeSWITCH 1704660936 1704660937 IN IP4 10.236.114.146
9fe2fe4b-f298-4b5d-b935-e77bd2837819 s=FreeSWITCH
9fe2fe4b-f298-4b5d-b935-e77bd2837819 c=IN IP4 10.236.114.146
9fe2fe4b-f298-4b5d-b935-e77bd2837819 t=0 0
9fe2fe4b-f298-4b5d-b935-e77bd2837819 m=audio 32280 RTP/AVP 8 0 9 101 13
9fe2fe4b-f298-4b5d-b935-e77bd2837819 a=rtpmap:8 PCMA/8000
9fe2fe4b-f298-4b5d-b935-e77bd2837819 a=rtpmap:0 PCMU/8000
9fe2fe4b-f298-4b5d-b935-e77bd2837819 a=rtpmap:9 G722/8000
9fe2fe4b-f298-4b5d-b935-e77bd2837819 a=rtpmap:101 telephone-event/8000
9fe2fe4b-f298-4b5d-b935-e77bd2837819 a=fmtp:101 0-15
9fe2fe4b-f298-4b5d-b935-e77bd2837819 a=rtpmap:13 CN/8000
9fe2fe4b-f298-4b5d-b935-e77bd2837819 a=ptime:20
9fe2fe4b-f298-4b5d-b935-e77bd2837819 a=sendrecv
9fe2fe4b-f298-4b5d-b935-e77bd2837819
Log message attached below :-
e.c:581 (sofia/internal/1001@122.160.138.110) Running State Change CS_ROUTING (Cur 1 Tot 17)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.841792 82.67% [DEBUG] switch_channel.c:2399 (sofia/internal/1001@122.160.138.110) Callstate Change DOWN -> RINGING
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.841792 82.67% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1001@122.160.138.110) State ROUTING
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.841792 82.67% [DEBUG] mod_sofia.c:158 sofia/internal/1001@122.160.138.110 SOFIA ROUTING
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.841792 82.67% [DEBUG] switch_core_state_machine.c:230 sofia/internal/1001@122.160.138.110 Standard ROUTING
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.841792 82.67% [INFO] mod_dialplan_xml.c:639 Processing 1001 <1001>->09971901254 in context 122.160.138.110
2024-01-08 11:23:36.861800 82.67% [NOTICE] switch_cpp.cpp:1465 [xml_handler] multiple key:dialplan:122.160.138.110
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->user_exists] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_exists] ${loopback_leg}() =~ /^B$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [user_exists] () =~ // break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(user_exists=false)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [user_exists]=[false]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(from_user_exists=true)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [from_user_exists]=[true]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->caller-details] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [caller-details] ${caller_destination}() =~ /^$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(caller_destination=${destination_number}) INLINE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(caller_destination=09971901254)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [caller_destination]=[09971901254]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(caller_id_name=${caller_id_name}) INLINE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(caller_id_name=1001)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [caller_id_name]=[1001]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(caller_id_number=${caller_id_number})
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->global-variables] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [global-variables] () =~ // break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->domain-variables] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [domain-variables] () =~ // break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action export(origination_callee_id_name=${caller_destination})
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(operator=1000) INLINE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(operator=1000)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [operator]=[1000]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->clear_sip_auto_answer] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->is_loopback] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->call-direction] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [call-direction] ${call_direction}() =~ /^$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action export(call_direction=local) INLINE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 export(call_direction=local)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [call_direction]=[local]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->user_record] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(from_user_record=)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [from_user_record]=[UNDEF]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->rtp_has_crypto] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 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
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->redial] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [redial] destination_number(09971901254) =~ /^(redial|\*870)$/ break=on-true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [redial] () =~ // break=never
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->speed_dial] continue=false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [speed_dial] destination_number(09971901254) =~ /^\*0(.*)$/ break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->user_hold_music] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [user_hold_music] ${user_exists}(false) =~ /true/ break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->default_caller_id] continue=true
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (FAIL) [default_caller_id] ${call_direction}(local) =~ /outbound/ break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 parsing [122.160.138.110->AirSip.11d] continue=false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [AirSip.11d] ${user_exists}(false) =~ /false/ break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Regex (PASS) [AirSip.11d] destination_number(09971901254) =~ /^\+?(\d{11})$/ break=on-false
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action export(call_direction=outbound) INLINE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 export(call_direction=outbound)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [call_direction]=[outbound]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action unset(call_timeout)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(hangup_after_bridge=true)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(effective_caller_id_name=${outbound_caller_id_name})
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(effective_caller_id_number=${outbound_caller_id_number})
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(inherit_codec=true)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(ignore_display_updates=true)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(callee_id_number=09971901254)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action set(continue_on_fail=1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 Dialplan: sofia/internal/1001@122.160.138.110 Action bridge(sofia/gateway/c9cc607f-aa7f-46e7-8f59-2089a51d7162/09971901254)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_core_state_machine.c:281 (sofia/internal/1001@122.160.138.110) State Change CS_ROUTING -> CS_EXECUTE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1001@122.160.138.110) State ROUTING going to sleep
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1001@122.160.138.110) Running State Change CS_EXECUTE (Cur 1 Tot 17)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/1001@122.160.138.110) State EXECUTE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_sofia.c:213 sofia/internal/1001@122.160.138.110 SOFIA EXECUTE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_core_state_machine.c:323 sofia/internal/1001@122.160.138.110 Standard EXECUTE
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(caller_id_number=1001)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [caller_id_number]=[1001]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(RFC2822_DATE=Mon, 08 Jan 2024 11:23:36 +0530)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [RFC2822_DATE]=[Mon, 08 Jan 2024 11:23:36 +0530]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 export(origination_callee_id_name=09971901254)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [origination_callee_id_name]=[09971901254]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 hash(insert/122.160.138.110-last_dial/1001/09971901254)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 unset(call_timeout)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1816 UNSET [call_timeout]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(hangup_after_bridge=true)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [hangup_after_bridge]=[true]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(effective_caller_id_name=)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [effective_caller_id_name]=[UNDEF]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(effective_caller_id_number=+911149116000)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [effective_caller_id_number]=[+911149116000]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(inherit_codec=true)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [inherit_codec]=[true]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(ignore_display_updates=true)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [ignore_display_updates]=[true]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(callee_id_number=09971901254)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [callee_id_number]=[09971901254]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 set(continue_on_fail=1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_dptools.c:1671 SET sofia/internal/1001@122.160.138.110 [continue_on_fail]=[1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607]
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 EXECUTE [depth=0] sofia/internal/1001@122.160.138.110 bridge(sofia/gateway/c9cc607f-aa7f-46e7-8f59-2089a51d7162/09971901254)
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_channel.c:1288 sofia/internal/1001@122.160.138.110 EXPORTING[export_vars] [domain_name]=[122.160.138.110] to event
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_channel.c:1288 sofia/internal/1001@122.160.138.110 EXPORTING[export_vars] [domain_uuid]=[40b025cb-8011-4f35-bb58-161a93e4ddb7] to event
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_channel.c:1288 sofia/internal/1001@122.160.138.110 EXPORTING[export_vars] [call_direction]=[outbound] to event
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_channel.c:1288 sofia/internal/1001@122.160.138.110 EXPORTING[export_vars] [call_direction]=[outbound] to event
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_channel.c:1288 sofia/internal/1001@122.160.138.110 EXPORTING[export_vars] [origination_callee_id_name]=[09971901254] to event
4a1862f9-5868-4fec-ad67-39e68f7ef5f0 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_ivr_originate.c:2297 Parsing global variables
9fe2fe4b-f298-4b5d-b935-e77bd2837819 2024-01-08 11:23:36.861800 82.67% [NOTICE] switch_channel.c:1142 New Channel sofia/Airsip/09971901254 [9fe2fe4b-f298-4b5d-b935-e77bd2837819]
9fe2fe4b-f298-4b5d-b935-e77bd2837819 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_sofia.c:5094 sofia/Airsip/09971901254 setting variable [sip_cid_type]=[none]
9fe2fe4b-f298-4b5d-b935-e77bd2837819 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_sofia.c:5110 (sofia/Airsip/09971901254) State Change CS_NEW -> CS_INIT
9fe2fe4b-f298-4b5d-b935-e77bd2837819 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_core_state_machine.c:581 (sofia/Airsip/09971901254) Running State Change CS_INIT (Cur 2 Tot 18)
9fe2fe4b-f298-4b5d-b935-e77bd2837819 2024-01-08 11:23:36.861800 82.67% [DEBUG] switch_core_state_machine.c:624 (sofia/Airsip/09971901254) State INIT
9fe2fe4b-f298-4b5d-b935-e77bd2837819 2024-01-08 11:23:36.861800 82.67% [DEBUG] mod_sofia.c:97 sofia/Airsip/09971901254 SOFIA INIT
9fe2fe4b-f298-4b5d-b935-e77bd2837819 2024-01-08 11:23:36.861800 82.67% [INFO] sofia_glue.c:1659 sofia/Airsip/09971901254 sending invite call-id: (null)
9fe2fe4b-f298-4b5d-b935-e77bd2837819 2024-01-08 11:23:36.861800 82.67% [DEBUG] sofia_glue.c:1662 sofia/Airsip/09971901254 sending invite version: 1.10.10 -release 64bit
9fe2fe4b-f298-4b5d-b935-e77bd2837819 Local SDP:
9fe2fe4b-f298-4b5d-b935-e77bd2837819 v=0
9fe2fe4b-f298-4b5d-b935-e77bd2837819 o=FreeSWITCH 1704660936 1704660937 IN IP4 10.236.114.146
9fe2fe4b-f298-4b5d-b935-e77bd2837819 s=FreeSWITCH
9fe2fe4b-f298-4b5d-b935-e77bd2837819 c=IN IP4 10.236.114.146
9fe2fe4b-f298-4b5d-b935-e77bd2837819 t=0 0
9fe2fe4b-f298-4b5d-b935-e77bd2837819 m=audio 32280 RTP/AVP 8 0 9 101 13
9fe2fe4b-f298-4b5d-b935-e77bd2837819 a=rtpmap:8 PCMA/8000
9fe2fe4b-f298-4b5d-b935-e77bd2837819 a=rtpmap:0 PCMU/8000
9fe2fe4b-f298-4b5d-b935-e77bd2837819 a=rtpmap:9 G722/8000
9fe2fe4b-f298-4b5d-b935-e77bd2837819 a=rtpmap:101 telephone-event/8000
9fe2fe4b-f298-4b5d-b935-e77bd2837819 a=fmtp:101 0-15
9fe2fe4b-f298-4b5d-b935-e77bd2837819 a=rtpmap:13 CN/8000
9fe2fe4b-f298-4b5d-b935-e77bd2837819 a=ptime:20
9fe2fe4b-f298-4b5d-b935-e77bd2837819 a=sendrecv
9fe2fe4b-f298-4b5d-b935-e77bd2837819