Inbound => External Not Working

Status
Not open for further replies.
I

Incred

Member
Jun 25, 2023
53
0
6
51
Hi,

We have a setup of:
Inbound Route => Time Condition (271) => Queue (1000) or External Number

When I call in using my mobile phone during business hours I get the queue (Working)
When I call in using my mobile phone after hours the call drops (not working)

When I call ext 271 from my internal extension during business hours I get the queue (Working)
When I call ext 271 from my internal extension after hours the call drops I get the external number (Working)

I cannot understand why I am not forwarded to the external number after hours when I call in using my mobile phone.

My thanks in advance...
 
Adrian Fretwell

Adrian Fretwell

Well-Known Member
Aug 13, 2017
1,498
413
83
You have not given much detail, so this is just a wild guess...
You may need to set the variable sip_invite_domain in your outbound route to match whatever you have set in the From Domain in your gateway record.
 
I

Incred

Member
Jun 25, 2023
53
0
6
51
Hi Adrain & thanks. New here (1st post) so was not sure what info to supply. Here's the log:


46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.421120 99.97% [NOTICE] switch_channel.c:1123 New Channel sofia/external/61411******@X.X.X.X [46f66835-460b-43b1-8e88-18b01bd900a5]
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.421120 99.97% [DEBUG] switch_core_state_machine.c:581 (sofia/external/61411******@X.X.X.X) Running State Change CS_NEW (Cur 1 Tot 54)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.421120 99.97% [INFO] sofia.c:10453 sofia/external/61411******@X.X.X.X receiving invite from X.X.X.X:5060 version: 1.10.9 -release 64bit call-id: 5359d36a-9035-123c-b3bf-d094666d3e87
2023-06-28 19:02:18.421120 99.97% [DEBUG] sofia.c:10547 verifying acl "providers" for ip/port X.X.X.X:0.
2023-06-28 19:02:18.421120 99.97% [DEBUG] sofia.c:10576 IP X.X.X.X Approved by acl "providers[]". Access Granted.
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] sofia.c:7487 Channel sofia/external/61411******@X.X.X.X entering state [received][100]
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] sofia.c:7497 Remote SDP:
46f66835-460b-43b1-8e88-18b01bd900a5 v=0
46f66835-460b-43b1-8e88-18b01bd900a5 o=FreeSWITCH 1687917908 1687917910 IN IP4 X.X.X.X
46f66835-460b-43b1-8e88-18b01bd900a5 s=FreeSWITCH
46f66835-460b-43b1-8e88-18b01bd900a5 c=IN IP4 X.X.X.X
46f66835-460b-43b1-8e88-18b01bd900a5 t=0 0
46f66835-460b-43b1-8e88-18b01bd900a5 m=audio 25030 RTP/AVP 8 0 18 101
46f66835-460b-43b1-8e88-18b01bd900a5 a=rtpmap:8 PCMA/8000
46f66835-460b-43b1-8e88-18b01bd900a5 a=rtpmap:0 PCMU/8000
46f66835-460b-43b1-8e88-18b01bd900a5 a=rtpmap:18 G729/8000
46f66835-460b-43b1-8e88-18b01bd900a5 a=fmtp:18 annexb=no
46f66835-460b-43b1-8e88-18b01bd900a5 a=rtpmap:101 telephone-event/8000
46f66835-460b-43b1-8e88-18b01bd900a5 a=fmtp:101 0-15
46f66835-460b-43b1-8e88-18b01bd900a5 a=ptime:20
46f66835-460b-43b1-8e88-18b01bd900a5
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] sofia.c:7900 (sofia/external/61411******@X.X.X.X) State Change CS_NEW -> CS_INIT
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_core_state_machine.c:600 (sofia/external/61411******@X.X.X.X) State NEW
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_core_state_machine.c:581 (sofia/external/61411******@X.X.X.X) Running State Change CS_INIT (Cur 1 Tot 54)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_core_state_machine.c:624 (sofia/external/61411******@X.X.X.X) State INIT
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] mod_sofia.c:97 sofia/external/61411******@X.X.X.X SOFIA INIT
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_core_state_machine.c:40 sofia/external/61411******@X.X.X.X Standard INIT
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_core_state_machine.c:48 (sofia/external/61411******@X.X.X.X) State Change CS_INIT -> CS_ROUTING
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_core_state_machine.c:624 (sofia/external/61411******@X.X.X.X) State INIT going to sleep
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_core_state_machine.c:581 (sofia/external/61411******@X.X.X.X) Running State Change CS_ROUTING (Cur 1 Tot 54)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_channel.c:2380 (sofia/external/61411******@X.X.X.X) Callstate Change DOWN -> RINGING
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_core_state_machine.c:640 (sofia/external/61411******@X.X.X.X) State ROUTING
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] mod_sofia.c:158 sofia/external/61411******@X.X.X.X SOFIA ROUTING
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_core_state_machine.c:230 sofia/external/61411******@X.X.X.X Standard ROUTING
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [INFO] mod_dialplan_xml.c:639 Processing 61411****** <61411******>->613******** in context public
2023-06-28 19:02:18.460940 99.97% [NOTICE] switch_cpp.cpp:1465 [xml_handler] multiple key:dialplan:public
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [public->61390816349] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [61390816349] destination_number(613********) =~ /^(61390816349)/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [public->611300108134] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [611300108134] destination_number(613********) =~ /^(611300108134)/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [public->613********] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [613********] destination_number(613********) =~ /^(613********)$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action export(call_direction=inbound) INLINE
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X export(call_direction=inbound)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [call_direction]=[inbound]
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(domain_uuid=fd6c52b5-b8f4-4fd6-9e3c-6b25907e260a) INLINE
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(domain_uuid=fd6c52b5-b8f4-4fd6-9e3c-6b25907e260a)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [domain_uuid]=[fd6c52b5-b8f4-4fd6-9e3c-6b25907e260a]
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(domain_name=pbx10251.mydomain.com.au) INLINE
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(domain_name=pbx10251.mydomain.com.au)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [domain_name]=[pbx10251.mydomain.com.au]
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action transfer(271 XML pbx10251.mydomain.com.au)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_core_state_machine.c:281 (sofia/external/61411******@X.X.X.X) State Change CS_ROUTING -> CS_EXECUTE
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_core_state_machine.c:640 (sofia/external/61411******@X.X.X.X) State ROUTING going to sleep
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_core_state_machine.c:581 (sofia/external/61411******@X.X.X.X) Running State Change CS_EXECUTE (Cur 1 Tot 54)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_core_state_machine.c:647 (sofia/external/61411******@X.X.X.X) State EXECUTE
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] mod_sofia.c:213 sofia/external/61411******@X.X.X.X SOFIA EXECUTE
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_core_state_machine.c:323 sofia/external/61411******@X.X.X.X Standard EXECUTE
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X transfer(271 XML pbx10251.mydomain.com.au)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_ivr.c:2288 (sofia/external/61411******@X.X.X.X) State Change CS_EXECUTE -> CS_ROUTING
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [NOTICE] switch_ivr.c:2295 Transfer sofia/external/61411******@X.X.X.X to XML[271@pbx10251.mydomain.com.au]
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_core_state_machine.c:647 (sofia/external/61411******@X.X.X.X) State EXECUTE going to sleep
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_core_state_machine.c:581 (sofia/external/61411******@X.X.X.X) Running State Change CS_ROUTING (Cur 1 Tot 54)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_core_state_machine.c:640 (sofia/external/61411******@X.X.X.X) State ROUTING
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] mod_sofia.c:149 Call appears to be already acknowledged
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] mod_sofia.c:158 sofia/external/61411******@X.X.X.X SOFIA ROUTING
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] switch_core_state_machine.c:230 sofia/external/61411******@X.X.X.X Standard ROUTING
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [INFO] mod_dialplan_xml.c:639 Processing 61411****** <61411******>->271 in context pbx10251.mydomain.com.au
2023-06-28 19:02:18.460940 99.97% [NOTICE] switch_cpp.cpp:1465 [xml_handler] multiple key:dialplan:pbx10251.mydomain.com.au
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->user_exists] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_exists] ${loopback_leg}() =~ /^B$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [user_exists] () =~ // break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(user_exists=false)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.460940 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [user_exists]=[false]
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(from_user_exists=false)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [from_user_exists]=[false]
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->caller-details] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [caller-details] ${caller_destination}() =~ /^$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(caller_destination=${destination_number}) INLINE
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(caller_destination=271)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [caller_destination]=[271]
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(caller_id_name=${caller_id_name}) INLINE
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(caller_id_name=61411******)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [caller_id_name]=[61411******]
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(caller_id_number=${caller_id_number})
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->global-variables] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [global-variables] () =~ // break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->domain-variables] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [domain-variables] () =~ // break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action export(origination_callee_id_name=${destination_number})
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(operator=1000) INLINE
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(operator=1000)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [operator]=[1000]
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->clear_sip_auto_answer] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->is_loopback] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->call-direction] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [call-direction] ${call_direction}(inbound) =~ /^$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->Fax] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [Fax] destination_number(271) =~ /^8000$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->user_record] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->rtp_has_crypto] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X 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
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->redial] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [redial] destination_number(271) =~ /^(redial|\*870)$/ break=on-true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [redial] () =~ // break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->speed_dial] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [speed_dial] destination_number(271) =~ /^\*0(.*)$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->user_hold_music] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_hold_music] ${user_exists}(false) =~ /true/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->default_caller_id] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [default_caller_id] ${call_direction}(inbound) =~ /outbound/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->MYISPACCT.8d] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [MYISPACCT.8d] ${user_exists}(false) =~ /false/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [MYISPACCT.8d] destination_number(271) =~ /^(\d{8})$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->MYISPACCT.10d] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [MYISPACCT.10d] ${user_exists}(false) =~ /false/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [MYISPACCT.10d] destination_number(271) =~ /^(\d{10})$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->agent_status] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [agent_status] destination_number(271) =~ /^\*22$/ break=on-true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [agent_status] destination_number(271) =~ /^(?:agent\+|\*22)(.+)$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->agent_status_id] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [agent_status_id] destination_number(271) =~ /^\*23$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->agent-status-break] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [agent-status-break] destination_number(271) =~ /^\*24$/ break=on-true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [agent-status-break] destination_number(271) =~ /^(?:agent\+|\*24)(.+)$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->Help Desk] continue=
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [Help Desk] destination_number(271) =~ /^([^#]+#)(.*)$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [Help Desk] destination_number(271) =~ /^(callcenter\+)?1000$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->group-intercept] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [group-intercept] destination_number(271) =~ /^\*8$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->page-extension] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [page-extension] destination_number(271) =~ /^\*8(\d{2,7})$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->eavesdrop] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [eavesdrop] destination_number(271) =~ /^\*33(\d{2,7})$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->call_privacy] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [call_privacy] destination_number(271) =~ /^\*67(\d+)$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->call_return] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [call_return] destination_number(271) =~ /^\*69$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->number_queue] continue=
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [number_queue] destination_number(271) =~ /^\*800(.*)$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->intercept-ext] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [intercept-ext] destination_number(271) =~ /^\*\*(\d+)$/ break=on-true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [intercept-ext] destination_number(271) =~ /^\*\*$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->intercept-ext-polycom] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [intercept-ext-polycom] destination_number(271) =~ /^\*97(\d+)$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->Business Hours] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [Business Hours] destination_number(271) =~ /^271$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Date/TimeMatch (FAIL) [Business Hours] break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [Business Hours] destination_number(271) =~ /^271$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action transfer(03******** XML pbx10251.mydomain.com.au)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] switch_core_state_machine.c:281 (sofia/external/61411******@X.X.X.X) State Change CS_ROUTING -> CS_EXECUTE
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] switch_core_state_machine.c:640 (sofia/external/61411******@X.X.X.X) State ROUTING going to sleep
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] switch_core_state_machine.c:581 (sofia/external/61411******@X.X.X.X) Running State Change CS_EXECUTE (Cur 1 Tot 54)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] switch_core_state_machine.c:647 (sofia/external/61411******@X.X.X.X) State EXECUTE
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] mod_sofia.c:213 sofia/external/61411******@X.X.X.X SOFIA EXECUTE
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] switch_core_state_machine.c:323 sofia/external/61411******@X.X.X.X Standard EXECUTE
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(caller_id_number=61411******)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [caller_id_number]=[61411******]
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(RFC2822_DATE=Wed, 28 Jun 2023 19:02:18 +1000)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [RFC2822_DATE]=[Wed, 28 Jun 2023 19:02:18 +1000]
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X export(origination_callee_id_name=271)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [origination_callee_id_name]=[271]
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X hash(insert/pbx10251.mydomain.com.au-last_dial/61411******/271)
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X transfer(03******** XML pbx10251.mydomain.com.au)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] switch_ivr.c:2288 (sofia/external/61411******@X.X.X.X) State Change CS_EXECUTE -> CS_ROUTING
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [NOTICE] switch_ivr.c:2295 Transfer sofia/external/61411******@X.X.X.X to XML[03********@pbx10251.mydomain.com.au]
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] switch_core_state_machine.c:647 (sofia/external/61411******@X.X.X.X) State EXECUTE going to sleep
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] switch_core_state_machine.c:581 (sofia/external/61411******@X.X.X.X) Running State Change CS_ROUTING (Cur 1 Tot 54)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] switch_core_state_machine.c:640 (sofia/external/61411******@X.X.X.X) State ROUTING
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] mod_sofia.c:149 Call appears to be already acknowledged
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] mod_sofia.c:158 sofia/external/61411******@X.X.X.X SOFIA ROUTING
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [DEBUG] switch_core_state_machine.c:230 sofia/external/61411******@X.X.X.X Standard ROUTING
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.480924 99.97% [INFO] mod_dialplan_xml.c:639 Processing 61411****** <61411******>->03******** in context pbx10251.mydomain.com.au
2023-06-28 19:02:18.500899 99.97% [NOTICE] switch_cpp.cpp:1465 [xml_handler] multiple key:dialplan:pbx10251.mydomain.com.au
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->user_exists] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_exists] ${loopback_leg}() =~ /^B$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [user_exists] () =~ // break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(user_exists=false)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [user_exists]=[false]
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(from_user_exists=false)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [from_user_exists]=[false]
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->caller-details] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [caller-details] ${caller_destination}(271) =~ /^$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->global-variables] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [global-variables] () =~ // break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->domain-variables] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [domain-variables] () =~ // break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action export(origination_callee_id_name=${destination_number})
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(operator=1000) INLINE
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(operator=1000)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [operator]=[1000]
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->clear_sip_auto_answer] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->is_loopback] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->call-direction] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [call-direction] ${call_direction}(inbound) =~ /^$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->Fax] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [Fax] destination_number(03********) =~ /^8000$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->user_record] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->rtp_has_crypto] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X 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
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->redial] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [redial] destination_number(03********) =~ /^(redial|\*870)$/ break=on-true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [redial] () =~ // break=never
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->speed_dial] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [speed_dial] destination_number(03********) =~ /^\*0(.*)$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->user_hold_music] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [user_hold_music] ${user_exists}(false) =~ /true/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->default_caller_id] continue=true
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [default_caller_id] ${call_direction}(inbound) =~ /outbound/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->MYISPACCT.8d] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [MYISPACCT.8d] ${user_exists}(false) =~ /false/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (FAIL) [MYISPACCT.8d] destination_number(03********) =~ /^(\d{8})$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X parsing [pbx10251.mydomain.com.au->MYISPACCT.10d] continue=false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [MYISPACCT.10d] ${user_exists}(false) =~ /false/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Regex (PASS) [MYISPACCT.10d] destination_number(03********) =~ /^(\d{10})$/ break=on-false
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action export(call_direction=outbound) INLINE
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X export(call_direction=outbound)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [call_direction]=[outbound]
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action unset(call_timeout)
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(hangup_after_bridge=true)
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(effective_caller_id_name=${outbound_caller_id_name})
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(effective_caller_id_number=${outbound_caller_id_number})
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(inherit_codec=true)
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(ignore_display_updates=true)
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action set(callee_id_number=03********)
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X 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)
46f66835-460b-43b1-8e88-18b01bd900a5 Dialplan: sofia/external/61411******@X.X.X.X Action bridge(sofia/gateway/f921a85f-c400-43b3-9b27-9b629948846e/03********)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_core_state_machine.c:281 (sofia/external/61411******@X.X.X.X) State Change CS_ROUTING -> CS_EXECUTE
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_core_state_machine.c:640 (sofia/external/61411******@X.X.X.X) State ROUTING going to sleep
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_core_state_machine.c:581 (sofia/external/61411******@X.X.X.X) Running State Change CS_EXECUTE (Cur 1 Tot 54)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_core_state_machine.c:647 (sofia/external/61411******@X.X.X.X) State EXECUTE
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] mod_sofia.c:213 sofia/external/61411******@X.X.X.X SOFIA EXECUTE
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_core_state_machine.c:323 sofia/external/61411******@X.X.X.X Standard EXECUTE
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(RFC2822_DATE=Wed, 28 Jun 2023 19:02:18 +1000)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [RFC2822_DATE]=[Wed, 28 Jun 2023 19:02:18 +1000]
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X export(origination_callee_id_name=03********)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [origination_callee_id_name]=[03********]
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X hash(insert/pbx10251.mydomain.com.au-last_dial/61411******/03********)
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X unset(call_timeout)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] mod_dptools.c:1818 UNSET [call_timeout]
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(hangup_after_bridge=true)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [hangup_after_bridge]=[true]
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(effective_caller_id_name=)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [effective_caller_id_name]=[UNDEF]
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(effective_caller_id_number=)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [effective_caller_id_number]=[UNDEF]
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(inherit_codec=true)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [inherit_codec]=[true]
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(ignore_display_updates=true)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [ignore_display_updates]=[true]
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X set(callee_id_number=03********)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [callee_id_number]=[03********]
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X 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)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] mod_dptools.c:1673 SET sofia/external/61411******@X.X.X.X [continue_on_fail]=[1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607]
46f66835-460b-43b1-8e88-18b01bd900a5 EXECUTE [depth=0] sofia/external/61411******@X.X.X.X bridge(sofia/gateway/f921a85f-c400-43b3-9b27-9b629948846e/03********)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_channel.c:1269 sofia/external/61411******@X.X.X.X EXPORTING[export_vars] [call_direction]=[outbound] to event
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_channel.c:1269 sofia/external/61411******@X.X.X.X EXPORTING[export_vars] [origination_callee_id_name]=[03********] to event
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_channel.c:1269 sofia/external/61411******@X.X.X.X EXPORTING[export_vars] [call_direction]=[outbound] to event
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_channel.c:1269 sofia/external/61411******@X.X.X.X EXPORTING[export_vars] [origination_callee_id_name]=[03********] to event
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_ivr_originate.c:2291 Parsing global variables
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [NOTICE] switch_channel.c:1123 New Channel sofia/external/03******** [11b1dc78-ddf3-422a-b651-b2f150f13b1b]
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [DEBUG] mod_sofia.c:5117 (sofia/external/03********) State Change CS_NEW -> CS_INIT
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_core_state_machine.c:581 (sofia/external/03********) Running State Change CS_INIT (Cur 2 Tot 55)
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_core_state_machine.c:624 (sofia/external/03********) State INIT
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [DEBUG] mod_sofia.c:97 sofia/external/03******** SOFIA INIT
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [INFO] sofia_glue.c:1654 sofia/external/03******** sending invite call-id: (null)
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [DEBUG] sofia_glue.c:1657 sofia/external/03******** sending invite version: 1.10.9 -release 64bit
11b1dc78-ddf3-422a-b651-b2f150f13b1b Local SDP:
11b1dc78-ddf3-422a-b651-b2f150f13b1b v=0
11b1dc78-ddf3-422a-b651-b2f150f13b1b o=FreeSWITCH 1687915630 1687915631 IN IP4 X.X.X.X
11b1dc78-ddf3-422a-b651-b2f150f13b1b s=FreeSWITCH
11b1dc78-ddf3-422a-b651-b2f150f13b1b c=IN IP4 X.X.X.X
11b1dc78-ddf3-422a-b651-b2f150f13b1b t=0 0
11b1dc78-ddf3-422a-b651-b2f150f13b1b m=audio 27308 RTP/AVP 8 0 101
11b1dc78-ddf3-422a-b651-b2f150f13b1b a=rtpmap:8 PCMA/8000
11b1dc78-ddf3-422a-b651-b2f150f13b1b a=rtpmap:0 PCMU/8000
11b1dc78-ddf3-422a-b651-b2f150f13b1b a=rtpmap:101 telephone-event/8000
11b1dc78-ddf3-422a-b651-b2f150f13b1b a=fmtp:101 0-15
11b1dc78-ddf3-422a-b651-b2f150f13b1b a=silenceSupp:eek:ff - - - -
11b1dc78-ddf3-422a-b651-b2f150f13b1b a=ptime:20
11b1dc78-ddf3-422a-b651-b2f150f13b1b a=sendrecv
11b1dc78-ddf3-422a-b651-b2f150f13b1b
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_core_state_machine.c:40 sofia/external/03******** Standard INIT
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_core_state_machine.c:48 (sofia/external/03********) State Change CS_INIT -> CS_ROUTING
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_core_state_machine.c:624 (sofia/external/03********) State INIT going to sleep
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_core_state_machine.c:581 (sofia/external/03********) Running State Change CS_ROUTING (Cur 2 Tot 55)
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_core_state_machine.c:640 (sofia/external/03********) State ROUTING
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [DEBUG] mod_sofia.c:158 sofia/external/03******** SOFIA ROUTING
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_ivr_originate.c:67 (sofia/external/03********) State Change CS_ROUTING -> CS_CONSUME_MEDIA
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_core_state_machine.c:640 (sofia/external/03********) State ROUTING going to sleep
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_core_state_machine.c:581 (sofia/external/03********) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 55)
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [DEBUG] sofia.c:7487 Channel sofia/external/03******** entering state [calling][0]
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_core_state_machine.c:659 (sofia/external/03********) State CONSUME_MEDIA
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.500899 99.97% [DEBUG] switch_core_state_machine.c:659 (sofia/external/03********) State CONSUME_MEDIA going to sleep
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:18.561063 99.97% [DEBUG] sofia.c:7487 Channel sofia/external/03******** entering state [calling][0]
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.141118 99.93% [DEBUG] sofia.c:7487 Channel sofia/external/61411******@X.X.X.X entering state [terminated][487]
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.141118 99.93% [NOTICE] sofia.c:8729 Hangup sofia/external/61411******@X.X.X.X [CS_EXECUTE] [ORIGINATOR_CANCEL]
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [NOTICE] switch_ivr_originate.c:3809 Hangup sofia/external/03******** [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL]
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_ivr_originate.c:4034 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL]
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:581 (sofia/external/03********) Running State Change CS_HANGUP (Cur 2 Tot 55)
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:844 (sofia/external/03********) Callstate Change DOWN -> HANGUP
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:846 (sofia/external/03********) State HANGUP
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] mod_sofia.c:462 sofia/external/03******** Overriding SIP cause 487 with 487 from the other leg
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] mod_sofia.c:468 Channel sofia/external/03******** hanging up, cause: ORIGINATOR_CANCEL
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] mod_sofia.c:536 Sending CANCEL to sofia/external/03********
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.181076 99.93% [INFO] mod_dptools.c:3637 Originate Failed. Cause: ORIGINATOR_CANCEL
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:59 sofia/external/03******** Standard HANGUP, cause: ORIGINATOR_CANCEL
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:846 (sofia/external/03********) State HANGUP going to sleep
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:616 (sofia/external/03********) State Change CS_HANGUP -> CS_REPORTING
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:581 (sofia/external/03********) Running State Change CS_REPORTING (Cur 2 Tot 55)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_session.c:2973 sofia/external/61411******@X.X.X.X skip receive message [PHONE_EVENT] (channel is hungup already)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:647 (sofia/external/61411******@X.X.X.X) State EXECUTE going to sleep
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:581 (sofia/external/61411******@X.X.X.X) Running State Change CS_HANGUP (Cur 2 Tot 55)
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:932 (sofia/external/03********) State REPORTING
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:168 sofia/external/03******** Standard REPORTING, cause: ORIGINATOR_CANCEL
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:932 (sofia/external/03********) State REPORTING going to sleep
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:844 (sofia/external/61411******@X.X.X.X) Callstate Change RINGING -> HANGUP
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:846 (sofia/external/61411******@X.X.X.X) State HANGUP
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.181076 99.93% [DEBUG] mod_sofia.c:468 Channel sofia/external/61411******@X.X.X.X hanging up, cause: ORIGINATOR_CANCEL
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:59 sofia/external/61411******@X.X.X.X Standard HANGUP, cause: ORIGINATOR_CANCEL
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:846 (sofia/external/61411******@X.X.X.X) State HANGUP going to sleep
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:616 (sofia/external/61411******@X.X.X.X) State Change CS_HANGUP -> CS_REPORTING
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:581 (sofia/external/61411******@X.X.X.X) Running State Change CS_REPORTING (Cur 2 Tot 55)
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:607 (sofia/external/03********) State Change CS_REPORTING -> CS_DESTROY
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_session.c:1743 Session 55 (sofia/external/03********) Locked, Waiting on external entities
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [NOTICE] switch_core_session.c:1761 Session 55 (sofia/external/03********) Ended
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [NOTICE] switch_core_session.c:1765 Close Channel sofia/external/03******** [CS_DESTROY]
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:932 (sofia/external/61411******@X.X.X.X) State REPORTING
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:735 (sofia/external/03********) Running State Change CS_DESTROY (Cur 1 Tot 55)
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:745 (sofia/external/03********) State DESTROY
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] mod_sofia.c:379 sofia/external/03******** SOFIA DESTROY
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:175 sofia/external/03******** Standard DESTROY
11b1dc78-ddf3-422a-b651-b2f150f13b1b 2023-06-28 19:02:22.181076 99.93% [DEBUG] switch_core_state_machine.c:745 (sofia/external/03********) State DESTROY going to sleep
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.360931 99.93% [DEBUG] switch_core_state_machine.c:168 sofia/external/61411******@X.X.X.X Standard REPORTING, cause: ORIGINATOR_CANCEL
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.360931 99.93% [DEBUG] switch_core_state_machine.c:932 (sofia/external/61411******@X.X.X.X) State REPORTING going to sleep
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.360931 99.93% [DEBUG] switch_core_state_machine.c:607 (sofia/external/61411******@X.X.X.X) State Change CS_REPORTING -> CS_DESTROY
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.360931 99.93% [DEBUG] switch_core_session.c:1743 Session 54 (sofia/external/61411******@X.X.X.X) Locked, Waiting on external entities
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.360931 99.93% [NOTICE] switch_core_session.c:1761 Session 54 (sofia/external/61411******@X.X.X.X) Ended
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.360931 99.93% [NOTICE] switch_core_session.c:1765 Close Channel sofia/external/61411******@X.X.X.X [CS_DESTROY]
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.360931 99.93% [DEBUG] switch_core_state_machine.c:735 (sofia/external/61411******@X.X.X.X) Running State Change CS_DESTROY (Cur 0 Tot 55)
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.360931 99.93% [DEBUG] switch_core_state_machine.c:745 (sofia/external/61411******@X.X.X.X) State DESTROY
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.360931 99.93% [DEBUG] mod_sofia.c:379 sofia/external/61411******@X.X.X.X SOFIA DESTROY
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.360931 99.93% [DEBUG] switch_core_state_machine.c:175 sofia/external/61411******@X.X.X.X Standard DESTROY
46f66835-460b-43b1-8e88-18b01bd900a5 2023-06-28 19:02:22.360931 99.93% [DEBUG] switch_core_state_machine.c:745 (sofia/external/61411******@X.X.X.X) State DESTROY going to sleep
 
