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:
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!!