when press *4 the log shows like below
2023-04-17 06:08:42.436124 95.80% [DEBUG] switch_rtp.c:1982 rtcp_stats_init: audio ssrc[476567410] base_seq[2641]
2023-04-17 06:09:03.876145 93.97% [NOTICE] switch_channel.c:1123 New Channel sofia/internal/
101@testSIP.com [c32199bc-2945-4957-afc0-33fd6254c952]
2023-04-17 06:09:03.876145 93.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/
101@testSIP.com) Running State Change CS_NEW (Cur 3 Tot 47)
2023-04-17 06:09:03.876145 93.97% [INFO] sofia.c:10462 sofia/internal/
101@testSIP.com receiving invite from 162.216.142.29:61345 version: 1.10.7 -release 64bit call-id: ju8hgnt1i7ddbg4p1n5j
2023-04-17 06:09:03.876145 93.97% [DEBUG] sofia.c:10556 verifying acl "providers" for ip/port 162.216.142.29:0.
2023-04-17 06:09:03.876145 93.97% [DEBUG] sofia.c:2419 detaching session c32199bc-2945-4957-afc0-33fd6254c952
2023-04-17 06:09:03.876145 93.97% [DEBUG] switch_core_state_machine.c:600 (sofia/internal/
101@testSIP.com) State NEW
2023-04-17 06:09:03.996123 93.90% [DEBUG] sofia.c:2532 Re-attaching to session c32199bc-2945-4957-afc0-33fd6254c952
2023-04-17 06:09:04.016100 93.90% [INFO] sofia.c:10462 sofia/internal/
101@testSIP.com receiving invite from 162.216.142.29:61345 version: 1.10.7 -release 64bit call-id: ju8hgnt1i7ddbg4p1n5j
2023-04-17 06:09:04.016100 93.90% [DEBUG] sofia.c:10556 verifying acl "providers" for ip/port 162.216.142.29:0.
2023-04-17 06:09:04.016100 93.90% [DEBUG] sofia.c:11672 Setting NAT mode based on via received
2023-04-17 06:09:04.016100 93.90% [DEBUG] sofia.c:7499 Channel sofia/internal/
101@testSIP.com entering state [received][100]
2023-04-17 06:09:04.016100 93.90% [DEBUG] sofia.c:7509 Remote SDP:
v=0
o=- 6568062604828577153 2 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE 0
a=extmap-allow-mixed
a=msid-semantic: WMS d92f4970-c8e8-44fc-baa9-7156c97b78c4
m=audio 52017 UDP/TLS/RTP/SAVPF 111 63 9 0 8 13 110 126
c=IN IP4 162.216.142.29
a=rtpmap:111 opus/48000/2
a=fmtp:111 minptime=10;useinbandfec=1
a=rtpmap:63 red/48000/2
a=fmtp:63 111/111
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:13 CN/8000
a=rtpmap:110 telephone-event/48000
a=rtpmap:126 telephone-event/8000
a=rtcp:9 IN IP4 0.0.0.0
a=candidate:3825433728 1 udp 2122260223 192.168.0.135 52017 typ host generation 0 network-id 1 network-cost 10
a=candidate:3842182822 1 udp 1686052607 162.216.142.29 52017 typ srflx raddr 192.168.0.135 rport 52017 generation 0 network-id 1 network-cost 10
a=candidate:2597101080 1 tcp 1518280447 192.168.0.135 9 typ host tcptype active generation 0 network-id 1 network-cost 10
a=ice-ufrag:dM8r
a=ice-pwd:/yEUvW4VF8Cd1OhbGTzTzkFp
a=ice-options:trickle
a=fingerprint:sha-256 8C:FD:F6:35:02:83:74:11:C8:71:02:0B
C:13
2:32:25:15:50:01:C8:62:B7:50:57:30:E5:BE:6C:0F:CB:78
a=setup:actpass
a=mid:0
a=extmap:1 urn:ietf
arams:rtp-hdrext:ssrc-audio-level
a=extmap:2
http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
a=extmap:4 urn:ietf
arams:rtp-hdrext:sdes:mid
a=msid:d92f4970-c8e8-44fc-baa9-7156c97b78c4 7470f09a-6491-40dc-8795-dfa53040be33
a=rtcp-mux
a=rtcp-fb:111 transport-cc
a=ssrc:746257995 cname:sKgMi3KyRQLSOHQ8
a=ssrc:746257995 msid:d92f4970-c8e8-44fc-baa9-7156c97b78c4 7470f09a-6491-40dc-8795-dfa53040be33
2023-04-17 06:09:04.016100 93.90% [DEBUG] sofia.c:7912 (sofia/internal/
101@testSIP.com) State Change CS_NEW -> CS_INIT
2023-04-17 06:09:04.016100 93.90% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/
101@testSIP.com) Running State Change CS_INIT (Cur 3 Tot 47)
2023-04-17 06:09:04.016100 93.90% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/
101@testSIP.com) State INIT
2023-04-17 06:09:04.016100 93.90% [DEBUG] mod_sofia.c:97 sofia/internal/
101@testSIP.com SOFIA INIT
2023-04-17 06:09:04.016100 93.90% [DEBUG] switch_core_state_machine.c:40 sofia/internal/
101@testSIP.com Standard INIT
2023-04-17 06:09:04.016100 93.90% [DEBUG] switch_core_state_machine.c:48 (sofia/internal/
101@testSIP.com) State Change CS_INIT -> CS_ROUTING
2023-04-17 06:09:04.016100 93.90% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/
101@testSIP.com) State INIT going to sleep
2023-04-17 06:09:04.016100 93.90% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/
101@testSIP.com) Running State Change CS_ROUTING (Cur 3 Tot 47)
2023-04-17 06:09:04.016100 93.90% [DEBUG] switch_channel.c:2380 (sofia/internal/
101@testSIP.com) Callstate Change DOWN -> RINGING
2023-04-17 06:09:04.016100 93.90% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/
101@testSIP.com) State ROUTING
2023-04-17 06:09:04.016100 93.90% [DEBUG] mod_sofia.c:158 sofia/internal/
101@testSIP.com SOFIA ROUTING
2023-04-17 06:09:04.016100 93.90% [DEBUG] switch_core_state_machine.c:230 sofia/internal/
101@testSIP.com Standard ROUTING
2023-04-17 06:09:04.016100 93.90% [INFO] mod_dialplan_xml.c:639 Processing RT <101>->*4 in context testSIP.com
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->caller-details] continue=true
Dialplan: sofia/internal/
101@testSIP.com Regex (PASS) [caller-details] () =~ // break=never
Dialplan: sofia/internal/
101@testSIP.com Action set(caller_destination=${sip_to_user}) INLINE
EXECUTE [depth=0] sofia/internal/
101@testSIP.com set(caller_destination=*4)
2023-04-17 06:09:04.016100 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/internal/
101@testSIP.com [caller_destination]=[*4]
Dialplan: sofia/internal/
101@testSIP.com Action set(caller_id_name=${caller_id_name}) INLINE
EXECUTE [depth=0] sofia/internal/
101@testSIP.com set(caller_id_name=101)
2023-04-17 06:09:04.016100 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/internal/
101@testSIP.com [caller_id_name]=[101]
Dialplan: sofia/internal/
101@testSIP.com Action set(caller_id_number=${caller_id_number})
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->user_exists] continue=true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [user_exists] ${loopback_leg}() =~ /^B$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (PASS) [user_exists] () =~ // break=on-false
Dialplan: sofia/internal/
101@testSIP.com Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
EXECUTE [depth=0] sofia/internal/
101@testSIP.com set(user_exists=false)
2023-04-17 06:09:04.036104 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/internal/
101@testSIP.com [user_exists]=[false]
Dialplan: sofia/internal/
101@testSIP.com Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
EXECUTE [depth=0] sofia/internal/
101@testSIP.com set(from_user_exists=true)
2023-04-17 06:09:04.036104 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/internal/
101@testSIP.com [from_user_exists]=[true]
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->caller-details] continue=true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [caller-details] ${caller_destination}(*4) =~ /^$/ break=never
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->global-variables] continue=true
Dialplan: sofia/internal/
101@testSIP.com Regex (PASS) [global-variables] () =~ // break=on-false
Dialplan: sofia/internal/
101@testSIP.com Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->domain-variables] continue=true
Dialplan: sofia/internal/
101@testSIP.com Regex (PASS) [domain-variables] () =~ // break=on-false
Dialplan: sofia/internal/
101@testSIP.com Action export(origination_callee_id_name=${destination_number})
Dialplan: sofia/internal/
101@testSIP.com Action set(operator=1000) INLINE
EXECUTE [depth=0] sofia/internal/
101@testSIP.com set(operator=1000)
2023-04-17 06:09:04.036104 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/internal/
101@testSIP.com [operator]=[1000]
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->clear_sip_auto_answer] continue=true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->call-direction] continue=true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never
Dialplan: sofia/internal/
101@testSIP.com ANTI-Action export(call_direction=local) INLINE
EXECUTE [depth=0] sofia/internal/
101@testSIP.com export(call_direction=local)
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [call_direction]=[local]
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->is_loopback] continue=true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->user_record] continue=true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (PASS) [user_record] ${user_record}(all) =~ /^all$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Action set(record_session=true) INLINE
EXECUTE [depth=0] sofia/internal/
101@testSIP.com set(record_session=true)
2023-04-17 06:09:04.036104 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/internal/
101@testSIP.com [record_session]=[true]
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [user_record] ${user_record}(all) =~ /^inbound$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [user_record] ${user_record}(all) =~ /^outbound$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [user_record] ${user_record}(all) =~ /^local$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
EXECUTE [depth=0] sofia/internal/
101@testSIP.com set(from_user_record=all)
2023-04-17 06:09:04.036104 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/internal/
101@testSIP.com [from_user_record]=[all]
Dialplan: sofia/internal/
101@testSIP.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (PASS) [user_record] ${from_user_record}(all) =~ /^all$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Action set(record_session=true) INLINE
EXECUTE [depth=0] sofia/internal/
101@testSIP.com set(record_session=true)
2023-04-17 06:09:04.036104 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/internal/
101@testSIP.com [record_session]=[true]
Dialplan: sofia/internal/
101@testSIP.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [user_record] ${from_user_record}(all) =~ /^inbound$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [user_record] ${from_user_record}(all) =~ /^outbound$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [user_record] ${from_user_record}(all) =~ /^local$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (PASS) [user_record] ${record_session}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [user_record] destination_number(*4) =~ /^(?
?!\*).|\*59|\*\*|\*8|\*67|\*69)+$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->rtp_has_crypto] continue=true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [rtp_has_crypto] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->redial] continue=true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [redial] destination_number(*4) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/internal/
101@testSIP.com Regex (PASS) [redial] () =~ // break=never
Dialplan: sofia/internal/
101@testSIP.com Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->speed_dial] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [speed_dial] destination_number(*4) =~ /^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->user_hold_music] continue=true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [user_hold_music] ${user_exists}(false) =~ /true/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->default_caller_id] continue=true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [default_caller_id] ${call_direction}(local) =~ /outbound/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->bind_digit_action] continue=true
Dialplan: sofia/internal/
101@testSIP.com Regex (PASS) [bind_digit_action] ${from_user_exists}(true) =~ /true/ break=never
Dialplan: sofia/internal/
101@testSIP.com Action set(bind_target=self) INLINE
EXECUTE [depth=0] sofia/internal/
101@testSIP.com set(bind_target=self)
2023-04-17 06:09:04.036104 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/internal/
101@testSIP.com [bind_target]=[self]
Dialplan: sofia/internal/
101@testSIP.com Action set(bind_action_target=self) INLINE
EXECUTE [depth=0] sofia/internal/
101@testSIP.com set(bind_action_target=self)
2023-04-17 06:09:04.036104 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/internal/
101@testSIP.com [bind_action_target]=[self]
Dialplan: sofia/internal/
101@testSIP.com Absolute Condition [bind_digit_action]
Dialplan: sofia/internal/
101@testSIP.com Action bind_digit_action(local,*1,exec:execute_extension,dx XML ${context},${bind_target},${bind_action_target})
Dialplan: sofia/internal/
101@testSIP.com Action bind_digit_action(local,*3,exec:execute_extension,cf XML ${context},${bind_target},${bind_action_target})
Dialplan: sofia/internal/
101@testSIP.com Action bind_digit_action(local,*4,exec:execute_extension,att_xfer XML ${context},${bind_target},${bind_action_target})
Dialplan: sofia/internal/
101@testSIP.com Action digit_action_set_realm(local)
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [bind_digit_action] ${user_exists}(false) =~ /true/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->Callncall.800] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (PASS) [Callncall.800] ${user_exists}(false) =~ /false/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [Callncall.800] destination_number(*4) =~ /^1?(8(00|33|44|55|66|77|88)[2-9]\d{6})$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->testivr] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [testivr] destination_number(*4) =~ /^507$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->rtyui] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [rtyui] destination_number(*4) =~ /^124$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->test_IVR] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [test_IVR] destination_number(*4) =~ /^400$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->IVR1_] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [IVR1_] destination_number(*4) =~ /^402$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->agent_status] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [agent_status] destination_number(*4) =~ /^\*22$/ break=on-true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [agent_status] destination_number(*4) =~ /^(?:agent\+|\*22)(.+)$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->agent_status_id] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [agent_status_id] destination_number(*4) =~ /^\*23$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->queueOne] continue=
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [queueOne] destination_number(*4) =~ /^([^#]+#)(.*)$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [queueOne] destination_number(*4) =~ /^(callcenter\+)?900$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->rfsdfse] continue=
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [rfsdfse] destination_number(*4) =~ /^([^#]+#)(.*)$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [rfsdfse] destination_number(*4) =~ /^(callcenter\+)?333$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->group-intercept] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [group-intercept] destination_number(*4) =~ /^\*8$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->queueOne] continue=
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [queueOne] destination_number(*4) =~ /^([^#]+#)(.*)$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [queueOne] destination_number(*4) =~ /^(callcenter\+)?900$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->sdas] continue=
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [sdas] destination_number(*4) =~ /^([^#]+#)(.*)$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [sdas] destination_number(*4) =~ /^(callcenter\+)?112$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->sdas] continue=
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [sdas] destination_number(*4) =~ /^([^#]+#)(.*)$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [sdas] destination_number(*4) =~ /^(callcenter\+)?112$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->Support Queue] continue=
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [Support Queue] destination_number(*4) =~ /^([^#]+#)(.*)$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [Support Queue] destination_number(*4) =~ /^(callcenter\+)?701$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->Test] continue=
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [Test] destination_number(*4) =~ /^([^#]+#)(.*)$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [Test] destination_number(*4) =~ /^(callcenter\+)?801$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->mksui] continue=
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [mksui] destination_number(*4) =~ /^([^#]+#)(.*)$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [mksui] destination_number(*4) =~ /^(callcenter\+)?902$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->lliiie] continue=
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [lliiie] destination_number(*4) =~ /^([^#]+#)(.*)$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [lliiie] destination_number(*4) =~ /^(callcenter\+)?999$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->page-extension] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [page-extension] destination_number(*4) =~ /^\*8(\d{2,7})$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->eavesdrop] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [eavesdrop] destination_number(*4) =~ /^\*33(\d{2,7})$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->call_privacy] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [call_privacy] destination_number(*4) =~ /^\*67(\d+)$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->call_return] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [call_return] destination_number(*4) =~ /^\*69$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->number_queue] continue=
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [number_queue] destination_number(*4) =~ /^\*800(.*)$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->intercept-ext-polycom] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [intercept-ext-polycom] destination_number(*4) =~ /^\*97(\d+)$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->intercept-ext] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [intercept-ext] destination_number(*4) =~ /^\*\*(\d+)$/ break=on-true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [intercept-ext] destination_number(*4) =~ /^\*\*$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->dx] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [dx] destination_number(*4) =~ /^dx$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->send_to_voicemail] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [send_to_voicemail] destination_number(*4) =~ /^\*99(\d{2,10})$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->att_xfer] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [att_xfer] destination_number(*4) =~ /^att_xfer$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->extension-to-voicemail] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [extension-to-voicemail] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->vmain] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [vmain] destination_number(*4) =~ /^vmain$|^\*4000$|^\*98$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [vmain] destination_number(*4) =~ /^(vmain$|^\*4000$|^\*98|voicemail\+)(\d{2,12})$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->xfer_vm] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [xfer_vm] destination_number(*4) =~ /^xfer_vm$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->is_transfer] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [is_transfer] destination_number(*4) =~ /^is_transfer$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->vmain_user] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [vmain_user] destination_number(*4) =~ /^\*97$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->delay_echo] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [delay_echo] destination_number(*4) =~ /^\*9195$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->cf] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [cf] destination_number(*4) =~ /^cf$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->echo] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [echo] destination_number(*4) =~ /^\*9196$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->milliwatt] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [milliwatt] destination_number(*4) =~ /^\*9197$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->is_zrtp_secure] continue=true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [is_zrtp_secure] ${zrtp_secure_media_confirmed}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com ANTI-Action eval(not_secure)
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->tone_stream] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [tone_stream] destination_number(*4) =~ /^\*9198$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->is_secure] continue=true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [is_secure] ${sip_via_protocol}(wss) =~ /tls/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->hold_music] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [hold_music] destination_number(*4) =~ /^\*9664$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->recordings] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [recordings] destination_number(*4) =~ /^\*(732)$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->directory] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [directory] destination_number(*4) =~ /^\*411$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->wake-up] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [wake-up] destination_number(*4) =~ /^\*(925)$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->valet_park] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [valet_park] destination_number(*4) =~ /^(park)?\+?\*(59[0-9][0-9])$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [valet_park] ${sip_h_Referred-By}() =~ /sip
.*)@.*/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [valet_park] destination_number(*4) =~ /^(park)?\+?\*(59[0-9][0-9])$/ break=never
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [valet_park] destination_number(*4) =~ /^(park)?\+?\*(59[0-9][0-9])$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->operator-forward] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [operator-forward] destination_number(*4) =~ /^\*000$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->do-not-disturb] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [do-not-disturb] destination_number(*4) =~ /^\*77$/ break=on-true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [do-not-disturb] destination_number(*4) =~ /^\*78$|\*363$/ break=on-true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [do-not-disturb] destination_number(*4) =~ /^\*79$/ break=on-true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [do-not-disturb] destination_number(*4) =~ /^dnd\+101$/ break=on-true
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->call_screen] continue=true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [call_screen] ${call_screen_enabled}(false) =~ /^true$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->call-forward] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [call-forward] destination_number(*4) =~ /^\*72(\d{2,20})$/ break=on-true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [call-forward] destination_number(*4) =~ /^\*72$/ break=on-true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [call-forward] destination_number(*4) =~ /^\*73$/ break=on-true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [call-forward] destination_number(*4) =~ /^\*74$/ break=on-true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [call-forward] destination_number(*4) =~ /^forward\+(\Q101\E)(?:\/(\d+))?$/ break=on-true
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->ring-group-forward] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [ring-group-forward] destination_number(*4) =~ /^\*75$/ break=on-true
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->follow-me-destinations] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [follow-me-destinations] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->call-forward-all] continue=
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [call-forward-all] ${forward_all_enabled}() =~ /true/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->call-forward-not-registered] continue=
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [call-forward-not-registered] ${user_exists}(false) =~ /true/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->follow-me] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [follow-me] destination_number(*4) =~ /^\*21$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->talking clock date and time] continue=
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [talking clock date and time] destination_number(*4) =~ /^\*9172$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->talking clock time] continue=
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [talking clock time] destination_number(*4) =~ /^\*9170$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->talking clock date] continue=
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [talking clock date] destination_number(*4) =~ /^\*9171$/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->local_extension] continue=true
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [local_extension] ${user_exists}(false) =~ /true/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->voicemail] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (FAIL) [voicemail] ${user_exists}(false) =~ /true/ break=on-false
Dialplan: sofia/internal/
101@testSIP.com parsing [testSIP.com->not-found] continue=false
Dialplan: sofia/internal/
101@testSIP.com Regex (PASS) [not-found] () =~ // break=on-false
Dialplan: sofia/internal/
101@testSIP.com Action set(call_direction=inbound) INLINE
EXECUTE [depth=0] sofia/internal/
101@testSIP.com set(call_direction=inbound)
2023-04-17 06:09:04.036104 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/internal/
101@testSIP.com [call_direction]=[inbound]
Dialplan: sofia/internal/
101@testSIP.com Action log(WARNING [inbound routes] 404 not found ${sip_network_ip})
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_core_state_machine.c:281 (sofia/internal/
101@testSIP.com) State Change CS_ROUTING -> CS_EXECUTE
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/
101@testSIP.com) State ROUTING going to sleep
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/
101@testSIP.com) Running State Change CS_EXECUTE (Cur 3 Tot 47)
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/
101@testSIP.com) State EXECUTE
2023-04-17 06:09:04.036104 93.90% [DEBUG] mod_sofia.c:213 sofia/internal/
101@testSIP.com SOFIA EXECUTE
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_core_state_machine.c:323 sofia/internal/
101@testSIP.com Standard EXECUTE
EXECUTE [depth=0] sofia/internal/
101@testSIP.com set(caller_id_number=101)
2023-04-17 06:09:04.036104 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/internal/
101@testSIP.com [caller_id_number]=[101]
EXECUTE [depth=0] sofia/internal/
101@testSIP.com set(RFC2822_DATE=Mon, 17 Apr 2023 06:09:04 +0000)
2023-04-17 06:09:04.036104 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/internal/
101@testSIP.com [RFC2822_DATE]=[Mon, 17 Apr 2023 06:09:04 +0000]
EXECUTE [depth=0] sofia/internal/
101@testSIP.com export(origination_callee_id_name=*4)
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [origination_callee_id_name]=[*4]
EXECUTE [depth=0] sofia/internal/
101@testSIP.com hash(insert/testSIP.com-last_dial/101/*4)
EXECUTE [depth=0] sofia/internal/
101@testSIP.com bind_digit_action(local,*1,exec:execute_extension,dx XML testSIP.com,self,self)
2023-04-17 06:09:04.036104 93.90% [INFO] switch_ivr_async.c:221 Digit parser DPTOOLS: Setting realm to 'local'
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_ivr_async.c:346 Digit parser DPTOOLS: binding *1/local/0 callback: 0x7efd6a0ff0c0 data: 0x7efd4c0e23b8
EXECUTE [depth=0] sofia/internal/
101@testSIP.com bind_digit_action(local,*3,exec:execute_extension,cf XML testSIP.com,self,self)
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_ivr_async.c:346 Digit parser DPTOOLS: binding *3/local/0 callback: 0x7efd6a0ff0c0 data: 0x7efd4c0e2518
EXECUTE [depth=0] sofia/internal/
101@testSIP.com bind_digit_action(local,*4,exec:execute_extension,att_xfer XML testSIP.com,self,self)
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_ivr_async.c:346 Digit parser DPTOOLS: binding *4/local/0 callback: 0x7efd6a0ff0c0 data: 0x7efd4c0e2688
EXECUTE [depth=0] sofia/internal/
101@testSIP.com digit_action_set_realm(local)
2023-04-17 06:09:04.036104 93.90% [INFO] switch_ivr_async.c:221 Digit parser DPTOOLS: Setting realm to 'local'
EXECUTE [depth=0] sofia/internal/
101@testSIP.com eval(not_secure)
EXECUTE [depth=0] sofia/internal/
101@testSIP.com log(WARNING [inbound routes] 404 not found 162.216.142.29)
2023-04-17 06:09:04.036104 93.90% [WARNING] mod_dptools.c:1879 [inbound routes] 404 not found 162.216.142.29
2023-04-17 06:09:04.036104 93.90% [NOTICE] switch_core_state_machine.c:382 sofia/internal/
101@testSIP.com has executed the last dialplan instruction, hanging up.
2023-04-17 06:09:04.036104 93.90% [NOTICE] switch_core_state_machine.c:384 Hangup sofia/internal/
101@testSIP.com [CS_EXECUTE] [NORMAL_CLEARING]
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/
101@testSIP.com) State EXECUTE going to sleep
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/
101@testSIP.com) Running State Change CS_HANGUP (Cur 3 Tot 47)
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_core_state_machine.c:844 (sofia/internal/
101@testSIP.com) Callstate Change RINGING -> HANGUP
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/
101@testSIP.com) State HANGUP
2023-04-17 06:09:04.036104 93.90% [DEBUG] mod_sofia.c:468 Channel sofia/internal/
101@testSIP.com hanging up, cause: NORMAL_CLEARING
2023-04-17 06:09:04.036104 93.90% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 480
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_core_state_machine.c:59 sofia/internal/
101@testSIP.com Standard HANGUP, cause: NORMAL_CLEARING
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/
101@testSIP.com) State HANGUP going to sleep
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_core_state_machine.c:616 (sofia/internal/
101@testSIP.com) State Change CS_HANGUP -> CS_REPORTING
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/
101@testSIP.com) Running State Change CS_REPORTING (Cur 3 Tot 47)
2023-04-17 06:09:04.036104 93.90% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/
101@testSIP.com) State REPORTING
2023-04-17 06:09:04.156110 93.90% [DEBUG] switch_core_state_machine.c:168 sofia/internal/
101@testSIP.com Standard REPORTING, cause: NORMAL_CLEARING
2023-04-17 06:09:04.156110 93.90% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/
101@testSIP.com) State REPORTING going to sleep
2023-04-17 06:09:04.156110 93.90% [DEBUG] switch_core_state_machine.c:607 (sofia/internal/
101@testSIP.com) State Change CS_REPORTING -> CS_DESTROY
2023-04-17 06:09:04.156110 93.90% [DEBUG] switch_core_session.c:1753 Session 47 (sofia/internal/
101@testSIP.com) Locked, Waiting on external entities
2023-04-17 06:09:04.156110 93.90% [NOTICE] switch_core_session.c:1771 Session 47 (sofia/internal/
101@testSIP.com) Ended
2023-04-17 06:09:04.156110 93.90% [NOTICE] switch_core_session.c:1775 Close Channel sofia/internal/
101@testSIP.com [CS_DESTROY]
2023-04-17 06:09:04.156110 93.90% [DEBUG] switch_core_state_machine.c:735 (sofia/internal/
101@testSIP.com) Running State Change CS_DESTROY (Cur 2 Tot 47)
2023-04-17 06:09:04.156110 93.90% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/
101@testSIP.com) State DESTROY
2023-04-17 06:09:04.156110 93.90% [DEBUG] mod_sofia.c:379 sofia/internal/
101@testSIP.com SOFIA DESTROY
2023-04-17 06:09:04.156110 93.90% [DEBUG] switch_core_state_machine.c:175 sofia/internal/
101@testSIP.com Standard DESTROY
2023-04-17 06:09:04.156110 93.90% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/
101@testSIP.com) State DESTROY going to sleep