Last edited:
I

Incred

Member
Jun 25, 2023
53
0
6
51
Update....

I suspected my carrier was blocking the outbound call as the outbound CID was set to that of the inbound number. Similar to this post:

Private/Blocked Caller ID passed through with call forwarding

Hi Everyone, This occurs with both extension call forward and follow me and also just a straight "Outbound Destination" as a mobile number. I've set set up and IVR (without options) to play a greeting 2 minutes long and then timeout to a mobile number via either of the 3 methods mentioned...
www.pbxforums.com www.pbxforums.com

I have now created a ring group with a single outbound number and specified my CID which works. Accordingly, it appears that when using a "Destination" the call was being blocked by the carrier as the outbound CID was set to that of the inbound call.

My question is, can I specify the outbound CID when using a "Destination" like I can do with a "Ring Group"?
 
Last edited:
Adrian Fretwell

Adrian Fretwell

Well-Known Member
Aug 13, 2017
1,498
413
83
You can set the channel variables effective_caller_id_name and effective_caller_id_number in the destination itself.
 
I

Incred

Member
Jun 25, 2023
53
0
6
51
Thanks again Adrian however on which screen do I specify this variable? On the "Destination" screen I see a form with fields but nowhere to enter custom variables.
 
Adrian Fretwell

Adrian Fretwell

Well-Known Member
Aug 13, 2017
1,498
413
83
A destination is any record in the Dialplan Manager that has the destination field set to True, see screenshot below. Your destination will typically be an outbound route, but there is nothing to stop anything being classed as a destination. You would add/modify the variables in the details of the dialplan record.
Screenshot from 2023-06-29 11-35-14.png
 
I

Incred

Member
Jun 25, 2023
53
0
6
51
Appreciate the help but I am not following sorry. I'll need to get more familiar with FusionPBX and then return to this. At least it is working in the ring group for the time being, not perfect but working...
 
Status
Not open for further replies.
Top Bottom