span style="color: goldenrod; font-weight: bold;"> 2019-04-30 12:22:57.674080 [DEBUG] sofia.c:9873 sofia/default/18323352735@login.lugertel.com receiving invite from 166.173.61.47:22516 version: 1.6.19 git 7a77e0b 2017-07-13 12:01:45Z 64bit
2019-04-30 12:22:57.674080 [DEBUG] sofia.c:10044 IP 166.173.61.47 Rejected by acl "default". Falling back to Digest auth.
2019-04-30 12:22:57.694002 [DEBUG] sofia.c:7084 Channel sofia/default/18323352735@login.lugertel.com entering state [received][100]
2019-04-30 12:22:57.694002 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=- 3765633777 3765633777 IN IP4 10.199.189.217
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 0 8 96
c=IN IP4 10.199.189.217
b=TIAS:64000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-16
a=rtcp:4001 IN IP4 10.199.189.217
2019-04-30 12:22:57.694002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2019-04-30 12:22:57.694002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2019-04-30 12:22:57.694002 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2019-04-30 12:22:57.694002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2019-04-30 12:22:57.694002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2019-04-30 12:22:57.694002 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2019-04-30 12:22:57.694002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2019-04-30 12:22:57.694002 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2019-04-30 12:22:57.694002 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 96@8000
2019-04-30 12:22:57.694002 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/18323352735@login.lugertel.com PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2019-04-30 12:22:57.694002 [DEBUG] switch_core_codec.c:111 sofia/default/18323352735@login.lugertel.com Original read codec set to PCMU:0
2019-04-30 12:22:57.694002 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 96@8000
2019-04-30 12:22:57.694002 [DEBUG] switch_core_media.c:4767 sofia/default/18323352735@login.lugertel.com Set 2833 dtmf send payload to 96 recv payload to 96
2019-04-30 12:22:57.694002 [DEBUG] sofia.c:7507 (sofia/default/18323352735@login.lugertel.com) State Change CS_NEW -> CS_INIT
2019-04-30 12:22:57.694002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/18323352735@login.lugertel.com) Running State Change CS_INIT (Cur 1 Tot 123782)
2019-04-30 12:22:57.694002 [DEBUG] switch_core_state_machine.c:627 (sofia/default/18323352735@login.lugertel.com) State INIT
2019-04-30 12:22:57.694002 [DEBUG] mod_sofia.c:90 sofia/default/18323352735@login.lugertel.com SOFIA INIT
2019-04-30 12:22:57.694002 [DEBUG] switch_core_state_machine.c:40 sofia/default/18323352735@login.lugertel.com Standard INIT
2019-04-30 12:22:57.694002 [DEBUG] switch_core_state_machine.c:48 (sofia/default/18323352735@login.lugertel.com) State Change CS_INIT -> CS_ROUTING
2019-04-30 12:22:57.694002 [DEBUG] switch_core_state_machine.c:627 (sofia/default/18323352735@login.lugertel.com) State INIT going to sleep
2019-04-30 12:22:57.694002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/18323352735@login.lugertel.com) Running State Change CS_ROUTING (Cur 1 Tot 123782)
2019-04-30 12:22:57.694002 [DEBUG] switch_channel.c:2249 (sofia/default/18323352735@login.lugertel.com) Callstate Change DOWN -> RINGING
2019-04-30 12:22:57.694002 [DEBUG] switch_core_state_machine.c:643 (sofia/default/18323352735@login.lugertel.com) State ROUTING
2019-04-30 12:22:57.694002 [DEBUG] mod_sofia.c:143 sofia/default/18323352735@login.lugertel.com SOFIA ROUTING
2019-04-30 12:22:57.694002 [DEBUG] switch_core_state_machine.c:236 sofia/default/18323352735@login.lugertel.com Standard ROUTING
2019-04-30 12:22:57.694002 [INFO] mod_dialplan_xml.c:637 Processing 18323352735 <18323352735>->22549366911 in context default
2019-04-30 12:22:57.694002 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f82700602f0 Connected.
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM system WHERE group_title IN ('global','opensips','callingcard')
2019-04-30 12:22:57.694002 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [xml_handler] Params:
'Event-Name: REQUEST_PARAMS
Core-UUID: 0a2e92b8-66a3-11e9-b415-7bf171c29d1f
FreeSWITCH-Hostname: D6014.dallas-idc.com
FreeSWITCH-Switchname: D6014.dallas-idc.com
FreeSWITCH-IPv4: 74.63.227.206
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2019-04-30%2012%3A22%3A57
Event-Date-GMT: Tue,%2030%20Apr%202019%2017%3A22%3A57%20GMT
Event-Date-Timestamp: 1556644977694002
Event-Calling-File: mod_dialplan_xml.c
Event-Calling-Function: dialplan_xml_locate
Event-Calling-Line-Number: 608
Event-Sequence: 2713658
Channel-State: CS_ROUTING
Channel-Call-State: RINGING
Channel-State-Number: 2
Channel-Name: sofia/default/18323352735%40login.lugertel.com
Unique-ID: 98d307e2-6b6c-11e9-a1d4-7bf171c29d1f
Call-Direction: inbound
Presence-Call-Direction: inbound
Channel-HIT-Dialplan: true
Channel-Presence-ID: 18323352735%40login.lugertel.com
Channel-Call-UUID: 98d307e2-6b6c-11e9-a1d4-7bf171c29d1f
Answer-State: ringing
Channel-Read-Codec-Name: PCMU
Channel-Read-Codec-Rate: 8000
Channel-Read-Codec-Bit-Rate: 64000
Channel-Write-Codec-Name: PCMU
Channel-Write-Codec-Rate: 8000
Channel-Write-Codec-Bit-Rate: 64000
Caller-Direction: inbound
Caller-Logical-Direction: inbound
Caller-Username: 18323352735
Caller-Dialplan: XML
Caller-Caller-ID-Name: 18323352735
Caller-Caller-ID-Number: 18323352735
Caller-Orig-Caller-ID-Name: 18323352735
Caller-Orig-Caller-ID-Number: 18323352735
Caller-Network-Addr: 166.173.61.47
Caller-ANI: 18323352735
Caller-Destination-Number: 22549366911
Caller-Unique-ID: 98d307e2-6b6c-11e9-a1d4-7bf171c29d1f
Caller-Source: mod_sofia
Caller-Context: default
Caller-Channel-Name: sofia/default/18323352735%40login.lugertel.com
Caller-Profile-Index: 1
Caller-Profile-Created-Time: 1556644977694002
Caller-Channel-Created-Time: 1556644977694002
Caller-Channel-Answered-Time: 0
Caller-Channel-Progress-Time: 0
Caller-Channel-Progress-Media-Time: 0
Caller-Channel-Hangup-Time: 0
Caller-Channel-Transfer-Time: 0
Caller-Channel-Resurrect-Time: 0
Caller-Channel-Bridged-Time: 0
Caller-Channel-Last-Hold: 0
Caller-Channel-Hold-Accum: 0
Caller-Screen-Bit: true
Caller-Privacy-Hide-Name: false
Caller-Privacy-Hide-Number: false
variable_direction: inbound
variable_uuid: 98d307e2-6b6c-11e9-a1d4-7bf171c29d1f
variable_session_id: 123782
variable_sip_from_user: 18323352735
variable_sip_from_uri: 18323352735%40login.lugertel.com
variable_sip_from_host: login.lugertel.com
variable_video_media_flow: sendrecv
variable_channel_name: sofia/default/18323352735%40login.lugertel.com
variable_sip_call_id: WilUifZLM5niKvJty2Rs5SZbrLZmyFQ9
variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b
variable_sip_local_network_addr: 74.63.227.206
variable_sip_network_ip: 166.173.61.47
variable_sip_network_port: 22516
variable_sip_invite_stamp: 1556644977674080
variable_sip_received_ip: 166.173.61.47
variable_sip_received_port: 22516
variable_sip_via_protocol: udp
variable_sip_authorized: true
variable_Event-Name: REQUEST_PARAMS
variable_Core-UUID: 0a2e92b8-66a3-11e9-b415-7bf171c29d1f
variable_FreeSWITCH-Hostname: D6014.dallas-idc.com
variable_FreeSWITCH-Switchname: D6014.dallas-idc.com
variable_FreeSWITCH-IPv4: 74.63.227.206
variable_FreeSWITCH-IPv6: %3A%3A1
variable_Event-Date-Local: 2019-04-30%2012%3A22%3A57
variable_Event-Date-GMT: Tue,%2030%20Apr%202019%2017%3A22%3A57%20GMT
variable_Event-Date-Timestamp: 1556644977674080
variable_Event-Calling-File: sofia.c
variable_Event-Calling-Function: sofia_handle_sip_i_invite
variable_Event-Calling-Line-Number: 10096
variable_Event-Sequence: 2713649
variable_sip_number_alias: 18323352735
variable_sip_auth_username: 18323352735
variable_sip_auth_realm: login.lugertel.com
variable_number_alias: 18323352735
variable_requested_user_name: 18323352735
variable_requested_domain_name: 74.63.227.206
variable_effective_caller_id_name: Gerald%20Chenyi
variable_effective_caller_id_number: 18323352735
variable_sipcall: true
variable_accountcode: 18323352735
variable_user_name: 18323352735
variable_domain_name: 74.63.227.206
variable_sip_from_user_stripped: 18323352735
variable_sip_from_tag: gcC7bqMkkOcq5A8mh4oxFlqex0Q5nqQ7
variable_sofia_profile_name: default
variable_recovery_profile_name: default
variable_sip_full_via: SIP/2.0/UDP%20166.173.61.47%3A22516%3Brport%3D22516%3Bbranch%3Dz9hG4bKPje-YSWYjYo0svmWZA6ZKUxXRb8Cx0qLtN
variable_sip_full_from: sip%3A18323352735%40login.lugertel.com%3Btag%3DgcC7bqMkkOcq5A8mh4oxFlqex0Q5nqQ7
variable_sip_full_to: sip%3A22549366911%40login.lugertel.com
variable_sip_allow: PRACK,%20INVITE,%20ACK,%20BYE,%20CANCEL,%20UPDATE,%20INFO,%20SUBSCRIBE,%20NOTIFY,%20REFER,%20MESSAGE,%20OPTIONS
variable_sip_req_user: 22549366911
variable_sip_req_port: 5060
variable_sip_req_uri: 22549366911%40login.lugertel.com%3A5060
variable_sip_req_host: login.lugertel.com
variable_sip_to_user: 22549366911
variable_sip_to_uri: 22549366911%40login.lugertel.com
variable_sip_to_host: login.lugertel.com
variable_sip_contact_params: ob
variable_sip_contact_user: 18323352735
variable_sip_contact_port: 22516
variable_sip_contact_uri: 18323352735%40166.173.61.47%3A22516
variable_sip_contact_host: 166.173.61.47
variable_sip_user_agent: LugerTel
variable_sip_via_host: 166.173.61.47
variable_sip_via_port: 22516
variable_sip_via_rport: 22516
variable_max_forwards: 70
variable_presence_id: 18323352735%40login.lugertel.com
variable_switch_r_sdp: v%3D0%0D%0Ao%3D-%203765633777%203765633777%20IN%20IP4%2010.199.189.217%0D%0As%3Dpjmedia%0D%0Ab%3DAS%3A84%0D%0At%3D0%200%0D%0Aa%3DX-nat%3A0%0D%0Am%3Daudio%204000%20RTP/AVP%200%208%2096%0D%0Ac%3DIN%20IP4%2010.199.189.217%0D%0Ab%3DTIAS%3A64000%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A96%20telephone-event/8000%0D%0Aa%3Dfmtp%3A96%200-16%0D%0Aa%3Drtcp%3A4001%20IN%20IP4%2010.199.189.217%0D%0A
variable_rtp_use_codec_string: PCMA,PCMU,G729
variable_audio_media_flow: sendrecv
variable_rtp_remote_audio_rtcp_port: 4001
variable_remote_media_ip: 10.199.189.217
variable_remote_media_port: 4000
variable_rtp_audio_recv_pt: 0
variable_rtp_use_codec_name: PCMU
variable_rtp_use_codec_rate: 8000
variable_rtp_use_codec_ptime: 20
variable_rtp_use_codec_channels: 1
variable_rtp_last_audio_codec_string: PCMU%408000h%4020i%401c
variable_read_codec: PCMU
variable_original_read_codec: PCMU
variable_read_rate: 8000
variable_original_read_rate: 8000
variable_write_codec: PCMU
variable_write_rate: 8000
variable_dtmf_type: rfc2833
variable_endpoint_disposition: RECEIVED
variable_call_uuid: 98d307e2-6b6c-11e9-a1d4-7bf171c29d1f
Hunt-Direction: inbound
Hunt-Logical-Direction: inbound
Hunt-Username: 18323352735
Hunt-Dialplan: XML
Hunt-Caller-ID-Name: 18323352735
Hunt-Caller-ID-Number: 18323352735
Hunt-Orig-Caller-ID-Name: 18323352735
Hunt-Orig-Caller-ID-Number: 18323352735
Hunt-Network-Addr: 166.173.61.47
Hunt-ANI: 18323352735
Hunt-Destination-Number: 22549366911
Hunt-Unique-ID: 98d307e2-6b6c-11e9-a1d4-7bf171c29d1f
Hunt-Source: mod_sofia
Hunt-Context: default
Hunt-Channel-Name: sofia/default/18323352735%40login.lugertel.com
Hunt-Profile-Index: 1
Hunt-Profile-Created-Time: 1556644977694002
Hunt-Channel-Created-Time: 1556644977694002
Hunt-Channel-Answered-Time: 0
Hunt-Channel-Progress-Time: 0
Hunt-Channel-Progress-Media-Time: 0
Hunt-Channel-Hangup-Time: 0
Hunt-Channel-Transfer-Time: 0
Hunt-Channel-Resurrect-Time: 0
Hunt-Channel-Bridged-Time: 0
Hunt-Channel-Last-Hold: 0
Hunt-Channel-Hold-Accum: 0
Hunt-Screen-Bit: true
Hunt-Privacy-Hide-Name: false
Hunt-Privacy-Hide-Number: false
'
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] key_name:
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] key_value:
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] tag_name:
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] section: dialplan
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Dialed number : 22549366911
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = 22549366911 AND status=0 limit 1
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Caller Id name / number : Gerald Chenyi / 18323352735
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [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.always,A.always_destination,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 ="22549366911" LIMIT 1
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="22549366911" limit 1
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call direction : outbound
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCAL_NUMBER_INFO] Query :select * from local_number WHERE number = '22549366911' and status = 0 limit 1
2019-04-30 12:22:57.694002 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [Accountcode : 18323352735]
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix from pricelists WHERE (routing_prefix ='^22549366911.*' OR routing_prefix ='^2254936691.*' OR routing_prefix ='^225493669.*' OR routing_prefix ='^22549366.*' OR routing_prefix ='^2254936.*' OR routing_prefix ='^225493.*' OR routing_prefix ='^22549.*' OR routing_prefix ='^2254.*' OR routing_prefix ='^225.*' OR routing_prefix ='^22.*' OR routing_prefix ='^2.*' OR routing_prefix ='--') AND status = 0 limit 1
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "18323352735" OR id="18323352735") AND status=0 AND deleted=0 AND (expiry >= '2019-04-30 17:22:57' OR expiry = '0000-00-00 00:00:00') limit 1
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns ='^22549366911.*' OR blocked_patterns ='^2254936691.*' OR blocked_patterns ='^225493669.*' OR blocked_patterns ='^22549366.*' OR blocked_patterns ='^2254936.*' OR blocked_patterns ='^225493.*' OR blocked_patterns ='^22549.*' OR blocked_patterns ='^2254.*' OR blocked_patterns ='^225.*' OR blocked_patterns ='^22.*' OR blocked_patterns ='^2.*' OR blocked_patterns ='--') AND accountid = 444 limit 1
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT * FROM packages as P inner join package_patterns as PKGPTR on P.id = PKGPTR.package_id WHERE (patterns ='^22549366911.*' OR patterns ='^2254936691.*' OR patterns ='^225493669.*' OR patterns ='^22549366.*' OR patterns ='^2254936.*' OR patterns ='^225493.*' OR patterns ='^22549.*' OR patterns ='^2254.*' OR patterns ='^225.*' OR patterns ='^22.*' OR patterns ='^2.*' OR patterns ='--') AND status = 0 AND pricelist_id = 1 ORDER BY LENGTH(PKGPTR.patterns) DESC LIMIT 1
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="22549366911" AND A.accountid = '444' limit 1
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 22549366911
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [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.always,A.always_destination,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 ="22549366911" LIMIT 1
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="22549366911" limit 1
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] New Call direction : outbound
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Account Information ===================
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] User id : 444
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] Account code : 18323352735
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] Ratecard id : 1
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] ========================================================
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] [DIALPLAN] Caller ID Translation Starts
2019-04-30 12:22:57.694002 [WARNING] switch_cpp.cpp:1365 [ASTPP] [DONUMBERTRANSLATION] Before number translation : 18323352735
2019-04-30 12:22:57.694002 [WARNING] switch_cpp.cpp:1365 [ASTPP] [DONUMBERTRANSLATION] After number translation : 8323352735
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] [DIALPLAN] Caller ID Translation Ends
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern ='^22549366911.*' OR pattern ='^2254936691.*' OR pattern ='^225493669.*' OR pattern ='^22549366.*' OR pattern ='^2254936.*' OR pattern ='^225493.*' OR pattern ='^22549.*' OR pattern ='^2254.*' OR pattern ='^225.*' OR pattern ='^22.*' OR pattern ='^2.*' OR pattern ='--') AND status = 0 AND pricelist_id = 1 ORDER BY LENGTH(pattern) DESC,cost DESC LIMIT 1
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Rates Information ===================
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] ID : 809
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] Connectcost : 0.00000
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] Includedseconds : 5
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] Cost : 0.40000
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] comment : Ivory Coast
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] Accid : 444
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] Trunk ID: 0
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] Routing type:
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] ================================================================
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] Call Max length duration : 98.621 minutes
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Routing Type : 1
2019-04-30 12:22:57.694002 [INFO] switch_cpp.cpp:1365 [ASTPP] [DIALPLAN] User Rate ID : 809
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Trunk ids 1111******************:0
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_COUNTER_INFO] force_trunk_type here :1
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_CARRIER_RATES]1111122222 Query :SELECT TK.id as trunk_id,TK.codec,GW.name as path,GW.dialplan_variable,TK.provider_id,TR.init_inc,TK.status,TK.cid_translation,TK.leg_timeout,TK.dialed_modify,TK.maxchannels,TR.pattern,TR.id as outbound_route_id,TR.connectcost,TR.comment,TR.includedseconds,TR.cost,TR.inc,TR.prepend,TR.strip,(select percentage from routing where routes_id=(select id from routes where (pattern ='^22549366911.*' OR pattern ='^2254936691.*' OR pattern ='^225493669.*' OR pattern ='^22549366.*' OR pattern ='^2254936.*' OR pattern ='^225493.*' OR pattern ='^22549.*' OR pattern ='^2254.*' OR pattern ='^225.*' OR pattern ='^22.*' OR pattern ='^2.*' OR pattern ='--') and pricelist_id=1 ORDER BY LENGTH(pattern) DESC,cost DESC limit 1) AND trunk_id = TR.trunk_id limit 1) as percentage,(select call_count from routing where routes_id=(select id from routes where (pattern ='^22549366911.*' OR pattern ='^2254936691.*' OR pattern ='^225493669.*' OR pattern ='^22549366.*' OR pattern ='^2254936.*' OR pattern ='^225493.*' OR pattern ='^22549.*' OR pattern ='^2254.*' OR pattern ='^225.*' OR pattern ='^22.*' OR pattern ='^2.*' OR pattern ='--') and pricelist_id=1 ORDER BY LENGTH(pattern) DESC,cost DESC limit 1) AND trunk_id = TR.trunk_id) as trunk_count,(select call_count from routes where (pattern ='^22549366911.*' OR pattern ='^2254936691.*' OR pattern ='^225493669.*' OR pattern ='^22549366.*' OR pattern ='^2254936.*' OR pattern ='^225493.*' OR pattern ='^22549.*' OR pattern ='^2254.*' OR pattern ='^225.*' OR pattern ='^22.*' OR pattern ='^2.*' OR pattern ='--') and pricelist_id=1 ORDER BY LENGTH(pattern) DESC,cost DESC limit 1 ) as routes_count,100 * (select call_count from routing where routes_id=(select id from routes where (pattern ='^22549366911.*' OR pattern ='^2254936691.*' OR pattern ='^225493669.*' OR pattern ='^22549366.*' OR pattern ='^2254936.*' OR pattern ='^225493.*' OR pattern ='^22549.*' OR pattern ='^2254.*' OR pattern ='^225.*' OR pattern ='^22.*' OR pattern ='^2.*' OR pattern ='--') and pricelist_id=1 ORDER BY LENGTH(pattern) DESC,cost DESC limit 1) AND trunk_id = TR.trunk_id) / (select call_count from routes where (pattern ='^22549366911.*' OR pattern ='^2254936691.*' OR pattern ='^225493669.*' OR pattern ='^22549366.*' OR pattern ='^2254936.*' OR pattern ='^225493.*' OR pattern ='^22549.*' OR pattern ='^2254.*' OR pattern ='^225.*' OR pattern ='^22.*' OR pattern ='^2.*' OR pattern ='--') and pricelist_id=1 ORDER BY LENGTH(pattern) DESC,cost DESC limit 1 ) AS percent_atual,(select name from gateways where status=0 AND id = TK.failover_gateway_id) as path1,(select name from gateways where status=0 AND id = TK.failover_gateway_id1) as path2 FROM outbound_routes as TR,trunks as TK,gateways as GW WHERE GW.status=0 AND GW.id= TK.gateway_id AND TK.status=0 AND TK.id= TR.trunk_id AND (pattern ='^22549366911.*' OR pattern ='^2254936691.*' OR pattern ='^225493669.*' OR pattern ='^22549366.*' OR pattern ='^2254936.*' OR pattern ='^225493.*' OR pattern ='^22549.*' OR pattern ='^2254.*' OR pattern ='^225.*' OR pattern ='^22.*' OR pattern ='^2.*' OR pattern ='--') AND TR.status = 0 AND TR.trunk_id IN (0)
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Trunk ids ******************:0
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_ROUTING_TYPE] Routing type ******************:1
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.codec,GW.name as path,GW.dialplan_variable,TK.provider_id,TR.init_inc,TK.status,TK.cid_translation,TK.leg_timeout,TK.dialed_modify,TK.maxchannels,TR.pattern,TR.id as outbound_route_id,TR.connectcost,TR.comment,TR.includedseconds,TR.cost,TR.inc,TR.prepend,TR.strip,(select percentage from routing where routes_id=(select id from routes where (pattern ='^22549366911.*' OR pattern ='^2254936691.*' OR pattern ='^225493669.*' OR pattern ='^22549366.*' OR pattern ='^2254936.*' OR pattern ='^225493.*' OR pattern ='^22549.*' OR pattern ='^2254.*' OR pattern ='^225.*' OR pattern ='^22.*' OR pattern ='^2.*' OR pattern ='--') and pricelist_id=1 ORDER BY LENGTH(pattern) DESC,cost DESC limit 1) AND trunk_id = TR.trunk_id limit 1) as percentage,(select call_count from routing where routes_id=(select id from routes where (pattern ='^22549366911.*' OR pattern ='^2254936691.*' OR pattern ='^225493669.*' OR pattern ='^22549366.*' OR pattern ='^2254936.*' OR pattern ='^225493.*' OR pattern ='^22549.*' OR pattern ='^2254.*' OR pattern ='^225.*' OR pattern ='^22.*' OR pattern ='^2.*' OR pattern ='--') and pricelist_id=1 ORDER BY LENGTH(pattern) DESC,cost DESC limit 1) AND trunk_id = TR.trunk_id) as trunk_count,(select call_count from routes where (pattern ='^22549366911.*' OR pattern ='^2254936691.*' OR pattern ='^225493669.*' OR pattern ='^22549366.*' OR pattern ='^2254936.*' OR pattern ='^225493.*' OR pattern ='^22549.*' OR pattern ='^2254.*' OR pattern ='^225.*' OR pattern ='^22.*' OR pattern ='^2.*' OR pattern ='--') and pricelist_id=1 ORDER BY LENGTH(pattern) DESC,cost DESC limit 1 ) as routes_count,100 * (select call_count from routing where routes_id=(select id from routes where (pattern ='^22549366911.*' OR pattern ='^2254936691.*' OR pattern ='^225493669.*' OR pattern ='^22549366.*' OR pattern ='^2254936.*' OR pattern ='^225493.*' OR pattern ='^22549.*' OR pattern ='^2254.*' OR pattern ='^225.*' OR pattern ='^22.*' OR pattern ='^2.*' OR pattern ='--') and pricelist_id=1 ORDER BY LENGTH(pattern) DESC,cost DESC limit 1) AND trunk_id = TR.trunk_id) / (select call_count from routes where (pattern ='^22549366911.*' OR pattern ='^2254936691.*' OR pattern ='^225493669.*' OR pattern ='^22549366.*' OR pattern ='^2254936.*' OR pattern ='^225493.*' OR pattern ='^22549.*' OR pattern ='^2254.*' OR pattern ='^225.*' OR pattern ='^22.*' OR pattern ='^2.*' OR pattern ='--') and pricelist_id=1 ORDER BY LENGTH(pattern) DESC,cost DESC limit 1 ) AS percent_atual,(select name from gateways where status=0 AND id = TK.failover_gateway_id) as path1,(select name from gateways where status=0 AND id = TK.failover_gateway_id1) as path2 FROM outbound_routes as TR,trunks as TK,gateways as GW WHERE GW.status=0 AND GW.id= TK.gateway_id AND TK.status=0 AND TK.id= TR.trunk_id AND (pattern ='^22549366911.*' OR pattern ='^2254936691.*' OR pattern ='^225493669.*' OR pattern ='^22549366.*' OR pattern ='^2254936.*' OR pattern ='^225493.*' OR pattern ='^22549.*' OR pattern ='^2254.*' OR pattern ='^225.*' OR pattern ='^22.*' OR pattern ='^2.*' OR pattern ='--') AND TR.status = 0 AND TR.trunk_id IN (0) ORDER by IF( percent_atual >= percentage, percentage, '' ) ASC , IF( percent_atual <= percentage, percentage, '' ) DESC
2019-04-30 12:22:57.694002 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [Dialplan] No termination rates found...!!!
2019-04-30 12:22:57.694002 [DEBUG] switch_cpp.cpp:1365 [ASTPP] 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="22549366911">
<condition field="destination_number" expression="22549366911">
<action application="log" data="WARNING Accountcode 18323352735. Dialed number (22549366911) termination rates not found!!"/>
<action application="playback" data="/usr/local/freeswitch/sounds/en/us/callie/astpp-badphone.wav"/>
<action application="set" data="original_caller_id_name=Gerald Chenyi"/>
<action application="set" data="original_caller_id_number=Gerald Chenyi"/>
<action application="set" data="error_cdr=1"/>
<action application="set" data="callstart=2019-04-30 17:22:57"/>
<action application="set" data="account_id=444"/>
<action application="set" data="call_direction=outbound"/>
<action application="set" data="sip_ignore_remote_cause=true"/>
<action application="set" data="call_processed=internal"/>
<action application="set" data="effective_destination_number=22549366911"/>
<action application="set" data="last_bridge_hangup_cause=TERMINATION_RATE_NOT_FOUND"/>
<action application="hangup" data="TERMINATION_RATE_NOT_FOUND"/>
</condition>
</extension>
</context>
</section>
</document>
2019-04-30 12:22:57.694002 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f82700602f0 released.
Dialplan: sofia/default/18323352735@login.lugertel.com parsing [default->22549366911] continue=false
Dialplan: sofia/default/18323352735@login.lugertel.com Regex (PASS) [22549366911] destination_number(22549366911) =~ /22549366911/ break=on-false
Dialplan: sofia/default/18323352735@login.lugertel.com Action log(WARNING Accountcode 18323352735. Dialed number (22549366911) termination rates not found!!)
Dialplan: sofia/default/18323352735@login.lugertel.com Action playback(/usr/local/freeswitch/sounds/en/us/callie/astpp-badphone.wav)
Dialplan: sofia/default/18323352735@login.lugertel.com Action set(original_caller_id_name=Gerald Chenyi)
Dialplan: sofia/default/18323352735@login.lugertel.com Action set(original_caller_id_number=Gerald Chenyi)
Dialplan: sofia/default/18323352735@login.lugertel.com Action set(error_cdr=1)
Dialplan: sofia/default/18323352735@login.lugertel.com Action set(callstart=2019-04-30 17:22:57)
Dialplan: sofia/default/18323352735@login.lugertel.com Action set(account_id=444)
Dialplan: sofia/default/18323352735@login.lugertel.com Action set(call_direction=outbound)
Dialplan: sofia/default/18323352735@login.lugertel.com Action set(sip_ignore_remote_cause=true)
Dialplan: sofia/default/18323352735@login.lugertel.com Action set(call_processed=internal)
Dialplan: sofia/default/18323352735@login.lugertel.com Action set(effective_destination_number=22549366911)
Dialplan: sofia/default/18323352735@login.lugertel.com Action set(last_bridge_hangup_cause=TERMINATION_RATE_NOT_FOUND)
Dialplan: sofia/default/18323352735@login.lugertel.com Action hangup(TERMINATION_RATE_NOT_FOUND)
2019-04-30 12:22:57.694002 [DEBUG] switch_core_state_machine.c:286 (sofia/default/18323352735@login.lugertel.com) State Change CS_ROUTING -> CS_EXECUTE
2019-04-30 12:22:57.694002 [DEBUG] switch_core_state_machine.c:643 (sofia/default/18323352735@login.lugertel.com) State ROUTING going to sleep
2019-04-30 12:22:57.694002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/18323352735@login.lugertel.com) Running State Change CS_EXECUTE (Cur 1 Tot 123782)
2019-04-30 12:22:57.694002 [DEBUG] switch_core_state_machine.c:650 (sofia/default/18323352735@login.lugertel.com) State EXECUTE
2019-04-30 12:22:57.694002 [DEBUG] mod_sofia.c:198 sofia/default/18323352735@login.lugertel.com SOFIA EXECUTE
2019-04-30 12:22:57.694002 [DEBUG] switch_core_state_machine.c:328 sofia/default/18323352735@login.lugertel.com Standard EXECUTE
EXECUTE sofia/default/18323352735@login.lugertel.com log(WARNING Accountcode 18323352735. Dialed number (22549366911) termination rates not found!!)
2019-04-30 12:22:57.694002 [WARNING] mod_dptools.c:1742 Accountcode 18323352735. Dialed number (22549366911) termination rates not found!!