82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:45.745450 93.33% [NOTICE] switch_channel.c:1123 New Channel sofia/internal/
333@my.domain.com [82d7f8b2-5f2a-4bb4-b999-fe173f798457]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:45.745450 93.33% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/
333@my.domain.com) Running State Change CS_NEW (Cur 1 Tot 170)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:45.745450 93.33% [INFO] sofia.c:10453 sofia/internal/
333@my.domain.com receiving invite from 49.37.224.3:61121 version: 1.10.9 -release 64bit call-id: 69a42cbc09104c7f8d63c8f26691d29d
2023-09-28 11:48:45.745450 93.33% [DEBUG] sofia.c:10547 verifying acl "providers" for ip/port 49.37.224.3:0.
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:45.745450 93.33% [DEBUG] switch_core_state_machine.c:600 (sofia/internal/
333@my.domain.com) State NEW
2023-09-28 11:48:45.745450 93.33% [DEBUG] sofia.c:2419 detaching session 82d7f8b2-5f2a-4bb4-b999-fe173f798457
2023-09-28 11:48:46.345435 93.33% [DEBUG] sofia.c:2532 Re-attaching to session 82d7f8b2-5f2a-4bb4-b999-fe173f798457
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.345435 93.33% [INFO] sofia.c:10453 sofia/internal/
333@my.domain.com receiving invite from 49.37.224.3:61884 version: 1.10.9 -release 64bit call-id: 69a42cbc09104c7f8d63c8f26691d29d
2023-09-28 11:48:46.345435 93.33% [DEBUG] sofia.c:10547 verifying acl "providers" for ip/port 49.37.224.3:0.
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.365403 93.33% [DEBUG] sofia.c:11668 Setting NAT mode based on via received
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.365403 93.33% [DEBUG] sofia.c:7487 Channel sofia/internal/
333@my.domain.com entering state [received][100]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.365403 93.33% [DEBUG] sofia.c:7497 Remote SDP:
82d7f8b2-5f2a-4bb4-b999-fe173f798457 v=0
82d7f8b2-5f2a-4bb4-b999-fe173f798457 o=- 3904910326 3904910326 IN IP4 10.193.200.92
82d7f8b2-5f2a-4bb4-b999-fe173f798457 s=pjmedia
82d7f8b2-5f2a-4bb4-b999-fe173f798457 b=AS:84
82d7f8b2-5f2a-4bb4-b999-fe173f798457 t=0 0
82d7f8b2-5f2a-4bb4-b999-fe173f798457 a=X-nat:0
82d7f8b2-5f2a-4bb4-b999-fe173f798457 m=audio 4002 RTP/AVP 8 0 18 101
82d7f8b2-5f2a-4bb4-b999-fe173f798457 c=IN IP4 10.193.200.92
82d7f8b2-5f2a-4bb4-b999-fe173f798457 b=TIAS:64000
82d7f8b2-5f2a-4bb4-b999-fe173f798457 a=rtpmap:8 PCMA/8000
82d7f8b2-5f2a-4bb4-b999-fe173f798457 a=rtpmap:0 PCMU/8000
82d7f8b2-5f2a-4bb4-b999-fe173f798457 a=rtpmap:18 G729/8000
82d7f8b2-5f2a-4bb4-b999-fe173f798457 a=rtpmap:101 telephone-event/8000
82d7f8b2-5f2a-4bb4-b999-fe173f798457 a=fmtp:101 0-16
82d7f8b2-5f2a-4bb4-b999-fe173f798457 a=rtcp:4003 IN IP4 192.168.29.17
82d7f8b2-5f2a-4bb4-b999-fe173f798457 a=ssrc:1199523442 cname:4aa2433b05de7b1d
82d7f8b2-5f2a-4bb4-b999-fe173f798457
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.365403 93.33% [DEBUG] sofia.c:7900 (sofia/internal/
333@my.domain.com) State Change CS_NEW -> CS_INIT
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.365403 93.33% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/
333@my.domain.com) Running State Change CS_INIT (Cur 1 Tot 170)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.365403 93.33% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/
333@my.domain.com) State INIT
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.365403 93.33% [DEBUG] mod_sofia.c:97 sofia/internal/
333@my.domain.com SOFIA INIT
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.365403 93.33% [DEBUG] switch_core_state_machine.c:40 sofia/internal/
333@my.domain.com Standard INIT
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.365403 93.33% [DEBUG] switch_core_state_machine.c:48 (sofia/internal/
333@my.domain.com) State Change CS_INIT -> CS_ROUTING
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.365403 93.33% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/
333@my.domain.com) State INIT going to sleep
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.365403 93.33% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/
333@my.domain.com) Running State Change CS_ROUTING (Cur 1 Tot 170)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.365403 93.33% [DEBUG] switch_channel.c:2380 (sofia/internal/
333@my.domain.com) Callstate Change DOWN -> RINGING
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.365403 93.33% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/
333@my.domain.com) State ROUTING
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.365403 93.33% [DEBUG] mod_sofia.c:158 sofia/internal/
333@my.domain.com SOFIA ROUTING
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.365403 93.33% [DEBUG] switch_core_state_machine.c:230 sofia/internal/
333@my.domain.com Standard ROUTING
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.365403 93.33% [INFO] mod_dialplan_xml.c:639 Processing Promo <333>->15715705843 in context my.domain.com
2023-09-28 11:48:46.365403 93.33% [NOTICE] switch_cpp.cpp:1465 [xml_handler] multiple key:dialplan:my.domain.com
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com parsing [my.domain.com->user_exists] continue=true
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [user_exists] ${loopback_leg}() =~ /^B$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (PASS) [user_exists] () =~ // break=on-false
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(user_exists=false)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [user_exists]=[false]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(from_user_exists=true)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [from_user_exists]=[true]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com parsing [my.domain.com->caller-details] continue=true
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (PASS) [caller-details] ${caller_destination}() =~ /^$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(caller_destination=${destination_number}) INLINE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(caller_destination=15715705843)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [caller_destination]=[15715705843]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(caller_id_name=${caller_id_name}) INLINE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(caller_id_name=333)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [caller_id_name]=[333]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(caller_id_number=${caller_id_number})
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com parsing [my.domain.com->global-variables] continue=true
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (PASS) [global-variables] () =~ // break=on-false
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(timezone=Asia/Kolkata) INLINE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(timezone=Asia/Kolkata)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [timezone]=[Asia/Kolkata]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com parsing [my.domain.com->domain-variables] continue=true
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (PASS) [domain-variables] () =~ // break=on-false
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action export(origination_callee_id_name=${caller_destination})
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(operator=1000) INLINE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(operator=1000)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [operator]=[1000]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com parsing [my.domain.com->clear_sip_auto_answer] continue=true
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com parsing [my.domain.com->is_loopback] continue=true
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com parsing [my.domain.com->call-direction] continue=true
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (PASS) [call-direction] ${call_direction}() =~ /^$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action export(call_direction=local) INLINE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com export(call_direction=local)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [call_direction]=[local]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com parsing [my.domain.com->user_record] continue=true
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (PASS) [user_record] ${user_record}(all) =~ /^all$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(record_session=true) INLINE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(record_session=true)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [record_session]=[true]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [user_record] ${user_record}(all) =~ /^inbound$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [user_record] ${user_record}(all) =~ /^outbound$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [user_record] ${user_record}(all) =~ /^local$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(from_user_record=all)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [from_user_record]=[all]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (PASS) [user_record] ${from_user_record}(all) =~ /^all$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(record_session=true) INLINE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(record_session=true)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [record_session]=[true]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [user_record] ${from_user_record}(all) =~ /^inbound$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [user_record] ${from_user_record}(all) =~ /^outbound$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [user_record] ${from_user_record}(all) =~ /^local$/ break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (PASS) [user_record] ${record_session}(true) =~ /^true$/ break=on-false
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(record_path=${recordings_dir}/${domain_name}/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}) INLINE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(record_path=/var/lib/freeswitch/recordings/my.domain.com/archive/2023/Sep/28)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [record_path]=[/var/lib/freeswitch/recordings/my.domain.com/archive/2023/Sep/28]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(record_name=${uuid}.${record_ext}) INLINE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(record_name=82d7f8b2-5f2a-4bb4-b999-fe173f798457.wav)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [record_name]=[82d7f8b2-5f2a-4bb4-b999-fe173f798457.wav]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action mkdir(${record_path})
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(recording_follow_transfer=true) INLINE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(recording_follow_transfer=true)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [recording_follow_transfer]=[true]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action bind_digit_action(local,*5,api:uuid_record,${uuid} mask ${recordings_dir}/${domain_name}/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}/${uuid}.${record_ext},both,self)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action bind_digit_action(local,*6,api:uuid_record,${uuid} unmask ${recordings_dir}/${domain_name}/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}/${uuid}.${record_ext},both,self)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(record_append=true) INLINE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(record_append=true)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [record_append]=[true]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(record_in_progress=true) INLINE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(record_in_progress=true)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [record_in_progress]=[true]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(RECORD_ANSWER_REQ=true)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action record_session(${record_path}/${record_name})
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com parsing [my.domain.com->rtp_has_crypto] continue=true
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.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
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com parsing [my.domain.com->redial] continue=true
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [redial] destination_number(15715705843) =~ /^(redial|\*870)$/ break=on-true
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (PASS) [redial] () =~ // break=never
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com parsing [my.domain.com->speed_dial] continue=false
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [speed_dial] destination_number(15715705843) =~ /^\*0(.*)$/ break=on-false
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com parsing [my.domain.com->default_caller_id] continue=true
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [default_caller_id] ${call_direction}(local) =~ /outbound/ break=on-false
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com parsing [my.domain.com->user_hold_music] continue=true
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (FAIL) [user_hold_music] ${user_exists}(false) =~ /true/ break=on-false
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com parsing [my.domain.com->Telnyx.129d229d2d4] continue=false
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (PASS) [Telnyx.129d229d2d4] ${user_exists}(false) =~ /false/ break=on-false
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Regex (PASS) [Telnyx.129d229d2d4] destination_number(15715705843) =~ /^\+?1?([2-9]\d{2}[2-9]\d{2}\d{4})$/ break=on-false
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action export(call_direction=outbound) INLINE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com export(call_direction=outbound)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [call_direction]=[outbound]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action unset(call_timeout)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(hangup_after_bridge=true)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(effective_caller_id_name=${outbound_caller_id_name})
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(effective_caller_id_number=${outbound_caller_id_number})
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(inherit_codec=true)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(ignore_display_updates=true)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(callee_id_number=5715705843)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action set(continue_on_fail=1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607,809)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 Dialplan: sofia/internal/
333@my.domain.com Action bridge(sofia/gateway/343f74f4-773e-401a-bc5a-3e3e3b4b7aa3/5715705843)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] switch_core_state_machine.c:281 (sofia/internal/
333@my.domain.com) State Change CS_ROUTING -> CS_EXECUTE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/
333@my.domain.com) State ROUTING going to sleep
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/
333@my.domain.com) Running State Change CS_EXECUTE (Cur 1 Tot 170)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/
333@my.domain.com) State EXECUTE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] mod_sofia.c:213 sofia/internal/
333@my.domain.com SOFIA EXECUTE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] switch_core_state_machine.c:323 sofia/internal/
333@my.domain.com Standard EXECUTE
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(caller_id_number=333)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.385404 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [caller_id_number]=[333]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(RFC2822_DATE=Thu, 28 Sep 2023 11:48:46 +0000)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [RFC2822_DATE]=[Thu, 28 Sep 2023 11:48:46 +0000]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com export(origination_callee_id_name=15715705843)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [origination_callee_id_name]=[15715705843]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com mkdir(/var/lib/freeswitch/recordings/my.domain.com/archive/2023/Sep/28)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] mod_dptools.c:666 sofia/internal/
333@my.domain.com MKDIR: /var/lib/freeswitch/recordings/my.domain.com/archive/2023/Sep/28
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com bind_digit_action(local,*5,api:uuid_record,82d7f8b2-5f2a-4bb4-b999-fe173f798457 mask /var/lib/freeswitch/recordings/my.domain.com/archive/2023/Sep/28/82d7f8b2-5f2a-4bb4-b999-fe173f798457.wav,both,self)
2023-09-28 11:48:46.405395 93.33% [INFO] switch_ivr_async.c:221 Digit parser DPTOOLS: Setting realm to 'local'
2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_ivr_async.c:346 Digit parser DPTOOLS: binding *5/local/0 callback: 0x7f8d9629e750 data: 0x7f8d90345070
2023-09-28 11:48:46.405395 93.33% [INFO] switch_ivr_async.c:221 Digit parser DPTOOLS: Setting realm to 'local'
2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_ivr_async.c:346 Digit parser DPTOOLS: binding *5/local/0 callback: 0x7f8d9629e750 data: 0x7f8d90345170
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com bind_digit_action(local,*6,api:uuid_record,82d7f8b2-5f2a-4bb4-b999-fe173f798457 unmask /var/lib/freeswitch/recordings/my.domain.com/archive/2023/Sep/28/82d7f8b2-5f2a-4bb4-b999-fe173f798457.wav,both,self)
2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_ivr_async.c:346 Digit parser DPTOOLS: binding *6/local/0 callback: 0x7f8d9629e750 data: 0x7f8d903454b8
2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_ivr_async.c:346 Digit parser DPTOOLS: binding *6/local/0 callback: 0x7f8d9629e750 data: 0x7f8d903455b8
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(RECORD_ANSWER_REQ=true)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [RECORD_ANSWER_REQ]=[true]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_session.c:2785 Application record_session Requires media! pre_answering channel sofia/internal/
333@my.domain.com
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [INFO] switch_core_session.c:2787 Sending early media
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:3750 Set Codec sofia/internal/
333@my.domain.com PCMA/8000 20 ms 160 samples 64000 bits 1 channels
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_codec.c:111 sofia/internal/
333@my.domain.com Original read codec set to PCMA:8
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:5850 sofia/internal/
333@my.domain.com Set 2833 dtmf send payload to 101 recv payload to 101
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:8651 AUDIO RTP [sofia/internal/
333@my.domain.com] 10.128.15.212 port 17592 -> 10.193.200.92 port 4002 codec: 8 ms: 20
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_rtp.c:4377 Starting timer [soft] 160 bytes per 20ms
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:8871 Activating RTCP PORT 4003
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_rtp.c:4708 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 4003
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_rtp.c:2534 Setting RTCP remote addr to 10.193.200.92:4003 2
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:8963 sofia/internal/
333@my.domain.com Set 2833 dtmf send payload to 101
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:8970 sofia/internal/
333@my.domain.com Set 2833 dtmf receive payload to 101
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:8993 sofia/internal/
333@my.domain.com Set rtp dtmf delay to 40
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [NOTICE] sofia_media.c:90 Pre-Answer sofia/internal/
333@my.domain.com!
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_channel.c:3566 (sofia/internal/
333@my.domain.com) Callstate Change RINGING -> EARLY
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media.c:8633 Audio params are unchanged for sofia/internal/
333@my.domain.com.
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] mod_sofia.c:2609 Ring SDP:
82d7f8b2-5f2a-4bb4-b999-fe173f798457 v=0
82d7f8b2-5f2a-4bb4-b999-fe173f798457 o=FreeSWITCH 1695884134 1695884135 IN IP4 my.IP
82d7f8b2-5f2a-4bb4-b999-fe173f798457 s=FreeSWITCH
82d7f8b2-5f2a-4bb4-b999-fe173f798457 c=IN IP4 my.IP
82d7f8b2-5f2a-4bb4-b999-fe173f798457 t=0 0
82d7f8b2-5f2a-4bb4-b999-fe173f798457 m=audio 17592 RTP/AVP 8 101
82d7f8b2-5f2a-4bb4-b999-fe173f798457 a=rtpmap:8 PCMA/8000
82d7f8b2-5f2a-4bb4-b999-fe173f798457 a=rtpmap:101 telephone-event/8000
82d7f8b2-5f2a-4bb4-b999-fe173f798457 a=fmtp:101 0-15
82d7f8b2-5f2a-4bb4-b999-fe173f798457 a=ptime:20
82d7f8b2-5f2a-4bb4-b999-fe173f798457 a=sendrecv
82d7f8b2-5f2a-4bb4-b999-fe173f798457 a=rtcp:17593 IN IP4 my.IP
82d7f8b2-5f2a-4bb4-b999-fe173f798457
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com record_session(/var/lib/freeswitch/recordings/my.domain.com/archive/2023/Sep/28/82d7f8b2-5f2a-4bb4-b999-fe173f798457.wav)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] sofia.c:7487 Channel sofia/internal/
333@my.domain.com entering state [early][183]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_ivr_async.c:1503 Record session sample rate: 8000 -> 8000
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.405395 93.33% [DEBUG] switch_core_media_bug.c:976 Attaching BUG to sofia/internal/
333@my.domain.com
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com hash(insert/my.domain.com-last_dial/333/15715705843)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com unset(call_timeout)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.425391 93.33% [DEBUG] mod_dptools.c:1818 UNSET [call_timeout]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(hangup_after_bridge=true)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.425391 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [hangup_after_bridge]=[true]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(effective_caller_id_name=Mentorprime Promo)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.425391 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [effective_caller_id_name]=[Mentorprime Promo]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(effective_caller_id_number=19292967776)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.425391 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [effective_caller_id_number]=[19292967776]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(inherit_codec=true)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.425391 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [inherit_codec]=[true]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(ignore_display_updates=true)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.425391 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [ignore_display_updates]=[true]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(callee_id_number=5715705843)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.425391 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [callee_id_number]=[5715705843]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com set(continue_on_fail=1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607,809)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.425391 93.33% [DEBUG] mod_dptools.c:1673 SET sofia/internal/
333@my.domain.com [continue_on_fail]=[1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607,809]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 EXECUTE [depth=0] sofia/internal/
333@my.domain.com bridge(sofia/gateway/343f74f4-773e-401a-bc5a-3e3e3b4b7aa3/5715705843)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.425391 93.33% [DEBUG] switch_channel.c:1269 sofia/internal/
333@my.domain.com EXPORTING[export_vars] [domain_name]=[my.domain.com] to event
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.425391 93.33% [DEBUG] switch_channel.c:1269 sofia/internal/
333@my.domain.com EXPORTING[export_vars] [domain_uuid]=[60528b6d-d11a-4948-b1a9-7edc62bc21e8] to event
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.425391 93.33% [DEBUG] switch_channel.c:1269 sofia/internal/
333@my.domain.com EXPORTING[export_vars] [call_direction]=[outbound] to event
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.425391 93.33% [DEBUG] switch_channel.c:1269 sofia/internal/
333@my.domain.com EXPORTING[export_vars] [call_direction]=[outbound] to event
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.425391 93.33% [DEBUG] switch_channel.c:1269 sofia/internal/
333@my.domain.com EXPORTING[export_vars] [origination_callee_id_name]=[15715705843] to event
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.425391 93.33% [DEBUG] switch_ivr_originate.c:2291 Parsing global variables
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [NOTICE] switch_channel.c:1123 New Channel sofia/external/5715705843 [1da7bcf0-336f-4031-a679-7f62c7a59886]
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [DEBUG] mod_sofia.c:5117 (sofia/external/5715705843) State Change CS_NEW -> CS_INIT
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [DEBUG] switch_core_state_machine.c:581 (sofia/external/5715705843) Running State Change CS_INIT (Cur 2 Tot 171)
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [DEBUG] switch_core_state_machine.c:624 (sofia/external/5715705843) State INIT
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [DEBUG] mod_sofia.c:97 sofia/external/5715705843 SOFIA INIT
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [INFO] sofia_glue.c:1654 sofia/external/5715705843 sending invite call-id: (null)
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [DEBUG] sofia_glue.c:1657 sofia/external/5715705843 sending invite version: 1.10.9 -release 64bit
1da7bcf0-336f-4031-a679-7f62c7a59886 Local SDP:
1da7bcf0-336f-4031-a679-7f62c7a59886 v=0
1da7bcf0-336f-4031-a679-7f62c7a59886 o=FreeSWITCH 1695875356 1695875357 IN IP4 my.IP
1da7bcf0-336f-4031-a679-7f62c7a59886 s=FreeSWITCH
1da7bcf0-336f-4031-a679-7f62c7a59886 c=IN IP4 my.IP
1da7bcf0-336f-4031-a679-7f62c7a59886 t=0 0
1da7bcf0-336f-4031-a679-7f62c7a59886 m=audio 26370 RTP/AVP 8 0 101
1da7bcf0-336f-4031-a679-7f62c7a59886 a=rtpmap:8 PCMA/8000
1da7bcf0-336f-4031-a679-7f62c7a59886 a=rtpmap:0 PCMU/8000
1da7bcf0-336f-4031-a679-7f62c7a59886 a=rtpmap:101 telephone-event/8000
1da7bcf0-336f-4031-a679-7f62c7a59886 a=fmtp:101 0-15
1da7bcf0-336f-4031-a679-7f62c7a59886 a=silenceSupp
ff - - - -
1da7bcf0-336f-4031-a679-7f62c7a59886 a=ptime:20
1da7bcf0-336f-4031-a679-7f62c7a59886 a=sendrecv
1da7bcf0-336f-4031-a679-7f62c7a59886
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [DEBUG] switch_core_state_machine.c:40 sofia/external/5715705843 Standard INIT
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [DEBUG] switch_core_state_machine.c:48 (sofia/external/5715705843) State Change CS_INIT -> CS_ROUTING
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [DEBUG] switch_core_state_machine.c:624 (sofia/external/5715705843) State INIT going to sleep
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [DEBUG] switch_core_state_machine.c:581 (sofia/external/5715705843) Running State Change CS_ROUTING (Cur 2 Tot 171)
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [DEBUG] sofia.c:7487 Channel sofia/external/5715705843 entering state [calling][0]
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [DEBUG] switch_core_state_machine.c:640 (sofia/external/5715705843) State ROUTING
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [DEBUG] mod_sofia.c:158 sofia/external/5715705843 SOFIA ROUTING
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [DEBUG] switch_ivr_originate.c:67 (sofia/external/5715705843) State Change CS_ROUTING -> CS_CONSUME_MEDIA
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [DEBUG] switch_core_state_machine.c:640 (sofia/external/5715705843) State ROUTING going to sleep
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [DEBUG] switch_core_state_machine.c:581 (sofia/external/5715705843) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 171)
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [DEBUG] switch_core_state_machine.c:659 (sofia/external/5715705843) State CONSUME_MEDIA
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.425391 93.33% [DEBUG] switch_core_state_machine.c:659 (sofia/external/5715705843) State CONSUME_MEDIA going to sleep
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.605464 93.23% [DEBUG] sofia.c:7487 Channel sofia/external/5715705843 entering state [calling][0]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.685466 93.23% [INFO] switch_rtp.c:6917 Auto Changing audio RTCP port from 10.193.200.92:4003 to 49.37.224.3:4003
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.685466 93.23% [DEBUG] switch_rtp.c:2534 Setting RTCP remote addr to 49.37.224.3:4003 2
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.705458 93.23% [DEBUG] switch_rtp.c:1775 rtcp_stats_init: audio ssrc[1199523442] base_seq[1656]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.745460 93.23% [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMA:8
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] sofia.c:6666 Remote Reason: 21
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] sofia.c:7487 Channel sofia/external/5715705843 entering state [terminated][403]
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [NOTICE] sofia.c:8729 Hangup sofia/external/5715705843 [CS_CONSUME_MEDIA] [CALL_REJECTED]
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:581 (sofia/external/5715705843) Running State Change CS_HANGUP (Cur 2 Tot 171)
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:844 (sofia/external/5715705843) Callstate Change DOWN -> HANGUP
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:846 (sofia/external/5715705843) State HANGUP
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] mod_sofia.c:468 Channel sofia/external/5715705843 hanging up, cause: CALL_REJECTED
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:59 sofia/external/5715705843 Standard HANGUP, cause: CALL_REJECTED
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:846 (sofia/external/5715705843) State HANGUP going to sleep
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:616 (sofia/external/5715705843) State Change CS_HANGUP -> CS_REPORTING
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:581 (sofia/external/5715705843) Running State Change CS_REPORTING (Cur 2 Tot 171)
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:932 (sofia/external/5715705843) State REPORTING
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:168 sofia/external/5715705843 Standard REPORTING, cause: CALL_REJECTED
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:932 (sofia/external/5715705843) State REPORTING going to sleep
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:607 (sofia/external/5715705843) State Change CS_REPORTING -> CS_DESTROY
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_session.c:1743 Session 171 (sofia/external/5715705843) Locked, Waiting on external entities
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_ivr_originate.c:4045 Originate Resulted in Error Cause: 21 [CALL_REJECTED]
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [NOTICE] switch_core_session.c:1761 Session 171 (sofia/external/5715705843) Ended
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [NOTICE] switch_core_session.c:1765 Close Channel sofia/external/5715705843 [CS_DESTROY]
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:735 (sofia/external/5715705843) Running State Change CS_DESTROY (Cur 1 Tot 171)
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:745 (sofia/external/5715705843) State DESTROY
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] mod_sofia.c:379 sofia/external/5715705843 SOFIA DESTROY
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:175 sofia/external/5715705843 Standard DESTROY
1da7bcf0-336f-4031-a679-7f62c7a59886 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:745 (sofia/external/5715705843) State DESTROY going to sleep
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [INFO] mod_dptools.c:3637 Originate Failed. Cause: CALL_REJECTED
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_channel.c:4924 Continue on fail [1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607,809]: Cause: CALL_REJECTED
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [NOTICE] switch_core_state_machine.c:382 sofia/internal/
333@my.domain.com has executed the last dialplan instruction, hanging up.
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [NOTICE] switch_core_state_machine.c:384 Hangup sofia/internal/
333@my.domain.com [CS_EXECUTE] [NORMAL_CLEARING]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/
333@my.domain.com) State EXECUTE going to sleep
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/
333@my.domain.com) Running State Change CS_HANGUP (Cur 1 Tot 171)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_ivr_async.c:1596 Stop recording file /var/lib/freeswitch/recordings/my.domain.com/archive/2023/Sep/28/82d7f8b2-5f2a-4bb4-b999-fe173f798457.wav
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_ivr_async.c:1677 Channel is hung up
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_media_bug.c:1326 Removing BUG from sofia/internal/
333@my.domain.com
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:844 (sofia/internal/
333@my.domain.com) Callstate Change EARLY -> HANGUP
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/
333@my.domain.com) State HANGUP
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [DEBUG] mod_sofia.c:462 sofia/internal/
333@my.domain.com Overriding SIP cause 480 with 403 from the other leg
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [DEBUG] mod_sofia.c:468 Channel sofia/internal/
333@my.domain.com hanging up, cause: NORMAL_CLEARING
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 403
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:59 sofia/internal/
333@my.domain.com Standard HANGUP, cause: NORMAL_CLEARING
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/
333@my.domain.com) State HANGUP going to sleep
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:616 (sofia/internal/
333@my.domain.com) State Change CS_HANGUP -> CS_REPORTING
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/
333@my.domain.com) Running State Change CS_REPORTING (Cur 1 Tot 171)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:46.905468 93.23% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/
333@my.domain.com) State REPORTING
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:47.005418 93.23% [DEBUG] switch_core_state_machine.c:168 sofia/internal/
333@my.domain.com Standard REPORTING, cause: NORMAL_CLEARING
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:47.005418 93.23% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/
333@my.domain.com) State REPORTING going to sleep
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:47.005418 93.23% [DEBUG] switch_core_state_machine.c:607 (sofia/internal/
333@my.domain.com) State Change CS_REPORTING -> CS_DESTROY
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:47.005418 93.23% [DEBUG] switch_core_session.c:1743 Session 170 (sofia/internal/
333@my.domain.com) Locked, Waiting on external entities
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:47.005418 93.23% [NOTICE] switch_core_session.c:1761 Session 170 (sofia/internal/
333@my.domain.com) Ended
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:47.005418 93.23% [NOTICE] switch_core_session.c:1765 Close Channel sofia/internal/
333@my.domain.com [CS_DESTROY]
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:47.005418 93.23% [DEBUG] switch_core_state_machine.c:735 (sofia/internal/
333@my.domain.com) Running State Change CS_DESTROY (Cur 0 Tot 171)
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:47.005418 93.23% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/
333@my.domain.com) State DESTROY
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:47.005418 93.23% [DEBUG] mod_sofia.c:379 sofia/internal/
333@my.domain.com SOFIA DESTROY
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:47.005418 93.23% [DEBUG] switch_core_state_machine.c:175 sofia/internal/
333@my.domain.com Standard DESTROY
82d7f8b2-5f2a-4bb4-b999-fe173f798457 2023-09-28 11:48:47.005418 93.23% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/
333@my.domain.com) State DESTROY going to sleep