.=============================================================.
|   _____              ______        _____ _____ ____ _   _   |
|  |  ___| __ ___  ___/ ___\ \      / /_ _|_   _/ ___| | | |  |
|  | |_ | '__/ _ \/ _ \___ \\ \ /\ / / | |  | || |   | |_| |  |
|  |  _|| | |  __/  __/___) |\ V  V /  | |  | || |___|  _  |  |
|  |_|  |_|  \___|\___|____/  \_/\_/  |___| |_| \____|_| |_|  |
|                                                             |
.=============================================================.
|   Anthony Minessale II, Michael Jerris, Brian West, Others  |
|   FreeSWITCH (http://www.freeswitch.org)                    |
|   Paypal Donations Appreciated: paypal@freeswitch.org       |
|   Brought to you by ClueCon http://www.cluecon.com/         |
.=============================================================.

.=======================================================================================================.
|    ____ _             ____                                                                            |
|   / ___| |_   _  ___ / ___|___  _ __                                                                  |
|  | |   | | | | |/ _ \ |   / _ \| '_ \                                                                 |
|  | |___| | |_| |  __/ |__| (_) | | | |                                                                |
|   \____|_|\__,_|\___|\____\___/|_| |_|                                                                |
|                                                                                                       |
|   _____    _            _                          ____             __                                |
|  |_   _|__| | ___ _ __ | |__   ___  _ __  _   _   / ___|___  _ __  / _| ___ _ __ ___ _ __   ___ ___   |
|    | |/ _ \ |/ _ \ '_ \| '_ \ / _ \| '_ \| | | | | |   / _ \| '_ \| |_ / _ \ '__/ _ \ '_ \ / __/ _ \  |
|    | |  __/ |  __/ |_) | | | | (_) | | | | |_| | | |__| (_) | | | |  _|  __/ | |  __/ | | | (_|  __/  |
|    |_|\___|_|\___| .__/|_| |_|\___/|_| |_|\__, |  \____\___/|_| |_|_|  \___|_|  \___|_| |_|\___\___|  |
|                  |_|                      |___/                                                       |
|   _____                           _                         _                                         |
|  | ____|_   _____ _ __ _   _     / \  _   _  __ _ _   _ ___| |_                                       |
|  |  _| \ \ / / _ \ '__| | | |   / _ \| | | |/ _` | | | / __| __|                                      |
|  | |___ \ V /  __/ |  | |_| |  / ___ \ |_| | (_| | |_| \__ \ |_                                       |
|  |_____| \_/ \___|_|   \__, | /_/   \_\__,_|\__, |\__,_|___/\__|                                      |
|                        |___/                |___/                                                     |
|                                       ____ _             ____                                         |
|  __      ____      ____      __      / ___| |_   _  ___ / ___|___  _ __         ___ ___  _ __ ___     |
|  \ \ /\ / /\ \ /\ / /\ \ /\ / /     | |   | | | | |/ _ \ |   / _ \| '_ \       / __/ _ \| '_ ` _ \    |
|   \ V  V /  \ V  V /  \ V  V /   _  | |___| | |_| |  __/ |__| (_) | | | |  _  | (_| (_) | | | | | |   |
|    \_/\_/    \_/\_/    \_/\_/   (_)  \____|_|\__,_|\___|\____\___/|_| |_| (_)  \___\___/|_| |_| |_|   |
|                                                                                                       |
.=======================================================================================================.
[m

2018-12-07 03:11:55.116652 [INFO] switch_core.c:2424 
FreeSWITCH Version 1.6.8+git~20160505T153832Z~99de0ad502~64bit (git 99de0ad 2016-05-05 15:38:32Z 64bit)

FreeSWITCH Started
Max Sessions [1000]
Session Rate [30]
SQL [Enabled]
2018-12-07 03:11:55.459162 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip1@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:11:55.479163 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip8@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:11:55.639162 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [TSaesp@saesp.cloudcom.com.br] from ip 177.139.155.205
2018-12-07 03:11:58.699160 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [TAbooh@abooh.cloudcom.com.br] from ip 187.116.80.8
2018-12-07 03:11:59.259166 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [TSingular@singular.cloudcom.com.br] from ip 187.116.80.8
2018-12-07 03:12:00.459165 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip3@54.233.223.179] from ip 177.189.208.91
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [NOTICE] switch_channel.c:1104 New Channel sofia/Default/11999683333@189.8.83.10:5998 [712b3f88-c863-40ee-b9ee-eb0394df72ea]
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [DEBUG] switch_core_state_machine.c:543 (sofia/Default/11999683333@189.8.83.10:5998) Running State Change CS_NEW
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [DEBUG] sofia.c:9376 sofia/Default/11999683333@189.8.83.10:5998 receiving invite from 189.8.83.10:5998 version: 1.6.8 git 99de0ad 2016-05-05 15:38:32Z 64bit
2018-12-07 03:12:18.239162 [DEBUG] sofia.c:9488 IP 189.8.83.10 Approved by acl "default[]". Access Granted.
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [DEBUG] sofia.c:6858 Channel sofia/Default/11999683333@189.8.83.10:5998 entering state [received][100]
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [DEBUG] sofia.c:6868 Remote SDP:
712b3f88-c863-40ee-b9ee-eb0394df72ea v=0
712b3f88-c863-40ee-b9ee-eb0394df72ea o=BN4000 687071 709988 IN IP4 189.8.83.10
712b3f88-c863-40ee-b9ee-eb0394df72ea s=-
712b3f88-c863-40ee-b9ee-eb0394df72ea c=IN IP4 189.8.83.10
712b3f88-c863-40ee-b9ee-eb0394df72ea t=0 0
712b3f88-c863-40ee-b9ee-eb0394df72ea m=audio 39088 RTP/AVP 8 0 101
712b3f88-c863-40ee-b9ee-eb0394df72ea a=rtpmap:101 telephone-event/8000
712b3f88-c863-40ee-b9ee-eb0394df72ea a=fmtp:101 0-15
712b3f88-c863-40ee-b9ee-eb0394df72ea a=ptime:20
712b3f88-c863-40ee-b9ee-eb0394df72ea a=X-vrzcap:vbd Ver=1 Mode=FaxPr ModemRtpRed=0
712b3f88-c863-40ee-b9ee-eb0394df72ea a=X-vrzcap:identification bin=DSR2898 Prot=mgcp App=MG
712b3f88-c863-40ee-b9ee-eb0394df72ea 
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [DEBUG] sofia.c:7227 (sofia/Default/11999683333@189.8.83.10:5998) State Change CS_NEW -> CS_INIT
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [DEBUG] switch_core_state_machine.c:562 (sofia/Default/11999683333@189.8.83.10:5998) State NEW
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [DEBUG] switch_core_state_machine.c:543 (sofia/Default/11999683333@189.8.83.10:5998) Running State Change CS_INIT
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [DEBUG] switch_core_state_machine.c:586 (sofia/Default/11999683333@189.8.83.10:5998) State INIT
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [DEBUG] mod_sofia.c:89 sofia/Default/11999683333@189.8.83.10:5998 SOFIA INIT
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [DEBUG] switch_core_state_machine.c:40 sofia/Default/11999683333@189.8.83.10:5998 Standard INIT
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [DEBUG] switch_core_state_machine.c:48 (sofia/Default/11999683333@189.8.83.10:5998) State Change CS_INIT -> CS_ROUTING
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [DEBUG] switch_core_state_machine.c:586 (sofia/Default/11999683333@189.8.83.10:5998) State INIT going to sleep
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [DEBUG] switch_core_state_machine.c:543 (sofia/Default/11999683333@189.8.83.10:5998) Running State Change CS_ROUTING
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [DEBUG] switch_channel.c:2249 (sofia/Default/11999683333@189.8.83.10:5998) Callstate Change DOWN -> RINGING
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [DEBUG] switch_core_state_machine.c:602 (sofia/Default/11999683333@189.8.83.10:5998) State ROUTING
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [DEBUG] mod_sofia.c:142 sofia/Default/11999683333@189.8.83.10:5998 SOFIA ROUTING
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [DEBUG] switch_core_state_machine.c:236 sofia/Default/11999683333@189.8.83.10:5998 Standard ROUTING
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.239162 [INFO] mod_dialplan_xml.c:637 Processing 11999683333 <11999683333>->551130740080 in context default
2018-12-07 03:12:18.259163 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fe430068200 Connected.
2018-12-07 03:12:18.259163 [DEBUG] switch_cpp.cpp:1360 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM system WHERE group_title IN ('global','opensips','callingcard')
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] [Dialplan] Dialed number : 551130740080
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] [Dialplan] Caller Id name / number  : 11999683333 / 11999683333
2018-12-07 03:12:18.279165 [DEBUG] switch_cpp.cpp:1360 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,B.id as accountid,B.number as account_code,B.did_cid_translation as did_cid_translation,A.number as  did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout FROM dids AS A,accounts AS B WHERE A.status=0 AND B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="551130740080" LIMIT 1
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] [Dialplan] Call direction : inbound
2018-12-07 03:12:18.279165 [DEBUG] switch_cpp.cpp:1360 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("189.8.83.10") BETWEEN(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) & 0xffffffff ^((0x1 <<(32 -  SUBSTRING_INDEX(`ip`, '/', -1))) -1 )) AND(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) |((0x100000000 >> SUBSTRING_INDEX(`ip`,'/', -1)) -1))  AND "551130740080"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
2018-12-07 03:12:18.279165 [NOTICE] switch_cpp.cpp:1360 [ASTPP] [Accountcode : 3243171778]
2018-12-07 03:12:18.279165 [DEBUG] switch_cpp.cpp:1360 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "3243171778" OR id="3243171778") AND status=0 AND deleted=0 AND (expiry >= '2018-12-07 05:12:18' OR expiry = '0000-00-00 00:00:00') limit 1
2018-12-07 03:12:18.279165 [DEBUG] switch_cpp.cpp:1360 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT * FROM packages  as P inner join package_patterns as PKGPTR on P.id = PKGPTR.package_id WHERE (patterns ='^551130740080.*' OR patterns ='^55113074008.*' OR patterns ='^5511307400.*' OR patterns ='^551130740.*' OR patterns ='^55113074.*' OR patterns ='^5511307.*' OR patterns ='^551130.*' OR patterns ='^55113.*' OR patterns ='^5511.*' OR patterns ='^551.*' OR patterns ='^55.*' OR patterns ='^5.*' OR patterns ='--') AND status = 0 AND pricelist_id = 5 ORDER BY LENGTH(PKGPTR.patterns) DESC LIMIT 1
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] =============== Account Information ===================
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] User id : 18
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] Account code : 3243171778
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] Balance : 10000
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] Ratecard id : 5
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] ========================================================
2018-12-07 03:12:18.279165 [DEBUG] switch_cpp.cpp:1360 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 5 AND status = 0
2018-12-07 03:12:18.279165 [DEBUG] switch_cpp.cpp:1360 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,B.id as accountid,B.number as account_code,B.did_cid_translation as did_cid_translation,A.number as  did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout FROM dids AS A,accounts AS B WHERE A.status=0 AND B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="551130740080" LIMIT 1
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 100
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] =============== Rates Information ===================
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] ID : 39
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] Code : ^.551130740080.*
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] Destination : Sao Paulo SP
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] Connectcost : 0.00000
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] Includedseconds : 4
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] Cost : 0.00000
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] Initial Increment : 30
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] Increment : 6
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] Accid : 18
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] ================================================================
2018-12-07 03:12:18.279165 [INFO] switch_cpp.cpp:1360 [ASTPP] Call Max length duration : 100 minutes
2018-12-07 03:12:18.279165 [DEBUG] switch_cpp.cpp:1360 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "73" OR id="73") AND status=0 AND deleted=0 AND (expiry >= '2018-12-07 05:12:18' OR expiry = '0000-00-00 00:00:00') limit 1
2018-12-07 03:12:18.299166 [DEBUG] switch_cpp.cpp:1360 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 5 AND status = 0
2018-12-07 03:12:18.299166 [DEBUG] switch_cpp.cpp:1360 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern ='^551130740080.*' OR pattern ='^55113074008.*' OR pattern ='^5511307400.*' OR pattern ='^551130740.*' OR pattern ='^55113074.*' OR pattern ='^5511307.*' OR pattern ='^551130.*' OR pattern ='^55113.*' OR pattern ='^5511.*' OR pattern ='^551.*' OR pattern ='^55.*' OR pattern ='^5.*' OR pattern ='--') AND status = 0 AND pricelist_id = 5  ORDER BY LENGTH(pattern) DESC,cost DESC LIMIT 1
2018-12-07 03:12:18.299166 [INFO] switch_cpp.cpp:1360 [ASTPP] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 100
2018-12-07 03:12:18.299166 [INFO] switch_cpp.cpp:1360 [ASTPP] =============== Rates Information ===================
2018-12-07 03:12:18.299166 [INFO] switch_cpp.cpp:1360 [ASTPP] ID : 515195
2018-12-07 03:12:18.299166 [INFO] switch_cpp.cpp:1360 [ASTPP] Code : ^55.*
2018-12-07 03:12:18.299166 [INFO] switch_cpp.cpp:1360 [ASTPP] Destination : DID Basix
2018-12-07 03:12:18.299166 [INFO] switch_cpp.cpp:1360 [ASTPP] Connectcost : 0.00000
2018-12-07 03:12:18.299166 [INFO] switch_cpp.cpp:1360 [ASTPP] Includedseconds : 3
2018-12-07 03:12:18.299166 [INFO] switch_cpp.cpp:1360 [ASTPP] Cost : 0.00000
2018-12-07 03:12:18.299166 [INFO] switch_cpp.cpp:1360 [ASTPP] Initial Increment : 30
2018-12-07 03:12:18.299166 [INFO] switch_cpp.cpp:1360 [ASTPP] Increment : 6
2018-12-07 03:12:18.299166 [INFO] switch_cpp.cpp:1360 [ASTPP] Accid : 18
2018-12-07 03:12:18.299166 [INFO] switch_cpp.cpp:1360 [ASTPP] ================================================================
2018-12-07 03:12:18.299166 [INFO] switch_cpp.cpp:1360 [ASTPP] [userinfo] Actual CustomerInfo XML:18
2018-12-07 03:12:18.299166 [INFO] switch_cpp.cpp:1360 [ASTPP] [userinfo] Userinfo XML:18
2018-12-07 03:12:18.299166 [INFO] switch_cpp.cpp:1360 [ASTPP] [userinfo] Actual CustomerInfo XML : 18
2018-12-07 03:12:18.299166 [WARNING] switch_cpp.cpp:1360 [ASTPP] [FSXMLINBOUND] Caller ID Translation Starts
2018-12-07 03:12:18.299166 [WARNING] switch_cpp.cpp:1360 [ASTPP] [FSXMLINBOUND] Caller ID Translation Ends
2018-12-07 03:12:18.299166 [DEBUG] switch_cpp.cpp:1360 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
<document type="freeswitch/xml">
<section name="dialplan" description="ASTPP Dialplan">
<context name="default">
<extension name="551130740080">
<condition field="destination_number" expression="551130740080">
<action application="set" data="effective_destination_number=551130740080"/>
<action application="sched_hangup" data="+6000 normal_clearing"/>
<action application="set" data="callstart=2018-12-07 05:12:18"/>
<action application="set" data="hangup_after_bridge=true"/>
<action application="set" data="continue_on_fail=!USER_BUSY"/>
<action application="set" data="account_id=18"/>
<action application="set" data="parent_id=0"/>
<action application="set" data="entity_id=3"/>
<action application="set" data="call_processed=internal"/>
<action application="set" data="call_direction=inbound"/>
<action application="set" data="accountname=América Net"/>
<action application="export" data="t38_passthru=true"/>
<action application="set" data="fax_enable_t38=true"/>
<action application="set" data="fax_enable_t38_request=true"/>
<action application="set" data="origination_rates_did=ID:39|CODE:^.551130740080.*|DESTINATION:Sao Paulo SP|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:4|COST:0.00000|INC:6|INITIALBLOCK:30|RATEGROUP:0|MARKUP:0|ACCID:73"/>
<action application="set" data="origination_rates=ID:515195|CODE:^55.*|DESTINATION:DID Basix|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:3|COST:0.00000|INC:6|INITIALBLOCK:30|RATEGROUP:5|MARKUP:0|ACCID:18"/>
<action application="set" data="original_caller_id_name=11999683333"/>
<action application="set" data="original_caller_id_number=11999683333"/>
<action application="set" data="effective_caller_id_name=11999683333"/>
<action application="set" data="effective_caller_id_number=11999683333"/>
<action application="set" data="receiver_accid=73"/>
<action application="limit" data="db 551130740080 did_551130740080 3 !SWITCH_CONGESTION"/>
<action application="set" data="calltype=SIP-DID"/>
<action application="bridge" data="{sip_contact_user=551130740080}[leg_timeout=120]sofia/${sofia_profile_name}/551130740080${regex(${sofia_contact(TSingular@${domain_name})}|^[^@]+(.*)|%1)}"/>
<condition field="${cond(${user_data TSingular@${domain_name} param vm-enabled} == true ? YES : NO)}" expression="^YES$">
<action application="answer"/>
<action application="export" data="voicemail_alternate_greet_id=551130740080"/>
<action application="voicemail" data="default $${domain_name} TSingular"/>
<anti-action application="hangup" data="${originate_disposition}"/>
</condition>
</condition>
</extension>
</context>
</section>
</document>
2018-12-07 03:12:18.299166 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fe430068200 released.
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 parsing [default->551130740080] continue=false
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Regex (PASS) [551130740080] destination_number(551130740080) =~ /551130740080/ break=on-false
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(effective_destination_number=551130740080)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action sched_hangup(+6000 normal_clearing)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(callstart=2018-12-07 05:12:18)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(hangup_after_bridge=true)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(continue_on_fail=!USER_BUSY)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(account_id=18)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(parent_id=0)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(entity_id=3)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(call_processed=internal)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(call_direction=inbound)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(accountname=América Net)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action export(t38_passthru=true)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(fax_enable_t38=true)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(fax_enable_t38_request=true)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(origination_rates_did=ID:39|CODE:^.551130740080.*|DESTINATION:Sao Paulo SP|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:4|COST:0.00000|INC:6|INITIALBLOCK:30|RATEGROUP:0|MARKUP:0|ACCID:73)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(origination_rates=ID:515195|CODE:^55.*|DESTINATION:DID Basix|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:3|COST:0.00000|INC:6|INITIALBLOCK:30|RATEGROUP:5|MARKUP:0|ACCID:18)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(original_caller_id_name=11999683333)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(original_caller_id_number=11999683333)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(effective_caller_id_name=11999683333)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(effective_caller_id_number=11999683333)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(receiver_accid=73)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action limit(db 551130740080 did_551130740080 3 !SWITCH_CONGESTION)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action set(calltype=SIP-DID)
712b3f88-c863-40ee-b9ee-eb0394df72ea Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Action bridge({sip_contact_user=551130740080}[leg_timeout=120]sofia/${sofia_profile_name}/551130740080${regex(${sofia_contact(TSingular@${domain_name})}|^[^@]+(.*)|%1)})
712b3f88-c863-40ee-b9ee-eb0394df72ea |--- Dialplan: Processing recursive conditions level:1 [551130740080_recur_1] require-nested=TRUE
712b3f88-c863-40ee-b9ee-eb0394df72ea |--- Dialplan: sofia/Default/11999683333@189.8.83.10:5998 Regex (FAIL) [551130740080_recur_1] ${cond(${user_data TSingular@${domain_name} param vm-enabled} == true ? YES : NO)}(NO) =~ /^YES$/ break=on-false
712b3f88-c863-40ee-b9ee-eb0394df72ea |--- Dialplan: sofia/Default/11999683333@189.8.83.10:5998 ANTI-Action hangup(${originate_disposition})
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.299166 [DEBUG] switch_core_state_machine.c:286 (sofia/Default/11999683333@189.8.83.10:5998) State Change CS_ROUTING -> CS_EXECUTE
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.299166 [DEBUG] switch_core_state_machine.c:602 (sofia/Default/11999683333@189.8.83.10:5998) State ROUTING going to sleep
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.299166 [DEBUG] switch_core_state_machine.c:543 (sofia/Default/11999683333@189.8.83.10:5998) Running State Change CS_EXECUTE
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.299166 [DEBUG] switch_core_state_machine.c:609 (sofia/Default/11999683333@189.8.83.10:5998) State EXECUTE
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.299166 [DEBUG] mod_sofia.c:197 sofia/Default/11999683333@189.8.83.10:5998 SOFIA EXECUTE
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.299166 [DEBUG] switch_core_state_machine.c:328 sofia/Default/11999683333@189.8.83.10:5998 Standard EXECUTE
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(effective_destination_number=551130740080)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.299166 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [effective_destination_number]=[551130740080]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 sched_hangup(+6000 normal_clearing)
2018-12-07 03:12:18.299166 [DEBUG] switch_scheduler.c:249 Added task 3 switch_ivr_schedule_hangup (712b3f88-c863-40ee-b9ee-eb0394df72ea) to run at 1544165538
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(callstart=2018-12-07 05:12:18)
2018-12-07 03:12:18.319164 [CONSOLE] sofia_presence.c:1618 Event Thread Started
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [callstart]=[2018-12-07 05:12:18]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(hangup_after_bridge=true)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [hangup_after_bridge]=[true]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(continue_on_fail=!USER_BUSY)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [continue_on_fail]=[!USER_BUSY]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(account_id=18)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [account_id]=[18]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(parent_id=0)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [parent_id]=[0]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(entity_id=3)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [entity_id]=[3]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(call_processed=internal)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [call_processed]=[internal]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(call_direction=inbound)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [call_direction]=[inbound]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(accountname=América Net)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [accountname]=[América Net]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 export(t38_passthru=true)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [t38_passthru]=[true]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(fax_enable_t38=true)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [fax_enable_t38]=[true]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(fax_enable_t38_request=true)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [fax_enable_t38_request]=[true]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(origination_rates_did=ID:39|CODE:^.551130740080.*|DESTINATION:Sao Paulo SP|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:4|COST:0.00000|INC:6|INITIALBLOCK:30|RATEGROUP:0|MARKUP:0|ACCID:73)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [origination_rates_did]=[ID:39|CODE:^.551130740080.*|DESTINATION:Sao Paulo SP|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:4|COST:0.00000|INC:6|INITIALBLOCK:30|RATEGROUP:0|MARKUP:0|ACCID:73]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(origination_rates=ID:515195|CODE:^55.*|DESTINATION:DID Basix|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:3|COST:0.00000|INC:6|INITIALBLOCK:30|RATEGROUP:5|MARKUP:0|ACCID:18)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [origination_rates]=[ID:515195|CODE:^55.*|DESTINATION:DID Basix|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:3|COST:0.00000|INC:6|INITIALBLOCK:30|RATEGROUP:5|MARKUP:0|ACCID:18]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(original_caller_id_name=11999683333)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [original_caller_id_name]=[11999683333]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(original_caller_id_number=11999683333)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [original_caller_id_number]=[11999683333]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(effective_caller_id_name=11999683333)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [effective_caller_id_name]=[11999683333]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(effective_caller_id_number=11999683333)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [effective_caller_id_number]=[11999683333]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(receiver_accid=73)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [receiver_accid]=[73]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 limit(db 551130740080 did_551130740080 3 !SWITCH_CONGESTION)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] switch_limit.c:126 incr called: 551130740080_did_551130740080 max:3, interval:0
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_db.c:194 Usage for 551130740080_did_551130740080 is now 1/3
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 set(calltype=SIP-DID)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_dptools.c:1519 SET sofia/Default/11999683333@189.8.83.10:5998 [calltype]=[SIP-DID]
712b3f88-c863-40ee-b9ee-eb0394df72ea EXECUTE sofia/Default/11999683333@189.8.83.10:5998 bridge({sip_contact_user=551130740080}[leg_timeout=120]sofia/Default/551130740080)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] switch_channel.c:1250 sofia/Default/11999683333@189.8.83.10:5998 EXPORTING[export_vars] [t38_passthru]=[true] to event
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] switch_ivr_originate.c:2580 Parsing session specific variables
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [WARNING] mod_sofia.c:4658 Cannot locate registered user 551130740080@Default
27f40c21-dd3a-4446-842a-3282af2ca3bd 2018-12-07 03:12:18.319164 [NOTICE] mod_sofia.c:4912 Close Channel N/A [CS_NEW]
27f40c21-dd3a-4446-842a-3282af2ca3bd 2018-12-07 03:12:18.319164 [DEBUG] switch_core_state_machine.c:700 () Running State Change CS_DESTROY
27f40c21-dd3a-4446-842a-3282af2ca3bd 2018-12-07 03:12:18.319164 [DEBUG] switch_core_state_machine.c:710 (N/A) State DESTROY
27f40c21-dd3a-4446-842a-3282af2ca3bd 2018-12-07 03:12:18.319164 [DEBUG] mod_sofia.c:342 N/A SOFIA DESTROY
27f40c21-dd3a-4446-842a-3282af2ca3bd 2018-12-07 03:12:18.319164 [DEBUG] switch_core_state_machine.c:710 (N/A) State DESTROY going to sleep
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [NOTICE] switch_ivr_originate.c:2762 Cannot create outgoing channel of type [sofia] cause: [USER_NOT_REGISTERED]
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] switch_ivr_originate.c:3750 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED]
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [INFO] mod_dptools.c:3401 Originate Failed.  Cause: USER_NOT_REGISTERED
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [NOTICE] switch_channel.c:4820 Hangup sofia/Default/11999683333@189.8.83.10:5998 [CS_EXECUTE] [USER_NOT_REGISTERED]
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] switch_core_session.c:2796 sofia/Default/11999683333@189.8.83.10:5998 skip receive message [PHONE_EVENT] (channel is hungup already)
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] switch_core_state_machine.c:609 (sofia/Default/11999683333@189.8.83.10:5998) State EXECUTE going to sleep
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] switch_core_state_machine.c:543 (sofia/Default/11999683333@189.8.83.10:5998) Running State Change CS_HANGUP
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] switch_core_state_machine.c:809 (sofia/Default/11999683333@189.8.83.10:5998) Callstate Change RINGING -> HANGUP
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] switch_core_state_machine.c:811 (sofia/Default/11999683333@189.8.83.10:5998) State HANGUP
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_sofia.c:437 Channel sofia/Default/11999683333@189.8.83.10:5998 hanging up, cause: USER_NOT_REGISTERED
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] mod_sofia.c:574 Responding to INVITE with: 480
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] switch_core_state_machine.c:60 sofia/Default/11999683333@189.8.83.10:5998 Standard HANGUP, cause: USER_NOT_REGISTERED
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] switch_core_state_machine.c:811 (sofia/Default/11999683333@189.8.83.10:5998) State HANGUP going to sleep
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] switch_core_state_machine.c:578 (sofia/Default/11999683333@189.8.83.10:5998) State Change CS_HANGUP -> CS_REPORTING
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] switch_core_state_machine.c:543 (sofia/Default/11999683333@189.8.83.10:5998) Running State Change CS_REPORTING
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [DEBUG] switch_core_state_machine.c:897 (sofia/Default/11999683333@189.8.83.10:5998) State REPORTING
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.319164 [INFO] mod_json_cdr.c:271 Process [712b3f88-c863-40ee-b9ee-eb0394df72ea.cdr.json]
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.339164 [DEBUG] switch_core_state_machine.c:174 sofia/Default/11999683333@189.8.83.10:5998 Standard REPORTING, cause: USER_NOT_REGISTERED
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.339164 [DEBUG] switch_core_state_machine.c:897 (sofia/Default/11999683333@189.8.83.10:5998) State REPORTING going to sleep
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.339164 [DEBUG] switch_core_state_machine.c:569 (sofia/Default/11999683333@189.8.83.10:5998) State Change CS_REPORTING -> CS_DESTROY
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.339164 [DEBUG] switch_core_session.c:1646 Session 1 (sofia/Default/11999683333@189.8.83.10:5998) Locked, Waiting on external entities
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.339164 [NOTICE] switch_core_session.c:1664 Session 1 (sofia/Default/11999683333@189.8.83.10:5998) Ended
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.339164 [NOTICE] switch_core_session.c:1668 Close Channel sofia/Default/11999683333@189.8.83.10:5998 [CS_DESTROY]
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.339164 [DEBUG] switch_core_state_machine.c:700 (sofia/Default/11999683333@189.8.83.10:5998) Running State Change CS_DESTROY
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.339164 [DEBUG] switch_core_state_machine.c:710 (sofia/Default/11999683333@189.8.83.10:5998) State DESTROY
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.339164 [DEBUG] mod_sofia.c:342 sofia/Default/11999683333@189.8.83.10:5998 SOFIA DESTROY
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.339164 [DEBUG] switch_core_state_machine.c:181 sofia/Default/11999683333@189.8.83.10:5998 Standard DESTROY
712b3f88-c863-40ee-b9ee-eb0394df72ea 2018-12-07 03:12:18.339164 [DEBUG] switch_core_state_machine.c:710 (sofia/Default/11999683333@189.8.83.10:5998) State DESTROY going to sleep
2018-12-07 03:12:18.819170 [DEBUG] switch_scheduler.c:144 Deleting task 3 switch_ivr_schedule_hangup (712b3f88-c863-40ee-b9ee-eb0394df72ea)
2018-12-07 03:12:43.639159 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip1@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:12:43.739160 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip8@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:12:48.699159 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip3@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:12:57.399166 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip6@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:12:57.399166 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip2@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:12:57.399166 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip7@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:12:57.399166 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip4@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:12:57.399166 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip5@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:12:57.399166 [WARNING] sofia.c:5912 Ping succeeded 551130701112 with code 200 - count 1/1/1, state UP
2018-12-07 03:13:31.899172 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip1@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:13:31.999171 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip8@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:13:36.919164 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip3@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:13:45.599164 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip6@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:13:45.699165 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip2@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:13:45.819165 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip7@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:13:45.919165 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip4@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:13:46.019159 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip5@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:14:17.619163 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [TSingular@54.233.223.179] from ip 186.220.88.2
2018-12-07 03:14:20.159161 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip1@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:14:20.259166 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip8@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:14:25.159194 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip3@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:14:33.839165 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip6@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:14:33.939167 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip2@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:14:34.039170 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip7@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:14:34.159168 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip4@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:14:34.259163 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip5@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:15:08.399166 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip1@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:15:08.539168 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip8@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:15:12.659167 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [TSingular@54.233.223.179] from ip 186.220.88.2
2018-12-07 03:15:13.379167 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip3@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:15:22.059176 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip6@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:15:22.199183 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip2@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:15:22.279180 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip7@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:15:22.419169 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip4@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:15:22.519163 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip5@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:15:41.619162 [ERR] mod_xml_curl.c:315 Received HTTP error 500 trying to fetch http://localhost:8735/index.php
data: [hostname=ip-172-31-6-59&section=directory&tag_name=domain&key_name=name&key_value=172.31.6.59&Event-Name=REQUEST_PARAMS&Core-UUID=d2b3e759-0a38-454b-a2c5-ae2d057f5e69&FreeSWITCH-Hostname=ip-172-31-6-59&FreeSWITCH-Switchname=ip-172-31-6-59&FreeSWITCH-IPv4=172.31.6.59&FreeSWITCH-IPv6=%3A%3A1&Event-Date-Local=2018-12-07%2003%3A15%3A41&Event-Date-GMT=Fri,%2007%20Dec%202018%2005%3A15%3A41%20GMT&Event-Date-Timestamp=1544159741619162&Event-Calling-File=sofia_reg.c&Event-Calling-Function=sofia_reg_parse_auth&Event-Calling-Line-Number=2803&Event-Sequence=916&action=sip_auth&sip_profile=Default&sip_user_agent=Asterisk%20PBX%2011.23.1&sip_auth_username=TSaesp&sip_auth_realm=saesp.cloudcom.com.br&sip_auth_nonce=f4123822-5ebf-4681-9b43-c90cebabff1f&sip_auth_uri=sip%3Asaesp.cloudcom.com.br&sip_contact_user=TSaesp&sip_contact_host=201.83.11.186&sip_to_user=TSaesp&sip_to_host=saesp.cloudcom.com.br&sip_via_protocol=udp&sip_from_user=TSaesp&sip_from_host=saesp.cloudcom.com.br&sip_call_id=230ac3cd1086ef621bc092464532baae%40172.16.50.10&sip_request_host=saesp.cloudcom.com.br&sip_auth_qop=auth&sip_auth_cnonce=5576396f&sip_auth_nc=00000006&sip_auth_response=279a33544e790e101096276bb54ba8c4&sip_auth_method=REGISTER&client_port=1024&key=id&user=TSaesp&domain=172.31.6.59&ip=177.139.155.205]
2018-12-07 03:15:41.639158 [WARNING] sofia_reg.c:2889 Can't find user [TSaesp@172.31.6.59] from 177.139.155.205
You must define a domain called '172.31.6.59' in your directory and add a user with the id="TSaesp" attribute
and you must configure your device to use the proper domain in it's authentication credentials.
2018-12-07 03:15:41.639158 [WARNING] sofia_reg.c:1720 SIP auth failure (REGISTER) on sofia profile 'Default' for [TSaesp@saesp.cloudcom.com.br] from ip 177.139.155.205
2018-12-07 03:15:44.859165 [ERR] mod_xml_curl.c:315 Received HTTP error 404 trying to fetch http://localhost:8735/index.php
data: [hostname=ip-172-31-6-59&section=directory&tag_name=domain&key_name=name&key_value=172.31.6.59&Event-Name=REQUEST_PARAMS&Core-UUID=d2b3e759-0a38-454b-a2c5-ae2d057f5e69&FreeSWITCH-Hostname=ip-172-31-6-59&FreeSWITCH-Switchname=ip-172-31-6-59&FreeSWITCH-IPv4=172.31.6.59&FreeSWITCH-IPv6=%3A%3A1&Event-Date-Local=2018-12-07%2003%3A15%3A44&Event-Date-GMT=Fri,%2007%20Dec%202018%2005%3A15%3A44%20GMT&Event-Date-Timestamp=1544159744859165&Event-Calling-File=sofia_reg.c&Event-Calling-Function=sofia_reg_parse_auth&Event-Calling-Line-Number=2803&Event-Sequence=921&action=sip_auth&sip_profile=Default&sip_user_agent=Asterisk%20PBX%2011.23.1&sip_auth_username=TAbooh&sip_auth_realm=abooh.cloudcom.com.br&sip_auth_nonce=2fba061f-3620-4e01-b964-3ecdfb9f0d18&sip_auth_uri=sip%3Aabooh.cloudcom.com.br&sip_contact_user=TAbooh&sip_contact_host=187.116.80.8&sip_to_user=TAbooh&sip_to_host=abooh.cloudcom.com.br&sip_via_protocol=udp&sip_from_user=TAbooh&sip_from_host=abooh.cloudcom.com.br&sip_call_id=770d820d2f2f12334569538953e094c8%4010.107.10.108&sip_request_host=abooh.cloudcom.com.br&sip_auth_qop=auth&sip_auth_cnonce=7ad783e4&sip_auth_nc=00000006&sip_auth_response=1032c1dacec68f8580f0270ae998f959&sip_auth_method=REGISTER&client_port=1024&key=id&user=TAbooh&domain=172.31.6.59&ip=187.116.80.8]
2018-12-07 03:15:44.859165 [WARNING] sofia_reg.c:2889 Can't find user [TAbooh@172.31.6.59] from 187.116.80.8
You must define a domain called '172.31.6.59' in your directory and add a user with the id="TAbooh" attribute
and you must configure your device to use the proper domain in it's authentication credentials.
2018-12-07 03:15:44.859165 [WARNING] sofia_reg.c:1720 SIP auth failure (REGISTER) on sofia profile 'Default' for [TAbooh@abooh.cloudcom.com.br] from ip 187.116.80.8
2018-12-07 03:15:50.479167 [ERR] mod_xml_curl.c:315 Received HTTP error 404 trying to fetch http://localhost:8735/index.php
data: [hostname=ip-172-31-6-59&section=directory&tag_name=domain&key_name=name&key_value=172.31.6.59&Event-Name=REQUEST_PARAMS&Core-UUID=d2b3e759-0a38-454b-a2c5-ae2d057f5e69&FreeSWITCH-Hostname=ip-172-31-6-59&FreeSWITCH-Switchname=ip-172-31-6-59&FreeSWITCH-IPv4=172.31.6.59&FreeSWITCH-IPv6=%3A%3A1&Event-Date-Local=2018-12-07%2003%3A15%3A50&Event-Date-GMT=Fri,%2007%20Dec%202018%2005%3A15%3A50%20GMT&Event-Date-Timestamp=1544159750479167&Event-Calling-File=sofia_reg.c&Event-Calling-Function=sofia_reg_parse_auth&Event-Calling-Line-Number=2803&Event-Sequence=924&action=sip_auth&sip_profile=Default&sip_user_agent=Asterisk%20PBX%2011.23.1&sip_auth_username=TSingular&sip_auth_realm=singular.cloudcom.com.br&sip_auth_nonce=b551a5d2-6ef2-4b70-bb15-7eb9bfc53eaa&sip_auth_uri=sip%3Asingular.cloudcom.com.br&sip_contact_user=TSingular&sip_contact_host=187.116.80.8&sip_to_user=TSingular&sip_to_host=singular.cloudcom.com.br&sip_via_protocol=udp&sip_from_user=TSingular&sip_from_host=singular.cloudcom.com.br&sip_call_id=424d119210a263441a3ab2732e129a19%4010.107.10.108&sip_request_host=singular.cloudcom.com.br&sip_auth_qop=auth&sip_auth_cnonce=191486f9&sip_auth_nc=00000006&sip_auth_response=d1b1e6749a422abc0332d6c973de0595&sip_auth_method=REGISTER&client_port=1024&key=id&user=TSingular&domain=172.31.6.59&ip=187.116.80.8]
2018-12-07 03:15:50.479167 [WARNING] sofia_reg.c:2889 Can't find user [TSingular@172.31.6.59] from 187.116.80.8
You must define a domain called '172.31.6.59' in your directory and add a user with the id="TSingular" attribute
and you must configure your device to use the proper domain in it's authentication credentials.
2018-12-07 03:15:50.479167 [WARNING] sofia_reg.c:1720 SIP auth failure (REGISTER) on sofia profile 'Default' for [TSingular@singular.cloudcom.com.br] from ip 187.116.80.8
2018-12-07 03:15:56.659161 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip1@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:15:56.759170 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip8@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:16:01.599171 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip3@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:16:07.699172 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [TSingular@54.233.223.179] from ip 186.220.88.2
2018-12-07 03:16:10.299168 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip6@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:16:10.539171 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip2@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:16:10.659162 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip7@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:16:10.759168 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip4@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:16:10.879230 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip5@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:16:44.919166 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip1@54.233.223.179] from ip 177.189.208.91
2018-12-07 03:16:44.999165 [WARNING] sofia_reg.c:1775 SIP auth challenge (REGISTER) on sofia profile 'Default' for [gsm1-chip8@54.233.223.179] from ip 177.189.208.91