Zopier registration not working

Status
Not open for further replies.
M

mrjoli021

Member
Jul 20, 2017
133
2
18
47
I have some Zopier phones soft phones. The phones are coming from the internet both using different ISP's and as a matter of fact both soft phones in question are in different states. Somehow they both show up as registered with the same public IP "185.117.83.XXX". (note that I am posting this IP since it is not any of my IP's). When I check the phones public IP both phones have different public IP's. As as stated above this IP is not my FusionPBX's public IP. Not sure where this IP is coming from, but the phones do register and I am able to make outbound calls. I have also checked and there are no outbound proxy's that I can see on the app or anywhere else. The problem is with Inbound.
When I attempt to dial the phone I keep getting sent to VM with the following error on the logs.

[ERR] sofia_reg.c:2616 Cannot locate any authentication credentials to complete an authentication request for realm '"185.117.83.XXX"'

Any idea why this is happening and how to fix this?
 
Last edited:
M

mrjoli021

Member
Jul 20, 2017
133
2
18
47
I am running a Kamailio server publicly facing. This server is just a pass through box that blocks non allowed domains to register to the fusionPBX behind a router (not a firewall). This setup is currently working for different domains other physical and soft phones phones. Other phones register and are able to make inbound and outbound without any issues, but not sure why the Zopier soft phones dont seem to work. The other thing that is weird is that IP shows up for both soft phones as the registering IP when they are in different geolocations.
 
F

fusionpbxnoaudio

Member
Jul 15, 2021
102
9
18
34
Zoiper Android clients seem to have issues atleast for me when they are behind NAT - it is very inconsistent even for registrations, it seems to retain some stale auth data and try to register which the server responds with a 401. Are you using Zoiper on a PC or Android? If Android, I would suggest using sipnetic which seems to be working under all NAT conditions without much fuss.
 
M

mrjoli021

Member
Jul 20, 2017
133
2
18
47
They are installing this on Android and Apple phones. The reason I choose Zoiper is that these clients are different geolocations than where I am and Zopier has a QR code feature. So there is very minimal config from the user side. They can download it and use it. Is there a way to get this to work with Zopier?
 
F

fusionpbxnoaudio

Member
Jul 15, 2021
102
9
18
34
What are the settings on Zoiper? is it possible to share screen shots of network connectivity (under accounts), also Listening port screen etc - as to see what options are enabled which may make it work.
 
M

mrjoli021

Member
Jul 20, 2017
133
2
18
47
Here are the settings. Let me know if you think I should change anything. At this point I am willing to try anything. Screenshot_20210805-131356_ZoiPer_Premium.jpg
 

Attachments

  • Screenshot_20210805-131433_ZoiPer_Premium.jpg
    Screenshot_20210805-131433_ZoiPer_Premium.jpg
    38 KB · Views: 7
  • Screenshot_20210805-131445_ZoiPer_Premium.jpg
    Screenshot_20210805-131445_ZoiPer_Premium.jpg
    35.8 KB · Views: 7
  • Screenshot_20210805-131907_ZoiPer_Premium.jpg
    Screenshot_20210805-131907_ZoiPer_Premium.jpg
    33.8 KB · Views: 6
F

fusionpbxnoaudio

Member
Jul 15, 2021
102
9
18
34
I have the push proxy disabled, subscribe for presence & publish presence disabled as I am using the free version. Also subscribe for register unchecked. RTP for signaling and media ticked.
 
DigitalDaz

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,071
577
113
@mrjoli021 Can you post a fuller log of an inbound call, also set the zoiper transport to TCP.

Zoiper actually seems to work the best from all my testing.

Zoipers push servers are 185.117.83.192/27 so what you are seeing is perfectly normal.
 
M

mrjoli021

Member
Jul 20, 2017
133
2
18
47
On the FS logs, I show the extension registered and able to make outbound calls. When I attempt to make an inbound call I get this on the logs:
[ERR] sofia_reg.c:2616 Cannot locate any authentication credentials to complete an authentication request for realm '"185.117.83.2XX"'
 
DigitalDaz

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,071
577
113
Yes, its before that that I want to see, from the beginning of the call preferably.
 
M

mrjoli021

Member
Jul 20, 2017
133
2
18
47
Here is the entire output. I have changed the numbers a bit
Caller: 7862369083
Called: 18775914444

-------------------------------------------------------------------------

2021-08-06 13:10:39.342585 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/+17862369083@fl.gg [6e33019a-c220-40b7-9981-c1c39da8d918]
2021-08-06 13:10:39.342585 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/+17862369083@fl.gg) Running State Change CS_NEW (Cur 1 Tot 266)
2021-08-06 13:10:39.342585 [DEBUG] sofia.c:9873 sofia/internal/+17862369083@fl.gg receiving invite from 10.21.10.5:5060 version: 1.6.20 -37-987c9b9 64bit
2021-08-06 13:10:39.342585 [DEBUG] sofia.c:9989 IP 10.21.10.5 Approved by acl "domains[]". Access Granted.
2021-08-06 13:10:39.342585 [DEBUG] sofia.c:7084 Channel sofia/internal/+17862369083@fl.gg entering state [received][100]
2021-08-06 13:10:39.342585 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=Sonus_UAC 892138 388636 IN IP4 10.21.10.5
s=SIP Media Capabilities
c=IN IP4 10.21.10.5
t=0 0
m=audio 16404 RTP/AVP 0 18 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=maxptime:20

