Suppose I've set option in IVR 350 and set destination to 350 but call dropped. Here are the logs.
2020-09-24 18:17:09.603975 [NOTICE] switch_channel.c:1114 New Channel sofia/external/Restricted@173.239.37.248 [189d036a-3dcc-4633-9644-7ae979ec3779]
2020-09-24 18:17:09.603975 [DEBUG] switch_core_state_machine.c:584 (sofia/external/Restricted@173.239.37.248) Running State Change CS_NEW (Cur 1 Tot 73)
2020-09-24 18:17:09.603975 [DEBUG] sofia.c:10164 sofia/external/Restricted@173.239.37.248 receiving invite from 173.239.37.248:5060 version: 1.8.7 -8-6047ebddfc 64bit
2020-09-24 18:17:09.603975 [DEBUG] sofia.c:10260 verifying acl "domains" for ip/port 173.239.37.248:0.
2020-09-24 18:17:09.603975 [DEBUG] sofia.c:10289 IP 173.239.37.248 Approved by acl "domains[]". Access Granted.
2020-09-24 18:17:09.603975 [DEBUG] sofia.c:7323 Channel sofia/external/Restricted@173.239.37.248 entering state [received][100]
2020-09-24 18:17:09.603975 [DEBUG] sofia.c:7333 Remote SDP:
v=0
o=root 1989458358 1989458358 IN IP4 173.239.37.248
s=Asterisk PBX 11.6-cert10
c=IN IP4 173.239.37.248
t=0 0
m=audio 17604 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
2020-09-24 18:17:09.603975 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2020-09-24 18:17:09.603975 [DEBUG] switch_core_media.c:5566 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2020-09-24 18:17:09.603975 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2020-09-24 18:17:09.603975 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2020-09-24 18:17:09.603975 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2020-09-24 18:17:09.603975 [DEBUG] switch_core_media.c:5427 Set telephone-event payload to 101@8000
2020-09-24 18:17:09.603975 [DEBUG] switch_core_media.c:3781 Set Codec sofia/external/Restricted@173.239.37.248 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2020-09-24 18:17:09.603975 [DEBUG] switch_core_codec.c:111 sofia/external/Restricted@173.239.37.248 Original read codec set to PCMU:0
2020-09-24 18:17:09.603975 [DEBUG] switch_core_media.c:5770 Set telephone-event payload to 101@8000
2020-09-24 18:17:09.603975 [DEBUG] switch_core_media.c:5828 sofia/external/Restricted@173.239.37.248 Set 2833 dtmf send payload to 101 recv payload to 101
2020-09-24 18:17:09.603975 [DEBUG] sofia.c:7748 (sofia/external/Restricted@173.239.37.248) State Change CS_NEW -> CS_INIT
2020-09-24 18:17:09.603975 [DEBUG] switch_core_state_machine.c:603 (sofia/external/Restricted@173.239.37.248) State NEW
2020-09-24 18:17:09.603975 [DEBUG] switch_core_state_machine.c:584 (sofia/external/Restricted@173.239.37.248) Running State Change CS_INIT (Cur 1 Tot 73)
2020-09-24 18:17:09.603975 [DEBUG] switch_core_state_machine.c:627 (sofia/external/Restricted@173.239.37.248) State INIT
2020-09-24 18:17:09.603975 [DEBUG] mod_sofia.c:93 sofia/external/Restricted@173.239.37.248 SOFIA INIT
2020-09-24 18:17:09.603975 [DEBUG] switch_core_state_machine.c:40 sofia/external/Restricted@173.239.37.248 Standard INIT
2020-09-24 18:17:09.603975 [DEBUG] switch_core_state_machine.c:48 (sofia/external/Restricted@173.239.37.248) State Change CS_INIT -> CS_ROUTING
2020-09-24 18:17:09.603975 [DEBUG] switch_core_state_machine.c:627 (sofia/external/Restricted@173.239.37.248) State INIT going to sleep
2020-09-24 18:17:09.603975 [DEBUG] switch_core_state_machine.c:584 (sofia/external/Restricted@173.239.37.248) Running State Change CS_ROUTING (Cur 1 Tot 73)
2020-09-24 18:17:09.603975 [DEBUG] switch_channel.c:2293 (sofia/external/Restricted@173.239.37.248) Callstate Change DOWN -> RINGING
2020-09-24 18:17:09.603975 [DEBUG] switch_core_state_machine.c:643 (sofia/external/Restricted@173.239.37.248) State ROUTING
2020-09-24 18:17:09.603975 [DEBUG] mod_sofia.c:154 sofia/external/Restricted@173.239.37.248 SOFIA ROUTING
2020-09-24 18:17:09.603975 [DEBUG] switch_core_state_machine.c:236 sofia/external/Restricted@173.239.37.248 Standard ROUTING
2020-09-24 18:17:09.603975 [INFO] mod_dialplan_xml.c:637 Processing Anonymous <Restricted>->5162268256 in context public
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [public->caller-details] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [caller-details] () =~ // break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Action export(call_direction=inbound) INLINE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 export(call_direction=inbound)
2020-09-24 18:17:09.603975 [DEBUG] switch_channel.c:1306 EXPORT (export_vars) [call_direction]=[inbound]
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(caller_destination=${sip_to_user}) INLINE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(caller_destination=5162268256)
2020-09-24 18:17:09.603975 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [caller_destination]=[5162268256]
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(caller_id_name=${caller_id_name}) INLINE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(caller_id_name=Anonymous)
2020-09-24 18:17:09.603975 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [caller_id_name]=[Anonymous]
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(caller_id_number=${caller_id_number})
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [public->6467579144] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [6467579144] destination_number(5162268256) =~ /^(6467579144)$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [public->5162268256] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [5162268256] destination_number(5162268256) =~ /^(5162268256)$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 Action export(call_direction=inbound) INLINE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 export(call_direction=inbound)
2020-09-24 18:17:09.603975 [DEBUG] switch_channel.c:1306 EXPORT (export_vars) [call_direction]=[inbound]
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(domain_uuid=4daea463-a232-4f69-8432-3a2e29cb431c) INLINE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(domain_uuid=4daea463-a232-4f69-8432-3a2e29cb431c)
2020-09-24 18:17:09.623966 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [domain_uuid]=[4daea463-a232-4f69-8432-3a2e29cb431c]
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(domain_name=ippbx.bpsna.net) INLINE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(domain_name=ippbx.bpsna.net)
2020-09-24 18:17:09.623966 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [domain_name]=[ippbx.bpsna.net]
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(hangup_after_bridge=true) INLINE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(hangup_after_bridge=true)
2020-09-24 18:17:09.623966 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [hangup_after_bridge]=[true]
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(continue_on_fail=true) INLINE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(continue_on_fail=true)
2020-09-24 18:17:09.623966 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [continue_on_fail]=[true]
Dialplan: sofia/external/Restricted@173.239.37.248 Action transfer(360 XML ippbx.bpsna.net)
2020-09-24 18:17:09.623966 [DEBUG] switch_core_state_machine.c:286 (sofia/external/Restricted@173.239.37.248) State Change CS_ROUTING -> CS_EXECUTE
2020-09-24 18:17:09.623966 [DEBUG] switch_core_state_machine.c:643 (sofia/external/Restricted@173.239.37.248) State ROUTING going to sleep
2020-09-24 18:17:09.623966 [DEBUG] switch_core_state_machine.c:584 (sofia/external/Restricted@173.239.37.248) Running State Change CS_EXECUTE (Cur 1 Tot 73)
2020-09-24 18:17:09.623966 [DEBUG] switch_core_state_machine.c:650 (sofia/external/Restricted@173.239.37.248) State EXECUTE
2020-09-24 18:17:09.623966 [DEBUG] mod_sofia.c:209 sofia/external/Restricted@173.239.37.248 SOFIA EXECUTE
2020-09-24 18:17:09.623966 [DEBUG] switch_core_state_machine.c:328 sofia/external/Restricted@173.239.37.248 Standard EXECUTE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(caller_id_number=Restricted)
2020-09-24 18:17:09.623966 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [caller_id_number]=[Restricted]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 transfer(360 XML ippbx.bpsna.net)
2020-09-24 18:17:09.623966 [DEBUG] switch_ivr.c:2224 (sofia/external/Restricted@173.239.37.248) State Change CS_EXECUTE -> CS_ROUTING
2020-09-24 18:17:09.623966 [NOTICE] switch_ivr.c:2231 Transfer sofia/external/Restricted@173.239.37.248 to XML[
360@ippbx.bpsna.net]
2020-09-24 18:17:09.623966 [DEBUG] switch_core_state_machine.c:650 (sofia/external/Restricted@173.239.37.248) State EXECUTE going to sleep
2020-09-24 18:17:09.623966 [DEBUG] switch_core_state_machine.c:584 (sofia/external/Restricted@173.239.37.248) Running State Change CS_ROUTING (Cur 1 Tot 73)
2020-09-24 18:17:09.623966 [DEBUG] switch_core_state_machine.c:643 (sofia/external/Restricted@173.239.37.248) State ROUTING
2020-09-24 18:17:09.623966 [DEBUG] mod_sofia.c:145 Call appears to be already acknowledged
2020-09-24 18:17:09.623966 [DEBUG] mod_sofia.c:154 sofia/external/Restricted@173.239.37.248 SOFIA ROUTING
2020-09-24 18:17:09.623966 [DEBUG] switch_core_state_machine.c:236 sofia/external/Restricted@173.239.37.248 Standard ROUTING
2020-09-24 18:17:09.623966 [INFO] mod_dialplan_xml.c:637 Processing Anonymous <Restricted>->360 in context ippbx.bpsna.net
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->user_exists] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [user_exists] () =~ // break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(user_exists=false)
2020-09-24 18:17:09.643994 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [user_exists]=[false]
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->caller-details] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [caller-details] ${caller_destination}(5162268256) =~ /^$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->global-variables] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [global-variables] () =~ // break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->variables] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [variables] () =~ // break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 Action export(origination_callee_id_name=${destination_number})
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->call-direction] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->is_loopback] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->call_block] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [call_block] ${call_direction}(inbound) =~ /^inbound$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 Action lua(app.lua call_block)
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->user_record] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${from_user_exists}() =~ /^true$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${from_user_exists}() =~ /^true$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${from_user_exists}() =~ /^true$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${from_user_exists}() =~ /^true$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${from_user_exists}() =~ /^true$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->redial] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [redial] destination_number(360) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [redial] () =~ // break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->speed_dial] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [speed_dial] destination_number(360) =~ /^\*0(.*)$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->default_caller_id] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [default_caller_id] ${emergency_caller_id_number}() =~ /^$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(emergency_caller_id_name=${default_emergency_caller_id_name}) INLINE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(emergency_caller_id_name=)
2020-09-24 18:17:09.643994 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [emergency_caller_id_name]=[UNDEF]
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(emergency_caller_id_number=${default_emergency_caller_id_number}) INLINE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(emergency_caller_id_number=)
2020-09-24 18:17:09.643994 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [emergency_caller_id_number]=[UNDEF]
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [default_caller_id] ${outbound_caller_id_number}() =~ /^$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(outbound_caller_id_name=${default_outbound_caller_id_name}) INLINE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(outbound_caller_id_name=)
2020-09-24 18:17:09.643994 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [outbound_caller_id_name]=[UNDEF]
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(outbound_caller_id_number=${default_outbound_caller_id_number}) INLINE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(outbound_caller_id_number=)
2020-09-24 18:17:09.643994 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [outbound_caller_id_number]=[UNDEF]
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->user_hold_music] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_hold_music] ${hold_music}(local_stream://default) =~ /^$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_hold_music] ${user_exists}(false) =~ /true/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->Asus.1d10] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [Asus.1d10] ${user_exists}(false) =~ /false/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [Asus.1d10] destination_number(360) =~ /^\+?1?(\d{10})$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->Test] continue=
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [Test] destination_number(360) =~ /^350$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->IVR] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [IVR] destination_number(360) =~ /^360$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 Action ring_ready()
Dialplan: sofia/external/Restricted@173.239.37.248 Action answer()
Dialplan: sofia/external/Restricted@173.239.37.248 Action sleep(1000)
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(hangup_after_bridge=true)
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(ringback=${us-ring})
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(presence_id=
360@ippbx.bpsna.net)
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(default_language=)
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(default_dialect=)
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(default_voice=)
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(transfer_ringback=${us-ring})
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(ivr_menu_uuid=534f42ce-cc21-4534-93d6-23db427e2277)
Dialplan: sofia/external/Restricted@173.239.37.248 Action ivr(534f42ce-cc21-4534-93d6-23db427e2277)
2020-09-24 18:17:09.643994 [DEBUG] switch_core_state_machine.c:286 (sofia/external/Restricted@173.239.37.248) State Change CS_ROUTING -> CS_EXECUTE
2020-09-24 18:17:09.643994 [DEBUG] switch_core_state_machine.c:643 (sofia/external/Restricted@173.239.37.248) State ROUTING going to sleep
2020-09-24 18:17:09.643994 [DEBUG] switch_core_state_machine.c:584 (sofia/external/Restricted@173.239.37.248) Running State Change CS_EXECUTE (Cur 1 Tot 73)
2020-09-24 18:17:09.643994 [DEBUG] switch_core_state_machine.c:650 (sofia/external/Restricted@173.239.37.248) State EXECUTE
2020-09-24 18:17:09.643994 [DEBUG] mod_sofia.c:209 sofia/external/Restricted@173.239.37.248 SOFIA EXECUTE
2020-09-24 18:17:09.643994 [DEBUG] switch_core_state_machine.c:328 sofia/external/Restricted@173.239.37.248 Standard EXECUTE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(RFC2822_DATE=Thu, 24 Sep 2020 18:17:09 -0400)
2020-09-24 18:17:09.643994 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [RFC2822_DATE]=[Thu, 24 Sep 2020 18:17:09 -0400]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 export(origination_callee_id_name=360)
2020-09-24 18:17:09.643994 [DEBUG] switch_channel.c:1306 EXPORT (export_vars) [origination_callee_id_name]=[360]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(RFC2822_DATE=Thu, 24 Sep 2020 18:17:09 -0400)
2020-09-24 18:17:09.643994 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [RFC2822_DATE]=[Thu, 24 Sep 2020 18:17:09 -0400]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 lua(app.lua call_block)
2020-09-24 18:17:09.643994 [DEBUG] switch_cpp.cpp:1187 sofia/external/Restricted@173.239.37.248 destroy/unlink session from object
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 hash(insert/ippbx.bpsna.net-last_dial/Restricted/360)
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 ring_ready()
2020-09-24 18:17:09.643994 [NOTICE] mod_sofia.c:2420 Ring-Ready sofia/external/Restricted@173.239.37.248!
2020-09-24 18:17:09.663990 [DEBUG] sofia.c:7323 Channel sofia/external/Restricted@173.239.37.248 entering state [early][180]
2020-09-24 18:17:09.663990 [NOTICE] mod_dptools.c:1093 Ring Ready sofia/external/Restricted@173.239.37.248!
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 answer()
2020-09-24 18:17:09.663990 [DEBUG] switch_core_media.c:8568 AUDIO RTP [sofia/external/Restricted@173.239.37.248] 67.207.180.73 port 32024 -> 173.239.37.248 port 17604 codec: 0 ms: 20
2020-09-24 18:17:09.663990 [DEBUG] switch_rtp.c:4349 Starting timer [soft] 160 bytes per 20ms
2020-09-24 18:17:09.663990 [DEBUG] switch_core_media.c:8875 sofia/external/Restricted@173.239.37.248 Set 2833 dtmf send payload to 101
2020-09-24 18:17:09.663990 [DEBUG] switch_core_media.c:8882 sofia/external/Restricted@173.239.37.248 Set 2833 dtmf receive payload to 101
2020-09-24 18:17:09.663990 [DEBUG] switch_core_media.c:8905 sofia/external/Restricted@173.239.37.248 Set rtp dtmf delay to 40
2020-09-24 18:17:09.663990 [DEBUG] mod_sofia.c:893 Local SDP sofia/external/Restricted@173.239.37.248:
v=0
o=FreeSWITCH 1600953805 1600953806 IN IP4 67.207.180.73
s=FreeSWITCH
c=IN IP4 67.207.180.73
t=0 0
m=audio 32024 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
2020-09-24 18:17:09.663990 [NOTICE] mod_dptools.c:1377 Channel [sofia/external/Restricted@173.239.37.248] has been answered
2020-09-24 18:17:09.663990 [DEBUG] switch_channel.c:3825 (sofia/external/Restricted@173.239.37.248) Callstate Change RINGING -> ACTIVE
2020-09-24 18:17:09.663990 [DEBUG] sofia.c:7323 Channel sofia/external/Restricted@173.239.37.248 entering state [completed][200]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 sleep(1000)
2020-09-24 18:17:09.743969 [DEBUG] sofia.c:7323 Channel sofia/external/Restricted@173.239.37.248 entering state [ready][200]
2020-09-24 18:17:09.803992 [DEBUG] switch_rtp.c:7604 Correct audio ip/port confirmed.
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(hangup_after_bridge=true)
2020-09-24 18:17:10.683971 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [hangup_after_bridge]=[true]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(ringback=%(2000,4000,440,480))
2020-09-24 18:17:10.683971 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [ringback]=[%(2000,4000,440,480)]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(presence_id=
360@ippbx.bpsna.net)
2020-09-24 18:17:10.683971 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [presence_id]=[
360@ippbx.bpsna.net]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(default_language=)
2020-09-24 18:17:10.683971 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [default_language]=[UNDEF]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(default_dialect=)
2020-09-24 18:17:10.683971 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [default_dialect]=[UNDEF]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(default_voice=)
2020-09-24 18:17:10.683971 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [default_voice]=[UNDEF]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(transfer_ringback=%(2000,4000,440,480))
2020-09-24 18:17:10.683971 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [transfer_ringback]=[%(2000,4000,440,480)]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(ivr_menu_uuid=534f42ce-cc21-4534-93d6-23db427e2277)
2020-09-24 18:17:10.683971 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [ivr_menu_uuid]=[534f42ce-cc21-4534-93d6-23db427e2277]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 ivr(534f42ce-cc21-4534-93d6-23db427e2277)
2020-09-24 18:17:10.683971 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-exit'
2020-09-24 18:17:10.683971 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-sub'
2020-09-24 18:17:10.683971 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-exec-app'
2020-09-24 18:17:10.683971 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-play-sound'
2020-09-24 18:17:10.683971 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-back'
2020-09-24 18:17:10.683971 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-top'
2020-09-24 18:17:10.683971 [DEBUG] switch_ivr_menu.c:880 building menu '534f42ce-cc21-4534-93d6-23db427e2277'
2020-09-24 18:17:10.683971 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '8'
2020-09-24 18:17:10.683971 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '350'
2020-09-24 18:17:10.683971 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '9'
2020-09-24 18:17:10.683971 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '/^(\d{2,11})$/'
2020-09-24 18:17:10.683971 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '/^(\d{2,11})$/'
2020-09-24 18:17:10.683971 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '/^(\d{2,11})$/'
2020-09-24 18:17:10.683971 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '/^(\d{2,11})$/'
2020-09-24 18:17:10.683971 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '/^(\d{2,11})$/'
2020-09-24 18:17:10.683971 [DEBUG] switch_ivr_menu.c:483 Executing IVR menu 534f42ce-cc21-4534-93d6-23db427e2277
2020-09-24 18:17:10.683971 [DEBUG] switch_ivr_play_say.c:1497 Codec Activated L16@8000hz 1 channels 20ms
2020-09-24 18:17:13.723974 [DEBUG] switch_rtp.c:7843 RTP RECV DTMF 3:1120
2020-09-24 18:17:13.723974 [INFO] switch_channel.c:522 RECV DTMF 3:1120
2020-09-24 18:17:13.723974 [DEBUG] switch_ivr_play_say.c:1941 done playing file /var/lib/freeswitch/recordings/ippbx.bpsna.net/BPSNA.wav
2020-09-24 18:17:13.723974 [DEBUG] switch_ivr_menu.c:377 waiting for 4/5 digits t/o 5000
2020-09-24 18:17:14.583979 [DEBUG] switch_rtp.c:7843 RTP RECV DTMF 5:800
2020-09-24 18:17:14.583979 [INFO] switch_channel.c:522 RECV DTMF 5:800
2020-09-24 18:17:15.323970 [DEBUG] switch_rtp.c:7843 RTP RECV DTMF 0:800
2020-09-24 18:17:15.323970 [INFO] switch_channel.c:522 RECV DTMF 0:800
2020-09-24 18:17:16.723997 [DEBUG] switch_ivr_menu.c:424 digits '350'
2020-09-24 18:17:16.723997 [DEBUG] switch_ivr_menu.c:577 IVR action on menu '534f42ce-cc21-4534-93d6-23db427e2277' matched '350' param 'transfer 350 XML ippbx.bpsna.net'
2020-09-24 18:17:16.723997 [DEBUG] switch_ivr_menu.c:581 switch_ivr_menu_execute todo=[2]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 transfer(350 XML ippbx.bpsna.net)
2020-09-24 18:17:16.723997 [DEBUG] switch_ivr.c:2224 (sofia/external/Restricted@173.239.37.248) State Change CS_EXECUTE -> CS_ROUTING
2020-09-24 18:17:16.723997 [NOTICE] switch_ivr.c:2231 Transfer sofia/external/Restricted@173.239.37.248 to XML[
350@ippbx.bpsna.net]
2020-09-24 18:17:16.723997 [DEBUG] switch_ivr_menu.c:559 action regex [350] [/^(\d{2,11})$/] [2]
2020-09-24 18:17:16.723997 [DEBUG] switch_ivr_menu.c:577 IVR action on menu '534f42ce-cc21-4534-93d6-23db427e2277' matched '350' param 'set ${cond(${user_exists id 350 ippbx.bpsna.net} == true ? user_exists=true : user_exists=false)}'
2020-09-24 18:17:16.723997 [DEBUG] switch_ivr_menu.c:581 switch_ivr_menu_execute todo=[2]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(user_exists=false)
2020-09-24 18:17:16.723997 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [user_exists]=[false]
2020-09-24 18:17:16.723997 [DEBUG] switch_ivr_menu.c:559 action regex [350] [/^(\d{2,11})$/] [2]
2020-09-24 18:17:16.723997 [DEBUG] switch_ivr_menu.c:577 IVR action on menu '534f42ce-cc21-4534-93d6-23db427e2277' matched '350' param 'set ${cond(${user_exists} == true ? user_exists=true : ivr_max_failures=${system(expr ${ivr_max_failures} + 1)})}'
2020-09-24 18:17:16.723997 [DEBUG] switch_ivr_menu.c:581 switch_ivr_menu_execute todo=[2]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(ivr_max_failures=1)
2020-09-24 18:17:16.743980 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [ivr_max_failures]=[1]
2020-09-24 18:17:16.743980 [DEBUG] switch_ivr_menu.c:559 action regex [350] [/^(\d{2,11})$/] [2]
2020-09-24 18:17:16.743980 [DEBUG] switch_ivr_menu.c:577 IVR action on menu '534f42ce-cc21-4534-93d6-23db427e2277' matched '350' param 'playback ${cond(${user_exists} == true ? /usr/share/freeswitch/sounds/${default_language}/${default_dialect}/${default_voice}/ivr/ivr-call_being_transferred.wav : /usr/share/freeswitch/sounds/${default_language}/${default_dialect}/${default_voice}/ivr/ivr-that_was_an_invalid_entry.wav)}'
2020-09-24 18:17:16.743980 [DEBUG] switch_ivr_menu.c:581 switch_ivr_menu_execute todo=[2]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 playback(/usr/share/freeswitch/sounds/en/us/callie/ivr/ivr-that_was_an_invalid_entry.wav)
2020-09-24 18:17:16.743980 [DEBUG] switch_ivr_menu.c:559 action regex [350] [/^(\d{2,11})$/] [2]
2020-09-24 18:17:16.743980 [DEBUG] switch_ivr_menu.c:577 IVR action on menu '534f42ce-cc21-4534-93d6-23db427e2277' matched '350' param 'transfer ${cond(${ivr_max_failures} == 3 ? )}'
2020-09-24 18:17:16.743980 [DEBUG] switch_ivr_menu.c:581 switch_ivr_menu_execute todo=[2]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 transfer(-ERR)
2020-09-24 18:17:16.743980 [NOTICE] switch_ivr.c:2231 Transfer sofia/external/Restricted@173.239.37.248 to XML[
-ERR@ippbx.bpsna.net]
2020-09-24 18:17:16.743980 [DEBUG] switch_ivr_menu.c:559 action regex [350] [/^(\d{2,11})$/] [2]
2020-09-24 18:17:16.743980 [DEBUG] switch_ivr_menu.c:577 IVR action on menu '534f42ce-cc21-4534-93d6-23db427e2277' matched '350' param 'transfer ${cond(${user_exists} == true ? 350 XML ippbx.bpsna.net)}'
2020-09-24 18:17:16.743980 [DEBUG] switch_ivr_menu.c:581 switch_ivr_menu_execute todo=[2]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 transfer()
2020-09-24 18:17:16.743980 [DEBUG] switch_ivr_menu.c:673 exit-sound '(null)'
2020-09-24 18:17:16.743980 [DEBUG] switch_core_state_machine.c:650 (sofia/external/Restricted@173.239.37.248) State EXECUTE going to sleep
2020-09-24 18:17:16.743980 [DEBUG] switch_core_state_machine.c:584 (sofia/external/Restricted@173.239.37.248) Running State Change CS_ROUTING (Cur 1 Tot 73)
2020-09-24 18:17:16.743980 [DEBUG] switch_channel.c:2293 (sofia/external/Restricted@173.239.37.248) Callstate Change ACTIVE -> RINGING
2020-09-24 18:17:16.743980 [DEBUG] switch_core_state_machine.c:643 (sofia/external/Restricted@173.239.37.248) State ROUTING
2020-09-24 18:17:16.743980 [DEBUG] mod_sofia.c:154 sofia/external/Restricted@173.239.37.248 SOFIA ROUTING
2020-09-24 18:17:16.743980 [DEBUG] switch_core_state_machine.c:236 sofia/external/Restricted@173.239.37.248 Standard ROUTING
2020-09-24 18:17:16.743980 [INFO] mod_dialplan_xml.c:637 Processing Anonymous <Restricted>->-ERR in context ippbx.bpsna.net
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->user_exists] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [user_exists] () =~ // break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
2020-09-24 18:17:16.763967 [ERR] mod_lua.cpp:202 ...hare/freeswitch/scripts/resources/functions/database.lua:194: attempt to concatenate a nil value
stack traceback:
...hare/freeswitch/scripts/resources/functions/database.lua:194: in function <...hare/freeswitch/scripts/resources/functions/database.lua:193>
(...tail calls...)
[C]: in function 'gsub'
...hare/freeswitch/scripts/resources/functions/database.lua:42: in function <...hare/freeswitch/scripts/resources/functions/database.lua:37>
(...tail calls...)
...hare/freeswitch/scripts/resources/functions/database.lua:131: in function 'query'
...pp/xml_handler/resources/scripts/directory/directory.lua:284: in main chunk
[C]: in function 'dofile'
/usr/share/freeswitch/scripts/app/xml_handler/index.lua:99: in main chunk
/usr/share/freeswitch/scripts/app.lua:48: in main chunk
2020-09-24 18:17:16.763967 [ERR] mod_lua.cpp:270 LUA script parse/execute error!
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(user_exists=false)
2020-09-24 18:17:16.763967 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [user_exists]=[false]
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->caller-details] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [caller-details] ${caller_destination}(5162268256) =~ /^$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->global-variables] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [global-variables] () =~ // break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->variables] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [variables] () =~ // break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 Action export(origination_callee_id_name=${destination_number})
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->call-direction] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->is_loopback] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->call_block] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [call_block] ${call_direction}(inbound) =~ /^inbound$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 Action lua(app.lua call_block)
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->user_record] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${from_user_exists}() =~ /^true$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${from_user_exists}() =~ /^true$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${from_user_exists}() =~ /^true$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${from_user_exists}() =~ /^true$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${from_user_exists}() =~ /^true$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->redial] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [redial] destination_number(-ERR) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [redial] () =~ // break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->speed_dial] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [speed_dial] destination_number(-ERR) =~ /^\*0(.*)$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->default_caller_id] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [default_caller_id] ${emergency_caller_id_number}() =~ /^$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(emergency_caller_id_name=${default_emergency_caller_id_name}) INLINE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(emergency_caller_id_name=)
2020-09-24 18:17:16.763967 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [emergency_caller_id_name]=[UNDEF]
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(emergency_caller_id_number=${default_emergency_caller_id_number}) INLINE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(emergency_caller_id_number=)
2020-09-24 18:17:16.763967 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [emergency_caller_id_number]=[UNDEF]
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [default_caller_id] ${outbound_caller_id_number}() =~ /^$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(outbound_caller_id_name=${default_outbound_caller_id_name}) INLINE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(outbound_caller_id_name=)
2020-09-24 18:17:16.763967 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [outbound_caller_id_name]=[UNDEF]
Dialplan: sofia/external/Restricted@173.239.37.248 Action set(outbound_caller_id_number=${default_outbound_caller_id_number}) INLINE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(outbound_caller_id_number=)
2020-09-24 18:17:16.763967 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [outbound_caller_id_number]=[UNDEF]
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->user_hold_music] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_hold_music] ${hold_music}(local_stream://default) =~ /^$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [user_hold_music] ${user_exists}(false) =~ /true/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->Asus.1d10] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (PASS) [Asus.1d10] ${user_exists}(false) =~ /false/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [Asus.1d10] destination_number(-ERR) =~ /^\+?1?(\d{10})$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->Test] continue=
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [Test] destination_number(-ERR) =~ /^350$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->IVR] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [IVR] destination_number(-ERR) =~ /^360$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->agent_status] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [agent_status] destination_number(-ERR) =~ /^\*22$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [agent_status] destination_number(-ERR) =~ /^(agent\+)(.*)$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->agent_status_id] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [agent_status_id] destination_number(-ERR) =~ /^\*23$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->group-intercept] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [group-intercept] destination_number(-ERR) =~ /^\*8$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->ACD] continue=
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [ACD] destination_number(-ERR) =~ /^([^#]+#)(.*)$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [ACD] destination_number(-ERR) =~ /^320$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->page] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [page] destination_number(-ERR) =~ /^\*724$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->page-extension] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [page-extension] destination_number(-ERR) =~ /^\*8(\d{2,7})$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->eavesdrop] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [eavesdrop] destination_number(-ERR) =~ /^\*33(\d{2,7})$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->call_privacy] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [call_privacy] destination_number(-ERR) =~ /^\*67(\d+)$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->call_return] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [call_return] destination_number(-ERR) =~ /^\*69$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->intercept-ext] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [intercept-ext] destination_number(-ERR) =~ /^\*\*(\d+)$/ break=on-true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [intercept-ext] destination_number(-ERR) =~ /^\*\*$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->extension_queue] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [extension_queue] destination_number(-ERR) =~ /^\*800(.*)$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->intercept-ext-polycom] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [intercept-ext-polycom] destination_number(-ERR) =~ /^\*97(\d+)$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->Regular] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [Regular] destination_number(-ERR) =~ /^180$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->dx] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [dx] destination_number(-ERR) =~ /^dx$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->ACD] continue=
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [ACD] destination_number(-ERR) =~ /^315$/ break=on-true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [ACD] destination_number(-ERR) =~ /^214 216$/ break=on-true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [ACD] destination_number(-ERR) =~ /^214 216$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->send_to_voicemail] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [send_to_voicemail] destination_number(-ERR) =~ /^\*99(\d{2,10})$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->att_xfer] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [att_xfer] destination_number(-ERR) =~ /^att_xfer$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->extension-to-voicemail] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [extension-to-voicemail] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->Fax] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [Fax] destination_number(-ERR) =~ /^5162268256$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->vmain] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [vmain] destination_number(-ERR) =~ /^vmain$|^\*4000$|^\*98$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [vmain] destination_number(-ERR) =~ /^(vmain$|^\*4000$|^\*98)(\d{2,12})$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->xfer_vm] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [xfer_vm] destination_number(-ERR) =~ /^xfer_vm$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->vmain_user] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [vmain_user] destination_number(-ERR) =~ /^\*97$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->is_transfer] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [is_transfer] destination_number(-ERR) =~ /^is_transfer$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->Test] continue=
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [Test] destination_number(-ERR) =~ /^888$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->Day/Night Mode] continue=
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [Day/Night Mode] destination_number(-ERR) =~ /^(?:flow\+)?\*66$/ break=on-true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [Day/Night Mode] destination_number(-ERR) =~ /^199$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->cf] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [cf] destination_number(-ERR) =~ /^cf$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->delay_echo] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [delay_echo] destination_number(-ERR) =~ /^\*9195$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->echo] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [echo] destination_number(-ERR) =~ /^\*9196$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->milliwatt] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [milliwatt] destination_number(-ERR) =~ /^\*9197$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->is_zrtp_secure] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [is_zrtp_secure] ${zrtp_secure_media_confirmed}() =~ /^true$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 ANTI-Action eval(not_secure)
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->tone_stream] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [tone_stream] destination_number(-ERR) =~ /^\*9198$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->is_secure] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [is_secure] ${sip_via_protocol}(udp) =~ /tls/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->hold_music] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [hold_music] destination_number(-ERR) =~ /^\*9664$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->recordings] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [recordings] destination_number(-ERR) =~ /^\*(732)$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->disa] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [disa] destination_number(-ERR) =~ /^\*(3472)$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->directory] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [directory] destination_number(-ERR) =~ /^\*411$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->wake-up] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [wake-up] destination_number(-ERR) =~ /^\*(925)$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->valet_park] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [valet_park] destination_number(-ERR) =~ /^(park\+)?\*(59[0-9][0-9])$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [valet_park] ${sip_h_Referred-By}() =~ /sip
.*)@.*/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [valet_park] destination_number(-ERR) =~ /^(park\+)?\*(59[0-9][0-9])$/ break=never
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [valet_park] destination_number(-ERR) =~ /^(park\+)?\*(59[0-9][0-9])$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->operator] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [operator] destination_number(-ERR) =~ /^0$|^operator$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->operator-forward] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [operator-forward] destination_number(-ERR) =~ /^\*000$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->do-not-disturb] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [do-not-disturb] destination_number(-ERR) =~ /^\*77$/ break=on-true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [do-not-disturb] destination_number(-ERR) =~ /^\*78$|\*363$/ break=on-true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [do-not-disturb] destination_number(-ERR) =~ /^\*79$/ break=on-true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [do-not-disturb] destination_number(-ERR) =~ /^dnd\+Restricted$/ break=on-true
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->call_screen] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [call_screen] ${call_screen_enabled}() =~ /^true$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->follow-me-destinations] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [follow-me-destinations] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->call-forward] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [call-forward] destination_number(-ERR) =~ /^\*72(\d*)$/ break=on-true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [call-forward] destination_number(-ERR) =~ /^\*73$/ break=on-true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [call-forward] destination_number(-ERR) =~ /^\*74$/ break=on-true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [call-forward] destination_number(-ERR) =~ /^forward\+(\QRestricted\E)(?:\/(\d+))?$/ break=on-true
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->call forward all] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [call forward all] ${user_exists}(false) =~ /true/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->call forward not registered] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [call forward not registered] ${user_exists}(false) =~ /true/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->follow-me] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [follow-me] destination_number(-ERR) =~ /^\*21$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->clear_sip_auto_answer] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->talking clock date and time] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [talking clock date and time] destination_number(-ERR) =~ /^\*9172$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->talking clock time] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [talking clock time] destination_number(-ERR) =~ /^\*9170$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->talking clock date] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [talking clock date] destination_number(-ERR) =~ /^\*9171$/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->local_extension] continue=true
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [local_extension] ${user_exists}(false) =~ /true/ break=on-false
Dialplan: sofia/external/Restricted@173.239.37.248 parsing [ippbx.bpsna.net->voicemail] continue=false
Dialplan: sofia/external/Restricted@173.239.37.248 Regex (FAIL) [voicemail] ${user_exists}(false) =~ /true/ break=on-false
2020-09-24 18:17:16.763967 [DEBUG] switch_core_state_machine.c:286 (sofia/external/Restricted@173.239.37.248) State Change CS_ROUTING -> CS_EXECUTE
2020-09-24 18:17:16.763967 [DEBUG] switch_core_state_machine.c:643 (sofia/external/Restricted@173.239.37.248) State ROUTING going to sleep
2020-09-24 18:17:16.763967 [DEBUG] switch_core_state_machine.c:584 (sofia/external/Restricted@173.239.37.248) Running State Change CS_EXECUTE (Cur 1 Tot 73)
2020-09-24 18:17:16.763967 [DEBUG] switch_channel.c:2295 (sofia/external/Restricted@173.239.37.248) Callstate Change RINGING -> ACTIVE
2020-09-24 18:17:16.763967 [DEBUG] switch_core_state_machine.c:650 (sofia/external/Restricted@173.239.37.248) State EXECUTE
2020-09-24 18:17:16.763967 [DEBUG] mod_sofia.c:209 sofia/external/Restricted@173.239.37.248 SOFIA EXECUTE
2020-09-24 18:17:16.763967 [DEBUG] switch_core_state_machine.c:328 sofia/external/Restricted@173.239.37.248 Standard EXECUTE
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(RFC2822_DATE=Thu, 24 Sep 2020 18:17:16 -0400)
2020-09-24 18:17:16.763967 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [RFC2822_DATE]=[Thu, 24 Sep 2020 18:17:16 -0400]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 export(origination_callee_id_name=-ERR)
2020-09-24 18:17:16.763967 [DEBUG] switch_channel.c:1306 EXPORT (export_vars) [origination_callee_id_name]=[-ERR]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 set(RFC2822_DATE=Thu, 24 Sep 2020 18:17:16 -0400)
2020-09-24 18:17:16.763967 [DEBUG] mod_dptools.c:1615 SET sofia/external/Restricted@173.239.37.248 [RFC2822_DATE]=[Thu, 24 Sep 2020 18:17:16 -0400]
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 lua(app.lua call_block)
2020-09-24 18:17:16.783989 [DEBUG] switch_cpp.cpp:1187 sofia/external/Restricted@173.239.37.248 destroy/unlink session from object
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 hash(insert/ippbx.bpsna.net-last_dial/Restricted/-ERR)
EXECUTE [depth=0] sofia/external/Restricted@173.239.37.248 eval(not_secure)
2020-09-24 18:17:16.783989 [NOTICE] switch_core_state_machine.c:385 sofia/external/Restricted@173.239.37.248 has executed the last dialplan instruction, hanging up.
2020-09-24 18:17:16.783989 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/external/Restricted@173.239.37.248 [CS_EXECUTE] [NORMAL_CLEARING]
2020-09-24 18:17:16.783989 [DEBUG] switch_core_state_machine.c:650 (sofia/external/Restricted@173.239.37.248) State EXECUTE going to sleep
2020-09-24 18:17:16.783989 [DEBUG] switch_core_state_machine.c:584 (sofia/external/Restricted@173.239.37.248) Running State Change CS_HANGUP (Cur 1 Tot 73)
2020-09-24 18:17:16.783989 [DEBUG] switch_core_state_machine.c:847 (sofia/external/Restricted@173.239.37.248) Callstate Change ACTIVE -> HANGUP
2020-09-24 18:17:16.783989 [DEBUG] switch_core_state_machine.c:849 (sofia/external/Restricted@173.239.37.248) State HANGUP
2020-09-24 18:17:16.783989 [DEBUG] mod_sofia.c:460 Channel sofia/external/Restricted@173.239.37.248 hanging up, cause: NORMAL_CLEARING
2020-09-24 18:17:16.783989 [DEBUG] mod_sofia.c:513 Sending BYE to sofia/external/Restricted@173.239.37.248
2020-09-24 18:17:16.783989 [DEBUG] switch_core_state_machine.c:60 sofia/external/Restricted@173.239.37.248 Standard HANGUP, cause: NORMAL_CLEARING
2020-09-24 18:17:16.783989 [DEBUG] switch_core_state_machine.c:849 (sofia/external/Restricted@173.239.37.248) State HANGUP going to sleep
2020-09-24 18:17:16.783989 [DEBUG] switch_core_state_machine.c:619 (sofia/external/Restricted@173.239.37.248) State Change CS_HANGUP -> CS_REPORTING
2020-09-24 18:17:16.783989 [DEBUG] switch_core_state_machine.c:584 (sofia/external/Restricted@173.239.37.248) Running State Change CS_REPORTING (Cur 1 Tot 73)
2020-09-24 18:17:16.783989 [DEBUG] switch_core_state_machine.c:935 (sofia/external/Restricted@173.239.37.248) State REPORTING
2020-09-24 18:17:16.883980 [DEBUG] switch_core_state_machine.c:174 sofia/external/Restricted@173.239.37.248 Standard REPORTING, cause: NORMAL_CLEARING
2020-09-24 18:17:16.883980 [DEBUG] switch_core_state_machine.c:935 (sofia/external/Restricted@173.239.37.248) State REPORTING going to sleep
2020-09-24 18:17:16.883980 [DEBUG] switch_core_state_machine.c:610 (sofia/external/Restricted@173.239.37.248) State Change CS_REPORTING -> CS_DESTROY
2020-09-24 18:17:16.883980 [DEBUG] switch_core_session.c:1726 Session 73 (sofia/external/Restricted@173.239.37.248) Locked, Waiting on external entities
2020-09-24 18:17:16.883980 [NOTICE] switch_core_session.c:1744 Session 73 (sofia/external/Restricted@173.239.37.248) Ended
2020-09-24 18:17:16.883980 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/Restricted@173.239.37.248 [CS_DESTROY]
2020-09-24 18:17:16.883980 [DEBUG] switch_core_state_machine.c:738 (sofia/external/Restricted@173.239.37.248) Running State Change CS_DESTROY (Cur 0 Tot 73)
2020-09-24 18:17:16.883980 [DEBUG] switch_core_state_machine.c:748 (sofia/external/Restricted@173.239.37.248) State DESTROY
2020-09-24 18:17:16.883980 [DEBUG] mod_sofia.c:365 sofia/external/Restricted@173.239.37.248 SOFIA DESTROY
2020-09-24 18:17:16.883980 [DEBUG] switch_core_state_machine.c:181 sofia/external/Restricted@173.239.37.248 Standard DESTROY
2020-09-24 18:17:16.883980 [DEBUG] switch_core_state_machine.c:748 (sofia/external/Restricted@173.239.37.248) State DESTROY going to sleep