I intentionally blocked my cell # 770-827-4079. Then I called 678-928-9100. Here are the logs from the call. It doesn't even look like that call block is active but I show it is in the GUI
2019-07-22 16:50:19.378255 [NOTICE] switch_channel.c:1114 New Channel sofia/external/+17708274079@52.41.52.34 [6362717a-f004-4372-8512-9837ef24abb6]
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+17708274079@52.41.52.34) Running State Change CS_NEW (Cur 1 Tot 380)
2019-07-22 16:50:19.378255 [DEBUG] sofia.c:10164 sofia/external/+17708274079@52.41.52.34 receiving invite from 52.41.52.34:50920 version: 1.8.7 -8-6047ebddfc 64bit
2019-07-22 16:50:19.378255 [DEBUG] sofia.c:10260 verifying acl "domains" for ip/port 52.41.52.34:0.
2019-07-22 16:50:19.378255 [DEBUG] sofia.c:10289 IP 52.41.52.34 Approved by acl "domains[]". Access Granted.
2019-07-22 16:50:19.378255 [DEBUG] sofia.c:7323 Channel sofia/external/+17708274079@52.41.52.34 entering state [received][100]
2019-07-22 16:50:19.378255 [DEBUG] sofia.c:7333 Remote SDP:
v=0
o=Sonus_UAC 850876 135590 IN IP4 206.147.92.21
s=SIP Media Capabilities
c=IN IP4 206.147.92.25
t=0 0
m=audio 12308 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
2019-07-22 16:50:19.378255 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2019-07-22 16:50:19.378255 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2019-07-22 16:50:19.378255 [DEBUG] switch_core_media.c:5566 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2019-07-22 16:50:19.378255 [DEBUG] switch_core_media.c:5511 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2019-07-22 16:50:19.378255 [DEBUG] switch_core_media.c:5427 Set telephone-event payload to 101@8000
2019-07-22 16:50:19.378255 [DEBUG] switch_core_media.c:3781 Set Codec sofia/external/+17708274079@52.41.52.34 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2019-07-22 16:50:19.378255 [DEBUG] switch_core_codec.c:111 sofia/external/+17708274079@52.41.52.34 Original read codec set to PCMU:0
2019-07-22 16:50:19.378255 [DEBUG] switch_core_media.c:5770 Set telephone-event payload to 101@8000
2019-07-22 16:50:19.378255 [DEBUG] switch_core_media.c:5828 sofia/external/+17708274079@52.41.52.34 Set 2833 dtmf send payload to 101 recv payload to 101
2019-07-22 16:50:19.378255 [DEBUG] sofia.c:7748 (sofia/external/+17708274079@52.41.52.34) State Change CS_NEW -> CS_INIT
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:603 (sofia/external/+17708274079@52.41.52.34) State NEW
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+17708274079@52.41.52.34) Running State Change CS_INIT (Cur 1 Tot 380)
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:627 (sofia/external/+17708274079@52.41.52.34) State INIT
2019-07-22 16:50:19.378255 [DEBUG] mod_sofia.c:93 sofia/external/+17708274079@52.41.52.34 SOFIA INIT
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:40 sofia/external/+17708274079@52.41.52.34 Standard INIT
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:48 (sofia/external/+17708274079@52.41.52.34) State Change CS_INIT -> CS_ROUTING
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:627 (sofia/external/+17708274079@52.41.52.34) State INIT going to sleep
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+17708274079@52.41.52.34) Running State Change CS_ROUTING (Cur 1 Tot 380)
2019-07-22 16:50:19.378255 [DEBUG] switch_channel.c:2293 (sofia/external/+17708274079@52.41.52.34) Callstate Change DOWN -> RINGING
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+17708274079@52.41.52.34) State ROUTING
2019-07-22 16:50:19.378255 [DEBUG] mod_sofia.c:154 sofia/external/+17708274079@52.41.52.34 SOFIA ROUTING
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:236 sofia/external/+17708274079@52.41.52.34 Standard ROUTING
2019-07-22 16:50:19.378255 [INFO] mod_dialplan_xml.c:637 Processing Byrd,Andrew <+17708274079>->16789289100 in context public
Dialplan: sofia/external/+17708274079@52.41.52.34 parsing [public->caller-details] continue=true
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (PASS) [caller-details] () =~ // break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Action export(call_direction=inbound) INLINE
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 export(call_direction=inbound)
2019-07-22 16:50:19.378255 [DEBUG] switch_channel.c:1306 EXPORT (export_vars) [call_direction]=[inbound]
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(caller_destination=${sip_to_user}) INLINE
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(caller_destination=+16789289100)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [caller_destination]=[+16789289100]
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(caller_id_name=${caller_id_name}) INLINE
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(caller_id_name=Byrd,Andrew)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [caller_id_name]=[Byrd,Andrew]
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(caller_id_number=${caller_id_number})
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(effective_caller_id_number=${regex(${caller_id_number}|^\+1([0-9]{10})$|%1)})
Dialplan: sofia/external/+17708274079@52.41.52.34 parsing [public->16789289100] continue=false
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (PASS) [16789289100] destination_number(16789289100) =~ /^(16789289100)$/ break=on-false
Dialplan: sofia/external/+17708274079@52.41.52.34 Action export(call_direction=inbound) INLINE
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 export(call_direction=inbound)
2019-07-22 16:50:19.378255 [DEBUG] switch_channel.c:1306 EXPORT (export_vars) [call_direction]=[inbound]
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(domain_uuid=e76a4e2f-01bb-4ce0-921c-8f175873d205) INLINE
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(domain_uuid=e76a4e2f-01bb-4ce0-921c-8f175873d205)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [domain_uuid]=[e76a4e2f-01bb-4ce0-921c-8f175873d205]
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(domain_name=fpbx1.npusa.us) INLINE
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(domain_name=fpbx1.npusa.us)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [domain_name]=[fpbx1.npusa.us]
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(hangup_after_bridge=true)
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(continue_on_fail=true)
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(record_path=${recordings_dir}/${domain_name}/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}) INLINE
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(record_path=/var/lib/freeswitch/recordings/fpbx1.npusa.us/archive/2019/Jul/22)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [record_path]=[/var/lib/freeswitch/recordings/fpbx1.npusa.us/archive/2019/Jul/22]
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(record_name=${uuid}.${record_ext}) INLINE
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(record_name=6362717a-f004-4372-8512-9837ef24abb6.wav)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [record_name]=[6362717a-f004-4372-8512-9837ef24abb6.wav]
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(record_append=true) INLINE
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(record_append=true)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [record_append]=[true]
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(record_in_progress=true) INLINE
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(record_in_progress=true)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [record_in_progress]=[true]
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(recording_follow_transfer=true) INLINE
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(recording_follow_transfer=true)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [recording_follow_transfer]=[true]
Dialplan: sofia/external/+17708274079@52.41.52.34 Action record_session(${record_path}/${record_name})
Dialplan: sofia/external/+17708274079@52.41.52.34 Action transfer(6001 XML fpbx1.npusa.us)
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:286 (sofia/external/+17708274079@52.41.52.34) State Change CS_ROUTING -> CS_EXECUTE
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+17708274079@52.41.52.34) State ROUTING going to sleep
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+17708274079@52.41.52.34) Running State Change CS_EXECUTE (Cur 1 Tot 380)
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+17708274079@52.41.52.34) State EXECUTE
2019-07-22 16:50:19.378255 [DEBUG] mod_sofia.c:209 sofia/external/+17708274079@52.41.52.34 SOFIA EXECUTE
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:328 sofia/external/+17708274079@52.41.52.34 Standard EXECUTE
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(caller_id_number=+17708274079)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [caller_id_number]=[+17708274079]
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(effective_caller_id_number=7708274079)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [effective_caller_id_number]=[7708274079]
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(hangup_after_bridge=true)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [hangup_after_bridge]=[true]
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(continue_on_fail=true)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [continue_on_fail]=[true]
2019-07-22 16:50:19.378255 [DEBUG] switch_core_session.c:2722 Application record_session Requires media! pre_answering channel sofia/external/+17708274079@52.41.52.34
2019-07-22 16:50:19.378255 [INFO] switch_core_session.c:2724 Sending early media
2019-07-22 16:50:19.378255 [DEBUG] switch_core_media.c:8568 AUDIO RTP [sofia/external/+17708274079@52.41.52.34] 74.208.214.70 port 23234 -> 206.147.92.25 port 12308 codec: 0 ms: 20
2019-07-22 16:50:19.378255 [DEBUG] switch_rtp.c:4349 Starting timer [soft] 160 bytes per 20ms
2019-07-22 16:50:19.378255 [DEBUG] switch_core_media.c:8875 sofia/external/+17708274079@52.41.52.34 Set 2833 dtmf send payload to 101
2019-07-22 16:50:19.378255 [DEBUG] switch_core_media.c:8882 sofia/external/+17708274079@52.41.52.34 Set 2833 dtmf receive payload to 101
2019-07-22 16:50:19.378255 [DEBUG] switch_core_media.c:8905 sofia/external/+17708274079@52.41.52.34 Set rtp dtmf delay to 40
2019-07-22 16:50:19.378255 [DEBUG] mod_sofia.c:2515 Ring SDP:
v=0
o=FreeSWITCH 1563805385 1563805386 IN IP4 74.208.214.70
s=FreeSWITCH
c=IN IP4 74.208.214.70
t=0 0
m=audio 23234 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
2019-07-22 16:50:19.378255 [NOTICE] mod_sofia.c:2518 Pre-Answer sofia/external/+17708274079@52.41.52.34!
2019-07-22 16:50:19.378255 [DEBUG] switch_channel.c:3526 (sofia/external/+17708274079@52.41.52.34) Callstate Change RINGING -> EARLY
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 record_session(/var/lib/freeswitch/recordings/fpbx1.npusa.us/archive/2019/Jul/22/6362717a-f004-4372-8512-9837ef24abb6.wav)
2019-07-22 16:50:19.378255 [DEBUG] sofia.c:7323 Channel sofia/external/+17708274079@52.41.52.34 entering state [early][183]
2019-07-22 16:50:19.378255 [DEBUG] switch_ivr_async.c:1347 Record session sample rate: 8000 -> 8000
2019-07-22 16:50:19.378255 [DEBUG] switch_core_media_bug.c:962 Attaching BUG to sofia/external/+17708274079@52.41.52.34
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 transfer(6001 XML fpbx1.npusa.us)
2019-07-22 16:50:19.378255 [DEBUG] switch_ivr.c:2224 (sofia/external/+17708274079@52.41.52.34) State Change CS_EXECUTE -> CS_ROUTING
2019-07-22 16:50:19.378255 [NOTICE] switch_ivr.c:2231 Transfer sofia/external/+17708274079@52.41.52.34 to XML[
6001@fpbx1.npusa.us]
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+17708274079@52.41.52.34) State EXECUTE going to sleep
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+17708274079@52.41.52.34) Running State Change CS_ROUTING (Cur 1 Tot 380)
2019-07-22 16:50:19.378255 [DEBUG] switch_channel.c:2293 (sofia/external/+17708274079@52.41.52.34) Callstate Change EARLY -> RINGING
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+17708274079@52.41.52.34) State ROUTING
2019-07-22 16:50:19.378255 [DEBUG] mod_sofia.c:145 Call appears to be already acknowledged
2019-07-22 16:50:19.378255 [DEBUG] mod_sofia.c:154 sofia/external/+17708274079@52.41.52.34 SOFIA ROUTING
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:236 sofia/external/+17708274079@52.41.52.34 Standard ROUTING
2019-07-22 16:50:19.378255 [INFO] mod_dialplan_xml.c:637 Processing Byrd,Andrew <+17708274079>->6001 in context fpbx1.npusa.us
Dialplan: sofia/external/+17708274079@52.41.52.34 parsing [fpbx1.npusa.us->user_exists] continue=true
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (PASS) [user_exists] () =~ // break=on-false
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(user_exists=false)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [user_exists]=[false]
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/external/+17708274079@52.41.52.34 parsing [fpbx1.npusa.us->caller-details] continue=true
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [caller-details] ${caller_destination}(+16789289100) =~ /^$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 parsing [fpbx1.npusa.us->call-direction] continue=true
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 parsing [fpbx1.npusa.us->variables] continue=true
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (PASS) [variables] () =~ // break=on-false
Dialplan: sofia/external/+17708274079@52.41.52.34 Action export(origination_callee_id_name=${destination_number})
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/external/+17708274079@52.41.52.34 parsing [fpbx1.npusa.us->is_loopback] continue=true
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false
Dialplan: sofia/external/+17708274079@52.41.52.34 parsing [fpbx1.npusa.us->user_record] continue=true
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${from_user_exists}() =~ /^true$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${from_user_exists}() =~ /^true$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${from_user_exists}() =~ /^true$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${from_user_exists}() =~ /^true$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${from_user_exists}() =~ /^true$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
Dialplan: sofia/external/+17708274079@52.41.52.34 parsing [fpbx1.npusa.us->redial] continue=true
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [redial] destination_number(6001) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (PASS) [redial] () =~ // break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/external/+17708274079@52.41.52.34 parsing [fpbx1.npusa.us->speed_dial] continue=false
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [speed_dial] destination_number(6001) =~ /^\*0(.*)$/ break=on-false
Dialplan: sofia/external/+17708274079@52.41.52.34 parsing [fpbx1.npusa.us->default_caller_id] continue=true
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (PASS) [default_caller_id] ${emergency_caller_id_number}() =~ /^$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(emergency_caller_id_name=${default_emergency_caller_id_name}) INLINE
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(emergency_caller_id_name=)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [emergency_caller_id_name]=[UNDEF]
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(emergency_caller_id_number=${default_emergency_caller_id_number}) INLINE
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(emergency_caller_id_number=)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [emergency_caller_id_number]=[UNDEF]
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (PASS) [default_caller_id] ${outbound_caller_id_number}() =~ /^$/ break=never
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(outbound_caller_id_name=${default_outbound_caller_id_name}) INLINE
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(outbound_caller_id_name=)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [outbound_caller_id_name]=[UNDEF]
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(outbound_caller_id_number=${default_outbound_caller_id_number}) INLINE
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(outbound_caller_id_number=)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [outbound_caller_id_number]=[UNDEF]
Dialplan: sofia/external/+17708274079@52.41.52.34 parsing [fpbx1.npusa.us->skyetel] continue=false
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (PASS) [skyetel] ${user_exists}(false) =~ /false/ break=on-false
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (FAIL) [skyetel] destination_number(6001) =~ /(^911$|^933$)/ break=on-false
Dialplan: sofia/external/+17708274079@52.41.52.34 parsing [fpbx1.npusa.us->ring group] continue=
Dialplan: sofia/external/+17708274079@52.41.52.34 Regex (PASS) [ring group] destination_number(6001) =~ /^6001$/ break=on-false
Dialplan: sofia/external/+17708274079@52.41.52.34 Action ring_ready()
Dialplan: sofia/external/+17708274079@52.41.52.34 Action set(ring_group_uuid=bcdd8187-b159-4066-8a5e-e32e5bf0c7b3)
Dialplan: sofia/external/+17708274079@52.41.52.34 Action lua(app.lua ring_groups)
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:286 (sofia/external/+17708274079@52.41.52.34) State Change CS_ROUTING -> CS_EXECUTE
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+17708274079@52.41.52.34) State ROUTING going to sleep
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+17708274079@52.41.52.34) Running State Change CS_EXECUTE (Cur 1 Tot 380)
2019-07-22 16:50:19.378255 [DEBUG] switch_channel.c:2297 (sofia/external/+17708274079@52.41.52.34) Callstate Change RINGING -> EARLY
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+17708274079@52.41.52.34) State EXECUTE
2019-07-22 16:50:19.378255 [DEBUG] mod_sofia.c:209 sofia/external/+17708274079@52.41.52.34 SOFIA EXECUTE
2019-07-22 16:50:19.378255 [DEBUG] switch_core_state_machine.c:328 sofia/external/+17708274079@52.41.52.34 Standard EXECUTE
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 export(origination_callee_id_name=6001)
2019-07-22 16:50:19.378255 [DEBUG] switch_channel.c:1306 EXPORT (export_vars) [origination_callee_id_name]=[6001]
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(RFC2822_DATE=Mon, 22 Jul 2019 16:50:19 -0400)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [RFC2822_DATE]=[Mon, 22 Jul 2019 16:50:19 -0400]
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 hash(insert/fpbx1.npusa.us-last_dial/+17708274079/6001)
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 ring_ready()
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(ring_group_uuid=bcdd8187-b159-4066-8a5e-e32e5bf0c7b3)
2019-07-22 16:50:19.378255 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [ring_group_uuid]=[bcdd8187-b159-4066-8a5e-e32e5bf0c7b3]
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 lua(app.lua ring_groups)
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 ring_ready()
2019-07-22 16:50:19.378255 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('call_timeout', '300')
2019-07-22 16:50:19.378255 [DEBUG] switch_core_media.c:8551 Audio params are unchanged for sofia/external/+17708274079@52.41.52.34.
2019-07-22 16:50:19.378255 [DEBUG] mod_sofia.c:893 Local SDP sofia/external/+17708274079@52.41.52.34:
v=0
o=FreeSWITCH 1563805385 1563805387 IN IP4 74.208.214.70
s=FreeSWITCH
c=IN IP4 74.208.214.70
t=0 0
m=audio 23234 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
2019-07-22 16:50:19.378255 [NOTICE] switch_cpp.cpp:685 Channel [sofia/external/+17708274079@52.41.52.34] has been answered
2019-07-22 16:50:19.378255 [DEBUG] switch_channel.c:3825 (sofia/external/+17708274079@52.41.52.34) Callstate Change EARLY -> ACTIVE
2019-07-22 16:50:19.378255 [DEBUG] sofia.c:7323 Channel sofia/external/+17708274079@52.41.52.34 entering state [completed][200]
2019-07-22 16:50:19.378255 [DEBUG] switch_ivr_async.c:1636 No silence detection configured; assuming start of speech
2019-07-22 16:50:19.538216 [DEBUG] sofia.c:7323 Channel sofia/external/+17708274079@52.41.52.34 entering state [ready][200]
2019-07-22 16:50:19.658190 [DEBUG] switch_rtp.c:7604 Correct audio ip/port confirmed.
2019-07-22 16:50:19.658190 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMU:0
2019-07-22 16:50:20.398162 [DEBUG] switch_cpp.cpp:1443 [ring_group] resolve `recording.wav` as recording `/var/lib/freeswitch/recordings/fpbx1.npusa.us/recording.wav`
2019-07-22 16:50:20.398162 [NOTICE] switch_cpp.cpp:1443 [ring_group] Found `recording.wav` as `/var/lib/freeswitch/recordings/fpbx1.npusa.us/recording.wav`
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 playback(/var/lib/freeswitch/recordings/fpbx1.npusa.us/recording.wav)
2019-07-22 16:50:20.398162 [DEBUG] switch_core_file.c:389 File /var/lib/freeswitch/recordings/fpbx1.npusa.us/recording.wav sample rate 16000 doesn't match requested rate 8000
2019-07-22 16:50:20.398162 [DEBUG] switch_ivr_play_say.c:1497 Codec Activated L16@8000hz 1 channels 20ms
2019-07-22 16:50:23.058022 [NOTICE] sofia.c:1079 Hangup sofia/external/+17708274079@52.41.52.34 [CS_EXECUTE] [NORMAL_CLEARING]
2019-07-22 16:50:23.058022 [DEBUG] switch_ivr_play_say.c:1941 done playing file /var/lib/freeswitch/recordings/fpbx1.npusa.us/recording.wav
2019-07-22 16:50:23.058022 [DEBUG] switch_core_session.c:2905 sofia/external/+17708274079@52.41.52.34 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2019-07-22 16:50:23.078011 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('ringback', 'tone_stream://%(2000,4000,440,480);loops=-1')
2019-07-22 16:50:23.078011 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('transfer_ringback', 'tone_stream://%(2000,4000,440,480);loops=-1')
2019-07-22 16:50:23.078011 [DEBUG] switch_core_session.c:2680 sofia/external/+17708274079@52.41.52.34 ZOMBIE EXEC set(uuids=40c235d1-b62b-43ca-8bb4-4640bac5310d)
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(uuids=40c235d1-b62b-43ca-8bb4-4640bac5310d)
2019-07-22 16:50:23.078011 [DEBUG] switch_core_session.c:2889 sofia/external/+17708274079@52.41.52.34 skip receive message [APPLICATION_EXEC] (channel is hungup already)
2019-07-22 16:50:23.078011 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [uuids]=[40c235d1-b62b-43ca-8bb4-4640bac5310d]
2019-07-22 16:50:23.078011 [DEBUG] switch_core_session.c:2905 sofia/external/+17708274079@52.41.52.34 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2019-07-22 16:50:23.078011 [DEBUG] switch_core_session.c:2680 sofia/external/+17708274079@52.41.52.34 ZOMBIE EXEC export(sip_h_Alert-Info=)
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 export(sip_h_Alert-Info=)
2019-07-22 16:50:23.078011 [DEBUG] switch_core_session.c:2889 sofia/external/+17708274079@52.41.52.34 skip receive message [APPLICATION_EXEC] (channel is hungup already)
2019-07-22 16:50:23.078011 [DEBUG] switch_channel.c:1306 EXPORT (export_vars) [sip_h_Alert-Info]=[UNDEF]
2019-07-22 16:50:23.078011 [DEBUG] switch_core_session.c:2905 sofia/external/+17708274079@52.41.52.34 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2019-07-22 16:50:23.098009 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('ringback', 'tone_stream://%(2000,4000,440,480);loops=-1')
2019-07-22 16:50:23.098009 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('transfer_ringback', 'tone_stream://%(2000,4000,440,480);loops=-1')
2019-07-22 16:50:23.098009 [DEBUG] switch_core_session.c:2680 sofia/external/+17708274079@52.41.52.34 ZOMBIE EXEC set(uuids=40c235d1-b62b-43ca-8bb4-4640bac5310d,480c82b3-cb15-4ed9-bb4f-08273c926be9)
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 set(uuids=40c235d1-b62b-43ca-8bb4-4640bac5310d,480c82b3-cb15-4ed9-bb4f-08273c926be9)
2019-07-22 16:50:23.098009 [DEBUG] switch_core_session.c:2889 sofia/external/+17708274079@52.41.52.34 skip receive message [APPLICATION_EXEC] (channel is hungup already)
2019-07-22 16:50:23.098009 [DEBUG] mod_dptools.c:1615 SET sofia/external/+17708274079@52.41.52.34 [uuids]=[40c235d1-b62b-43ca-8bb4-4640bac5310d,480c82b3-cb15-4ed9-bb4f-08273c926be9]
2019-07-22 16:50:23.098009 [DEBUG] switch_core_session.c:2905 sofia/external/+17708274079@52.41.52.34 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2019-07-22 16:50:23.098009 [DEBUG] switch_core_session.c:2680 sofia/external/+17708274079@52.41.52.34 ZOMBIE EXEC export(sip_h_Alert-Info=)
EXECUTE [depth=0] sofia/external/+17708274079@52.41.52.34 export(sip_h_Alert-Info=)
2019-07-22 16:50:23.098009 [DEBUG] switch_core_session.c:2889 sofia/external/+17708274079@52.41.52.34 skip receive message [APPLICATION_EXEC] (channel is hungup already)
2019-07-22 16:50:23.098009 [DEBUG] switch_channel.c:1306 EXPORT (export_vars) [sip_h_Alert-Info]=[UNDEF]
2019-07-22 16:50:23.098009 [DEBUG] switch_core_session.c:2905 sofia/external/+17708274079@52.41.52.34 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2019-07-22 16:50:23.098009 [DEBUG] switch_cpp.cpp:1187 sofia/external/+17708274079@52.41.52.34 destroy/unlink session from object
2019-07-22 16:50:23.098009 [DEBUG] switch_core_session.c:2905 sofia/external/+17708274079@52.41.52.34 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2019-07-22 16:50:23.098009 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+17708274079@52.41.52.34) State EXECUTE going to sleep
2019-07-22 16:50:23.098009 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+17708274079@52.41.52.34) Running State Change CS_HANGUP (Cur 1 Tot 380)
2019-07-22 16:50:23.098009 [DEBUG] switch_ivr_async.c:1441 Stop recording file /var/lib/freeswitch/recordings/fpbx1.npusa.us/archive/2019/Jul/22/6362717a-f004-4372-8512-9837ef24abb6.wav
2019-07-22 16:50:23.098009 [DEBUG] switch_ivr_async.c:1516 Channel is hung up
2019-07-22 16:50:23.098009 [DEBUG] switch_core_media_bug.c:1276 Removing BUG from sofia/external/+17708274079@52.41.52.34
2019-07-22 16:50:23.098009 [DEBUG] switch_core_state_machine.c:847 (sofia/external/+17708274079@52.41.52.34) Callstate Change ACTIVE -> HANGUP
2019-07-22 16:50:23.098009 [DEBUG] switch_core_state_machine.c:849 (sofia/external/+17708274079@52.41.52.34) State HANGUP
2019-07-22 16:50:23.098009 [DEBUG] mod_sofia.c:460 Channel sofia/external/+17708274079@52.41.52.34 hanging up, cause: NORMAL_CLEARING
2019-07-22 16:50:23.098009 [DEBUG] switch_core_state_machine.c:60 sofia/external/+17708274079@52.41.52.34 Standard HANGUP, cause: NORMAL_CLEARING
2019-07-22 16:50:23.098009 [DEBUG] switch_core_state_machine.c:849 (sofia/external/+17708274079@52.41.52.34) State HANGUP going to sleep
2019-07-22 16:50:23.098009 [DEBUG] switch_core_state_machine.c:619 (sofia/external/+17708274079@52.41.52.34) State Change CS_HANGUP -> CS_REPORTING
2019-07-22 16:50:23.098009 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+17708274079@52.41.52.34) Running State Change CS_REPORTING (Cur 1 Tot 380)
2019-07-22 16:50:23.098009 [DEBUG] switch_core_state_machine.c:935 (sofia/external/+17708274079@52.41.52.34) State REPORTING
2019-07-22 16:50:23.178035 [DEBUG] switch_core_state_machine.c:174 sofia/external/+17708274079@52.41.52.34 Standard REPORTING, cause: NORMAL_CLEARING
2019-07-22 16:50:23.178035 [DEBUG] switch_core_state_machine.c:935 (sofia/external/+17708274079@52.41.52.34) State REPORTING going to sleep
2019-07-22 16:50:23.178035 [DEBUG] switch_core_state_machine.c:610 (sofia/external/+17708274079@52.41.52.34) State Change CS_REPORTING -> CS_DESTROY
2019-07-22 16:50:23.178035 [DEBUG] switch_core_session.c:1726 Session 380 (sofia/external/+17708274079@52.41.52.34) Locked, Waiting on external entities
2019-07-22 16:50:23.178035 [NOTICE] switch_core_session.c:1744 Session 380 (sofia/external/+17708274079@52.41.52.34) Ended
2019-07-22 16:50:23.178035 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/+17708274079@52.41.52.34 [CS_DESTROY]
2019-07-22 16:50:23.178035 [DEBUG] switch_core_state_machine.c:738 (sofia/external/+17708274079@52.41.52.34) Running State Change CS_DESTROY (Cur 0 Tot 380)
2019-07-22 16:50:23.178035 [DEBUG] switch_core_state_machine.c:748 (sofia/external/+17708274079@52.41.52.34) State DESTROY
2019-07-22 16:50:23.178035 [DEBUG] mod_sofia.c:365 sofia/external/+17708274079@52.41.52.34 SOFIA DESTROY
2019-07-22 16:50:23.178035 [DEBUG] switch_core_state_machine.c:181 sofia/external/+17708274079@52.41.52.34 Standard DESTROY
2019-07-22 16:50:23.178035 [DEBUG] switch_core_state_machine.c:748 (sofia/external/+17708274079@52.41.52.34) State DESTROY going to sleep
freeswitch@468AC01>