2021-08-06 13:10:39.342585 [DEBUG] sofia.c:7486 (sofia/internal/+17862369083@fl.gg) State Change CS_NEW -> CS_INIT
2021-08-06 13:10:39.342585 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/+17862369083@fl.gg) State NEW
2021-08-06 13:10:39.342585 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/+17862369083@fl.gg) Running State Change CS_INIT (Cur 1 Tot 266)
2021-08-06 13:10:39.342585 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/+17862369083@fl.gg) State INIT
2021-08-06 13:10:39.342585 [DEBUG] mod_sofia.c:90 sofia/internal/+17862369083@fl.gg SOFIA INIT
2021-08-06 13:10:39.342585 [DEBUG] switch_core_state_machine.c:40 sofia/internal/+17862369083@fl.gg Standard INIT
2021-08-06 13:10:39.342585 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/+17862369083@fl.gg) State Change CS_INIT -> CS_ROUTING
2021-08-06 13:10:39.342585 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/+17862369083@fl.gg) State INIT going to sleep
2021-08-06 13:10:39.342585 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/+17862369083@fl.gg) Running State Change CS_ROUTING (Cur 1 Tot 266)
2021-08-06 13:10:39.342585 [DEBUG] switch_channel.c:2249 (sofia/internal/+17862369083@fl.gg) Callstate Change DOWN -> RINGING
2021-08-06 13:10:39.342585 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/+17862369083@fl.gg) State ROUTING
2021-08-06 13:10:39.342585 [DEBUG] mod_sofia.c:143 sofia/internal/+17862369083@fl.gg SOFIA ROUTING
2021-08-06 13:10:39.342585 [DEBUG] switch_core_state_machine.c:236 sofia/internal/+17862369083@fl.gg Standard ROUTING
2021-08-06 13:10:39.342585 [INFO] mod_dialplan_xml.c:637 Processing FLORIDA <+17862369083>->18775914444 in context public
2021-08-06 13:10:39.402568 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fef0833f3d0 Connected.
2021-08-06 13:10:39.402568 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fef0833f3d0 released.
Dialplan: sofia/internal/+17862369083@fl.gg parsing [public->CNAM] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [CNAM] context(public) =~ /Public/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [public->caller-details] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [caller-details] () =~ // break=never
Dialplan: sofia/internal/+17862369083@fl.gg Action export(call_direction=inbound) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg export(call_direction=inbound)
2021-08-06 13:10:39.402568 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_direction]=[inbound]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(caller_destination=${destination_number}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(caller_destination=18775914444)
2021-08-06 13:10:39.402568 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [caller_destination]=[18775914444]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(caller_id_name=${caller_id_name}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(caller_id_name=FLORIDA)
2021-08-06 13:10:39.402568 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [caller_id_name]=[FLORIDA]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(caller_id_number=${caller_id_number}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(caller_id_number=+17862369083)
2021-08-06 13:10:39.402568 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [caller_id_number]=[+17862369083]
Dialplan: sofia/internal/+17862369083@fl.gg parsing [public->18775914444] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [18775914444] destination_number(18775914444) =~ /^(18775914444)$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg Action export(call_direction=inbound) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg export(call_direction=inbound)
2021-08-06 13:10:39.402568 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_direction]=[inbound]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(domain_uuid=9d61dd0d-9ba6-45d0-b02c-8e5d603c5aa2) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(domain_uuid=9d61dd0d-9ba6-45d0-b02c-8e5d603c5aa2)
2021-08-06 13:10:39.402568 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [domain_uuid]=[9d61dd0d-9ba6-45d0-b02c-8e5d603c5aa2]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(domain_name=18775914444.phones.somedomain.com) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(domain_name=18775914444.phones.somedomain.com)
2021-08-06 13:10:39.402568 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [domain_name]=[18775914444.phones.somedomain.com]
Dialplan: sofia/internal/+17862369083@fl.gg Action transfer(306 XML 18775914444.phones.somedomain.com)
2021-08-06 13:10:39.422567 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/+17862369083@fl.gg) State Change CS_ROUTING -> CS_EXECUTE
2021-08-06 13:10:39.422567 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/+17862369083@fl.gg) State ROUTING going to sleep
2021-08-06 13:10:39.422567 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/+17862369083@fl.gg) Running State Change CS_EXECUTE (Cur 1 Tot 266)
2021-08-06 13:10:39.422567 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/+17862369083@fl.gg) State EXECUTE
2021-08-06 13:10:39.422567 [DEBUG] mod_sofia.c:198 sofia/internal/+17862369083@fl.gg SOFIA EXECUTE
2021-08-06 13:10:39.422567 [DEBUG] switch_core_state_machine.c:328 sofia/internal/+17862369083@fl.gg Standard EXECUTE
EXECUTE sofia/internal/+17862369083@fl.gg transfer(306 XML 18775914444.phones.somedomain.com)
2021-08-06 13:10:39.422567 [DEBUG] switch_ivr.c:2165 (sofia/internal/+17862369083@fl.gg) State Change CS_EXECUTE -> CS_ROUTING
2021-08-06 13:10:39.422567 [NOTICE] switch_ivr.c:2172 Transfer sofia/internal/+17862369083@fl.gg to XML[306@18775914444.phones.somedomain.com]
2021-08-06 13:10:39.422567 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/+17862369083@fl.gg) State EXECUTE going to sleep
2021-08-06 13:10:39.422567 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/+17862369083@fl.gg) Running State Change CS_ROUTING (Cur 1 Tot 266)
2021-08-06 13:10:39.422567 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/+17862369083@fl.gg) State ROUTING
2021-08-06 13:10:39.422567 [DEBUG] mod_sofia.c:143 sofia/internal/+17862369083@fl.gg SOFIA ROUTING
2021-08-06 13:10:39.422567 [DEBUG] switch_core_state_machine.c:236 sofia/internal/+17862369083@fl.gg Standard ROUTING
2021-08-06 13:10:39.422567 [INFO] mod_dialplan_xml.c:637 Processing FLORIDA <+17862369083>->306 in context 18775914444.phones.somedomain.com
2021-08-06 13:10:39.422567 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fef0833f3d0 Connected.
2021-08-06 13:10:39.462567 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fef0833f3d0 released.
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->user_exists] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_exists] ${loopback_leg}() =~ /^B$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [user_exists] () =~ // break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(user_exists=true)
2021-08-06 13:10:39.462567 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [user_exists]=[true]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
2021-08-06 13:10:39.482568 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fef0833f3d0 Connected.
2021-08-06 13:10:39.482568 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fef0833f3d0 released.
EXECUTE sofia/internal/+17862369083@fl.gg set(from_user_exists=false)
2021-08-06 13:10:39.482568 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [from_user_exists]=[false]
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [user_exists] ${user_exists}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg Action set(extension_uuid=${user_data ${destination_number}@${domain_name} var extension_uuid}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(extension_uuid=1ae9289d-f803-4365-80e1-a985aa380706)
2021-08-06 13:10:39.482568 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [extension_uuid]=[1ae9289d-f803-4365-80e1-a985aa380706]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(forward_all_enabled=${user_data ${destination_number}@${domain_name} var forward_all_enabled}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(forward_all_enabled=)
2021-08-06 13:10:39.482568 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [forward_all_enabled]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(forward_all_destination=${user_data ${destination_number}@${domain_name} var forward_all_destination}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(forward_all_destination=)
2021-08-06 13:10:39.502568 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [forward_all_destination]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(forward_busy_enabled=${user_data ${destination_number}@${domain_name} var forward_busy_enabled}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(forward_busy_enabled=)
2021-08-06 13:10:39.502568 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [forward_busy_enabled]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(forward_busy_destination=${user_data ${destination_number}@${domain_name} var forward_busy_destination}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(forward_busy_destination=)
2021-08-06 13:10:39.502568 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [forward_busy_destination]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(forward_no_answer_enabled=${user_data ${destination_number}@${domain_name} var forward_no_answer_enabled}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(forward_no_answer_enabled=)
2021-08-06 13:10:39.502568 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [forward_no_answer_enabled]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(forward_no_answer_destination=${user_data ${destination_number}@${domain_name} var forward_no_answer_destination}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(forward_no_answer_destination=)
2021-08-06 13:10:39.522567 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [forward_no_answer_destination]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(forward_user_not_registered_enabled=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_enabled}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(forward_user_not_registered_enabled=)
2021-08-06 13:10:39.522567 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [forward_user_not_registered_enabled]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(forward_user_not_registered_destination=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_destination}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(forward_user_not_registered_destination=)
2021-08-06 13:10:39.522567 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [forward_user_not_registered_destination]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(forward_all_enabled=${user_data ${destination_number}@${domain_name} var forward_all_enabled}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(forward_all_enabled=)
2021-08-06 13:10:39.522567 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [forward_all_enabled]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(follow_me_enabled=${user_data ${destination_number}@${domain_name} var follow_me_enabled}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(follow_me_enabled=)
2021-08-06 13:10:39.542568 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [follow_me_enabled]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(do_not_disturb=${user_data ${destination_number}@${domain_name} var do_not_disturb}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(do_not_disturb=)
2021-08-06 13:10:39.542568 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [do_not_disturb]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(call_timeout=${user_data ${destination_number}@${domain_name} var call_timeout}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(call_timeout=30)
2021-08-06 13:10:39.562575 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [call_timeout]=[30]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(missed_call_app=${user_data ${destination_number}@${domain_name} var missed_call_app}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(missed_call_app=)
2021-08-06 13:10:39.562575 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [missed_call_app]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(missed_call_data=${user_data ${destination_number}@${domain_name} var missed_call_data}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(missed_call_data=)
2021-08-06 13:10:39.562575 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [missed_call_data]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(toll_allow=${user_data ${destination_number}@${domain_name} var toll_allow}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(toll_allow=)
2021-08-06 13:10:39.562575 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [toll_allow]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(call_screen_enabled=${user_data ${destination_number}@${domain_name} var call_screen_enabled}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(call_screen_enabled=false)
2021-08-06 13:10:39.582569 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [call_screen_enabled]=[false]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(user_record=)
2021-08-06 13:10:39.582569 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [user_record]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->caller-details] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [caller-details] ${caller_destination}(18775914444) =~ /^$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->call-direction] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->global-variables] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [global-variables] () =~ // break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/+17862369083@fl.gg Action set(timezone=America/New_York) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(timezone=America/New_York)
2021-08-06 13:10:39.582569 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [timezone]=[America/New_York]
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->domain-variables] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [domain-variables] () =~ // break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg Action export(origination_callee_id_name=${destination_number})
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->is_loopback] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->user_record] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->rtp_has_crypto] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg 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/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->redial] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [redial] destination_number(306) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [redial] () =~ // break=never
Dialplan: sofia/internal/+17862369083@fl.gg Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->speed_dial] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [speed_dial] destination_number(306) =~ /^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->user_hold_music] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [user_hold_music] ${hold_music}(local_stream://default) =~ /^$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [user_hold_music] ${user_exists}(true) =~ /true/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg Action set(hold_music=${user_data ${destination_number}@${domain_name} var hold_music}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(hold_music=)
2021-08-06 13:10:39.602565 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [hold_music]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->default_caller_id] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [default_caller_id] ${emergency_caller_id_name}() =~ /^$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Action set(emergency_caller_id_name=${default_emergency_caller_id_name}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(emergency_caller_id_name=)
2021-08-06 13:10:39.602565 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [emergency_caller_id_name]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [default_caller_id] ${emergency_caller_id_number}() =~ /^$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Action set(emergency_caller_id_number=${default_emergency_caller_id_number}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(emergency_caller_id_number=)
2021-08-06 13:10:39.602565 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [emergency_caller_id_number]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [default_caller_id] ${outbound_caller_id_name}() =~ /^$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Action set(outbound_caller_id_name=${default_outbound_caller_id_name}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(outbound_caller_id_name=)
2021-08-06 13:10:39.602565 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [outbound_caller_id_name]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [default_caller_id] ${outbound_caller_id_number}() =~ /^$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Action set(outbound_caller_id_number=${default_outbound_caller_id_number}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(outbound_caller_id_number=)
2021-08-06 13:10:39.602565 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [outbound_caller_id_number]=[UNDEF]
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->SonusSBC.11d_Domain] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [SonusSBC.11d_Domain] destination_number(306) =~ /^(\d{11})$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->SonusSBC.10d_Domain] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [SonusSBC.10d_Domain] destination_number(306) =~ /^(\d{10})$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->Main] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [Main] destination_number(306) =~ /^300$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->agent_status] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [agent_status] destination_number(306) =~ /^\*22$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [agent_status] destination_number(306) =~ /^(agent\+)(.*)$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->agent_status_id] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [agent_status_id] destination_number(306) =~ /^\*23$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->group-intercept] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [group-intercept] destination_number(306) =~ /^\*8$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->page-extension] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [page-extension] destination_number(306) =~ /^\*8(\d{2,7})$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->eavesdrop] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [eavesdrop] destination_number(306) =~ /^\*33(\d{2,7})$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->call_privacy] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [call_privacy] destination_number(306) =~ /^\*67(\d+)$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->call_return] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [call_return] destination_number(306) =~ /^\*69$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->extension_queue] continue=
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [extension_queue] destination_number(306) =~ /^\*800(.*)$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->intercept-ext] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [intercept-ext] destination_number(306) =~ /^\*\*(\d+)$/ break=on-true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [intercept-ext] destination_number(306) =~ /^\*\*$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->intercept-ext-polycom] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [intercept-ext-polycom] destination_number(306) =~ /^\*97(\d+)$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->Main] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [Main] destination_number(306) =~ /^3002$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->dx] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [dx] destination_number(306) =~ /^dx$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->att_xfer] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [att_xfer] destination_number(306) =~ /^att_xfer$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->send_to_voicemail] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [send_to_voicemail] destination_number(306) =~ /^\*99(\d{2,10})$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->extension-to-voicemail] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [extension-to-voicemail] ${user_exists}(true) =~ /^true$/ break=on-false
2021-08-06 13:10:39.602565 [ERR] switch_regex.c:104 COMPILE ERROR: 1 [nothing to repeat][^+17862369083$]
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [extension-to-voicemail] username(+17862369083) =~ /^+17862369083$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->xfer_vm] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [xfer_vm] destination_number(306) =~ /^xfer_vm$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->vmain] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [vmain] destination_number(306) =~ /^vmain$|^\*4000$|^\*98$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [vmain] destination_number(306) =~ /^(vmain$|^\*4000$|^\*98)(\d{2,12})$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->vmain_user] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [vmain_user] destination_number(306) =~ /^\*97$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->is_transfer] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [is_transfer] destination_number(306) =~ /^is_transfer$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->Overide AfterHours] continue=
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [Overide AfterHours] destination_number(306) =~ /^(?:flow\+)?\*41$/ break=on-true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [Overide AfterHours] destination_number(306) =~ /^3000$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->delay_echo] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [delay_echo] destination_number(306) =~ /^\*9195$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->cf] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [cf] destination_number(306) =~ /^cf$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->echo] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [echo] destination_number(306) =~ /^\*9196$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->milliwatt] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [milliwatt] destination_number(306) =~ /^\*9197$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->is_zrtp_secure] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [is_zrtp_secure] ${zrtp_secure_media_confirmed}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg ANTI-Action eval(not_secure)
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->is_secure] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [is_secure] ${sip_via_protocol}(udp) =~ /tls/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->tone_stream] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [tone_stream] destination_number(306) =~ /^\*9198$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->hold_music] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [hold_music] destination_number(306) =~ /^\*9664$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->recordings] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [recordings] destination_number(306) =~ /^\*(732)$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->directory] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [directory] destination_number(306) =~ /^\*411$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->wake-up] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [wake-up] destination_number(306) =~ /^\*(925)$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->valet_park] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [valet_park] destination_number(306) =~ /^(park\+)?\*(59[0-9][0-9])$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [valet_park] ${sip_h_Referred-By}() =~ /sip:(.*)@.*/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [valet_park] destination_number(306) =~ /^(park\+)?\*(59[0-9][0-9])$/ break=never
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [valet_park] destination_number(306) =~ /^(park\+)?\*(59[0-9][0-9])$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->operator] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [operator] destination_number(306) =~ /^0$|^operator$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->operator-forward] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [operator-forward] destination_number(306) =~ /^\*000$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->do-not-disturb] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [do-not-disturb] destination_number(306) =~ /^\*77$/ break=on-true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [do-not-disturb] destination_number(306) =~ /^\*78$|\*363$/ break=on-true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [do-not-disturb] destination_number(306) =~ /^\*79$/ break=on-true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [do-not-disturb] destination_number(306) =~ /^dnd\++17862369083$/ break=on-true
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->call_screen] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [call_screen] ${call_screen_enabled}(false) =~ /^true$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->follow-me-destinations] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [follow-me-destinations] ${user_exists}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [follow-me-destinations] ${follow_me_enabled}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->call-forward] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [call-forward] destination_number(306) =~ /^\*72(\d*)$/ break=on-true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [call-forward] destination_number(306) =~ /^\*73$/ break=on-true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [call-forward] destination_number(306) =~ /^\*74$/ break=on-true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [call-forward] destination_number(306) =~ /^forward\+(\Q+17862369083\E)(?:\/(\d+))?$/ break=on-true
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->call forward all] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [call forward all] ${user_exists}(true) =~ /^true/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [call forward all] ${forward_all_enabled}() =~ /^true/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->call forward not registered] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [call forward not registered] ${user_exists}(true) =~ /true/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [call forward not registered] ${forward_user_not_registered_enabled}() =~ /true/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->follow-me] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [follow-me] destination_number(306) =~ /^\*21$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->talking clock date and time] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [talking clock date and time] destination_number(306) =~ /^\*9172$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->clear_sip_auto_answer] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->talking clock time] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [talking clock time] destination_number(306) =~ /^\*9170$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->talking clock date] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (FAIL) [talking clock date] destination_number(306) =~ /^\*9171$/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->local_extension] continue=true
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [local_extension] ${user_exists}(true) =~ /true/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg Action export(dialed_extension=${destination_number}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg export(dialed_extension=306)
2021-08-06 13:10:39.602565 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[306]
Dialplan: sofia/internal/+17862369083@fl.gg Action limit(hash ${domain_name} ${destination_number} ${limit_max} ${limit_destination})
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [local_extension] () =~ // break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg Action set(hangup_after_bridge=true)
Dialplan: sofia/internal/+17862369083@fl.gg Action set(continue_on_fail=true)
Dialplan: sofia/internal/+17862369083@fl.gg Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
Dialplan: sofia/internal/+17862369083@fl.gg Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
Dialplan: sofia/internal/+17862369083@fl.gg Action set(called_party_call_group=${user_data(${dialed_extension}@${domain_name} var call_group)})
Dialplan: sofia/internal/+17862369083@fl.gg Action hash(insert/${domain_name}-last_dial/${called_party_call_group}/${uuid})
Dialplan: sofia/internal/+17862369083@fl.gg Action set(api_hangup_hook=lua app.lua hangup)
Dialplan: sofia/internal/+17862369083@fl.gg Action export(domain_name=${domain_name})
Dialplan: sofia/internal/+17862369083@fl.gg Action bridge(user/${destination_number}@${domain_name})
Dialplan: sofia/internal/+17862369083@fl.gg Action lua(app.lua failure_handler)
Dialplan: sofia/internal/+17862369083@fl.gg parsing [18775914444.phones.somedomain.com->voicemail] continue=false
Dialplan: sofia/internal/+17862369083@fl.gg Regex (PASS) [voicemail] ${user_exists}(true) =~ /true/ break=on-false
Dialplan: sofia/internal/+17862369083@fl.gg Action set(record_append=false) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(record_append=false)
2021-08-06 13:10:39.602565 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [record_append]=[false]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(voicemail_action=save) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(voicemail_action=save)
2021-08-06 13:10:39.602565 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [voicemail_action]=[save]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(voicemail_id=${destination_number}) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(voicemail_id=306)
2021-08-06 13:10:39.602565 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [voicemail_id]=[306]
Dialplan: sofia/internal/+17862369083@fl.gg Action set(voicemail_profile=default) INLINE
EXECUTE sofia/internal/+17862369083@fl.gg set(voicemail_profile=default)
2021-08-06 13:10:39.602565 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [voicemail_profile]=[default]
Dialplan: sofia/internal/+17862369083@fl.gg Action lua(app.lua voicemail)
2021-08-06 13:10:39.602565 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/+17862369083@fl.gg) State Change CS_ROUTING -> CS_EXECUTE
2021-08-06 13:10:39.602565 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/+17862369083@fl.gg) State ROUTING going to sleep
2021-08-06 13:10:39.602565 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/+17862369083@fl.gg) Running State Change CS_EXECUTE (Cur 1 Tot 266)
2021-08-06 13:10:39.602565 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/+17862369083@fl.gg) State EXECUTE
2021-08-06 13:10:39.602565 [DEBUG] mod_sofia.c:198 sofia/internal/+17862369083@fl.gg SOFIA EXECUTE
2021-08-06 13:10:39.602565 [DEBUG] switch_core_state_machine.c:328 sofia/internal/+17862369083@fl.gg Standard EXECUTE
EXECUTE sofia/internal/+17862369083@fl.gg set(RFC2822_DATE=Fri, 06 Aug 2021 13:10:39 -0400)
2021-08-06 13:10:39.602565 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [RFC2822_DATE]=[Fri, 06 Aug 2021 13:10:39 -0400]
EXECUTE sofia/internal/+17862369083@fl.gg export(origination_callee_id_name=306)
2021-08-06 13:10:39.602565 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[306]
EXECUTE sofia/internal/+17862369083@fl.gg hash(insert/18775914444.phones.somedomain.com-last_dial/+17862369083/306)
EXECUTE sofia/internal/+17862369083@fl.gg eval(not_secure)
EXECUTE sofia/internal/+17862369083@fl.gg limit(hash 18775914444.phones.somedomain.com 306 )
2021-08-06 13:10:39.602565 [DEBUG] switch_limit.c:126 incr called: 18775914444.phones.somedomain.com_306 max:-1, interval:0
2021-08-06 13:10:39.602565 [DEBUG] mod_hash.c:194 Usage for 18775914444.phones.somedomain.com_306 is now 1
EXECUTE sofia/internal/+17862369083@fl.gg set(hangup_after_bridge=true)
2021-08-06 13:10:39.602565 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [hangup_after_bridge]=[true]
EXECUTE sofia/internal/+17862369083@fl.gg set(continue_on_fail=true)
2021-08-06 13:10:39.602565 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [continue_on_fail]=[true]
EXECUTE sofia/internal/+17862369083@fl.gg hash(insert/18775914444.phones.somedomain.com-call_return/306/+17862369083)
EXECUTE sofia/internal/+17862369083@fl.gg hash(insert/18775914444.phones.somedomain.com-last_dial_ext/306/6e33019a-c220-40b7-9981-c1c39da8d918)
EXECUTE sofia/internal/+17862369083@fl.gg set(called_party_call_group=)
2021-08-06 13:10:39.622574 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [called_party_call_group]=[UNDEF]
EXECUTE sofia/internal/+17862369083@fl.gg hash(insert/18775914444.phones.somedomain.com-last_dial//6e33019a-c220-40b7-9981-c1c39da8d918)
EXECUTE sofia/internal/+17862369083@fl.gg set(api_hangup_hook=lua app.lua hangup)
2021-08-06 13:10:39.622574 [DEBUG] mod_dptools.c:1548 SET sofia/internal/+17862369083@fl.gg [api_hangup_hook]=[lua app.lua hangup]
EXECUTE sofia/internal/+17862369083@fl.gg export(domain_name=18775914444.phones.somedomain.com)
2021-08-06 13:10:39.622574 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [domain_name]=[18775914444.phones.somedomain.com]
EXECUTE sofia/internal/+17862369083@fl.gg bridge(user/306@18775914444.phones.somedomain.com)
2021-08-06 13:10:39.622574 [DEBUG] switch_channel.c:1250 sofia/internal/+17862369083@fl.gg EXPORTING[export_vars] [call_direction]=[inbound] to event
2021-08-06 13:10:39.622574 [DEBUG] switch_channel.c:1250 sofia/internal/+17862369083@fl.gg EXPORTING[export_vars] [call_direction]=[inbound] to event
2021-08-06 13:10:39.622574 [DEBUG] switch_channel.c:1250 sofia/internal/+17862369083@fl.gg EXPORTING[export_vars] [dialed_extension]=[306] to event
2021-08-06 13:10:39.622574 [DEBUG] switch_channel.c:1250 sofia/internal/+17862369083@fl.gg EXPORTING[export_vars] [origination_callee_id_name]=[306] to event
2021-08-06 13:10:39.622574 [DEBUG] switch_channel.c:1250 sofia/internal/+17862369083@fl.gg EXPORTING[export_vars] [domain_name]=[18775914444.phones.somedomain.com] to event
2021-08-06 13:10:39.622574 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
2021-08-06 13:10:39.622574 [DEBUG] switch_channel.c:1250 sofia/internal/+17862369083@fl.gg EXPORTING[export_vars] [call_direction]=[inbound] to event
2021-08-06 13:10:39.622574 [DEBUG] switch_channel.c:1250 sofia/internal/+17862369083@fl.gg EXPORTING[export_vars] [call_direction]=[inbound] to event
2021-08-06 13:10:39.622574 [DEBUG] switch_channel.c:1250 sofia/internal/+17862369083@fl.gg EXPORTING[export_vars] [dialed_extension]=[306] to event
2021-08-06 13:10:39.622574 [DEBUG] switch_channel.c:1250 sofia/internal/+17862369083@fl.gg EXPORTING[export_vars] [origination_callee_id_name]=[306] to event
2021-08-06 13:10:39.622574 [DEBUG] switch_channel.c:1250 sofia/internal/+17862369083@fl.gg EXPORTING[export_vars] [domain_name]=[18775914444.phones.somedomain.com] to event
2021-08-06 13:10:39.622574 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
2021-08-06 13:10:39.622574 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/3474424bf24336e8@185.117.83.202:25080 [05678e65-e326-4015-8f2d-831958e777ad]
2021-08-06 13:10:39.622574 [DEBUG] mod_sofia.c:4819 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) State Change CS_NEW -> CS_INIT
2021-08-06 13:10:39.622574 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) Running State Change CS_INIT (Cur 2 Tot 267)
2021-08-06 13:10:39.622574 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) State INIT
2021-08-06 13:10:39.622574 [DEBUG] mod_sofia.c:90 sofia/internal/3474424bf24336e8@185.117.83.202:25080 SOFIA INIT
2021-08-06 13:10:39.622574 [DEBUG] sofia_glue.c:1264 sip:306@70.237.3.59:5060;lr;received=sip:185.117.83.202:25080 Setting proxy route to sofia/internal/3474424bf24336e8@185.117.83.202:25080
2021-08-06 13:10:39.622574 [DEBUG] sofia_glue.c:1295 sofia/internal/3474424bf24336e8@185.117.83.202:25080 sending invite version: 1.6.20 -37-987c9b9 64bit
Local SDP:
v=0
o=FreeSWITCH 1628241693 1628241694 IN IP4 10.21.10.4
s=FreeSWITCH
c=IN IP4 10.21.10.4
t=0 0
m=audio 28146 RTP/AVP 0 8 9 3 101 13 103 104
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:103 telephone-event/48000
a=fmtp:103 0-16
a=rtpmap:13 CN/8000
a=rtpmap:104 CN/48000
a=ptime:20
a=sendrecv
m=audio 28146 RTP/AVP 102 101 13 103 104
a=rtpmap:102 opus/48000/2
a=fmtp:102 useinbandfec=1; maxaveragebitrate=20000; maxplaybackrate=16000; sprop-maxcapturerate=16000; ptime=40; minptime=10; maxptime=60
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:103 telephone-event/48000
a=fmtp:103 0-16
a=rtpmap:13 CN/8000
a=rtpmap:104 CN/48000
a=ptime:40
a=sendrecv

2021-08-06 13:10:39.622574 [DEBUG] switch_core_state_machine.c:40 sofia/internal/3474424bf24336e8@185.117.83.202:25080 Standard INIT
2021-08-06 13:10:39.622574 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) State Change CS_INIT -> CS_ROUTING
2021-08-06 13:10:39.622574 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) State INIT going to sleep
2021-08-06 13:10:39.622574 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) Running State Change CS_ROUTING (Cur 2 Tot 267)
2021-08-06 13:10:39.642572 [DEBUG] sofia.c:7084 Channel sofia/internal/3474424bf24336e8@185.117.83.202:25080 entering state [calling][0]
2021-08-06 13:10:39.642572 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) State ROUTING
2021-08-06 13:10:39.642572 [DEBUG] mod_sofia.c:143 sofia/internal/3474424bf24336e8@185.117.83.202:25080 SOFIA ROUTING
2021-08-06 13:10:39.642572 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2021-08-06 13:10:39.642572 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) State ROUTING going to sleep
2021-08-06 13:10:39.642572 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 267)
2021-08-06 13:10:39.642572 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) State CONSUME_MEDIA
2021-08-06 13:10:39.642572 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) State CONSUME_MEDIA going to sleep
2021-08-06 13:10:39.642572 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/+17862369083@18775914444.phones.somedomain.com [76737689-7f83-4373-aeef-17ede35a6e6f]
2021-08-06 13:10:39.642572 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/+17862369083@18775914444.phones.somedomain.com) Running State Change CS_NEW (Cur 3 Tot 268)
2021-08-06 13:10:39.642572 [DEBUG] sofia.c:9873 sofia/internal/+17862369083@18775914444.phones.somedomain.com receiving invite from 70.237.3.59:5060 version: 1.6.20 -37-987c9b9 64bit
2021-08-06 13:10:39.642572 [DEBUG] sofia.c:10044 IP 70.237.3.59Rejected by acl "domains". Falling back to Digest auth.
2021-08-06 13:10:39.642572 [DEBUG] sofia.c:2334 detaching session 76737689-7f83-4373-aeef-17ede35a6e6f
2021-08-06 13:10:39.642572 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'internal' for [3474424bf24336e8@185.117.83.202] from ip 70.237.3.59
2021-08-06 13:10:39.642572 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/+17862369083@18775914444.phones.somedomain.com) State NEW
2021-08-06 13:10:39.642572 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fef0833f3d0 Connected.
2021-08-06 13:10:39.642572 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fef0833f3d0 released.
2021-08-06 13:10:39.642572 [ERR] sofia_reg.c:2616 Cannot locate any authentication credentials to complete an authentication request for realm '"185.117.83.202"'
2021-08-06 13:10:39.642572 [NOTICE] sofia_reg.c:2639 Hangup sofia/internal/3474424bf24336e8@185.117.83.202:25080 [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
2021-08-06 13:10:39.642572 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) Running State Change CS_HANGUP (Cur 3 Tot 268)
2021-08-06 13:10:39.662576 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) Callstate Change DOWN -> HANGUP
2021-08-06 13:10:39.662576 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) State HANGUP
2021-08-06 13:10:39.662576 [DEBUG] mod_sofia.c:438 Channel sofia/internal/3474424bf24336e8@185.117.83.202:25080 hanging up, cause: MANDATORY_IE_MISSING
2021-08-06 13:10:39.662576 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/internal/3474424bf24336e8@185.117.83.202:25080
2021-08-06 13:10:39.662576 [DEBUG] switch_core_state_machine.c:60 sofia/internal/3474424bf24336e8@185.117.83.202:25080 Standard HANGUP, cause: MANDATORY_IE_MISSING
2021-08-06 13:10:39.662576 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) State HANGUP going to sleep
2021-08-06 13:10:39.662576 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) State Change CS_HANGUP -> CS_REPORTING
2021-08-06 13:10:39.662576 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) Running State Change CS_REPORTING (Cur 3 Tot 268)
2021-08-06 13:10:39.662576 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) State REPORTING
2021-08-06 13:10:39.662576 [DEBUG] switch_core_state_machine.c:174 sofia/internal/3474424bf24336e8@185.117.83.202:25080 Standard REPORTING, cause: MANDATORY_IE_MISSING
2021-08-06 13:10:39.662576 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) State REPORTING going to sleep
2021-08-06 13:10:39.662576 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) State Change CS_REPORTING -> CS_DESTROY
2021-08-06 13:10:39.662576 [DEBUG] switch_core_session.c:1665 Session 267 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) Locked, Waiting on external entities
2021-08-06 13:10:39.662576 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 96 [MANDATORY_IE_MISSING]
2021-08-06 13:10:39.662576 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [user] cause: [MANDATORY_IE_MISSING]
2021-08-06 13:10:39.662576 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 96 [MANDATORY_IE_MISSING]
2021-08-06 13:10:39.662576 [INFO] mod_dptools.c:3436 Originate Failed. Cause: MANDATORY_IE_MISSING
EXECUTE sofia/internal/+17862369083@fl.gg lua(app.lua failure_handler)
2021-08-06 13:10:39.662576 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fef0833f3d0 Connected.
2021-08-06 13:10:39.662576 [NOTICE] switch_core_session.c:1683 Session 267 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) Ended
2021-08-06 13:10:39.662576 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/3474424bf24336e8@185.117.83.202:25080 [CS_DESTROY]
2021-08-06 13:10:39.662576 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) Running State Change CS_DESTROY (Cur 2 Tot 268)
2021-08-06 13:10:39.662576 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) State DESTROY
2021-08-06 13:10:39.662576 [DEBUG] mod_sofia.c:343 sofia/internal/3474424bf24336e8@185.117.83.202:25080 SOFIA DESTROY
2021-08-06 13:10:39.662576 [DEBUG] switch_core_state_machine.c:181 sofia/internal/3474424bf24336e8@185.117.83.202:25080 Standard DESTROY
2021-08-06 13:10:39.662576 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/3474424bf24336e8@185.117.83.202:25080) State DESTROY going to sleep
2021-08-06 13:10:39.682569 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fef0833f3d0 released.
2021-08-06 13:10:39.682569 [DEBUG] switch_cpp.cpp:1112 sofia/internal/+17862369083@fl.gg destroy/unlink session from object
EXECUTE sofia/internal/+17862369083@fl.gg lua(app.lua voicemail)
2021-08-06 13:10:39.682569 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fef0833f3d0 Connected.
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:40:0:1]
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:40:0:1]
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1]
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:40:0:1]
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/+17862369083@fl.gg PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2021-08-06 13:10:39.782569 [DEBUG] switch_core_codec.c:111 sofia/internal/+17862369083@fl.gg Original read codec set to PCMU:0
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:4767 sofia/internal/+17862369083@fl.gg Set 2833 dtmf send payload to 101 recv payload to 101
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/+17862369083@fl.gg] 10.21.10.4 port 30200 -> 10.21.10.5 port 16404 codec: 0 ms: 20
2021-08-06 13:10:39.782569 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:7180 sofia/internal/+17862369083@fl.gg Set 2833 dtmf send payload to 101
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:7187 sofia/internal/+17862369083@fl.gg Set 2833 dtmf receive payload to 101
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:7210 sofia/internal/+17862369083@fl.gg Set rtp dtmf delay to 40
2021-08-06 13:10:39.782569 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/+17862369083@fl.gg!
2021-08-06 13:10:39.782569 [DEBUG] switch_channel.c:3474 (sofia/internal/+17862369083@fl.gg) Callstate Change RINGING -> EARLY
2021-08-06 13:10:39.782569 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/+17862369083@fl.gg.
2021-08-06 13:10:39.782569 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/+17862369083@fl.gg:
v=0
o=FreeSWITCH 1628239639 1628239640 IN IP4 10.21.10.4
s=FreeSWITCH
c=IN IP4 10.21.10.4
t=0 0
m=audio 30200 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

2021-08-06 13:10:39.782569 [DEBUG] sofia.c:7084 Channel sofia/internal/+17862369083@fl.gg entering state [completed][200]
2021-08-06 13:10:39.782569 [NOTICE] switch_cpp.cpp:685 Channel [sofia/internal/+17862369083@fl.gg] has been answered
2021-08-06 13:10:39.782569 [DEBUG] switch_channel.c:3773 (sofia/internal/+17862369083@fl.gg) Callstate Change EARLY -> ACTIVE
2021-08-06 13:10:40.182565 [DEBUG] sofia.c:7084 Channel sofia/internal/+17862369083@fl.gg entering state [ready][200]
2021-08-06 13:10:40.242571 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
EXECUTE sofia/internal/+17862369083@fl.gg unbind_meta_app()
2021-08-06 13:10:40.782582 [INFO] switch_ivr_async.c:4072 UnBound A-Leg: ALL
2021-08-06 13:10:40.782582 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('playback_terminators', '#')
2021-08-06 13:10:40.802574 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('missed_call', 'true')
2021-08-06 13:10:40.802574 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('voicemail_answer_stamp', '2021-08-06 13:10:40')
2021-08-06 13:10:40.802574 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('voicemail_answer_epoch', '1628269840')
EXECUTE sofia/internal/+17862369083@fl.gg playback(silence_stream://200)
2021-08-06 13:10:40.802574 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
2021-08-06 13:10:40.982566 [DEBUG] switch_ivr_play_say.c:1942 done playing file silence_stream://200
2021-08-06 13:10:40.982566 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
2021-08-06 13:10:41.002567 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fef08435ce0 Connected.
2021-08-06 13:10:41.002567 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fef08435ce0 released.
2021-08-06 13:10:41.002567 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-person.wav] (en:en)
2021-08-06 13:10:41.002567 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
2021-08-06 13:10:42.342571 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-person.wav
2021-08-06 13:10:42.462582 [DEBUG] switch_ivr_play_say.c:250 Handle say:[306] (en:en)
2021-08-06 13:10:42.462582 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
freeswitch@hostedpbx01> /quit
jolim@hostedpbx01:~$
 
DigitalDaz

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,071
577
113
mrjoli021 said:
2021-08-06 13:10:39.642572 [DEBUG] sofia.c:9873 sofia/internal/+17862369083@18775914444.phones.somedomain.com receiving invite from 70.237.3.59:5060 version: 1.6.20 -37-987c9b9 64bit
2021-08-06 13:10:39.642572 [DEBUG] sofia.c:10044 IP 70.237.3.59Rejected by acl "domains". Falling back to Digest auth.
2021-08-06 13:10:39.642572 [DEBUG] sofia.c:2334 detaching session 76737689-7f83-4373-aeef-17ede35a6e6f
2021-08-06 13:10:39.642572 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'internal' for [3474424bf24336e8@185.117.83.202] from ip 70.237.3.59
Click to expand...
That is your problem ^^^^^^ No idea what is going on there. Is there some sort of forwarding going on or something?
 
M

mrjoli021

Member
Jul 20, 2017
133
2
18
47
The 70.237.3.59 is the IP of my kamailio box. That box is just a passthrough for public registrations. It just filters out unwanted traffic. I have other clients with the same setup in other domains and it works fine. Specifically this one is the one giving me issues. What options could I set on this domain and/or extensions to get this to work? If I add the acl to the domain, it doesn't work either.
 
M

mrjoli021

Member
Jul 20, 2017
133
2
18
47
If I remove the push notifications the phone is able to send and receive calls, but with push notifications on the phone does not receive calls. Any ideas on how to make push notifications to work?
 
DigitalDaz

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,071
577
113
My guess is that Kamailio is trying to reinvite for some reason, can you get a siptrace of that call from sngrep. The kamailio is using the wrong domain though for the digest auth by the looks of things.
 
M

mrjoli021

Member
Jul 20, 2017
133
2
18
47
Attached are both pcap's from the Freeswitch side and the kamailio side. This for an inbound call. Outbound has always worked.
 

Attachments

  • Archive 2.zip
    5.3 KB · Views: 3
DigitalDaz

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,071
577
113
If the kamailio is the .4, kamailio is auth challenging the freeswitch,

First thing I would do is switch that zoiper transport to TCP you are getting frag on the UDP
 
Status
Not open for further replies.
Top Bottom